2013-03-03T22:29:28.320Z,1362349768.320 [Supervisor](DEBUG): Initializing supervisor.
2013-03-03T22:29:28.323Z,1362349768.323 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2013-03-03T22:29:28.323Z,1362349768.323 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2013-03-03T22:29:28.324Z,1362349768.324 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0
2013-03-03T22:29:28.328Z,1362349768.328 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2013-03-03T22:29:28.338Z,1362349768.339 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2013-03-03T22:29:28.340Z,1362349768.339 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0
2013-03-03T22:29:28.340Z,1362349768.340 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2013-03-03T22:29:28.341Z,1362349768.341 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403CB4E0
2013-03-03T22:29:28.342Z,1362349768.342 [Supervisor](INFO): Looking for Config files in directory: Config/
2013-03-03T22:29:28.346Z,1362349768.346 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2013-03-03T22:29:28.627Z,1362349768.627 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2013-03-03T22:29:28.628Z,1362349768.628 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2013-03-03T22:29:28.816Z,1362349768.816 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2013-03-03T22:29:28.817Z,1362349768.817 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2013-03-03T22:29:28.904Z,1362349768.904 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2013-03-03T22:29:28.906Z,1362349768.906 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2013-03-03T22:29:29.108Z,1362349769.108 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2013-03-03T22:29:29.108Z,1362349769.108 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2013-03-03T22:29:29.242Z,1362349769.242 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2013-03-03T22:29:29.245Z,1362349769.245 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2013-03-03T22:29:29.476Z,1362349769.476 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2013-03-03T22:29:29.477Z,1362349769.477 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2013-03-03T22:29:29.648Z,1362349769.648 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2013-03-03T22:29:29.650Z,1362349769.650 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2013-03-03T22:29:29.901Z,1362349769.901 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2013-03-03T22:29:29.903Z,1362349769.903 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2013-03-03T22:29:30.003Z,1362349770.003 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2013-03-03T22:29:30.004Z,1362349770.004 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2013-03-03T22:29:30.410Z,1362349770.411 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2013-03-03T22:29:30.411Z,1362349770.411 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2013-03-03T22:29:30.526Z,1362349770.526 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2013-03-03T22:29:30.527Z,1362349770.527 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2013-03-03T22:29:30.614Z,1362349770.614 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2013-03-03T22:29:30.618Z,1362349770.618 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2013-03-03T22:29:30.722Z,1362349770.722 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2013-03-03T22:29:30.852Z,1362349770.852 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2013-03-03T22:29:30.938Z,1362349770.938 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2013-03-03T22:29:31.041Z,1362349771.041 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2013-03-03T22:29:31.144Z,1362349771.144 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2013-03-03T22:29:31.265Z,1362349771.265 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2013-03-03T22:29:31.360Z,1362349771.360 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2013-03-03T22:29:31.449Z,1362349771.449 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2013-03-03T22:29:31.483Z,1362349771.483 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2013-03-03T22:29:31.667Z,1362349771.667 [InternalSim] Loaded
2013-03-03T22:29:31.668Z,1362349771.668 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2013-03-03T22:29:31.669Z,1362349771.668 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2013-03-03T22:29:31.669Z,1362349771.669 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2013-03-03T22:29:31.747Z,1362349771.747 [SBIT](DEBUG): Construct Startup Built In Test.
2013-03-03T22:29:31.775Z,1362349771.775 [SBIT] Loaded
2013-03-03T22:29:31.775Z,1362349771.776 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2013-03-03T22:29:31.776Z,1362349771.776 [IBIT](DEBUG): Construct Initiated Built In Test.
2013-03-03T22:29:31.805Z,1362349771.805 [IBIT] Loaded
2013-03-03T22:29:31.806Z,1362349771.806 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2013-03-03T22:29:31.809Z,1362349771.809 [CBIT](DEBUG): Construct CBIT Built In Test.
2013-03-03T22:29:31.929Z,1362349771.929 [CBIT] Loaded
2013-03-03T22:29:31.929Z,1362349771.929 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2013-03-03T22:29:31.929Z,1362349771.929 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2013-03-03T22:29:31.930Z,1362349771.930 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2013-03-03T22:29:32.116Z,1362349772.116 [BuoyancyServo] Loaded
2013-03-03T22:29:32.116Z,1362349772.116 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2013-03-03T22:29:32.129Z,1362349772.129 [ElevatorServo] Loaded
2013-03-03T22:29:32.129Z,1362349772.129 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2013-03-03T22:29:32.141Z,1362349772.141 [MassServo] Loaded
2013-03-03T22:29:32.141Z,1362349772.141 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2013-03-03T22:29:32.153Z,1362349772.153 [RudderServo] Loaded
2013-03-03T22:29:32.154Z,1362349772.154 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2013-03-03T22:29:32.166Z,1362349772.165 [ThrusterServo] Loaded
2013-03-03T22:29:32.166Z,1362349772.166 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2013-03-03T22:29:32.166Z,1362349772.166 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2013-03-03T22:29:32.167Z,1362349772.167 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2013-03-03T22:29:34.024Z,1362349774.024 [DepthRateCalculator] Loaded
2013-03-03T22:29:34.024Z,1362349774.024 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2013-03-03T22:29:37.557Z,1362349777.557 [HFRadarModelCalc] Loaded
2013-03-03T22:29:37.557Z,1362349777.557 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2013-03-03T22:29:37.573Z,1362349777.573 [NavChart] Loaded
2013-03-03T22:29:37.574Z,1362349777.574 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2013-03-03T22:29:37.580Z,1362349777.579 [PitchRateCalculator] Loaded
2013-03-03T22:29:37.580Z,1362349777.580 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2013-03-03T22:29:37.586Z,1362349777.586 [SpeedCalculator] Loaded
2013-03-03T22:29:37.587Z,1362349777.587 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2013-03-03T22:29:37.602Z,1362349777.602 [TempGradientCalculator] Loaded
2013-03-03T22:29:37.602Z,1362349777.602 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2013-03-03T22:29:37.608Z,1362349777.608 [YawRateCalculator] Loaded
2013-03-03T22:29:37.608Z,1362349777.608 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2013-03-03T22:29:37.649Z,1362349777.649 [Navigation] Loaded
2013-03-03T22:29:37.649Z,1362349777.649 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread.
2013-03-03T22:29:37.650Z,1362349777.650 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2013-03-03T22:29:37.650Z,1362349777.650 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2013-03-03T22:29:37.913Z,1362349777.913 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2013-03-03T22:29:37.913Z,1362349777.913 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2013-03-03T22:29:37.951Z,1362349777.951 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2013-03-03T22:29:37.952Z,1362349777.952 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2013-03-03T22:29:38.011Z,1362349778.011 [VerticalControl](DEBUG): Construct VerticalControl.
2013-03-03T22:29:38.105Z,1362349778.105 [VerticalControl] Loaded
2013-03-03T22:29:38.105Z,1362349778.105 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2013-03-03T22:29:38.106Z,1362349778.106 [HorizontalControl](DEBUG): Construct HorizontalControl.
2013-03-03T22:29:38.163Z,1362349778.163 [HorizontalControl] Loaded
2013-03-03T22:29:38.163Z,1362349778.163 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2013-03-03T22:29:38.164Z,1362349778.164 [SpeedControl](DEBUG): Construct SpeedControl.
2013-03-03T22:29:38.166Z,1362349778.166 [SpeedControl] Loaded
2013-03-03T22:29:38.166Z,1362349778.166 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2013-03-03T22:29:38.167Z,1362349778.167 [LoopControl](DEBUG): Construct LoopControl.
2013-03-03T22:29:38.168Z,1362349778.168 [LoopControl] Loaded
2013-03-03T22:29:38.168Z,1362349778.168 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2013-03-03T22:29:38.168Z,1362349778.168 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2013-03-03T22:29:38.169Z,1362349778.169 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2013-03-03T22:29:38.177Z,1362349778.177 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2013-03-03T22:29:38.182Z,1362349778.182 [AsyncPiEstimator] Loaded
2013-03-03T22:29:38.182Z,1362349778.182 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2013-03-03T22:29:38.184Z,1362349778.184 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 406A44E0
2013-03-03T22:29:38.184Z,1362349778.184 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2013-03-03T22:29:38.185Z,1362349778.185 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2013-03-03T22:29:38.354Z,1362349778.354 [AHRS_sp3003D] Loaded
2013-03-03T22:29:38.355Z,1362349778.355 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2013-03-03T22:29:38.616Z,1362349778.616 [Batt_Ocean_Server] Loaded
2013-03-03T22:29:38.617Z,1362349778.616 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread.
2013-03-03T22:29:38.631Z,1362349778.631 [Depth_Keller] Loaded
2013-03-03T22:29:38.631Z,1362349778.631 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2013-03-03T22:29:38.637Z,1362349778.637 [DropWeight] Loaded
2013-03-03T22:29:38.637Z,1362349778.637 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2013-03-03T22:29:38.812Z,1362349778.812 [DVL_micro] Loaded
2013-03-03T22:29:38.813Z,1362349778.813 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread.
2013-03-03T22:29:38.814Z,1362349778.814 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 407284E0
2013-03-03T22:29:38.898Z,1362349778.898 [NAL9602] Loaded
2013-03-03T22:29:38.898Z,1362349778.898 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2013-03-03T22:29:38.949Z,1362349778.949 [Onboard] Loaded
2013-03-03T22:29:38.949Z,1362349778.949 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2013-03-03T22:29:38.956Z,1362349778.956 [Radio_Freewave] Loaded
2013-03-03T22:29:38.956Z,1362349778.956 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread.
2013-03-03T22:29:38.957Z,1362349778.957 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2013-03-03T22:29:38.958Z,1362349778.958 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2013-03-03T22:29:39.038Z,1362349779.038 [Aanderaa_O2] Loaded
2013-03-03T22:29:39.038Z,1362349779.038 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2013-03-03T22:29:39.071Z,1362349779.071 [CTD_NeilBrown] Loaded
2013-03-03T22:29:39.072Z,1362349779.072 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2013-03-03T22:29:39.073Z,1362349779.073 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4077A4E0
2013-03-03T22:29:39.086Z,1362349779.086 [ISUS] Loaded
2013-03-03T22:29:39.086Z,1362349779.086 [ComponentRegistry](DEBUG): SyncComponent "ISUS" handled in the control thread.
2013-03-03T22:29:39.102Z,1362349779.102 [PAR_Licor] Loaded
2013-03-03T22:29:39.102Z,1362349779.102 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2013-03-03T22:29:39.133Z,1362349779.133 [WetLabsBB2FL] Loaded
2013-03-03T22:29:39.133Z,1362349779.133 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2013-03-03T22:29:39.134Z,1362349779.134 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407AA4E0
2013-03-03T22:29:39.135Z,1362349779.135 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2013-03-03T22:29:39.143Z,1362349779.143 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2013-03-03T22:29:39.144Z,1362349779.144 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2013-03-03T22:29:39.156Z,1362349779.156 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2013-03-03T22:29:39.157Z,1362349779.157 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407DA4E0
2013-03-03T22:29:39.162Z,1362349779.162 [Supervisor](DEBUG): Running supervisor.
2013-03-03T22:29:39.163Z,1362349779.163 [CommandLine](INFO): Thread ID is 746
2013-03-03T22:29:39.166Z,1362349779.166 [controlThread](INFO): Thread ID is 745
2013-03-03T22:29:39.166Z,1362349779.166 [controlThread](DEBUG): Initializing ControlThread
2013-03-03T22:29:39.166Z,1362349779.166 [CycleStarter](INFO): Thread ID is 744
2013-03-03T22:29:39.167Z,1362349779.167 [InternalSim](DEBUG):  InternalSim initializing...
2013-03-03T22:29:39.269Z,1362349779.269 [logger](INFO): Thread ID is 747
2013-03-03T22:29:39.356Z,1362349779.356 [AsyncPiEstimator](INFO): Thread ID is 808
2013-03-03T22:29:39.356Z,1362349779.356 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2013-03-03T22:29:39.369Z,1362349779.369 [DVL_micro](INFO): Thread ID is 809
2013-03-03T22:29:39.466Z,1362349779.466 [CTD_NeilBrown](INFO): Thread ID is 810
2013-03-03T22:29:39.467Z,1362349779.467 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2013-03-03T22:29:39.470Z,1362349779.470 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2013-03-03T22:29:39.519Z,1362349779.519 [WetLabsBB2FL](INFO): Thread ID is 811
2013-03-03T22:29:39.520Z,1362349779.520 [WetLabsBB2FL](INFO): Powering down
2013-03-03T22:29:39.540Z,1362349779.540 [DVL_micro](INFO): Initializing
2013-03-03T22:29:39.540Z,1362349779.540 [DVL_micro](INFO): start:Powering up
2013-03-03T22:29:39.541Z,1362349779.541 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-03-03T22:29:39.541Z,1362349779.541 [DVL_micro](INFO): Cycling power to configure device.
2013-03-03T22:29:39.547Z,1362349779.547 [SBIT](INFO): Initialize SBIT Component.
2013-03-03T22:29:39.547Z,1362349779.547 [SBIT](IMPORTANT): Tethys CM Info:
$Rev: 10204
2013-03-03T22:29:39.548Z,1362349779.548 [IBIT](INFO): Initialize IBIT Component.
2013-03-03T22:29:39.549Z,1362349779.549 [CBIT](DEBUG): Initialize CBIT Component.
2013-03-03T22:29:39.549Z,1362349779.549 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2013-03-03T22:29:39.550Z,1362349779.550 [NavChartDb](INFO): Thread ID is 812
2013-03-03T22:29:39.561Z,1362349779.561 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory
2013-03-03T22:29:39.565Z,1362349779.565 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2013-03-03T22:29:39.567Z,1362349779.567 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000
2013-03-03T22:29:39.568Z,1362349779.568 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000
2013-03-03T22:29:39.570Z,1362349779.570 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000
2013-03-03T22:29:39.580Z,1362349779.580 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000
2013-03-03T22:29:39.581Z,1362349779.581 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000
2013-03-03T22:29:39.588Z,1362349779.588 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2013-03-03T22:29:39.589Z,1362349779.589 [NavChart](DEBUG): Initialize NavChart Derivation.
2013-03-03T22:29:39.589Z,1362349779.589 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2013-03-03T22:29:39.589Z,1362349779.589 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2013-03-03T22:29:39.590Z,1362349779.590 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2013-03-03T22:29:39.591Z,1362349779.591 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2013-03-03T22:29:39.592Z,1362349779.592 [Navigation](DEBUG): Initializing Navigation.
2013-03-03T22:29:39.592Z,1362349779.592 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2013-03-03T22:29:39.594Z,1362349779.594 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2013-03-03T22:29:39.594Z,1362349779.594 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2013-03-03T22:29:39.595Z,1362349779.595 [LoopControl](DEBUG): Initialize LoopControlComponent.
2013-03-03T22:29:39.597Z,1362349779.597 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000
2013-03-03T22:29:39.623Z,1362349779.623 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000
2013-03-03T22:29:39.625Z,1362349779.625 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000
2013-03-03T22:29:39.767Z,1362349779.767 [NavChartDb](INFO): Setup scan of Resources/US5CA62M.000
2013-03-03T22:29:41.259Z,1362349781.260 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2013-03-03T22:29:41.303Z,1362349781.303 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2013-03-03T22:29:41.327Z,1362349781.327 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2013-03-03T22:29:41.337Z,1362349781.337 [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-03-03T22:29:41.338Z,1362349781.338 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2013-03-03T22:29:41.390Z,1362349781.390 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2013-03-03T22:29:41.396Z,1362349781.397 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2013-03-03T22:29:41.404Z,1362349781.404 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-03-03T22:29:41.412Z,1362349781.412 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2013-03-03T22:29:41.423Z,1362349781.423 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-03-03T22:29:41.430Z,1362349781.430 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2013-03-03T22:29:41.439Z,1362349781.439 [Default:D.SetSpeed](DEBUG): Construct.
2013-03-03T22:29:41.442Z,1362349781.442 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2013-03-03T22:29:41.447Z,1362349781.447 [Default:F.Wait](DEBUG): Construct Wait.
2013-03-03T22:29:41.451Z,1362349781.451 [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-03-03T22:29:41.456Z,1362349781.456 [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-03-03T22:29:41.511Z,1362349781.510 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-03-03T22:29:41.642Z,1362349781.642 [Radio_Freewave](INFO): Powering up
2013-03-03T22:29:41.647Z,1362349781.647 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-03-03T22:29:41.647Z,1362349781.647 [DVL_micro](INFO): Querying output modes
2013-03-03T22:29:41.648Z,1362349781.648 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-03-03T22:29:41.659Z,1362349781.659 [DVL_micro](DEBUG): cmdResponse: 01 03 16
2013-03-03T22:29:41.659Z,1362349781.659 [DVL_micro](INFO): NQ1 output enabled
2013-03-03T22:29:41.659Z,1362349781.659 [DVL_micro](INFO): RSSI output enabled
2013-03-03T22:29:41.659Z,1362349781.659 [DVL_micro](INFO): ADCP output enabled
2013-03-03T22:29:41.660Z,1362349781.660 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-03-03T22:29:41.671Z,1362349781.671 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2013-03-03T22:29:41.693Z,1362349781.693 [DVL_micro](INFO): pause:Powering down
2013-03-03T22:29:41.835Z,1362349781.835 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-03-03T22:29:41.836Z,1362349781.836 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-03-03T22:29:41.843Z,1362349781.843 [ElevatorServo](DEBUG): Initializing EZServoServo.
2013-03-03T22:29:41.844Z,1362349781.844 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2013-03-03T22:29:41.850Z,1362349781.850 [MassServo](DEBUG): Initializing EZServoServo.
2013-03-03T22:29:41.851Z,1362349781.851 [MassServo](DEBUG): Initializing MassServo.
2013-03-03T22:29:41.857Z,1362349781.857 [RudderServo](DEBUG): Initializing EZServoServo.
2013-03-03T22:29:41.859Z,1362349781.859 [RudderServo](DEBUG): Initializing RudderServo.
2013-03-03T22:29:41.865Z,1362349781.865 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-03-03T22:29:41.866Z,1362349781.866 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-03-03T22:29:42.996Z,1362349782.996 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-03-03T22:29:42.996Z,1362349782.996 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-03-03T22:29:42.996Z,1362349782.996 [BuoyancyServo] Communications Fault, FailCount= 1
2013-03-03T22:29:42.996Z,1362349782.996 [BuoyancyServo](ERROR): Communications Fault
2013-03-03T22:29:43.209Z,1362349783.209 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-03-03T22:29:43.446Z,1362349783.446 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-03-03T22:29:43.447Z,1362349783.447 [BuoyancyServo](INFO): Powering down
2013-03-03T22:29:44.218Z,1362349784.218 [NAL9602](INFO): Powering up NAL9602
2013-03-03T22:29:44.343Z,1362349784.343 [Aanderaa_O2](INFO): Powering down
2013-03-03T22:29:46.057Z,1362349786.057 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2013-03-03T22:29:46.057Z,1362349786.057 [BuoyancyServo] No Fault, FailCount= 1
2013-03-03T22:29:46.255Z,1362349786.254 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-03-03T22:29:46.373Z,1362349786.373 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-03-03T22:29:54.767Z,1362349794.767 [NAL9602](INFO): NAL9602 initialized
2013-03-03T22:29:55.184Z,1362349795.184 [SBIT](IMPORTANT): Beginning Startup BIT
2013-03-03T22:29:55.187Z,1362349795.187 [CBIT](IMPORTANT): Beginning GF scan
2013-03-03T22:30:22.065Z,1362349822.065 [CBIT](FAULT): Chan 4 High side GF detected
mA:
CHAN 5 (24V): 0.032043
CHAN 4 (Batt): 0.008991
CHAN 2 (12V): 0.002965
CHAN 1 (5V): 0.000765
CHAN 0 (3.3V): 0.000478
OPEN: 0.000670
Full Scale Calc: 0.392
2013-03-03T22:30:22.444Z,1362349822.444 [NAL9602](IMPORTANT): GPS fix at: 1362349820.00
2013-03-03T22:30:29.592Z,1362349829.592 [NAL9602](INFO): Powering down
2013-03-03T22:30:40.203Z,1362349840.203 [CommandLine](IMPORTANT): got command gfscan
2013-03-03T22:30:40.334Z,1362349840.334 [CBIT](IMPORTANT): Beginning GF scan
2013-03-03T22:30:48.620Z,1362349848.620 [SBIT](IMPORTANT): SBIT PASSED
2013-03-03T22:30:48.942Z,1362349848.942 [MissionManager](IMPORTANT): Started mission Startup
2013-03-03T22:30:48.942Z,1362349848.942 [Startup] Running Loop=1
2013-03-03T22:30:48.942Z,1362349848.942 [Startup](INFO): Aggregate::initialize Startup
2013-03-03T22:30:48.942Z,1362349848.942 [Startup:A.GoToSurface] Running Loop=1
2013-03-03T22:30:48.942Z,1362349848.943 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-03T22:30:48.948Z,1362349848.948 [Startup:StartupSatComms] Running Loop=1
2013-03-03T22:30:48.948Z,1362349848.948 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2013-03-03T22:30:48.949Z,1362349848.949 [Startup:StartupSatComms:A] Running Loop=1
2013-03-03T22:30:49.343Z,1362349849.343 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-03-03T22:30:49.728Z,1362349849.728 [NAL9602](INFO): Powering up
2013-03-03T22:31:00.339Z,1362349860.339 [NAL9602](INFO): NAL9602 initialized
2013-03-03T22:31:07.211Z,1362349867.211 [CBIT](INFO): No ground fault detected
2013-03-03T22:31:31.095Z,1362349891.095 [NAL9602](IMPORTANT): GPS fix at: 1362349889.00
2013-03-03T22:31:31.110Z,1362349891.110 [Startup:StartupSatComms:A] Stopped
2013-03-03T22:31:31.111Z,1362349891.111 [Startup:StartupSatComms:B] Running Loop=1
2013-03-03T22:31:31.527Z,1362349891.527 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2013-03-03T22:31:49.577Z,1362349909.577 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:31:49.577Z,1362349909.577 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:31:58.457Z,1362349918.457 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:31:58.458Z,1362349918.458 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:32:06.627Z,1362349926.627 [CommandLine](IMPORTANT): got command ibit
2013-03-03T22:32:06.961Z,1362349926.961 [IBIT](IMPORTANT): Beginning Initiated BIT
2013-03-03T22:32:06.962Z,1362349926.962 [IBIT](IMPORTANT): Beginning control surface checks.

2013-03-03T22:32:06.963Z,1362349926.963 [CBIT](IMPORTANT): Beginning GF scan
2013-03-03T22:32:07.597Z,1362349927.597 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:32:07.598Z,1362349927.598 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:32:08.917Z,1362349928.917 [NAL9602](IMPORTANT): GPS fix at: 1362349926.00
2013-03-03T22:32:24.297Z,1362349944.297 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:32:24.298Z,1362349944.298 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:32:25.540Z,1362349945.540 [NAL9602](IMPORTANT): GPS fix at: 1362349943.00
2013-03-03T22:32:33.482Z,1362349953.482 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:32:33.482Z,1362349953.482 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:32:33.911Z,1362349953.911 [CBIT](IMPORTANT): No ground fault detected
2013-03-03T22:32:34.683Z,1362349954.683 [NAL9602](IMPORTANT): GPS fix at: 1362349952.00
2013-03-03T22:32:50.742Z,1362349970.741 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:32:50.742Z,1362349970.742 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:32:51.943Z,1362349971.943 [NAL9602](IMPORTANT): GPS fix at: 1362349970.00
2013-03-03T22:32:52.822Z,1362349972.822 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802887 Longitude: -121.788033
2013-03-03T22:32:53.237Z,1362349973.237 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 235.169998
Voltage: 16.166813
2013-03-03T22:32:53.238Z,1362349973.238 [IBIT](IMPORTANT): batteryCapacityThreshold: 5.000000 Ah
2013-03-03T22:32:53.238Z,1362349973.238 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.500000 V
2013-03-03T22:32:53.637Z,1362349973.637 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2013-03-03T22:32:53.637Z,1362349973.637 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2013-03-03T22:32:53.638Z,1362349973.638 [IBIT](IMPORTANT): Pressure:16.575218 PSI
2013-03-03T22:32:53.638Z,1362349973.638 [IBIT](IMPORTANT): Humidity:6.441073 %
2013-03-03T22:32:54.057Z,1362349974.057 [IBIT](IMPORTANT): surfaceThreshold: 0.500000 m
2013-03-03T22:32:54.058Z,1362349974.057 [IBIT](IMPORTANT): buoyancyNeutral: 498.000000 cc
2013-03-03T22:32:54.058Z,1362349974.058 [IBIT](IMPORTANT): massDefault: 1.090000 cm
2013-03-03T22:32:54.058Z,1362349974.058 [IBIT](IMPORTANT): stopDepth: 100.000000 m
2013-03-03T22:32:54.059Z,1362349974.059 [IBIT](IMPORTANT): abortDepth: 125.000000 m
2013-03-03T22:32:54.059Z,1362349974.059 [IBIT](IMPORTANT): IBIT PASSED
2013-03-03T22:32:54.366Z,1362349974.366 [Startup:StartupSatComms:B](INFO): Timed out from 2013-03-03T22:31:31.1Z
2013-03-03T22:32:54.366Z,1362349974.366 [Startup:StartupSatComms:B:A_Timeout] Running Loop=1
2013-03-03T22:32:54.366Z,1362349974.366 [Startup:StartupSatComms:B:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:B:A_Timeout
2013-03-03T22:32:54.366Z,1362349974.366 [Startup:StartupSatComms:B:A_Timeout](INFO): Completed Startup:StartupSatComms:B:A_Timeout
2013-03-03T22:32:54.367Z,1362349974.367 [Startup:StartupSatComms:B] Stopped
2013-03-03T22:32:54.368Z,1362349974.368 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2013-03-03T22:32:54.368Z,1362349974.368 [Startup:StartupSatComms] Stopped
2013-03-03T22:32:54.368Z,1362349974.368 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2013-03-03T22:32:54.369Z,1362349974.369 [Startup](INFO): Completed Startup
2013-03-03T22:32:54.369Z,1362349974.369 [Startup] Stopped
2013-03-03T22:32:54.369Z,1362349974.369 [Startup](INFO): Aggregate::uninitialize Startup
2013-03-03T22:32:54.369Z,1362349974.369 [Startup:A.GoToSurface] Stopped
2013-03-03T22:32:54.369Z,1362349974.369 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-03T22:32:55.361Z,1362349975.361 [MissionManager](IMPORTANT): Started mission Default
2013-03-03T22:32:55.361Z,1362349975.361 [Default] Running Loop=1
2013-03-03T22:32:55.361Z,1362349975.361 [Default](INFO): Aggregate::initialize Default
2013-03-03T22:32:55.361Z,1362349975.361 [Default:D.SetSpeed] Running Loop=1
2013-03-03T22:32:55.361Z,1362349975.361 [Default:D.SetSpeed](DEBUG): Initialize.
2013-03-03T22:32:55.361Z,1362349975.362 [Default:E.GoToSurface] Running Loop=1
2013-03-03T22:32:55.362Z,1362349975.362 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-03T22:32:55.362Z,1362349975.362 [Default:Iridium] Running Loop=1
2013-03-03T22:32:55.362Z,1362349975.362 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-03T22:32:55.362Z,1362349975.362 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-03T22:32:55.362Z,1362349975.362 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-03T22:32:55.362Z,1362349975.362 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-03T22:32:55.363Z,1362349975.362 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-03T22:32:55.363Z,1362349975.363 [Default:E.GoToSurface] Running Loop=1
2013-03-03T22:32:55.369Z,1362349975.369 [Default:D.SetSpeed] Running Loop=1
2013-03-03T22:32:55.414Z,1362349975.414 [Default:CallIridium] Running Loop=1
2013-03-03T22:32:55.415Z,1362349975.415 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-03T22:32:55.415Z,1362349975.415 [Default:CallIridium:A] Running Loop=1
2013-03-03T22:32:55.419Z,1362349975.419 [Default:CallIridium:A] Stopped
2013-03-03T22:32:55.419Z,1362349975.419 [Default:CallIridium:B] Running Loop=1
2013-03-03T22:32:55.419Z,1362349975.419 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-03T22:32:55.426Z,1362349975.426 [Default:Iridium:B.GoToSurface] Stopped
2013-03-03T22:32:55.426Z,1362349975.426 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-03T22:32:55.426Z,1362349975.427 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-03T22:32:55.427Z,1362349975.427 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-03T22:32:55.432Z,1362349975.432 [Default:GPS] Running Loop=1
2013-03-03T22:32:55.432Z,1362349975.432 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-03T22:32:55.432Z,1362349975.432 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-03T22:32:55.432Z,1362349975.432 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-03T22:32:55.432Z,1362349975.432 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-03T22:32:55.433Z,1362349975.432 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-03T22:32:55.438Z,1362349975.438 [Default:GPS:B.GoToSurface] Stopped
2013-03-03T22:32:55.438Z,1362349975.438 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-03T22:32:55.438Z,1362349975.438 [Default:GPS:Read_GPS] Running Loop=1
2013-03-03T22:32:55.438Z,1362349975.438 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-03T22:32:55.839Z,1362349975.839 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2013-03-03T22:32:55.842Z,1362349975.842 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-03-03T22:33:08.050Z,1362349988.050 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:33:08.050Z,1362349988.050 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:33:09.219Z,1362349989.219 [NAL9602](IMPORTANT): GPS fix at: 1362349987.00
2013-03-03T22:33:09.236Z,1362349989.236 [Default:GPS:Read_GPS] Stopped
2013-03-03T22:33:09.236Z,1362349989.236 [Default:GPS:D] Running Loop=1
2013-03-03T22:33:09.644Z,1362349989.644 [Default:GPS:D] Stopped
2013-03-03T22:33:09.645Z,1362349989.645 [Default:GPS](INFO): Completed Default:GPS
2013-03-03T22:33:09.645Z,1362349989.645 [Default:GPS] Stopped
2013-03-03T22:33:09.645Z,1362349989.645 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-03T22:33:09.645Z,1362349989.645 [Default:GPS:A.SetSpeed] Stopped
2013-03-03T22:33:09.645Z,1362349989.645 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-03T22:33:25.233Z,1362350005.233 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:33:25.234Z,1362350005.234 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:33:42.497Z,1362350022.497 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:33:42.498Z,1362350022.498 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:33:51.677Z,1362350031.677 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:33:51.677Z,1362350031.677 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:34:00.077Z,1362350040.077 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:34:00.077Z,1362350040.077 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:34:17.612Z,1362350057.612 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:34:17.613Z,1362350057.612 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:34:25.965Z,1362350065.965 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:34:25.966Z,1362350065.966 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:34:43.506Z,1362350083.506 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:34:43.506Z,1362350083.506 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:35:00.217Z,1362350100.217 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:35:00.218Z,1362350100.218 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:35:08.683Z,1362350108.683 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:35:08.683Z,1362350108.683 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:35:24.553Z,1362350124.553 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:35:24.554Z,1362350124.554 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:35:46.277Z,1362350146.277 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:35:46.277Z,1362350146.277 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:36:03.558Z,1362350163.557 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:36:03.558Z,1362350163.558 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:37:38.509Z,1362350258.509 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:37:38.509Z,1362350258.509 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:38:26.076Z,1362350306.076 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:38:26.076Z,1362350306.076 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:38:44.977Z,1362350324.977 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:38:44.977Z,1362350324.977 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:39:39.276Z,1362350379.276 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:39:39.277Z,1362350379.277 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:39:56.077Z,1362350396.077 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:39:56.077Z,1362350396.077 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:40:30.802Z,1362350430.802 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:40:30.802Z,1362350430.802 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:40:44.678Z,1362350444.678 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:40:44.678Z,1362350444.678 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:40:54.001Z,1362350454.001 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:40:54.002Z,1362350454.001 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:41:09.477Z,1362350469.477 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:41:09.477Z,1362350469.477 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:41:17.870Z,1362350477.870 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:41:17.885Z,1362350477.885 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:41:26.561Z,1362350486.561 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:41:26.562Z,1362350486.562 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:41:35.329Z,1362350495.329 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:41:35.330Z,1362350495.330 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:41:52.521Z,1362350512.521 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:41:52.521Z,1362350512.521 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:42:00.978Z,1362350520.978 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:42:00.978Z,1362350520.978 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:42:09.657Z,1362350529.657 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:42:09.658Z,1362350529.658 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:42:18.781Z,1362350538.782 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:42:18.782Z,1362350538.782 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:42:27.957Z,1362350547.957 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:42:27.957Z,1362350547.957 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:42:37.157Z,1362350557.157 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:42:37.158Z,1362350557.158 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:42:46.317Z,1362350566.317 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:42:46.317Z,1362350566.318 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:42:55.505Z,1362350575.505 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:42:55.506Z,1362350575.506 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:43:12.677Z,1362350592.677 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:43:12.677Z,1362350592.677 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:43:26.217Z,1362350606.217 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:43:26.218Z,1362350606.217 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:43:35.526Z,1362350615.526 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:43:35.526Z,1362350615.526 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:43:43.897Z,1362350623.897 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:43:43.897Z,1362350623.898 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:43:52.609Z,1362350632.609 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:43:52.610Z,1362350632.610 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:44:05.002Z,1362350645.002 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:44:05.002Z,1362350645.002 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:44:13.949Z,1362350653.949 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:44:13.949Z,1362350653.949 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:44:36.334Z,1362350676.334 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:44:36.334Z,1362350676.334 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:45:31.409Z,1362350731.409 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:45:31.409Z,1362350731.409 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:45:41.095Z,1362350741.095 [CommandLine](IMPORTANT): got command failComponent
2013-03-03T22:45:41.095Z,1362350741.095 [CommandLine](IMPORTANT): Failed components:
2013-03-03T22:45:41.095Z,1362350741.095 [CommandLine](IMPORTANT): No failed Components.
2013-03-03T22:46:04.276Z,1362350764.276 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:46:04.276Z,1362350764.276 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:46:49.274Z,1362350809.274 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:46:49.274Z,1362350809.274 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:47:06.505Z,1362350826.505 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:47:06.505Z,1362350826.505 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:47:21.442Z,1362350841.442 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:47:21.442Z,1362350841.442 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:47:30.122Z,1362350850.122 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:47:30.122Z,1362350850.122 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:47:48.470Z,1362350868.470 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:47:48.477Z,1362350868.477 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:48:18.009Z,1362350898.009 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:48:18.010Z,1362350898.010 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:48:40.184Z,1362350920.184 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:48:40.184Z,1362350920.184 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:48:56.293Z,1362350936.293 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:48:56.293Z,1362350936.293 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:49:04.909Z,1362350944.909 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:49:04.910Z,1362350944.910 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:49:13.657Z,1362350953.657 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:49:13.658Z,1362350953.658 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:49:22.641Z,1362350962.641 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:49:22.642Z,1362350962.642 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:49:31.609Z,1362350971.609 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:49:31.609Z,1362350971.609 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:49:40.741Z,1362350980.741 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:49:40.741Z,1362350980.741 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:49:55.381Z,1362350995.381 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:49:55.382Z,1362350995.382 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:50:03.981Z,1362351003.981 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:50:03.981Z,1362351003.981 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:50:12.557Z,1362351012.557 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:50:12.558Z,1362351012.558 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:50:21.121Z,1362351021.121 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:50:21.121Z,1362351021.121 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:50:30.097Z,1362351030.097 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:50:30.097Z,1362351030.097 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:50:38.690Z,1362351038.690 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:50:38.690Z,1362351038.690 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:50:46.865Z,1362351046.865 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:50:46.865Z,1362351046.865 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:50:55.886Z,1362351055.885 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:50:55.886Z,1362351055.886 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:51:04.477Z,1362351064.477 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:51:04.477Z,1362351064.477 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:51:21.177Z,1362351081.177 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:51:21.177Z,1362351081.177 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:51:29.685Z,1362351089.685 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:51:29.685Z,1362351089.685 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:51:38.249Z,1362351098.249 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:51:38.250Z,1362351098.250 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:51:47.279Z,1362351107.279 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:51:47.280Z,1362351107.280 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:51:55.817Z,1362351115.817 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:51:55.817Z,1362351115.817 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:52:04.184Z,1362351124.184 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:52:04.184Z,1362351124.184 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:52:13.145Z,1362351133.145 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:52:13.145Z,1362351133.145 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:52:21.717Z,1362351141.717 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:52:21.718Z,1362351141.718 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:52:38.857Z,1362351158.857 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:52:38.858Z,1362351158.858 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:52:47.493Z,1362351167.493 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:52:47.493Z,1362351167.493 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:53:04.949Z,1362351184.949 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:53:04.950Z,1362351184.950 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:53:14.097Z,1362351194.097 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:53:14.097Z,1362351194.098 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:53:27.881Z,1362351207.881 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:53:27.881Z,1362351207.881 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:53:36.537Z,1362351216.537 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:53:36.538Z,1362351216.537 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:53:53.941Z,1362351233.941 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:53:53.942Z,1362351233.942 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:54:07.745Z,1362351247.745 [NAL9602](INFO): SBD MO Status=2, MOMSN=6075, MT Status=2, MTMSN=0
2013-03-03T22:54:07.745Z,1362351247.745 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-03T22:54:17.259Z,1362351257.259 [CommandLine](IMPORTANT): got command restart application
2013-03-03T22:54:18.382Z,1362351258.382 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2013-03-03T22:54:18.383Z,1362351258.383 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2013-03-03T22:54:18.479Z,1362351258.479 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2013-03-03T22:54:18.547Z,1362351258.547 [WetLabsBB2FL](INFO): Powering down
2013-03-03T22:54:18.558Z,1362351258.559 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2013-03-03T22:54:18.755Z,1362351258.755 [CTD_NeilBrown](INFO): Powering down
2013-03-03T22:54:18.758Z,1362351258.759 [ComponentRegistry](INFO): Shutting down DVL_micro ThreadHandler
2013-03-03T22:54:19.107Z,1362351259.107 [DVL_micro](INFO): uninitialize:Powering down
2013-03-03T22:54:19.126Z,1362351259.126 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler
2013-03-03T22:54:19.359Z,1362351259.359 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator.
2013-03-03T22:54:19.371Z,1362351259.372 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2013-03-03T22:54:19.374Z,1362351259.374 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2013-03-03T22:54:19.375Z,1362351259.375 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2013-03-03T22:54:19.533Z,1362351259.533 [controlThread](DEBUG): Uninitializing ControlThread
2013-03-03T22:54:19.533Z,1362351259.533 [AHRS_sp3003D](INFO): Powering down
2013-03-03T22:54:19.620Z,1362351259.620 [NAL9602](INFO): Powering down
2013-03-03T22:54:19.621Z,1362351259.621 [Aanderaa_O2](INFO): Powering down
2013-03-03T22:54:19.623Z,1362351259.623 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2013-03-03T22:54:19.624Z,1362351259.624 [NavChart](DEBUG): Uninitialize NavChart Derivation.
2013-03-03T22:54:19.625Z,1362351259.625 [Default] Stopped
2013-03-03T22:54:19.625Z,1362351259.625 [Default](INFO): Aggregate::uninitialize Default
2013-03-03T22:54:19.625Z,1362351259.625 [Default:Iridium] Stopped
2013-03-03T22:54:19.625Z,1362351259.625 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-03T22:54:19.625Z,1362351259.625 [Default:Iridium:A.SetSpeed] Stopped
2013-03-03T22:54:19.625Z,1362351259.625 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-03T22:54:19.625Z,1362351259.625 [Default:Iridium:Read_Iridium] Stopped
2013-03-03T22:54:19.625Z,1362351259.625 [Default:CallIridium] Stopped
2013-03-03T22:54:19.626Z,1362351259.625 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-03T22:54:19.626Z,1362351259.626 [Default:CallIridium:B] Stopped
2013-03-03T22:54:19.626Z,1362351259.626 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-03T22:54:19.626Z,1362351259.626 [Default:D.SetSpeed] Stopped
2013-03-03T22:54:19.626Z,1362351259.626 [Default:D.SetSpeed](DEBUG): Uninitialize.
2013-03-03T22:54:19.626Z,1362351259.626 [Default:E.GoToSurface] Stopped
2013-03-03T22:54:19.626Z,1362351259.626 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-03T22:54:19.630Z,1362351259.630 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2013-03-03T22:54:19.630Z,1362351259.630 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2013-03-03T22:54:19.631Z,1362351259.631 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2013-03-03T22:54:19.631Z,1362351259.631 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2013-03-03T22:54:19.631Z,1362351259.631 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-03-03T22:54:19.632Z,1362351259.632 [BuoyancyServo](INFO): Powering down
2013-03-03T22:54:19.643Z,1362351259.643 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2013-03-03T22:54:19.643Z,1362351259.643 [ElevatorServo](INFO): Powering down
2013-03-03T22:54:19.644Z,1362351259.644 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-03-03T22:54:19.644Z,1362351259.644 [MassServo](INFO): Powering down
2013-03-03T22:54:19.644Z,1362351259.644 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2013-03-03T22:54:19.645Z,1362351259.645 [RudderServo](INFO): Powering down
2013-03-03T22:54:19.645Z,1362351259.645 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-03-03T22:54:19.645Z,1362351259.645 [ThrusterServo](INFO): Powering down
2013-03-03T22:54:19.646Z,1362351259.646 [SBIT](DEBUG): Uninitialize SBIT Component.
2013-03-03T22:54:19.647Z,1362351259.647 [IBIT](DEBUG): Uninitialize IBIT Component.
2013-03-03T22:54:19.647Z,1362351259.647 [CBIT](DEBUG): Uninitialize CBIT Component.