2013-02-19T22:55:41.111Z,1361314541.111 [Supervisor](DEBUG): Initializing supervisor.
2013-02-19T22:55:41.113Z,1361314541.114 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2013-02-19T22:55:41.114Z,1361314541.114 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2013-02-19T22:55:41.123Z,1361314541.122 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0
2013-02-19T22:55:41.126Z,1361314541.126 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2013-02-19T22:55:41.139Z,1361314541.139 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2013-02-19T22:55:41.166Z,1361314541.166 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0
2013-02-19T22:55:41.167Z,1361314541.167 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2013-02-19T22:55:41.177Z,1361314541.177 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403CB4E0
2013-02-19T22:55:41.178Z,1361314541.178 [Supervisor](INFO): Looking for Config files in directory: Config/
2013-02-19T22:55:41.186Z,1361314541.186 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2013-02-19T22:55:41.541Z,1361314541.541 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2013-02-19T22:55:41.542Z,1361314541.542 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2013-02-19T22:55:41.749Z,1361314541.749 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2013-02-19T22:55:41.750Z,1361314541.750 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2013-02-19T22:55:41.847Z,1361314541.847 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2013-02-19T22:55:41.849Z,1361314541.849 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2013-02-19T22:55:42.073Z,1361314542.073 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2013-02-19T22:55:42.074Z,1361314542.074 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2013-02-19T22:55:42.225Z,1361314542.225 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2013-02-19T22:55:42.228Z,1361314542.228 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2013-02-19T22:55:42.488Z,1361314542.488 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2013-02-19T22:55:42.491Z,1361314542.491 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2013-02-19T22:55:42.696Z,1361314542.696 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2013-02-19T22:55:42.700Z,1361314542.700 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2013-02-19T22:55:42.987Z,1361314542.987 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2013-02-19T22:55:42.989Z,1361314542.990 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2013-02-19T22:55:43.103Z,1361314543.103 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2013-02-19T22:55:43.103Z,1361314543.103 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2013-02-19T22:55:43.561Z,1361314543.561 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2013-02-19T22:55:43.562Z,1361314543.562 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2013-02-19T22:55:43.697Z,1361314543.697 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2013-02-19T22:55:43.698Z,1361314543.698 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2013-02-19T22:55:43.798Z,1361314543.797 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2013-02-19T22:55:43.801Z,1361314543.801 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2013-02-19T22:55:43.921Z,1361314543.921 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2013-02-19T22:55:44.064Z,1361314544.064 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2013-02-19T22:55:44.163Z,1361314544.163 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2013-02-19T22:55:44.338Z,1361314544.338 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2013-02-19T22:55:44.573Z,1361314544.573 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2013-02-19T22:55:44.746Z,1361314544.746 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2013-02-19T22:55:44.851Z,1361314544.851 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2013-02-19T22:55:44.950Z,1361314544.950 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2013-02-19T22:55:44.982Z,1361314544.982 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2013-02-19T22:55:45.187Z,1361314545.187 [InternalSim] Loaded
2013-02-19T22:55:45.188Z,1361314545.188 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2013-02-19T22:55:45.188Z,1361314545.188 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2013-02-19T22:55:45.189Z,1361314545.189 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2013-02-19T22:55:45.273Z,1361314545.273 [SBIT](DEBUG): Construct Startup Built In Test.
2013-02-19T22:55:45.307Z,1361314545.307 [SBIT] Loaded
2013-02-19T22:55:45.308Z,1361314545.307 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2013-02-19T22:55:45.308Z,1361314545.309 [IBIT](DEBUG): Construct Initiated Built In Test.
2013-02-19T22:55:45.342Z,1361314545.342 [IBIT] Loaded
2013-02-19T22:55:45.342Z,1361314545.342 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2013-02-19T22:55:45.346Z,1361314545.346 [CBIT](DEBUG): Construct CBIT Built In Test.
2013-02-19T22:55:45.481Z,1361314545.481 [CBIT] Loaded
2013-02-19T22:55:45.481Z,1361314545.481 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2013-02-19T22:55:45.482Z,1361314545.482 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2013-02-19T22:55:45.483Z,1361314545.483 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2013-02-19T22:55:45.664Z,1361314545.664 [BuoyancyServo] Loaded
2013-02-19T22:55:45.665Z,1361314545.665 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2013-02-19T22:55:45.677Z,1361314545.677 [ElevatorServo] Loaded
2013-02-19T22:55:45.678Z,1361314545.678 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2013-02-19T22:55:45.694Z,1361314545.694 [MassServo] Loaded
2013-02-19T22:55:45.694Z,1361314545.694 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2013-02-19T22:55:45.706Z,1361314545.706 [RudderServo] Loaded
2013-02-19T22:55:45.707Z,1361314545.707 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2013-02-19T22:55:45.719Z,1361314545.719 [ThrusterServo] Loaded
2013-02-19T22:55:45.719Z,1361314545.719 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2013-02-19T22:55:45.719Z,1361314545.719 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2013-02-19T22:55:45.720Z,1361314545.720 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2013-02-19T22:55:45.757Z,1361314545.757 [DepthRateCalculator] Loaded
2013-02-19T22:55:45.757Z,1361314545.757 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2013-02-19T22:55:51.372Z,1361314551.372 [HFRadarModelCalc] Loaded
2013-02-19T22:55:51.373Z,1361314551.373 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2013-02-19T22:55:51.389Z,1361314551.389 [NavChart] Loaded
2013-02-19T22:55:51.389Z,1361314551.389 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2013-02-19T22:55:51.395Z,1361314551.395 [PitchRateCalculator] Loaded
2013-02-19T22:55:51.396Z,1361314551.396 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2013-02-19T22:55:51.402Z,1361314551.402 [SpeedCalculator] Loaded
2013-02-19T22:55:51.403Z,1361314551.402 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2013-02-19T22:55:51.422Z,1361314551.422 [TempGradientCalculator] Loaded
2013-02-19T22:55:51.422Z,1361314551.422 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2013-02-19T22:55:51.428Z,1361314551.428 [YawRateCalculator] Loaded
2013-02-19T22:55:51.428Z,1361314551.428 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2013-02-19T22:55:51.475Z,1361314551.475 [Navigation] Loaded
2013-02-19T22:55:51.475Z,1361314551.475 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread.
2013-02-19T22:55:51.475Z,1361314551.475 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2013-02-19T22:55:51.476Z,1361314551.476 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2013-02-19T22:55:51.770Z,1361314551.770 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2013-02-19T22:55:51.771Z,1361314551.771 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2013-02-19T22:55:51.812Z,1361314551.812 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2013-02-19T22:55:51.813Z,1361314551.813 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2013-02-19T22:55:51.876Z,1361314551.876 [VerticalControl](DEBUG): Construct VerticalControl.
2013-02-19T22:55:51.981Z,1361314551.981 [VerticalControl] Loaded
2013-02-19T22:55:51.982Z,1361314551.981 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2013-02-19T22:55:51.983Z,1361314551.983 [HorizontalControl](DEBUG): Construct HorizontalControl.
2013-02-19T22:55:52.045Z,1361314552.045 [HorizontalControl] Loaded
2013-02-19T22:55:52.045Z,1361314552.045 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2013-02-19T22:55:52.046Z,1361314552.046 [SpeedControl](DEBUG): Construct SpeedControl.
2013-02-19T22:55:52.048Z,1361314552.048 [SpeedControl] Loaded
2013-02-19T22:55:52.048Z,1361314552.048 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2013-02-19T22:55:52.049Z,1361314552.049 [LoopControl](DEBUG): Construct LoopControl.
2013-02-19T22:55:52.054Z,1361314552.054 [LoopControl] Loaded
2013-02-19T22:55:52.054Z,1361314552.054 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2013-02-19T22:55:52.055Z,1361314552.055 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2013-02-19T22:55:52.055Z,1361314552.055 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2013-02-19T22:55:52.063Z,1361314552.063 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2013-02-19T22:55:52.068Z,1361314552.068 [AsyncPiEstimator] Loaded
2013-02-19T22:55:52.069Z,1361314552.069 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2013-02-19T22:55:52.070Z,1361314552.070 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 406A44E0
2013-02-19T22:55:52.071Z,1361314552.071 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2013-02-19T22:55:52.071Z,1361314552.071 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2013-02-19T22:55:52.262Z,1361314552.262 [AHRS_sp3003D] Loaded
2013-02-19T22:55:52.262Z,1361314552.262 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2013-02-19T22:55:52.559Z,1361314552.559 [Batt_Ocean_Server] Loaded
2013-02-19T22:55:52.559Z,1361314552.559 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread.
2013-02-19T22:55:52.573Z,1361314552.573 [Depth_Keller] Loaded
2013-02-19T22:55:52.573Z,1361314552.573 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2013-02-19T22:55:52.583Z,1361314552.583 [DropWeight] Loaded
2013-02-19T22:55:52.584Z,1361314552.584 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2013-02-19T22:55:52.771Z,1361314552.772 [DVL_micro] Loaded
2013-02-19T22:55:52.772Z,1361314552.772 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread.
2013-02-19T22:55:52.774Z,1361314552.774 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 407284E0
2013-02-19T22:55:52.867Z,1361314552.867 [NAL9602] Loaded
2013-02-19T22:55:52.868Z,1361314552.868 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2013-02-19T22:55:52.924Z,1361314552.924 [Onboard] Loaded
2013-02-19T22:55:52.924Z,1361314552.924 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2013-02-19T22:55:52.936Z,1361314552.935 [Radio_Freewave] Loaded
2013-02-19T22:55:52.936Z,1361314552.936 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread.
2013-02-19T22:55:52.937Z,1361314552.937 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2013-02-19T22:55:52.937Z,1361314552.937 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2013-02-19T22:55:53.029Z,1361314553.029 [Aanderaa_O2] Loaded
2013-02-19T22:55:53.029Z,1361314553.029 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2013-02-19T22:55:53.043Z,1361314553.043 [ISUS] Loaded
2013-02-19T22:55:53.044Z,1361314553.044 [ComponentRegistry](DEBUG): SyncComponent "ISUS" handled in the control thread.
2013-02-19T22:55:53.060Z,1361314553.060 [PAR_Licor] Loaded
2013-02-19T22:55:53.060Z,1361314553.060 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2013-02-19T22:55:53.095Z,1361314553.095 [WetLabsBB2FL] Loaded
2013-02-19T22:55:53.095Z,1361314553.095 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2013-02-19T22:55:53.098Z,1361314553.098 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4077A4E0
2013-02-19T22:55:53.099Z,1361314553.099 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2013-02-19T22:55:53.101Z,1361314553.101 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2013-02-19T22:55:53.102Z,1361314553.102 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2013-02-19T22:55:53.112Z,1361314553.112 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2013-02-19T22:55:53.114Z,1361314553.114 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407AA4E0
2013-02-19T22:55:53.118Z,1361314553.118 [Supervisor](DEBUG): Running supervisor.
2013-02-19T22:55:53.119Z,1361314553.119 [CommandLine](INFO): Thread ID is 755
2013-02-19T22:55:53.123Z,1361314553.123 [controlThread](INFO): Thread ID is 754
2013-02-19T22:55:53.123Z,1361314553.123 [controlThread](DEBUG): Initializing ControlThread
2013-02-19T22:55:53.123Z,1361314553.123 [CycleStarter](INFO): Thread ID is 753
2013-02-19T22:55:53.124Z,1361314553.124 [InternalSim](DEBUG):  InternalSim initializing...
2013-02-19T22:55:53.234Z,1361314553.234 [logger](INFO): Thread ID is 756
2013-02-19T22:55:53.335Z,1361314553.335 [AsyncPiEstimator](INFO): Thread ID is 818
2013-02-19T22:55:53.335Z,1361314553.335 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2013-02-19T22:55:53.432Z,1361314553.432 [DVL_micro](INFO): Thread ID is 819
2013-02-19T22:55:53.468Z,1361314553.468 [DVL_micro](INFO): Initializing
2013-02-19T22:55:53.468Z,1361314553.468 [DVL_micro](INFO): start:Powering up
2013-02-19T22:55:53.469Z,1361314553.469 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-02-19T22:55:53.470Z,1361314553.470 [DVL_micro](INFO): Cycling power to configure device.
2013-02-19T22:55:53.507Z,1361314553.507 [WetLabsBB2FL](INFO): Thread ID is 820
2013-02-19T22:55:53.507Z,1361314553.507 [WetLabsBB2FL](INFO): Powering down
2013-02-19T22:55:53.531Z,1361314553.531 [NavChartDb](INFO): Thread ID is 821
2013-02-19T22:55:53.548Z,1361314553.548 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory
2013-02-19T22:55:53.559Z,1361314553.559 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2013-02-19T22:55:53.561Z,1361314553.561 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000
2013-02-19T22:55:53.562Z,1361314553.562 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000
2013-02-19T22:55:53.564Z,1361314553.564 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000
2013-02-19T22:55:53.566Z,1361314553.566 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000
2013-02-19T22:55:53.567Z,1361314553.568 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000
2013-02-19T22:55:53.569Z,1361314553.569 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000
2013-02-19T22:55:53.571Z,1361314553.571 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000
2013-02-19T22:55:53.573Z,1361314553.573 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000
2013-02-19T22:55:53.580Z,1361314553.580 [SBIT](INFO): Initialize SBIT Component.
2013-02-19T22:55:53.581Z,1361314553.581 [SBIT](IMPORTANT): Tethys CM Info:
$Rev: 10131
2013-02-19T22:55:53.581Z,1361314553.581 [IBIT](INFO): Initialize IBIT Component.
2013-02-19T22:55:53.582Z,1361314553.582 [CBIT](DEBUG): Initialize CBIT Component.
2013-02-19T22:55:53.582Z,1361314553.582 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2013-02-19T22:55:53.607Z,1361314553.607 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2013-02-19T22:55:53.608Z,1361314553.608 [NavChart](DEBUG): Initialize NavChart Derivation.
2013-02-19T22:55:53.608Z,1361314553.608 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2013-02-19T22:55:53.608Z,1361314553.608 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2013-02-19T22:55:53.608Z,1361314553.608 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2013-02-19T22:55:53.610Z,1361314553.610 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2013-02-19T22:55:53.610Z,1361314553.610 [Navigation](DEBUG): Initializing Navigation.
2013-02-19T22:55:53.610Z,1361314553.611 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2013-02-19T22:55:53.612Z,1361314553.612 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2013-02-19T22:55:53.613Z,1361314553.613 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2013-02-19T22:55:53.614Z,1361314553.614 [LoopControl](DEBUG): Initialize LoopControlComponent.
2013-02-19T22:55:53.754Z,1361314553.754 [NavChartDb](INFO): Setup scan of Resources/US5CA62M.000
2013-02-19T22:55:54.959Z,1361314554.958 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout
2013-02-19T22:55:54.959Z,1361314554.959 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing
2013-02-19T22:55:54.959Z,1361314554.959 [Batt_Ocean_Server] Communications Fault, FailCount= 1
2013-02-19T22:55:54.959Z,1361314554.959 [Batt_Ocean_Server](ERROR): Communications Fault
2013-02-19T22:55:54.987Z,1361314554.987 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2013-02-19T22:55:55.025Z,1361314555.026 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2013-02-19T22:55:55.064Z,1361314555.064 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface RunIn="Progression"/>

    <Aggregate Id="StartupSatComms" >

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

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

    </Aggregate>

</Mission>


2013-02-19T22:55:55.065Z,1361314555.065 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2013-02-19T22:55:55.121Z,1361314555.121 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2013-02-19T22:55:55.123Z,1361314555.123 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2013-02-19T22:55:55.130Z,1361314555.130 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-02-19T22:55:55.158Z,1361314555.158 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2013-02-19T22:55:55.161Z,1361314555.161 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-02-19T22:55:55.168Z,1361314555.168 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2013-02-19T22:55:55.188Z,1361314555.188 [Default:D.SetSpeed](DEBUG): Construct.
2013-02-19T22:55:55.191Z,1361314555.191 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2013-02-19T22:55:55.200Z,1361314555.200 [Default:F.Wait](DEBUG): Construct Wait.
2013-02-19T22:55:55.204Z,1361314555.204 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
xmlns:Control="Tethys/Control"
xmlns:Guidance="Tethys/Guidance" 
xmlns:Units="Tethys/Units"
xmlns:Universal="Tethys/Universal"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

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

    <Aggregate Id="GPS" >

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

<!-- Run at high speed -->

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

<!-- Get to the surface -->

        <Guidance:GoToSurface RunIn="Sequence"/>

<!-- Get GPS fix -->

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

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

    </Aggregate>

    <Aggregate Id="Iridium" >

<!-- Run at high speed -->

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

<!-- Get to the surface -->

        <Guidance:GoToSurface RunIn="Sequence"/>

<!-- Do data comms -->

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

    </Aggregate>

    <Aggregate Id="CallIridium" >

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

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

        <Call RefId="Iridium"/>

    </Aggregate>

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

<!-- Run at low speed -->

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

    <Guidance:GoToSurface RunIn="Parallel"/>

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

</Mission>


2013-02-19T22:55:55.208Z,1361314555.208 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,NAL9602,Onboard,Radio_Freewave,Aanderaa_O2,ISUS,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,HFRadarModelCalc,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,
2013-02-19T22:55:55.232Z,1361314555.232 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-02-19T22:55:55.425Z,1361314555.425 [Radio_Freewave](INFO): Powering up
2013-02-19T22:55:55.586Z,1361314555.586 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-02-19T22:55:55.587Z,1361314555.587 [DVL_micro](INFO): Querying output modes
2013-02-19T22:55:55.587Z,1361314555.587 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-02-19T22:55:55.603Z,1361314555.603 [DVL_micro](DEBUG): cmdResponse: 01
2013-02-19T22:55:55.603Z,1361314555.603 [DVL_micro](INFO): NQ1 output enabled
2013-02-19T22:55:55.603Z,1361314555.603 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-02-19T22:55:55.614Z,1361314555.614 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2013-02-19T22:55:55.619Z,1361314555.619 [DVL_micro](INFO): pause:Powering down
2013-02-19T22:55:55.691Z,1361314555.691 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:55:55.698Z,1361314555.698 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-02-19T22:55:55.706Z,1361314555.706 [ElevatorServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:55:55.710Z,1361314555.710 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2013-02-19T22:55:55.716Z,1361314555.716 [MassServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:55:55.717Z,1361314555.717 [MassServo](DEBUG): Initializing MassServo.
2013-02-19T22:55:55.723Z,1361314555.723 [RudderServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:55:55.726Z,1361314555.726 [RudderServo](DEBUG): Initializing RudderServo.
2013-02-19T22:55:55.732Z,1361314555.732 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:55:55.733Z,1361314555.733 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-02-19T22:55:55.779Z,1361314555.779 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server
2013-02-19T22:55:55.780Z,1361314555.780 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server
2013-02-19T22:55:55.780Z,1361314555.780 [Batt_Ocean_Server] No Fault, FailCount= 1
2013-02-19T22:55:57.581Z,1361314557.581 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2013-02-19T22:55:58.218Z,1361314558.218 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-02-19T22:55:58.218Z,1361314558.218 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-02-19T22:55:58.219Z,1361314558.218 [BuoyancyServo] Communications Fault, FailCount= 1
2013-02-19T22:55:58.219Z,1361314558.219 [BuoyancyServo](ERROR): Communications Fault
2013-02-19T22:55:58.722Z,1361314558.722 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2013-02-19T22:55:58.722Z,1361314558.722 [ElevatorServo](FAULT): Elevator failed to initialize
2013-02-19T22:55:58.722Z,1361314558.722 [ElevatorServo] Communications Fault, FailCount= 1
2013-02-19T22:55:58.722Z,1361314558.722 [ElevatorServo](ERROR): Communications Fault
2013-02-19T22:55:59.290Z,1361314559.290 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2013-02-19T22:55:59.315Z,1361314559.315 [ThrusterServo](FAULT): Thruster failed to initialize
2013-02-19T22:55:59.316Z,1361314559.316 [ThrusterServo] Communications Fault, FailCount= 1
2013-02-19T22:55:59.316Z,1361314559.316 [ThrusterServo](ERROR): Communications Fault
2013-02-19T22:55:59.361Z,1361314559.361 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-02-19T22:55:59.361Z,1361314559.361 [CBIT](ERROR): Communications Fault in component: ElevatorServo
2013-02-19T22:55:59.361Z,1361314559.361 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2013-02-19T22:55:59.600Z,1361314559.600 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-02-19T22:55:59.601Z,1361314559.601 [BuoyancyServo](INFO): Powering down
2013-02-19T22:55:59.651Z,1361314559.651 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2013-02-19T22:55:59.651Z,1361314559.651 [ElevatorServo](INFO): Powering down
2013-02-19T22:55:59.685Z,1361314559.685 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-02-19T22:55:59.685Z,1361314559.685 [ThrusterServo](INFO): Powering down
2013-02-19T22:56:00.501Z,1361314560.501 [ElevatorServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:56:00.619Z,1361314560.619 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2013-02-19T22:56:00.878Z,1361314560.878 [MassServo](FAULT): Mass initialization uart error:serial timeout
2013-02-19T22:56:00.878Z,1361314560.878 [MassServo] Communications Fault, FailCount= 1
2013-02-19T22:56:00.878Z,1361314560.878 [MassServo](ERROR): Communications Fault
2013-02-19T22:56:01.382Z,1361314561.382 [RudderServo](FAULT): Rudder initialization uart error:serial timeout
2013-02-19T22:56:01.382Z,1361314561.382 [RudderServo] Communications Fault, FailCount= 1
2013-02-19T22:56:01.382Z,1361314561.382 [RudderServo](ERROR): Communications Fault
2013-02-19T22:56:01.504Z,1361314561.504 [CBIT](INFO): Clearing failed state for component ElevatorServo
2013-02-19T22:56:01.504Z,1361314561.504 [ElevatorServo] No Fault, FailCount= 1
2013-02-19T22:56:01.504Z,1361314561.504 [CBIT](ERROR): Communications Fault in component: MassServo
2013-02-19T22:56:01.505Z,1361314561.505 [CBIT](ERROR): Communications Fault in component: RudderServo
2013-02-19T22:56:01.505Z,1361314561.505 [CBIT](INFO): Clearing failed state for component ThrusterServo
2013-02-19T22:56:01.505Z,1361314561.505 [ThrusterServo] No Fault, FailCount= 1
2013-02-19T22:56:02.294Z,1361314562.294 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2013-02-19T22:56:02.294Z,1361314562.294 [ElevatorServo](FAULT): Elevator failed to initialize
2013-02-19T22:56:02.294Z,1361314562.294 [ElevatorServo] Communications Fault, FailCount= 2
2013-02-19T22:56:02.294Z,1361314562.294 [ElevatorServo](ERROR): Communications Fault
2013-02-19T22:56:02.295Z,1361314562.295 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-02-19T22:56:02.295Z,1361314562.295 [MassServo](INFO): Powering down
2013-02-19T22:56:02.315Z,1361314562.315 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2013-02-19T22:56:02.315Z,1361314562.315 [RudderServo](INFO): Powering down
2013-02-19T22:56:02.335Z,1361314562.335 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:56:02.335Z,1361314562.335 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-02-19T22:56:02.350Z,1361314562.350 [CBIT](ERROR): Communications Fault in component: ElevatorServo
2013-02-19T22:56:02.596Z,1361314562.596 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2013-02-19T22:56:02.596Z,1361314562.596 [ElevatorServo](INFO): Powering down
2013-02-19T22:56:02.600Z,1361314562.600 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2013-02-19T22:56:02.600Z,1361314562.600 [BuoyancyServo] No Fault, FailCount= 1
2013-02-19T22:56:03.013Z,1361314563.013 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:56:03.127Z,1361314563.127 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-02-19T22:56:03.129Z,1361314563.129 [MassServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:56:03.247Z,1361314563.247 [MassServo](DEBUG): Initializing MassServo.
2013-02-19T22:56:03.248Z,1361314563.248 [RudderServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:56:03.367Z,1361314563.367 [RudderServo](DEBUG): Initializing RudderServo.
2013-02-19T22:56:03.890Z,1361314563.890 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2013-02-19T22:56:03.908Z,1361314563.908 [ThrusterServo](FAULT): Thruster failed to initialize
2013-02-19T22:56:03.908Z,1361314563.908 [ThrusterServo] Communications Fault, FailCount= 2
2013-02-19T22:56:03.909Z,1361314563.909 [ThrusterServo](ERROR): Communications Fault
2013-02-19T22:56:03.924Z,1361314563.924 [CBIT](INFO): Clearing failed state for component MassServo
2013-02-19T22:56:03.925Z,1361314563.925 [MassServo] No Fault, FailCount= 1
2013-02-19T22:56:03.926Z,1361314563.926 [CBIT](INFO): Clearing failed state for component RudderServo
2013-02-19T22:56:03.927Z,1361314563.927 [RudderServo] No Fault, FailCount= 1
2013-02-19T22:56:03.929Z,1361314563.929 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2013-02-19T22:56:04.746Z,1361314564.746 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-02-19T22:56:04.746Z,1361314564.746 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-02-19T22:56:04.746Z,1361314564.746 [BuoyancyServo] Communications Fault, FailCount= 2
2013-02-19T22:56:04.746Z,1361314564.746 [BuoyancyServo](ERROR): Communications Fault
2013-02-19T22:56:04.747Z,1361314564.747 [ElevatorServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:56:04.867Z,1361314564.867 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2013-02-19T22:56:05.378Z,1361314565.378 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2013-02-19T22:56:05.378Z,1361314565.378 [RudderServo](FAULT): Rudder failed to initialize
2013-02-19T22:56:05.378Z,1361314565.378 [RudderServo] Communications Fault, FailCount= 2
2013-02-19T22:56:05.378Z,1361314565.378 [RudderServo](ERROR): Communications Fault
2013-02-19T22:56:05.379Z,1361314565.379 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-02-19T22:56:05.379Z,1361314565.379 [ThrusterServo](INFO): Powering down
2013-02-19T22:56:05.382Z,1361314565.382 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-02-19T22:56:05.382Z,1361314565.382 [CBIT](INFO): Clearing failed state for component ElevatorServo
2013-02-19T22:56:05.382Z,1361314565.382 [ElevatorServo] No Fault, FailCount= 2
2013-02-19T22:56:05.382Z,1361314565.382 [CBIT](ERROR): Communications Fault in component: RudderServo
2013-02-19T22:56:05.590Z,1361314565.590 [Aanderaa_O2](FAULT): Timed out starting
2013-02-19T22:56:05.590Z,1361314565.591 [Aanderaa_O2] Communications Fault, FailCount= 1
2013-02-19T22:56:05.591Z,1361314565.591 [Aanderaa_O2](ERROR): Communications Fault
2013-02-19T22:56:05.603Z,1361314565.603 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-02-19T22:56:05.605Z,1361314565.605 [BuoyancyServo](INFO): Powering down
2013-02-19T22:56:06.130Z,1361314566.130 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2013-02-19T22:56:06.130Z,1361314566.130 [ElevatorServo](FAULT): Elevator failed to initialize
2013-02-19T22:56:06.130Z,1361314566.130 [ElevatorServo] Communications Fault, FailCount= 3
2013-02-19T22:56:06.130Z,1361314566.130 [ElevatorServo](ERROR): Communications Fault
2013-02-19T22:56:06.132Z,1361314566.132 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2013-02-19T22:56:06.132Z,1361314566.132 [RudderServo](INFO): Powering down
2013-02-19T22:56:06.134Z,1361314566.135 [CBIT](ERROR): Communications Fault in component: ElevatorServo
2013-02-19T22:56:06.135Z,1361314566.135 [CBIT](CRITICAL): Communications Fault in component: ElevatorServo
2013-02-19T22:56:06.135Z,1361314566.135 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2013-02-19T22:56:36.474Z,1361314596.474 [Aanderaa_O2](INFO): Powering down
2013-02-19T22:56:36.507Z,1361314596.507 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2013-02-19T22:56:36.507Z,1361314596.507 [ElevatorServo](INFO): Powering down
2013-02-19T22:56:36.675Z,1361314596.675 [CBIT](INFO): Clearing failed state for component ThrusterServo
2013-02-19T22:56:36.675Z,1361314596.675 [ThrusterServo] No Fault, FailCount= 2
2013-02-19T22:56:37.433Z,1361314597.433 [RudderServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:56:37.547Z,1361314597.547 [RudderServo](DEBUG): Initializing RudderServo.
2013-02-19T22:56:37.548Z,1361314597.548 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:56:37.549Z,1361314597.549 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-02-19T22:56:37.550Z,1361314597.550 [SBIT](IMPORTANT): Beginning Startup BIT
2013-02-19T22:56:37.553Z,1361314597.553 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2013-02-19T22:56:37.553Z,1361314597.553 [BuoyancyServo] No Fault, FailCount= 2
2013-02-19T22:56:37.553Z,1361314597.553 [CBIT](INFO): Clearing failed state for component RudderServo
2013-02-19T22:56:37.554Z,1361314597.554 [RudderServo] No Fault, FailCount= 2
2013-02-19T22:56:37.555Z,1361314597.555 [CBIT](IMPORTANT): Beginning GF scan
2013-02-19T22:56:37.771Z,1361314597.771 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:56:37.891Z,1361314597.891 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-02-19T22:56:37.892Z,1361314597.892 [ElevatorServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:56:38.011Z,1361314598.011 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2013-02-19T22:56:38.678Z,1361314598.678 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2013-02-19T22:56:38.743Z,1361314598.743 [ThrusterServo](FAULT): Thruster failed to initialize
2013-02-19T22:56:38.743Z,1361314598.743 [ThrusterServo] Communications Fault, FailCount= 3
2013-02-19T22:56:38.743Z,1361314598.743 [ThrusterServo](ERROR): Communications Fault
2013-02-19T22:56:38.746Z,1361314598.746 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2013-02-19T22:56:38.747Z,1361314598.747 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2013-02-19T22:56:38.747Z,1361314598.747 [Aanderaa_O2] No Fault, FailCount= 1
2013-02-19T22:56:39.123Z,1361314599.123 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-02-19T22:56:39.123Z,1361314599.123 [ThrusterServo](INFO): Powering down
2013-02-19T22:56:39.878Z,1361314599.878 [MassServo](ERROR): getPosition uart error serial timeout
2013-02-19T22:56:39.878Z,1361314599.878 [MassServo](FAULT): Invalid EZ Servo response:""
2013-02-19T22:56:39.878Z,1361314599.878 [MassServo] Communications Fault, FailCount= 2
2013-02-19T22:56:39.878Z,1361314599.878 [MassServo](ERROR): Communications Fault
2013-02-19T22:56:39.882Z,1361314599.882 [CBIT](ERROR): Communications Fault in component: MassServo
2013-02-19T22:56:40.127Z,1361314600.127 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-02-19T22:56:40.127Z,1361314600.127 [MassServo](INFO): Powering down
2013-02-19T22:56:40.634Z,1361314600.634 [RudderServo](FAULT): Rudder initialization uart error:serial timeout
2013-02-19T22:56:40.634Z,1361314600.634 [RudderServo] Communications Fault, FailCount= 3
2013-02-19T22:56:40.634Z,1361314600.634 [RudderServo](ERROR): Communications Fault
2013-02-19T22:56:40.758Z,1361314600.758 [CBIT](ERROR): Communications Fault in component: RudderServo
2013-02-19T22:56:40.758Z,1361314600.759 [CBIT](CRITICAL): Communications Fault in component: RudderServo
2013-02-19T22:56:40.759Z,1361314600.759 [CBIT](INFO): Clearing failed state for component ThrusterServo
2013-02-19T22:56:40.759Z,1361314600.759 [ThrusterServo] No Fault, FailCount= 3
2013-02-19T22:56:41.474Z,1361314601.474 [BuoyancyServo](FAULT): Buoyancy initialization uart error:serial timeout
2013-02-19T22:56:41.474Z,1361314601.474 [BuoyancyServo] Communications Fault, FailCount= 3
2013-02-19T22:56:41.474Z,1361314601.474 [BuoyancyServo](ERROR): Communications Fault
2013-02-19T22:56:41.475Z,1361314601.475 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2013-02-19T22:56:41.475Z,1361314601.475 [RudderServo](INFO): Powering down
2013-02-19T22:56:41.476Z,1361314601.476 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:56:41.477Z,1361314601.477 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-02-19T22:56:41.481Z,1361314601.480 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-02-19T22:56:41.695Z,1361314601.695 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-02-19T22:56:41.696Z,1361314601.696 [BuoyancyServo](INFO): Powering down
2013-02-19T22:56:41.719Z,1361314601.719 [MassServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:56:41.840Z,1361314601.840 [MassServo](DEBUG): Initializing MassServo.
2013-02-19T22:56:41.844Z,1361314601.844 [CBIT](INFO): Clearing failed state for component MassServo
2013-02-19T22:56:41.844Z,1361314601.844 [MassServo] No Fault, FailCount= 2
2013-02-19T22:56:42.092Z,1361314602.092 [RudderServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:56:42.207Z,1361314602.207 [RudderServo](DEBUG): Initializing RudderServo.
2013-02-19T22:56:42.726Z,1361314602.726 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2013-02-19T22:56:42.755Z,1361314602.755 [ThrusterServo](FAULT): Thruster failed to initialize
2013-02-19T22:56:42.756Z,1361314602.756 [ThrusterServo] Communications Fault, FailCount= 4
2013-02-19T22:56:42.756Z,1361314602.756 [ThrusterServo](ERROR): Communications Fault
2013-02-19T22:56:42.780Z,1361314602.780 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2013-02-19T22:56:43.028Z,1361314603.028 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-02-19T22:56:43.029Z,1361314603.029 [ThrusterServo](INFO): Powering down
2013-02-19T22:56:43.909Z,1361314603.909 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2013-02-19T22:56:43.909Z,1361314603.909 [BuoyancyServo] No Fault, FailCount= 3
2013-02-19T22:56:43.909Z,1361314603.909 [CBIT](INFO): Clearing failed state for component ThrusterServo
2013-02-19T22:56:43.909Z,1361314603.909 [ThrusterServo] No Fault, FailCount= 4
2013-02-19T22:56:44.183Z,1361314604.183 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:56:44.299Z,1361314604.299 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-02-19T22:56:44.301Z,1361314604.300 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:56:44.301Z,1361314604.301 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-02-19T22:56:44.854Z,1361314604.854 [MassServo](FAULT): Mass initialization uart error:serial timeout
2013-02-19T22:56:44.854Z,1361314604.854 [MassServo] Communications Fault, FailCount= 3
2013-02-19T22:56:44.854Z,1361314604.854 [MassServo](ERROR): Communications Fault
2013-02-19T22:56:45.378Z,1361314605.378 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2013-02-19T22:56:45.902Z,1361314605.902 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout
2013-02-19T22:56:45.902Z,1361314605.902 [ThrusterServo](FAULT): Thruster failed to initialize
2013-02-19T22:56:45.902Z,1361314605.902 [ThrusterServo] Communications Fault, FailCount= 5
2013-02-19T22:56:45.902Z,1361314605.902 [ThrusterServo](ERROR): Communications Fault
2013-02-19T22:56:45.905Z,1361314605.905 [CBIT](ERROR): Communications Fault in component: MassServo
2013-02-19T22:56:45.905Z,1361314605.905 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2013-02-19T22:56:45.906Z,1361314605.906 [CBIT](CRITICAL): Communications Fault in component: ThrusterServo
2013-02-19T22:56:46.658Z,1361314606.658 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-02-19T22:56:46.658Z,1361314606.658 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-02-19T22:56:46.658Z,1361314606.658 [BuoyancyServo] Communications Fault, FailCount= 4
2013-02-19T22:56:46.658Z,1361314606.658 [BuoyancyServo](ERROR): Communications Fault
2013-02-19T22:56:46.659Z,1361314606.659 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-02-19T22:56:46.659Z,1361314606.659 [MassServo](INFO): Powering down
2013-02-19T22:56:46.660Z,1361314606.660 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-02-19T22:56:46.660Z,1361314606.660 [ThrusterServo](INFO): Powering down
2013-02-19T22:56:46.664Z,1361314606.664 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-02-19T22:56:46.914Z,1361314606.914 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-02-19T22:56:46.916Z,1361314606.916 [BuoyancyServo](INFO): Powering down
2013-02-19T22:56:47.276Z,1361314607.276 [MassServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:56:47.395Z,1361314607.395 [MassServo](DEBUG): Initializing MassServo.
2013-02-19T22:56:47.517Z,1361314607.517 [CBIT](INFO): Clearing failed state for component MassServo
2013-02-19T22:56:47.517Z,1361314607.517 [MassServo] No Fault, FailCount= 3
2013-02-19T22:56:48.921Z,1361314608.921 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2013-02-19T22:56:48.921Z,1361314608.921 [BuoyancyServo] No Fault, FailCount= 4
2013-02-19T22:56:49.310Z,1361314609.309 [Aanderaa_O2](FAULT): Timed out starting
2013-02-19T22:56:49.310Z,1361314609.310 [Aanderaa_O2] Communications Fault, FailCount= 2
2013-02-19T22:56:49.310Z,1361314609.310 [Aanderaa_O2](ERROR): Communications Fault
2013-02-19T22:56:49.323Z,1361314609.323 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:56:49.440Z,1361314609.440 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-02-19T22:56:49.444Z,1361314609.444 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2013-02-19T22:56:58.286Z,1361314618.286 [CommandLine](IMPORTANT): got command failComponent
2013-02-19T22:56:58.287Z,1361314618.286 [CommandLine](IMPORTANT): Failed components:
2013-02-19T22:56:58.287Z,1361314618.287 [CommandLine](IMPORTANT): ElevatorServo: Communications Fault
2013-02-19T22:56:58.287Z,1361314618.287 [CommandLine](IMPORTANT): RudderServo: Communications Fault
2013-02-19T22:56:58.287Z,1361314618.287 [CommandLine](IMPORTANT): ThrusterServo: Communications Fault
2013-02-19T22:56:58.288Z,1361314618.288 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2013-02-19T22:57:19.839Z,1361314639.839 [Aanderaa_O2](INFO): Powering down
2013-02-19T22:57:19.985Z,1361314639.985 [CBIT](INFO): Clearing failed count for component ThrusterServo
2013-02-19T22:57:19.985Z,1361314639.985 [ThrusterServo] No Fault, FailCount= 5
2013-02-19T22:57:20.763Z,1361314640.763 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:57:20.765Z,1361314640.764 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-02-19T22:57:21.090Z,1361314641.090 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2013-02-19T22:57:21.090Z,1361314641.090 [Aanderaa_O2] No Fault, FailCount= 2
2013-02-19T22:57:22.306Z,1361314642.306 [MassServo](ERROR): getPosition uart error serial timeout
2013-02-19T22:57:22.306Z,1361314642.306 [MassServo](FAULT): Invalid EZ Servo response:""
2013-02-19T22:57:22.306Z,1361314642.306 [MassServo] Communications Fault, FailCount= 4
2013-02-19T22:57:22.306Z,1361314642.306 [MassServo](ERROR): Communications Fault
2013-02-19T22:57:22.310Z,1361314642.310 [CBIT](ERROR): Communications Fault in component: MassServo
2013-02-19T22:57:23.022Z,1361314643.022 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2013-02-19T22:57:23.022Z,1361314643.022 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2013-02-19T22:57:23.022Z,1361314643.022 [BuoyancyServo] Communications Fault, FailCount= 5
2013-02-19T22:57:23.022Z,1361314643.022 [BuoyancyServo](ERROR): Communications Fault
2013-02-19T22:57:23.023Z,1361314643.023 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-02-19T22:57:23.023Z,1361314643.023 [MassServo](INFO): Powering down
2013-02-19T22:57:23.027Z,1361314643.027 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-02-19T22:57:23.027Z,1361314643.027 [CBIT](CRITICAL): Communications Fault in component: BuoyancyServo
2013-02-19T22:57:23.278Z,1361314643.279 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-02-19T22:57:23.280Z,1361314643.280 [BuoyancyServo](INFO): Powering down
2013-02-19T22:57:23.810Z,1361314643.810 [ThrusterServo](FAULT): Thruster initialization uart error:serial timeout
2013-02-19T22:57:23.810Z,1361314643.810 [ThrusterServo] Communications Fault, FailCount= 1
2013-02-19T22:57:23.810Z,1361314643.810 [ThrusterServo](ERROR): Communications Fault
2013-02-19T22:57:23.813Z,1361314643.813 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2013-02-19T22:57:24.032Z,1361314644.032 [MassServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:57:24.147Z,1361314644.147 [MassServo](DEBUG): Initializing MassServo.
2013-02-19T22:57:24.148Z,1361314644.148 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-02-19T22:57:24.148Z,1361314644.148 [ThrusterServo](INFO): Powering down
2013-02-19T22:57:24.152Z,1361314644.152 [CBIT](INFO): Clearing failed state for component MassServo
2013-02-19T22:57:24.152Z,1361314644.152 [MassServo] No Fault, FailCount= 4
2013-02-19T22:57:24.945Z,1361314644.945 [CBIT](INFO): Clearing failed state for component ThrusterServo
2013-02-19T22:57:24.945Z,1361314644.945 [ThrusterServo] No Fault, FailCount= 1
2013-02-19T22:57:25.224Z,1361314645.224 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:57:25.225Z,1361314645.225 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-02-19T22:57:26.558Z,1361314646.558 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2013-02-19T22:57:26.576Z,1361314646.576 [ThrusterServo](FAULT): Thruster failed to initialize
2013-02-19T22:57:26.577Z,1361314646.577 [ThrusterServo] Communications Fault, FailCount= 2
2013-02-19T22:57:26.577Z,1361314646.577 [ThrusterServo](ERROR): Communications Fault
2013-02-19T22:57:26.597Z,1361314646.597 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2013-02-19T22:57:27.130Z,1361314647.130 [MassServo](FAULT): Mass initialization uart error:serial timeout
2013-02-19T22:57:27.130Z,1361314647.130 [MassServo] Communications Fault, FailCount= 5
2013-02-19T22:57:27.130Z,1361314647.130 [MassServo](ERROR): Communications Fault
2013-02-19T22:57:27.131Z,1361314647.131 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-02-19T22:57:27.131Z,1361314647.131 [ThrusterServo](INFO): Powering down
2013-02-19T22:57:27.134Z,1361314647.134 [CBIT](ERROR): Communications Fault in component: MassServo
2013-02-19T22:57:27.135Z,1361314647.135 [CBIT](CRITICAL): Communications Fault in component: MassServo
2013-02-19T22:57:27.355Z,1361314647.355 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-02-19T22:57:27.355Z,1361314647.355 [MassServo](INFO): Powering down
2013-02-19T22:57:27.877Z,1361314647.877 [CBIT](INFO): Clearing failed state for component ThrusterServo
2013-02-19T22:57:27.877Z,1361314647.877 [ThrusterServo] No Fault, FailCount= 2
2013-02-19T22:57:28.155Z,1361314648.155 [MassServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:57:28.275Z,1361314648.275 [MassServo](DEBUG): Initializing MassServo.
2013-02-19T22:57:28.276Z,1361314648.276 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:57:28.277Z,1361314648.277 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-02-19T22:57:29.470Z,1361314649.470 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2013-02-19T22:57:29.488Z,1361314649.488 [ThrusterServo](FAULT): Thruster failed to initialize
2013-02-19T22:57:29.488Z,1361314649.488 [ThrusterServo] Communications Fault, FailCount= 3
2013-02-19T22:57:29.489Z,1361314649.489 [ThrusterServo](ERROR): Communications Fault
2013-02-19T22:57:29.508Z,1361314649.508 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2013-02-19T22:57:29.819Z,1361314649.819 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-02-19T22:57:29.819Z,1361314649.819 [ThrusterServo](INFO): Powering down
2013-02-19T22:57:30.643Z,1361314650.643 [CBIT](INFO): Clearing failed state for component ThrusterServo
2013-02-19T22:57:30.643Z,1361314650.643 [ThrusterServo] No Fault, FailCount= 3
2013-02-19T22:57:30.923Z,1361314650.923 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:57:30.924Z,1361314650.924 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-02-19T22:57:30.930Z,1361314650.930 [CBIT](FAULT): Low side GF detected
mA:
CHAN 5 (24V): 0.391695
CHAN 4 (Batt): 0.391695
CHAN 2 (12V): 0.391695
CHAN 1 (5V): 0.391695
CHAN 0 (3.3V): 0.338991
OPEN: 0.000478
Full Scale Calc: 0.392
2013-02-19T22:57:31.702Z,1361314651.702 [Aanderaa_O2](FAULT): Timed out starting
2013-02-19T22:57:31.702Z,1361314651.702 [Aanderaa_O2] Communications Fault, FailCount= 3
2013-02-19T22:57:31.702Z,1361314651.702 [Aanderaa_O2](ERROR): Communications Fault
2013-02-19T22:57:32.234Z,1361314652.234 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2013-02-19T22:57:32.252Z,1361314652.252 [ThrusterServo](FAULT): Thruster failed to initialize
2013-02-19T22:57:32.252Z,1361314652.252 [ThrusterServo] Communications Fault, FailCount= 4
2013-02-19T22:57:32.253Z,1361314652.253 [ThrusterServo](ERROR): Communications Fault
2013-02-19T22:57:32.277Z,1361314652.277 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2013-02-19T22:57:32.279Z,1361314652.279 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2013-02-19T22:57:32.282Z,1361314652.282 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2013-02-19T22:58:02.602Z,1361314682.602 [Aanderaa_O2](INFO): Powering down
2013-02-19T22:58:02.622Z,1361314682.622 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-02-19T22:58:02.622Z,1361314682.622 [ThrusterServo](INFO): Powering down
2013-02-19T22:58:02.624Z,1361314682.624 [SBIT](FAULT): Mass: EXPECTED:-0.006000 ACTUAL:-0.004605
2013-02-19T22:58:02.624Z,1361314682.624 [SBIT](FAULT): Control surface position failure.
2013-02-19T22:58:03.745Z,1361314683.745 [CBIT](INFO): Clearing failed state for component ThrusterServo
2013-02-19T22:58:03.745Z,1361314683.745 [ThrusterServo] No Fault, FailCount= 4
2013-02-19T22:58:04.021Z,1361314684.021 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:58:04.022Z,1361314684.022 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-02-19T22:58:16.814Z,1361314696.814 [SBIT](CRITICAL): SBIT FAILED
2013-02-19T22:58:17.209Z,1361314697.209 [MissionManager](IMPORTANT): Started mission Startup
2013-02-19T22:58:17.209Z,1361314697.209 [Startup] Running Loop=1
2013-02-19T22:58:17.209Z,1361314697.210 [Startup](INFO): Aggregate::initialize Startup
2013-02-19T22:58:17.210Z,1361314697.210 [Startup:A.GoToSurface] Running Loop=1
2013-02-19T22:58:17.210Z,1361314697.210 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-02-19T22:58:17.216Z,1361314697.216 [Startup:StartupSatComms] Running Loop=1
2013-02-19T22:58:17.216Z,1361314697.216 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2013-02-19T22:58:17.216Z,1361314697.216 [Startup:StartupSatComms:A] Running Loop=1
2013-02-19T22:58:17.609Z,1361314697.609 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-02-19T22:58:21.318Z,1361314701.318 [CommandLine](IMPORTANT): got command failComponent
2013-02-19T22:58:21.318Z,1361314701.318 [CommandLine](IMPORTANT): Failed components:
2013-02-19T22:58:21.319Z,1361314701.319 [CommandLine](IMPORTANT): BuoyancyServo: Communications Fault
2013-02-19T22:58:21.319Z,1361314701.319 [CommandLine](IMPORTANT): ElevatorServo: Communications Fault
2013-02-19T22:58:21.319Z,1361314701.319 [CommandLine](IMPORTANT): MassServo: Communications Fault
2013-02-19T22:58:21.319Z,1361314701.319 [CommandLine](IMPORTANT): RudderServo: Communications Fault
2013-02-19T22:58:21.320Z,1361314701.320 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2013-02-19T22:58:25.182Z,1361314705.182 [CommandLine](IMPORTANT): got command failComponent
2013-02-19T22:58:25.182Z,1361314705.182 [CommandLine](IMPORTANT): Failed components:
2013-02-19T22:58:25.183Z,1361314705.183 [CommandLine](IMPORTANT): BuoyancyServo: Communications Fault
2013-02-19T22:58:25.183Z,1361314705.183 [CommandLine](IMPORTANT): ElevatorServo: Communications Fault
2013-02-19T22:58:25.183Z,1361314705.183 [CommandLine](IMPORTANT): MassServo: Communications Fault
2013-02-19T22:58:25.183Z,1361314705.183 [CommandLine](IMPORTANT): RudderServo: Communications Fault
2013-02-19T22:58:25.184Z,1361314705.184 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2013-02-19T22:58:31.766Z,1361314711.766 [CommandLine](IMPORTANT): got command failComponent none BuoyancyServo
2013-02-19T22:58:31.767Z,1361314711.767 [BuoyancyServo] No Fault, FailCount= 5
2013-02-19T22:58:31.767Z,1361314711.767 [CommandLine](IMPORTANT): BuoyancyServo failureMode is No Fault
2013-02-19T22:58:32.062Z,1361314712.062 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:58:32.187Z,1361314712.187 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-02-19T22:58:40.515Z,1361314720.514 [CommandLine](IMPORTANT): got command failComponent none ElevatorServo
2013-02-19T22:58:40.515Z,1361314720.515 [ElevatorServo] No Fault, FailCount= 3
2013-02-19T22:58:40.515Z,1361314720.515 [CommandLine](IMPORTANT): ElevatorServo failureMode is No Fault
2013-02-19T22:58:46.899Z,1361314726.899 [CommandLine](IMPORTANT): got command get platform_battery_voltage
2013-02-19T22:58:46.899Z,1361314726.899 [CommandLine](IMPORTANT): platform_battery_voltage 15.690563 volt
2013-02-19T22:58:57.090Z,1361314737.090 [CommandLine](IMPORTANT): got command failComponent
2013-02-19T22:58:57.090Z,1361314737.090 [CommandLine](IMPORTANT): Failed components:
2013-02-19T22:58:57.091Z,1361314737.091 [CommandLine](IMPORTANT): MassServo: Communications Fault
2013-02-19T22:58:57.091Z,1361314737.091 [CommandLine](IMPORTANT): RudderServo: Communications Fault
2013-02-19T22:58:57.091Z,1361314737.091 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2013-02-19T22:59:00.939Z,1361314740.939 [CommandLine](IMPORTANT): got command failComponent none MassServo
2013-02-19T22:59:00.939Z,1361314740.939 [MassServo] No Fault, FailCount= 5
2013-02-19T22:59:00.939Z,1361314740.939 [CommandLine](IMPORTANT): MassServo failureMode is No Fault
2013-02-19T22:59:03.514Z,1361314743.514 [CommandLine](IMPORTANT): got command failComponent 
2013-02-19T22:59:03.515Z,1361314743.514 [CommandLine](IMPORTANT): Failed components:
2013-02-19T22:59:03.515Z,1361314743.515 [CommandLine](IMPORTANT): RudderServo: Communications Fault
2013-02-19T22:59:03.515Z,1361314743.515 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2013-02-19T22:59:06.370Z,1361314746.371 [CommandLine](IMPORTANT): got command failComponent none RudderServo
2013-02-19T22:59:06.371Z,1361314746.371 [RudderServo] No Fault, FailCount= 3
2013-02-19T22:59:06.371Z,1361314746.371 [CommandLine](IMPORTANT): RudderServo failureMode is No Fault
2013-02-19T22:59:08.970Z,1361314748.970 [CommandLine](IMPORTANT): got command failComponent
2013-02-19T22:59:08.970Z,1361314748.970 [CommandLine](IMPORTANT): Failed components:
2013-02-19T22:59:08.971Z,1361314748.971 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2013-02-19T22:59:15.614Z,1361314755.615 [CommandLine](IMPORTANT): got command failComponent none Aanderaa_O2
2013-02-19T22:59:15.615Z,1361314755.615 [Aanderaa_O2] No Fault, FailCount= 3
2013-02-19T22:59:15.615Z,1361314755.615 [CommandLine](IMPORTANT): Aanderaa_O2 failureMode is No Fault
2013-02-19T22:59:16.634Z,1361314756.634 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2013-02-19T22:59:16.634Z,1361314756.634 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2013-02-19T22:59:16.634Z,1361314756.634 [BuoyancyServo] Communications Fault, FailCount= 6
2013-02-19T22:59:16.634Z,1361314756.634 [BuoyancyServo](ERROR): Communications Fault
2013-02-19T22:59:17.154Z,1361314757.154 [MassServo](ERROR): getPosition uart error serial timeout
2013-02-19T22:59:17.154Z,1361314757.154 [MassServo](FAULT): Invalid EZ Servo response:""
2013-02-19T22:59:17.154Z,1361314757.155 [MassServo] Communications Fault, FailCount= 6
2013-02-19T22:59:17.154Z,1361314757.155 [MassServo](ERROR): Communications Fault
2013-02-19T22:59:17.418Z,1361314757.418 [CommandLine](IMPORTANT): got command failComponent 
2013-02-19T22:59:17.418Z,1361314757.418 [CommandLine](IMPORTANT): Failed components:
2013-02-19T22:59:17.419Z,1361314757.419 [CommandLine](IMPORTANT): BuoyancyServo: Communications Fault
2013-02-19T22:59:17.419Z,1361314757.419 [CommandLine](IMPORTANT): MassServo: Communications Fault
2013-02-19T22:59:17.666Z,1361314757.666 [RudderServo](ERROR): getPosition uart error serial timeout
2013-02-19T22:59:17.666Z,1361314757.666 [RudderServo](FAULT): Rudder uart error - getPosition..serial timeout
2013-02-19T22:59:17.666Z,1361314757.666 [RudderServo] Communications Fault, FailCount= 4
2013-02-19T22:59:17.666Z,1361314757.666 [RudderServo](ERROR): Communications Fault
2013-02-19T22:59:17.669Z,1361314757.670 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-02-19T22:59:17.673Z,1361314757.673 [CBIT](CRITICAL): Communications Fault in component: BuoyancyServo
2013-02-19T22:59:17.673Z,1361314757.673 [CBIT](ERROR): Communications Fault in component: MassServo
2013-02-19T22:59:17.673Z,1361314757.673 [CBIT](CRITICAL): Communications Fault in component: MassServo
2013-02-19T22:59:17.674Z,1361314757.674 [CBIT](ERROR): Communications Fault in component: RudderServo
2013-02-19T22:59:17.674Z,1361314757.674 [CBIT](CRITICAL): Communications Fault in component: RudderServo
2013-02-19T22:59:17.932Z,1361314757.931 [Startup:StartupSatComms:A](INFO): Timed out from 2013-02-19T22:58:17.2Z
2013-02-19T22:59:17.932Z,1361314757.932 [Startup:StartupSatComms:A:A_Timeout] Running Loop=1
2013-02-19T22:59:17.932Z,1361314757.932 [Startup:StartupSatComms:A:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:A:A_Timeout
2013-02-19T22:59:17.932Z,1361314757.932 [Startup:StartupSatComms:A:A_Timeout](INFO): Completed Startup:StartupSatComms:A:A_Timeout
2013-02-19T22:59:17.932Z,1361314757.932 [Startup:StartupSatComms:A] Stopped
2013-02-19T22:59:17.932Z,1361314757.932 [Startup:StartupSatComms:B] Running Loop=1
2013-02-19T22:59:17.962Z,1361314757.962 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-02-19T22:59:17.974Z,1361314757.974 [BuoyancyServo](INFO): Powering down
2013-02-19T22:59:17.987Z,1361314757.987 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-02-19T22:59:17.987Z,1361314757.987 [MassServo](INFO): Powering down
2013-02-19T22:59:17.988Z,1361314757.988 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2013-02-19T22:59:17.988Z,1361314757.988 [RudderServo](INFO): Powering down
2013-02-19T22:59:18.282Z,1361314758.282 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2013-02-19T22:59:18.690Z,1361314758.690 [MassServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:59:18.807Z,1361314758.807 [MassServo](DEBUG): Initializing MassServo.
2013-02-19T22:59:18.808Z,1361314758.808 [RudderServo](DEBUG): Initializing EZServoServo.
2013-02-19T22:59:18.927Z,1361314758.927 [RudderServo](DEBUG): Initializing RudderServo.
2013-02-19T22:59:23.030Z,1361314763.030 [CommandLine](IMPORTANT): got command failComponent
2013-02-19T22:59:23.031Z,1361314763.030 [CommandLine](IMPORTANT): Failed components:
2013-02-19T22:59:23.031Z,1361314763.031 [CommandLine](IMPORTANT): BuoyancyServo: Communications Fault
2013-02-19T22:59:23.031Z,1361314763.031 [CommandLine](IMPORTANT): MassServo: Communications Fault
2013-02-19T22:59:23.031Z,1361314763.031 [CommandLine](IMPORTANT): RudderServo: Communications Fault
2013-02-19T22:59:24.349Z,1361314764.349 [NAL9602](INFO): Sent 332 bytes from file Logs/20130219T223715/shore0000.lzma
2013-02-19T22:59:24.349Z,1361314764.349 [NAL9602](INFO): Packets left to send: 2
2013-02-19T22:59:24.351Z,1361314764.351 [NAL9602](INFO): Stored copy of sent data in Logs/20130219T223715/shore0000.lzma.parts/0002.sbd
2013-02-19T22:59:25.520Z,1361314765.520 [NAL9602](INFO): Sent 332 bytes from file Logs/20130219T223715/shore0000.lzma
2013-02-19T22:59:25.520Z,1361314765.520 [NAL9602](INFO): Packets left to send: 1
2013-02-19T22:59:25.521Z,1361314765.521 [NAL9602](INFO): Stored copy of sent data in Logs/20130219T223715/shore0000.lzma.parts/0001.sbd
2013-02-19T22:59:25.944Z,1361314765.944 [Aanderaa_O2](FAULT): Timed out starting
2013-02-19T22:59:25.944Z,1361314765.944 [Aanderaa_O2] Communications Fault, FailCount= 4
2013-02-19T22:59:25.944Z,1361314765.944 [Aanderaa_O2](ERROR): Communications Fault
2013-02-19T22:59:25.962Z,1361314765.962 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2013-02-19T22:59:25.962Z,1361314765.962 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2013-02-19T22:59:56.498Z,1361314796.498 [Aanderaa_O2](INFO): Powering down
2013-02-19T22:59:57.269Z,1361314797.269 [NAL9602](INFO): Sent 100 bytes from file Logs/20130219T223715/shore0000.lzma
2013-02-19T22:59:57.269Z,1361314797.269 [NAL9602](INFO): Packets left to send: 0
2013-02-19T22:59:57.270Z,1361314797.270 [NAL9602](INFO): Stored copy of sent data in Logs/20130219T223715/shore0000.lzma.parts/0000.sbd
2013-02-19T22:59:58.276Z,1361314798.276 [NAL9602](INFO): Sent 332 bytes from file Logs/20130219T225541/shore0000.lzma
2013-02-19T22:59:58.276Z,1361314798.276 [NAL9602](INFO): Packets left to send: 4
2013-02-19T22:59:58.278Z,1361314798.278 [NAL9602](INFO): Stored copy of sent data in Logs/20130219T225541/shore0000.lzma.parts/0004.sbd
2013-02-19T22:59:59.476Z,1361314799.476 [NAL9602](INFO): Sent 332 bytes from file Logs/20130219T225541/shore0000.lzma
2013-02-19T22:59:59.476Z,1361314799.476 [NAL9602](INFO): Packets left to send: 3
2013-02-19T22:59:59.477Z,1361314799.477 [NAL9602](INFO): Stored copy of sent data in Logs/20130219T225541/shore0000.lzma.parts/0003.sbd
2013-02-19T23:00:00.708Z,1361314800.708 [NAL9602](INFO): Sent 332 bytes from file Logs/20130219T225541/shore0000.lzma
2013-02-19T23:00:00.709Z,1361314800.709 [NAL9602](INFO): Packets left to send: 2
2013-02-19T23:00:00.710Z,1361314800.710 [NAL9602](INFO): Stored copy of sent data in Logs/20130219T225541/shore0000.lzma.parts/0002.sbd
2013-02-19T23:00:01.924Z,1361314801.924 [NAL9602](INFO): Sent 332 bytes from file Logs/20130219T225541/shore0000.lzma
2013-02-19T23:00:01.925Z,1361314801.925 [NAL9602](INFO): Packets left to send: 1
2013-02-19T23:00:01.926Z,1361314801.926 [NAL9602](INFO): Stored copy of sent data in Logs/20130219T225541/shore0000.lzma.parts/0001.sbd
2013-02-19T23:00:03.076Z,1361314803.076 [NAL9602](INFO): Sent 263 bytes from file Logs/20130219T225541/shore0000.lzma
2013-02-19T23:00:03.076Z,1361314803.076 [NAL9602](INFO): Packets left to send: 0
2013-02-19T23:00:03.077Z,1361314803.077 [NAL9602](INFO): Stored copy of sent data in Logs/20130219T225541/shore0000.lzma.parts/0000.sbd
2013-02-19T23:00:03.928Z,1361314803.928 [Startup:StartupSatComms:B] Stopped
2013-02-19T23:00:03.928Z,1361314803.928 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2013-02-19T23:00:03.928Z,1361314803.928 [Startup:StartupSatComms] Stopped
2013-02-19T23:00:03.928Z,1361314803.928 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2013-02-19T23:00:03.929Z,1361314803.929 [Startup](INFO): Completed Startup
2013-02-19T23:00:03.929Z,1361314803.929 [Startup] Stopped
2013-02-19T23:00:03.929Z,1361314803.929 [Startup](INFO): Aggregate::uninitialize Startup
2013-02-19T23:00:03.929Z,1361314803.929 [Startup:A.GoToSurface] Stopped
2013-02-19T23:00:03.929Z,1361314803.929 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-02-19T23:00:04.289Z,1361314804.289 [MissionManager](IMPORTANT): Started mission Default
2013-02-19T23:00:04.289Z,1361314804.289 [Default] Running Loop=1
2013-02-19T23:00:04.289Z,1361314804.289 [Default](INFO): Aggregate::initialize Default
2013-02-19T23:00:04.289Z,1361314804.289 [Default:D.SetSpeed] Running Loop=1
2013-02-19T23:00:04.289Z,1361314804.289 [Default:D.SetSpeed](DEBUG): Initialize.
2013-02-19T23:00:04.290Z,1361314804.290 [Default:E.GoToSurface] Running Loop=1
2013-02-19T23:00:04.290Z,1361314804.290 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-02-19T23:00:04.290Z,1361314804.290 [Default:Iridium] Running Loop=1
2013-02-19T23:00:04.290Z,1361314804.290 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-02-19T23:00:04.290Z,1361314804.290 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-02-19T23:00:04.290Z,1361314804.290 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-02-19T23:00:04.291Z,1361314804.291 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-02-19T23:00:04.291Z,1361314804.291 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-02-19T23:00:04.291Z,1361314804.291 [Default:E.GoToSurface] Running Loop=1
2013-02-19T23:00:04.297Z,1361314804.297 [Default:D.SetSpeed] Running Loop=1
2013-02-19T23:00:04.307Z,1361314804.307 [Default:Iridium:B.GoToSurface] Stopped
2013-02-19T23:00:04.307Z,1361314804.307 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-02-19T23:00:04.307Z,1361314804.307 [Default:Iridium:Read_Iridium] Running Loop=1
2013-02-19T23:00:04.307Z,1361314804.307 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-02-19T23:00:04.312Z,1361314804.312 [Default:GPS] Running Loop=1
2013-02-19T23:00:04.312Z,1361314804.312 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-02-19T23:00:04.312Z,1361314804.312 [Default:GPS:A.SetSpeed] Running Loop=1
2013-02-19T23:00:04.313Z,1361314804.313 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-02-19T23:00:04.313Z,1361314804.313 [Default:GPS:B.GoToSurface] Running Loop=1
2013-02-19T23:00:04.313Z,1361314804.313 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-02-19T23:00:04.319Z,1361314804.319 [Default:GPS:B.GoToSurface] Stopped
2013-02-19T23:00:04.319Z,1361314804.319 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-02-19T23:00:04.319Z,1361314804.319 [Default:GPS:Read_GPS] Running Loop=1
2013-02-19T23:00:04.319Z,1361314804.319 [Default:GPS:A.SetSpeed] Running Loop=1
2013-02-19T23:00:04.689Z,1361314804.689 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2013-02-19T23:00:04.720Z,1361314804.720 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-02-19T23:00:09.876Z,1361314809.876 [NAL9602](INFO): Sent 154 bytes from file Logs/20130219T225541/shore0001.lzma
2013-02-19T23:00:09.876Z,1361314809.876 [NAL9602](INFO): Packets left to send: 0
2013-02-19T23:00:09.878Z,1361314809.878 [NAL9602](INFO): Stored copy of sent data in Logs/20130219T225541/shore0001.lzma.parts/0000.sbd
2013-02-19T23:00:10.695Z,1361314810.694 [Default:Iridium:Read_Iridium] Stopped
2013-02-19T23:00:10.695Z,1361314810.695 [Default:Iridium](INFO): Completed Default:Iridium
2013-02-19T23:00:10.695Z,1361314810.695 [Default:Iridium] Stopped
2013-02-19T23:00:10.695Z,1361314810.695 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-02-19T23:00:10.695Z,1361314810.695 [Default:Iridium:A.SetSpeed] Stopped
2013-02-19T23:00:10.695Z,1361314810.695 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-02-19T23:00:10.696Z,1361314810.695 [Default:F.Wait] Running Loop=1
2013-02-19T23:00:10.696Z,1361314810.696 [Default:F.Wait](DEBUG): Initialize Wait Component.
2013-02-19T23:00:25.239Z,1361314825.239 [CommandLine](IMPORTANT): got command failComponent
2013-02-19T23:00:25.239Z,1361314825.239 [CommandLine](IMPORTANT): Failed components:
2013-02-19T23:00:25.240Z,1361314825.240 [CommandLine](IMPORTANT): BuoyancyServo: Communications Fault
2013-02-19T23:00:25.240Z,1361314825.240 [CommandLine](IMPORTANT): MassServo: Communications Fault
2013-02-19T23:00:25.240Z,1361314825.240 [CommandLine](IMPORTANT): RudderServo: Communications Fault
2013-02-19T23:00:25.240Z,1361314825.240 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2013-02-19T23:00:33.943Z,1361314833.943 [CommandLine](IMPORTANT): got command failComponent none BuoyancyServo
2013-02-19T23:00:33.943Z,1361314833.943 [BuoyancyServo] No Fault, FailCount= 6
2013-02-19T23:00:33.943Z,1361314833.944 [CommandLine](IMPORTANT): BuoyancyServo failureMode is No Fault
2013-02-19T23:00:34.292Z,1361314834.292 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-02-19T23:00:34.407Z,1361314834.407 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-02-19T23:01:11.606Z,1361314871.606 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2013-02-19T23:01:11.606Z,1361314871.606 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2013-02-19T23:01:11.606Z,1361314871.606 [BuoyancyServo] Communications Fault, FailCount= 7
2013-02-19T23:01:11.606Z,1361314871.606 [BuoyancyServo](ERROR): Communications Fault
2013-02-19T23:01:11.609Z,1361314871.609 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-02-19T23:01:11.610Z,1361314871.610 [CBIT](CRITICAL): Communications Fault in component: BuoyancyServo
2013-02-19T23:01:11.807Z,1361314871.807 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-02-19T23:01:11.808Z,1361314871.808 [BuoyancyServo](INFO): Powering down
2013-02-19T23:01:14.774Z,1361314874.774 [CommandLine](IMPORTANT): got command failComponent none BuoyancyServo
2013-02-19T23:01:14.775Z,1361314874.774 [BuoyancyServo] No Fault, FailCount= 7
2013-02-19T23:01:14.775Z,1361314874.775 [CommandLine](IMPORTANT): BuoyancyServo failureMode is No Fault
2013-02-19T23:01:15.014Z,1361314875.014 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-02-19T23:01:15.131Z,1361314875.131 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-02-19T23:01:22.527Z,1361314882.527 [CommandLine](IMPORTANT): got command 
2013-02-19T23:01:22.527Z,1361314882.527 [CommandLine](FAULT): Incomplete syntax. Try: help
2013-02-19T23:01:24.114Z,1361314884.114 [CommandLine](IMPORTANT): got command failComponent
2013-02-19T23:01:24.115Z,1361314884.115 [CommandLine](IMPORTANT): Failed components:
2013-02-19T23:01:24.115Z,1361314884.115 [CommandLine](IMPORTANT): MassServo: Communications Fault
2013-02-19T23:01:24.115Z,1361314884.115 [CommandLine](IMPORTANT): RudderServo: Communications Fault
2013-02-19T23:01:24.115Z,1361314884.115 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2013-02-19T23:01:30.359Z,1361314890.359 [CommandLine](IMPORTANT): got command failComponent none MassServo
2013-02-19T23:01:30.359Z,1361314890.359 [MassServo] No Fault, FailCount= 6
2013-02-19T23:01:30.359Z,1361314890.359 [CommandLine](IMPORTANT): MassServo failureMode is No Fault
2013-02-19T23:01:55.053Z,1361314915.053 [CommandLine](IMPORTANT): got command failComponent
2013-02-19T23:01:55.053Z,1361314915.053 [CommandLine](IMPORTANT): Failed components:
2013-02-19T23:01:55.053Z,1361314915.053 [CommandLine](IMPORTANT): RudderServo: Communications Fault
2013-02-19T23:01:55.053Z,1361314915.053 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2013-02-19T23:01:56.293Z,1361314916.293 [CBIT](INFO): Clearing failed count for component Aanderaa_O2
2013-02-19T23:01:56.293Z,1361314916.293 [Aanderaa_O2] No Fault, FailCount= 4
2013-02-19T23:01:57.534Z,1361314917.534 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2013-02-19T23:01:57.534Z,1361314917.534 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2013-02-19T23:01:57.534Z,1361314917.534 [BuoyancyServo] Communications Fault, FailCount= 8
2013-02-19T23:01:57.534Z,1361314917.534 [BuoyancyServo](ERROR): Communications Fault
2013-02-19T23:01:57.581Z,1361314917.581 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-02-19T23:01:57.581Z,1361314917.581 [CBIT](CRITICAL): Communications Fault in component: BuoyancyServo
2013-02-19T23:01:57.794Z,1361314917.794 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-02-19T23:01:57.796Z,1361314917.796 [BuoyancyServo](INFO): Powering down
2013-02-19T23:01:58.330Z,1361314918.330 [MassServo](ERROR): getPosition uart error serial timeout
2013-02-19T23:01:58.330Z,1361314918.330 [MassServo](FAULT): Invalid EZ Servo response:""
2013-02-19T23:01:58.330Z,1361314918.330 [MassServo] Communications Fault, FailCount= 7
2013-02-19T23:01:58.330Z,1361314918.330 [MassServo](ERROR): Communications Fault
2013-02-19T23:01:58.333Z,1361314918.333 [CBIT](ERROR): Communications Fault in component: MassServo
2013-02-19T23:01:58.333Z,1361314918.333 [CBIT](CRITICAL): Communications Fault in component: MassServo
2013-02-19T23:01:58.601Z,1361314918.601 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-02-19T23:01:58.601Z,1361314918.601 [MassServo](INFO): Powering down
2013-02-19T23:01:59.360Z,1361314919.360 [MassServo](DEBUG): Initializing EZServoServo.
2013-02-19T23:01:59.479Z,1361314919.479 [MassServo](DEBUG): Initializing MassServo.
2013-02-19T23:02:05.282Z,1361314925.282 [CommandLine](IMPORTANT): got command failComponent
2013-02-19T23:02:05.282Z,1361314925.282 [CommandLine](IMPORTANT): Failed components:
2013-02-19T23:02:05.283Z,1361314925.283 [CommandLine](IMPORTANT): BuoyancyServo: Communications Fault
2013-02-19T23:02:05.283Z,1361314925.283 [CommandLine](IMPORTANT): MassServo: Communications Fault
2013-02-19T23:02:05.283Z,1361314925.283 [CommandLine](IMPORTANT): RudderServo: Communications Fault
2013-02-19T23:02:06.926Z,1361314926.926 [Aanderaa_O2](FAULT): Timed out starting
2013-02-19T23:02:06.926Z,1361314926.926 [Aanderaa_O2] Communications Fault, FailCount= 1
2013-02-19T23:02:06.926Z,1361314926.926 [Aanderaa_O2](ERROR): Communications Fault
2013-02-19T23:02:06.969Z,1361314926.969 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2013-02-19T23:02:37.442Z,1361314957.442 [Aanderaa_O2](INFO): Powering down
2013-02-19T23:02:38.467Z,1361314958.467 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2013-02-19T23:02:38.467Z,1361314958.467 [Aanderaa_O2] No Fault, FailCount= 1
2013-02-19T23:02:48.870Z,1361314968.870 [Aanderaa_O2](FAULT): Timed out starting
2013-02-19T23:02:48.870Z,1361314968.870 [Aanderaa_O2] Communications Fault, FailCount= 2
2013-02-19T23:02:48.871Z,1361314968.871 [Aanderaa_O2](ERROR): Communications Fault
2013-02-19T23:02:48.917Z,1361314968.917 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2013-02-19T23:03:19.394Z,1361314999.394 [Aanderaa_O2](INFO): Powering down
2013-02-19T23:03:20.399Z,1361315000.399 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2013-02-19T23:03:20.399Z,1361315000.399 [Aanderaa_O2] No Fault, FailCount= 2
2013-02-19T23:03:31.218Z,1361315011.218 [Aanderaa_O2](FAULT): Timed out starting
2013-02-19T23:03:31.218Z,1361315011.218 [Aanderaa_O2] Communications Fault, FailCount= 3
2013-02-19T23:03:31.218Z,1361315011.218 [Aanderaa_O2](ERROR): Communications Fault
2013-02-19T23:03:31.237Z,1361315011.237 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2013-02-19T23:03:31.237Z,1361315011.237 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2013-02-19T23:04:01.690Z,1361315041.690 [Aanderaa_O2](INFO): Powering down
2013-02-19T23:04:01.714Z,1361315041.714 [CBIT](INFO): Clearing failed count for component BuoyancyServo
2013-02-19T23:04:01.714Z,1361315041.714 [BuoyancyServo] No Fault, FailCount= 8
2013-02-19T23:04:02.489Z,1361315042.489 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-02-19T23:04:02.603Z,1361315042.603 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-02-19T23:05:10.797Z,1361315110.797 [Default:CallIridium] Running Loop=1
2013-02-19T23:05:10.798Z,1361315110.797 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-02-19T23:05:10.798Z,1361315110.798 [Default:CallIridium:A] Running Loop=1
2013-02-19T23:05:10.800Z,1361315110.800 [Default:CallIridium:A] Stopped
2013-02-19T23:05:10.800Z,1361315110.800 [Default:CallIridium:B] Running Loop=1
2013-02-19T23:05:10.800Z,1361315110.800 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-02-19T23:05:11.202Z,1361315111.202 [Default:Iridium] Running Loop=1
2013-02-19T23:05:11.203Z,1361315111.203 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-02-19T23:05:11.203Z,1361315111.203 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-02-19T23:05:11.203Z,1361315111.203 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-02-19T23:05:11.203Z,1361315111.203 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-02-19T23:05:11.203Z,1361315111.203 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-02-19T23:05:11.204Z,1361315111.204 [Default:Iridium:B.GoToSurface] Stopped
2013-02-19T23:05:11.204Z,1361315111.204 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-02-19T23:05:11.204Z,1361315111.204 [Default:Iridium:Read_Iridium] Running Loop=1
2013-02-19T23:05:14.792Z,1361315114.792 [NAL9602](INFO): Sent 332 bytes from file Logs/20130219T225541/shore0002.lzma
2013-02-19T23:05:14.792Z,1361315114.792 [NAL9602](INFO): Packets left to send: 1
2013-02-19T23:05:14.794Z,1361315114.794 [NAL9602](INFO): Stored copy of sent data in Logs/20130219T225541/shore0002.lzma.parts/0001.sbd
2013-02-19T23:05:15.988Z,1361315115.988 [NAL9602](INFO): Sent 262 bytes from file Logs/20130219T225541/shore0002.lzma
2013-02-19T23:05:15.988Z,1361315115.988 [NAL9602](INFO): Packets left to send: 0
2013-02-19T23:05:15.989Z,1361315115.989 [NAL9602](INFO): Stored copy of sent data in Logs/20130219T225541/shore0002.lzma.parts/0000.sbd
2013-02-19T23:05:16.807Z,1361315116.807 [Default:Iridium:Read_Iridium] Stopped
2013-02-19T23:05:16.808Z,1361315116.808 [Default:Iridium](INFO): Completed Default:Iridium
2013-02-19T23:05:16.808Z,1361315116.808 [Default:Iridium] Stopped
2013-02-19T23:05:16.808Z,1361315116.808 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-02-19T23:05:16.808Z,1361315116.808 [Default:Iridium:A.SetSpeed] Stopped
2013-02-19T23:05:16.808Z,1361315116.808 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-02-19T23:05:17.202Z,1361315117.202 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-02-19T23:05:17.202Z,1361315117.202 [Default:CallIridium:B] Stopped
2013-02-19T23:05:17.203Z,1361315117.203 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-02-19T23:05:17.203Z,1361315117.203 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-02-19T23:05:17.203Z,1361315117.203 [Default:CallIridium] Stopped
2013-02-19T23:05:17.203Z,1361315117.203 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-02-19T23:06:01.661Z,1361315161.661 [CBIT](INFO): Clearing failed count for component Aanderaa_O2
2013-02-19T23:06:01.661Z,1361315161.661 [Aanderaa_O2] No Fault, FailCount= 3
2013-02-19T23:06:11.993Z,1361315171.993 [Aanderaa_O2](FAULT): Timed out starting
2013-02-19T23:06:11.994Z,1361315171.994 [Aanderaa_O2] Communications Fault, FailCount= 1
2013-02-19T23:06:11.994Z,1361315171.994 [Aanderaa_O2](ERROR): Communications Fault
2013-02-19T23:06:12.015Z,1361315172.015 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2013-02-19T23:06:42.514Z,1361315202.514 [Aanderaa_O2](INFO): Powering down
2013-02-19T23:06:43.520Z,1361315203.520 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2013-02-19T23:06:43.520Z,1361315203.520 [Aanderaa_O2] No Fault, FailCount= 1
2013-02-19T23:06:54.306Z,1361315214.306 [Aanderaa_O2](FAULT): Timed out starting
2013-02-19T23:06:54.306Z,1361315214.306 [Aanderaa_O2] Communications Fault, FailCount= 2
2013-02-19T23:06:54.306Z,1361315214.306 [Aanderaa_O2](ERROR): Communications Fault
2013-02-19T23:06:54.324Z,1361315214.324 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2013-02-19T23:07:24.822Z,1361315244.822 [Aanderaa_O2](INFO): Powering down
2013-02-19T23:07:24.857Z,1361315244.857 [CBIT](INFO): Clearing failed count for component MassServo
2013-02-19T23:07:24.858Z,1361315244.858 [MassServo] No Fault, FailCount= 7
2013-02-19T23:07:25.885Z,1361315245.885 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2013-02-19T23:07:25.885Z,1361315245.885 [Aanderaa_O2] No Fault, FailCount= 2
2013-02-19T23:07:27.334Z,1361315247.334 [MassServo](FAULT): Mass initialization uart error:serial timeout
2013-02-19T23:07:27.334Z,1361315247.334 [MassServo] Communications Fault, FailCount= 1
2013-02-19T23:07:27.334Z,1361315247.334 [MassServo](ERROR): Communications Fault
2013-02-19T23:07:27.337Z,1361315247.337 [CBIT](ERROR): Communications Fault in component: MassServo
2013-02-19T23:07:27.556Z,1361315247.556 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-02-19T23:07:27.556Z,1361315247.556 [MassServo](INFO): Powering down
2013-02-19T23:07:28.361Z,1361315248.361 [MassServo](DEBUG): Initializing EZServoServo.
2013-02-19T23:07:28.483Z,1361315248.483 [MassServo](DEBUG): Initializing MassServo.
2013-02-19T23:07:28.486Z,1361315248.486 [CBIT](INFO): Clearing failed state for component MassServo
2013-02-19T23:07:28.486Z,1361315248.486 [MassServo] No Fault, FailCount= 1
2013-02-19T23:07:31.070Z,1361315251.070 [MassServo](FAULT): Mass initialization uart error:serial timeout
2013-02-19T23:07:31.070Z,1361315251.070 [MassServo] Communications Fault, FailCount= 2
2013-02-19T23:07:31.070Z,1361315251.070 [MassServo](ERROR): Communications Fault
2013-02-19T23:07:31.073Z,1361315251.073 [CBIT](ERROR): Communications Fault in component: MassServo
2013-02-19T23:07:31.277Z,1361315251.277 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-02-19T23:07:31.277Z,1361315251.277 [MassServo](INFO): Powering down
2013-02-19T23:07:32.079Z,1361315252.079 [MassServo](DEBUG): Initializing EZServoServo.
2013-02-19T23:07:32.195Z,1361315252.195 [MassServo](DEBUG): Initializing MassServo.
2013-02-19T23:07:32.198Z,1361315252.198 [CBIT](INFO): Clearing failed state for component MassServo
2013-02-19T23:07:32.198Z,1361315252.198 [MassServo] No Fault, FailCount= 2
2013-02-19T23:07:34.746Z,1361315254.746 [MassServo](FAULT): Mass initialization uart error:serial timeout
2013-02-19T23:07:34.746Z,1361315254.746 [MassServo] Communications Fault, FailCount= 3
2013-02-19T23:07:34.746Z,1361315254.746 [MassServo](ERROR): Communications Fault
2013-02-19T23:07:34.749Z,1361315254.749 [CBIT](ERROR): Communications Fault in component: MassServo
2013-02-19T23:07:34.964Z,1361315254.964 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-02-19T23:07:34.965Z,1361315254.964 [MassServo](INFO): Powering down
2013-02-19T23:07:35.821Z,1361315255.821 [MassServo](DEBUG): Initializing EZServoServo.
2013-02-19T23:07:35.935Z,1361315255.935 [MassServo](DEBUG): Initializing MassServo.
2013-02-19T23:07:35.942Z,1361315255.942 [CBIT](INFO): Clearing failed state for component MassServo
2013-02-19T23:07:35.942Z,1361315255.942 [MassServo] No Fault, FailCount= 3
2013-02-19T23:07:36.578Z,1361315256.578 [Aanderaa_O2](FAULT): Timed out starting
2013-02-19T23:07:36.578Z,1361315256.578 [Aanderaa_O2] Communications Fault, FailCount= 3
2013-02-19T23:07:36.578Z,1361315256.578 [Aanderaa_O2](ERROR): Communications Fault
2013-02-19T23:07:36.614Z,1361315256.614 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2013-02-19T23:07:36.616Z,1361315256.616 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2013-02-19T23:08:07.066Z,1361315287.066 [Aanderaa_O2](INFO): Powering down
2013-02-19T23:08:15.334Z,1361315295.334 [CommandLine](IMPORTANT): got command failComponent
2013-02-19T23:08:15.335Z,1361315295.335 [CommandLine](IMPORTANT): Failed components:
2013-02-19T23:08:15.335Z,1361315295.335 [CommandLine](IMPORTANT): RudderServo: Communications Fault
2013-02-19T23:08:15.335Z,1361315295.335 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2013-02-19T23:08:21.302Z,1361315301.302 [CommandLine](IMPORTANT): got command failComponent none RudderServo
2013-02-19T23:08:21.303Z,1361315301.303 [RudderServo] No Fault, FailCount= 4
2013-02-19T23:08:21.303Z,1361315301.303 [CommandLine](IMPORTANT): RudderServo failureMode is No Fault
2013-02-19T23:08:43.122Z,1361315323.122 [CommandLine](IMPORTANT): got command failComponent
2013-02-19T23:08:43.123Z,1361315323.122 [CommandLine](IMPORTANT): Failed components:
2013-02-19T23:08:43.123Z,1361315323.123 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2013-02-19T23:08:45.515Z,1361315325.514 [CommandLine](IMPORTANT): got command run ./Missions/Default.xml
2013-02-19T23:08:45.515Z,1361315325.515 [MissionManager](INFO): Loading Mission: ./Missions/Default.xml
2013-02-19T23:08:45.563Z,1361315325.563 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2013-02-19T23:08:45.564Z,1361315325.565 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2013-02-19T23:08:45.567Z,1361315325.567 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-02-19T23:08:45.573Z,1361315325.573 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2013-02-19T23:08:45.575Z,1361315325.575 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-02-19T23:08:45.580Z,1361315325.580 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2013-02-19T23:08:45.587Z,1361315325.587 [Default:D.SetSpeed](DEBUG): Construct.
2013-02-19T23:08:45.590Z,1361315325.590 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2013-02-19T23:08:45.593Z,1361315325.593 [Default:F.Wait](DEBUG): Construct Wait.
2013-02-19T23:08:45.597Z,1361315325.597 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
xmlns:Control="Tethys/Control"
xmlns:Guidance="Tethys/Guidance" 
xmlns:Units="Tethys/Units"
xmlns:Universal="Tethys/Universal"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

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

    <Aggregate Id="GPS" >

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

<!-- Run at high speed -->

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

<!-- Get to the surface -->

        <Guidance:GoToSurface RunIn="Sequence"/>

<!-- Get GPS fix -->

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

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

    </Aggregate>

    <Aggregate Id="Iridium" >

<!-- Run at high speed -->

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

<!-- Get to the surface -->

        <Guidance:GoToSurface RunIn="Sequence"/>

<!-- Do data comms -->

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

    </Aggregate>

    <Aggregate Id="CallIridium" >

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

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

        <Call RefId="Iridium"/>

    </Aggregate>

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

<!-- Run at low speed -->

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

    <Guidance:GoToSurface RunIn="Parallel"/>

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

</Mission>


2013-02-19T23:08:45.597Z,1361315325.597 [CommandLine](IMPORTANT): Running ./Missions/Default.xml
2013-02-19T23:08:45.715Z,1361315325.715 [Default] Stopped
2013-02-19T23:08:45.716Z,1361315325.716 [Default](INFO): Aggregate::uninitialize Default
2013-02-19T23:08:45.716Z,1361315325.716 [Default:GPS] Stopped
2013-02-19T23:08:45.716Z,1361315325.716 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-02-19T23:08:45.716Z,1361315325.716 [Default:GPS:A.SetSpeed] Stopped
2013-02-19T23:08:45.716Z,1361315325.716 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-02-19T23:08:45.716Z,1361315325.716 [Default:GPS:Read_GPS] Stopped
2013-02-19T23:08:45.716Z,1361315325.716 [Default:D.SetSpeed] Stopped
2013-02-19T23:08:45.716Z,1361315325.716 [Default:D.SetSpeed](DEBUG): Uninitialize.
2013-02-19T23:08:45.716Z,1361315325.716 [Default:E.GoToSurface] Stopped
2013-02-19T23:08:45.716Z,1361315325.716 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-02-19T23:08:45.716Z,1361315325.716 [Default:F.Wait] Stopped
2013-02-19T23:08:45.716Z,1361315325.717 [Default:F.Wait](DEBUG): Uninitialize Wait Component.
2013-02-19T23:08:45.717Z,1361315325.717 [MissionManager](IMPORTANT): Started mission Default
2013-02-19T23:08:45.717Z,1361315325.717 [Default] Running Loop=1
2013-02-19T23:08:45.717Z,1361315325.717 [Default](INFO): Aggregate::initialize Default
2013-02-19T23:08:45.717Z,1361315325.717 [Default:D.SetSpeed] Running Loop=1
2013-02-19T23:08:45.717Z,1361315325.717 [Default:D.SetSpeed](DEBUG): Initialize.
2013-02-19T23:08:45.717Z,1361315325.717 [Default:E.GoToSurface] Running Loop=1
2013-02-19T23:08:45.717Z,1361315325.717 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-02-19T23:08:45.717Z,1361315325.717 [Default:Iridium] Running Loop=1
2013-02-19T23:08:45.717Z,1361315325.717 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-02-19T23:08:45.718Z,1361315325.718 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-02-19T23:08:45.718Z,1361315325.718 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-02-19T23:08:45.718Z,1361315325.718 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-02-19T23:08:45.718Z,1361315325.718 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-02-19T23:08:45.718Z,1361315325.718 [Default:E.GoToSurface] Running Loop=1
2013-02-19T23:08:45.720Z,1361315325.720 [Default:D.SetSpeed] Running Loop=1
2013-02-19T23:08:45.722Z,1361315325.722 [Default:Iridium:B.GoToSurface] Stopped
2013-02-19T23:08:45.722Z,1361315325.722 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-02-19T23:08:45.722Z,1361315325.722 [Default:Iridium:Read_Iridium] Running Loop=1
2013-02-19T23:08:45.722Z,1361315325.722 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-02-19T23:08:45.723Z,1361315325.723 [Default:GPS] Running Loop=1
2013-02-19T23:08:45.723Z,1361315325.723 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-02-19T23:08:45.723Z,1361315325.723 [Default:GPS:A.SetSpeed] Running Loop=1
2013-02-19T23:08:45.723Z,1361315325.723 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-02-19T23:08:45.723Z,1361315325.723 [Default:GPS:B.GoToSurface] Running Loop=1
2013-02-19T23:08:45.723Z,1361315325.723 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-02-19T23:08:45.725Z,1361315325.725 [Default:GPS:B.GoToSurface] Stopped
2013-02-19T23:08:45.725Z,1361315325.725 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-02-19T23:08:45.725Z,1361315325.725 [Default:GPS:Read_GPS] Running Loop=1
2013-02-19T23:08:45.725Z,1361315325.725 [Default:GPS:A.SetSpeed] Running Loop=1
2013-02-19T23:08:46.128Z,1361315326.128 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2013-02-19T23:08:46.129Z,1361315326.129 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-02-19T23:08:49.309Z,1361315329.309 [NAL9602](INFO): Sent 332 bytes from file Logs/20130219T225541/shore0003.lzma
2013-02-19T23:08:49.310Z,1361315329.309 [NAL9602](INFO): Packets left to send: 1
2013-02-19T23:08:49.311Z,1361315329.311 [NAL9602](INFO): Stored copy of sent data in Logs/20130219T225541/shore0003.lzma.parts/0001.sbd
2013-02-19T23:08:49.342Z,1361315329.342 [CommandLine](IMPORTANT): got command failComponent
2013-02-19T23:08:49.342Z,1361315329.342 [CommandLine](IMPORTANT): Failed components:
2013-02-19T23:08:49.343Z,1361315329.343 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2013-02-19T23:08:50.512Z,1361315330.512 [NAL9602](INFO): Sent 37 bytes from file Logs/20130219T225541/shore0003.lzma
2013-02-19T23:08:50.512Z,1361315330.512 [NAL9602](INFO): Packets left to send: 0
2013-02-19T23:08:50.513Z,1361315330.513 [NAL9602](INFO): Stored copy of sent data in Logs/20130219T225541/shore0003.lzma.parts/0000.sbd
2013-02-19T23:08:51.332Z,1361315331.332 [Default:Iridium:Read_Iridium] Stopped
2013-02-19T23:08:51.333Z,1361315331.333 [Default:Iridium](INFO): Completed Default:Iridium
2013-02-19T23:08:51.333Z,1361315331.333 [Default:Iridium] Stopped
2013-02-19T23:08:51.333Z,1361315331.333 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-02-19T23:08:51.333Z,1361315331.333 [Default:Iridium:A.SetSpeed] Stopped
2013-02-19T23:08:51.333Z,1361315331.333 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-02-19T23:08:51.333Z,1361315331.333 [Default:F.Wait] Running Loop=1
2013-02-19T23:08:51.333Z,1361315331.333 [Default:F.Wait](DEBUG): Initialize Wait Component.
2013-02-19T23:08:56.067Z,1361315336.067 [CommandLine](IMPORTANT): got command failComponent none Aanderaa_O2
2013-02-19T23:08:56.067Z,1361315336.067 [Aanderaa_O2] No Fault, FailCount= 3
2013-02-19T23:08:56.067Z,1361315336.067 [CommandLine](IMPORTANT): Aanderaa_O2 failureMode is No Fault
2013-02-19T23:08:57.454Z,1361315337.454 [RudderServo](ERROR): getPosition uart error serial timeout
2013-02-19T23:08:57.454Z,1361315337.454 [RudderServo](FAULT): Rudder uart error - getPosition..serial timeout
2013-02-19T23:08:57.454Z,1361315337.454 [RudderServo] Communications Fault, FailCount= 5
2013-02-19T23:08:57.454Z,1361315337.454 [RudderServo](ERROR): Communications Fault
2013-02-19T23:08:57.458Z,1361315337.458 [CBIT](ERROR): Communications Fault in component: RudderServo
2013-02-19T23:08:57.458Z,1361315337.458 [CBIT](CRITICAL): Communications Fault in component: RudderServo
2013-02-19T23:08:57.730Z,1361315337.730 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2013-02-19T23:08:57.730Z,1361315337.730 [RudderServo](INFO): Powering down
2013-02-19T23:08:58.068Z,1361315338.068 [Default] Stopped
2013-02-19T23:08:58.068Z,1361315338.068 [Default](INFO): Aggregate::uninitialize Default
2013-02-19T23:08:58.068Z,1361315338.068 [Default:GPS] Stopped
2013-02-19T23:08:58.068Z,1361315338.069 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-02-19T23:08:58.069Z,1361315338.069 [Default:GPS:A.SetSpeed] Stopped
2013-02-19T23:08:58.069Z,1361315338.069 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-02-19T23:08:58.069Z,1361315338.069 [Default:GPS:Read_GPS] Stopped
2013-02-19T23:08:58.069Z,1361315338.069 [Default:D.SetSpeed] Stopped
2013-02-19T23:08:58.069Z,1361315338.069 [Default:D.SetSpeed](DEBUG): Uninitialize.
2013-02-19T23:08:58.069Z,1361315338.069 [Default:E.GoToSurface] Stopped
2013-02-19T23:08:58.069Z,1361315338.069 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-02-19T23:08:58.069Z,1361315338.069 [Default:F.Wait] Stopped
2013-02-19T23:08:58.069Z,1361315338.069 [Default:F.Wait](DEBUG): Uninitialize Wait Component.
2013-02-19T23:08:58.494Z,1361315338.494 [MissionManager](IMPORTANT): Started mission Default
2013-02-19T23:08:58.494Z,1361315338.494 [Default] Running Loop=1
2013-02-19T23:08:58.494Z,1361315338.494 [Default](INFO): Aggregate::initialize Default
2013-02-19T23:08:58.494Z,1361315338.494 [Default:D.SetSpeed] Running Loop=1
2013-02-19T23:08:58.494Z,1361315338.494 [Default:D.SetSpeed](DEBUG): Initialize.
2013-02-19T23:08:58.495Z,1361315338.495 [Default:E.GoToSurface] Running Loop=1
2013-02-19T23:08:58.495Z,1361315338.495 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-02-19T23:08:58.495Z,1361315338.495 [Default:Iridium] Running Loop=1
2013-02-19T23:08:58.495Z,1361315338.495 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-02-19T23:08:58.495Z,1361315338.495 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-02-19T23:08:58.495Z,1361315338.495 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-02-19T23:08:58.495Z,1361315338.495 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-02-19T23:08:58.495Z,1361315338.495 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-02-19T23:08:58.497Z,1361315338.497 [Default:Iridium:B.GoToSurface] Stopped
2013-02-19T23:08:58.497Z,1361315338.497 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-02-19T23:08:58.497Z,1361315338.497 [Default:Iridium:Read_Iridium] Running Loop=1
2013-02-19T23:08:58.498Z,1361315338.498 [Default:GPS] Running Loop=1
2013-02-19T23:08:58.498Z,1361315338.498 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-02-19T23:08:58.498Z,1361315338.498 [Default:GPS:A.SetSpeed] Running Loop=1
2013-02-19T23:08:58.498Z,1361315338.498 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-02-19T23:08:58.498Z,1361315338.498 [Default:GPS:B.GoToSurface] Running Loop=1
2013-02-19T23:08:58.498Z,1361315338.498 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-02-19T23:08:58.499Z,1361315338.499 [Default:GPS:B.GoToSurface] Stopped
2013-02-19T23:08:58.499Z,1361315338.499 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-02-19T23:08:58.499Z,1361315338.499 [Default:GPS:Read_GPS] Running Loop=1
2013-02-19T23:08:58.506Z,1361315338.506 [RudderServo](DEBUG): Initializing EZServoServo.
2013-02-19T23:08:58.627Z,1361315338.627 [RudderServo](DEBUG): Initializing RudderServo.
2013-02-19T23:09:00.786Z,1361315340.786 [CommandLine](IMPORTANT): got command failComponent
2013-02-19T23:09:00.786Z,1361315340.786 [CommandLine](IMPORTANT): Failed components:
2013-02-19T23:09:00.787Z,1361315340.787 [CommandLine](IMPORTANT): RudderServo: Communications Fault
2013-02-19T23:09:01.681Z,1361315341.681 [NAL9602](INFO): Sent 275 bytes from file Logs/20130219T225541/shore0004.lzma
2013-02-19T23:09:01.681Z,1361315341.681 [NAL9602](INFO): Packets left to send: 0
2013-02-19T23:09:01.683Z,1361315341.683 [NAL9602](INFO): Stored copy of sent data in Logs/20130219T225541/shore0004.lzma.parts/0000.sbd
2013-02-19T23:09:02.516Z,1361315342.516 [Default:Iridium:Read_Iridium] Stopped
2013-02-19T23:09:02.517Z,1361315342.517 [Default:Iridium](INFO): Completed Default:Iridium
2013-02-19T23:09:02.517Z,1361315342.517 [Default:Iridium] Stopped
2013-02-19T23:09:02.517Z,1361315342.517 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-02-19T23:09:02.517Z,1361315342.517 [Default:Iridium:A.SetSpeed] Stopped
2013-02-19T23:09:02.517Z,1361315342.517 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-02-19T23:09:02.517Z,1361315342.517 [Default:F.Wait] Running Loop=1
2013-02-19T23:09:02.517Z,1361315342.517 [Default:F.Wait](DEBUG): Initialize Wait Component.
2013-02-19T23:09:06.453Z,1361315346.453 [Aanderaa_O2](FAULT): Timed out starting
2013-02-19T23:09:06.454Z,1361315346.454 [Aanderaa_O2] Communications Fault, FailCount= 4
2013-02-19T23:09:06.454Z,1361315346.454 [Aanderaa_O2](ERROR): Communications Fault
2013-02-19T23:09:06.495Z,1361315346.495 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2013-02-19T23:09:06.495Z,1361315346.496 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2013-02-19T23:09:10.442Z,1361315350.442 [CommandLine](IMPORTANT): got command failComponent none RudderServo
2013-02-19T23:09:10.443Z,1361315350.443 [RudderServo] No Fault, FailCount= 5
2013-02-19T23:09:10.443Z,1361315350.443 [CommandLine](IMPORTANT): RudderServo failureMode is No Fault
2013-02-19T23:09:36.982Z,1361315376.982 [Aanderaa_O2](INFO): Powering down
2013-02-19T23:10:00.454Z,1361315400.454 [CommandLine](IMPORTANT): got command quit
2013-02-19T23:10:01.490Z,1361315401.490 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2013-02-19T23:10:01.490Z,1361315401.490 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2013-02-19T23:10:01.570Z,1361315401.570 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2013-02-19T23:10:01.662Z,1361315401.662 [WetLabsBB2FL](INFO): Powering down
2013-02-19T23:10:01.682Z,1361315401.682 [ComponentRegistry](INFO): Shutting down DVL_micro ThreadHandler
2013-02-19T23:10:01.751Z,1361315401.750 [DVL_micro](INFO): uninitialize:Powering down
2013-02-19T23:10:01.773Z,1361315401.773 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler
2013-02-19T23:10:02.002Z,1361315402.002 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator.
2013-02-19T23:10:02.014Z,1361315402.014 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2013-02-19T23:10:02.083Z,1361315402.083 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2013-02-19T23:10:02.084Z,1361315402.084 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2013-02-19T23:10:02.238Z,1361315402.238 [controlThread](DEBUG): Uninitializing ControlThread
2013-02-19T23:10:02.239Z,1361315402.239 [AHRS_sp3003D](INFO): Powering down
2013-02-19T23:10:02.327Z,1361315402.327 [NAL9602](INFO): Powering down
2013-02-19T23:10:02.328Z,1361315402.328 [Aanderaa_O2](INFO): Powering down
2013-02-19T23:10:02.330Z,1361315402.330 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2013-02-19T23:10:02.331Z,1361315402.331 [NavChart](DEBUG): Uninitialize NavChart Derivation.
2013-02-19T23:10:02.332Z,1361315402.332 [Default] Stopped
2013-02-19T23:10:02.332Z,1361315402.332 [Default](INFO): Aggregate::uninitialize Default
2013-02-19T23:10:02.332Z,1361315402.332 [Default:GPS] Stopped
2013-02-19T23:10:02.332Z,1361315402.332 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-02-19T23:10:02.332Z,1361315402.332 [Default:GPS:A.SetSpeed] Stopped
2013-02-19T23:10:02.332Z,1361315402.332 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-02-19T23:10:02.332Z,1361315402.332 [Default:GPS:Read_GPS] Stopped
2013-02-19T23:10:02.332Z,1361315402.332 [Default:D.SetSpeed] Stopped
2013-02-19T23:10:02.332Z,1361315402.332 [Default:D.SetSpeed](DEBUG): Uninitialize.
2013-02-19T23:10:02.332Z,1361315402.332 [Default:E.GoToSurface] Stopped
2013-02-19T23:10:02.333Z,1361315402.332 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-02-19T23:10:02.333Z,1361315402.333 [Default:F.Wait] Stopped
2013-02-19T23:10:02.333Z,1361315402.333 [Default:F.Wait](DEBUG): Uninitialize Wait Component.
2013-02-19T23:10:02.337Z,1361315402.337 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2013-02-19T23:10:02.337Z,1361315402.337 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2013-02-19T23:10:02.337Z,1361315402.337 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2013-02-19T23:10:02.338Z,1361315402.338 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2013-02-19T23:10:02.338Z,1361315402.338 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-02-19T23:10:02.338Z,1361315402.338 [BuoyancyServo](INFO): Powering down
2013-02-19T23:10:02.350Z,1361315402.350 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2013-02-19T23:10:02.350Z,1361315402.350 [ElevatorServo](INFO): Powering down
2013-02-19T23:10:02.351Z,1361315402.351 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-02-19T23:10:02.351Z,1361315402.351 [MassServo](INFO): Powering down
2013-02-19T23:10:02.352Z,1361315402.352 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2013-02-19T23:10:02.352Z,1361315402.352 [RudderServo](INFO): Powering down
2013-02-19T23:10:02.353Z,1361315402.353 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-02-19T23:10:02.353Z,1361315402.353 [ThrusterServo](INFO): Powering down
2013-02-19T23:10:02.354Z,1361315402.354 [SBIT](DEBUG): Uninitialize SBIT Component.
2013-02-19T23:10:02.354Z,1361315402.354 [IBIT](DEBUG): Uninitialize IBIT Component.
2013-02-19T23:10:02.354Z,1361315402.355 [CBIT](DEBUG): Uninitialize CBIT Component.