2013-11-05T18:47:09.247Z,1383677229.247 [Supervisor](DEBUG): Initializing supervisor. 2013-11-05T18:47:09.250Z,1383677229.250 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0 2013-11-05T18:47:09.251Z,1383677229.251 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2013-11-05T18:47:09.252Z,1383677229.252 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0 2013-11-05T18:47:09.256Z,1383677229.256 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2013-11-05T18:47:09.268Z,1383677229.268 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2013-11-05T18:47:09.269Z,1383677229.269 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0 2013-11-05T18:47:09.270Z,1383677229.270 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2013-11-05T18:47:09.271Z,1383677229.271 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403CB4E0 2013-11-05T18:47:09.273Z,1383677229.273 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2013-11-05T18:47:09.274Z,1383677229.274 [Supervisor](INFO): Looking for Config files in directory: Config/ 2013-11-05T18:47:09.275Z,1383677229.275 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2013-11-05T18:47:09.552Z,1383677229.552 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2013-11-05T18:47:09.553Z,1383677229.553 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2013-11-05T18:47:09.750Z,1383677229.750 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2013-11-05T18:47:09.751Z,1383677229.751 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2013-11-05T18:47:09.836Z,1383677229.836 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2013-11-05T18:47:09.836Z,1383677229.836 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2013-11-05T18:47:09.963Z,1383677229.963 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2013-11-05T18:47:09.964Z,1383677229.964 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2013-11-05T18:47:10.109Z,1383677230.109 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2013-11-05T18:47:10.110Z,1383677230.110 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2013-11-05T18:47:10.349Z,1383677230.349 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2013-11-05T18:47:10.349Z,1383677230.349 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2013-11-05T18:47:10.516Z,1383677230.516 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2013-11-05T18:47:10.517Z,1383677230.517 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2013-11-05T18:47:10.806Z,1383677230.806 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2013-11-05T18:47:10.806Z,1383677230.806 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2013-11-05T18:47:10.907Z,1383677230.907 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2013-11-05T18:47:10.907Z,1383677230.907 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2013-11-05T18:47:11.338Z,1383677231.338 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2013-11-05T18:47:11.339Z,1383677231.339 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2013-11-05T18:47:11.472Z,1383677231.472 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2013-11-05T18:47:11.473Z,1383677231.473 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2013-11-05T18:47:11.559Z,1383677231.559 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2013-11-05T18:47:11.659Z,1383677231.659 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2013-11-05T18:47:11.661Z,1383677231.661 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2013-11-05T18:47:11.662Z,1383677231.662 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2013-11-05T18:47:11.757Z,1383677231.757 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2013-11-05T18:47:11.885Z,1383677231.885 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2013-11-05T18:47:11.984Z,1383677231.984 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2013-11-05T18:47:12.112Z,1383677232.112 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2013-11-05T18:47:12.207Z,1383677232.207 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2013-11-05T18:47:12.323Z,1383677232.323 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2013-11-05T18:47:12.418Z,1383677232.418 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2013-11-05T18:47:12.504Z,1383677232.504 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2013-11-05T18:47:12.597Z,1383677232.597 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/LOGIN/ 2013-11-05T18:47:12.598Z,1383677232.598 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2013-11-05T18:47:12.599Z,1383677232.599 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2013-11-05T18:47:12.769Z,1383677232.769 [InternalSim] Loaded 2013-11-05T18:47:12.769Z,1383677232.769 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread. 2013-11-05T18:47:12.770Z,1383677232.770 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2013-11-05T18:47:12.771Z,1383677232.771 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2013-11-05T18:47:12.880Z,1383677232.880 [SBIT](DEBUG): Construct Startup Built In Test. 2013-11-05T18:47:12.924Z,1383677232.924 [SBIT] Loaded 2013-11-05T18:47:12.924Z,1383677232.924 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2013-11-05T18:47:12.925Z,1383677232.925 [IBIT](DEBUG): Construct Initiated Built In Test. 2013-11-05T18:47:12.964Z,1383677232.964 [IBIT] Loaded 2013-11-05T18:47:12.964Z,1383677232.964 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2013-11-05T18:47:12.966Z,1383677232.966 [CBIT](DEBUG): Construct CBIT Built In Test. 2013-11-05T18:47:13.104Z,1383677233.104 [CBIT] Loaded 2013-11-05T18:47:13.104Z,1383677233.104 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2013-11-05T18:47:13.105Z,1383677233.105 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2013-11-05T18:47:13.105Z,1383677233.105 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2013-11-05T18:47:13.277Z,1383677233.277 [BuoyancyServo] Loaded 2013-11-05T18:47:13.277Z,1383677233.277 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2013-11-05T18:47:13.289Z,1383677233.289 [ElevatorServo] Loaded 2013-11-05T18:47:13.289Z,1383677233.289 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2013-11-05T18:47:13.300Z,1383677233.300 [MassServo] Loaded 2013-11-05T18:47:13.301Z,1383677233.301 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2013-11-05T18:47:13.312Z,1383677233.312 [RudderServo] Loaded 2013-11-05T18:47:13.313Z,1383677233.313 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2013-11-05T18:47:13.324Z,1383677233.324 [ThrusterServo] Loaded 2013-11-05T18:47:13.324Z,1383677233.324 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2013-11-05T18:47:13.324Z,1383677233.324 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2013-11-05T18:47:13.325Z,1383677233.325 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2013-11-05T18:47:13.370Z,1383677233.370 [DepthRateCalculator] Loaded 2013-11-05T18:47:13.370Z,1383677233.370 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2013-11-05T18:47:16.252Z,1383677236.252 [HFRadarModelCalc] Loaded 2013-11-05T18:47:16.253Z,1383677236.253 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread. 2013-11-05T18:47:16.269Z,1383677236.269 [NavChart] Loaded 2013-11-05T18:47:16.269Z,1383677236.269 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2013-11-05T18:47:16.275Z,1383677236.275 [PitchRateCalculator] Loaded 2013-11-05T18:47:16.275Z,1383677236.275 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2013-11-05T18:47:16.286Z,1383677236.286 [SpeedCalculator] Loaded 2013-11-05T18:47:16.286Z,1383677236.286 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2013-11-05T18:47:16.303Z,1383677236.303 [TempGradientCalculator] Loaded 2013-11-05T18:47:16.303Z,1383677236.303 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2013-11-05T18:47:16.308Z,1383677236.308 [YawRateCalculator] Loaded 2013-11-05T18:47:16.309Z,1383677236.309 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2013-11-05T18:47:16.355Z,1383677236.355 [Navigation] Loaded 2013-11-05T18:47:16.356Z,1383677236.356 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread. 2013-11-05T18:47:16.356Z,1383677236.356 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2013-11-05T18:47:16.357Z,1383677236.357 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2013-11-05T18:47:16.603Z,1383677236.603 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2013-11-05T18:47:16.604Z,1383677236.604 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2013-11-05T18:47:16.629Z,1383677236.629 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2013-11-05T18:47:16.629Z,1383677236.629 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2013-11-05T18:47:16.680Z,1383677236.680 [VerticalControl](DEBUG): Construct VerticalControl. 2013-11-05T18:47:16.775Z,1383677236.775 [VerticalControl] Loaded 2013-11-05T18:47:16.775Z,1383677236.775 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2013-11-05T18:47:16.776Z,1383677236.776 [HorizontalControl](DEBUG): Construct HorizontalControl. 2013-11-05T18:47:16.834Z,1383677236.834 [HorizontalControl] Loaded 2013-11-05T18:47:16.834Z,1383677236.834 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2013-11-05T18:47:16.835Z,1383677236.835 [SpeedControl](DEBUG): Construct SpeedControl. 2013-11-05T18:47:16.836Z,1383677236.836 [SpeedControl] Loaded 2013-11-05T18:47:16.837Z,1383677236.837 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2013-11-05T18:47:16.837Z,1383677236.837 [LoopControl](DEBUG): Construct LoopControl. 2013-11-05T18:47:16.838Z,1383677236.838 [LoopControl] Loaded 2013-11-05T18:47:16.838Z,1383677236.838 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2013-11-05T18:47:16.839Z,1383677236.839 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2013-11-05T18:47:16.839Z,1383677236.839 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2013-11-05T18:47:16.845Z,1383677236.845 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator. 2013-11-05T18:47:16.850Z,1383677236.850 [AsyncPiEstimator] Loaded 2013-11-05T18:47:16.850Z,1383677236.850 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread. 2013-11-05T18:47:16.851Z,1383677236.851 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 406474E0 2013-11-05T18:47:16.852Z,1383677236.852 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2013-11-05T18:47:16.853Z,1383677236.853 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2013-11-05T18:47:17.080Z,1383677237.080 [AHRS_sp3003D] Loaded 2013-11-05T18:47:17.080Z,1383677237.080 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread. 2013-11-05T18:47:17.150Z,1383677237.150 [DataOverHttps] Loaded 2013-11-05T18:47:17.151Z,1383677237.151 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2013-11-05T18:47:17.164Z,1383677237.164 [Depth_Keller] Loaded 2013-11-05T18:47:17.164Z,1383677237.164 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2013-11-05T18:47:17.349Z,1383677237.349 [DVL_micro] Loaded 2013-11-05T18:47:17.349Z,1383677237.349 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread. 2013-11-05T18:47:17.350Z,1383677237.350 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 4085C4E0 2013-11-05T18:47:17.440Z,1383677237.440 [NAL9602] Loaded 2013-11-05T18:47:17.440Z,1383677237.440 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2013-11-05T18:47:17.482Z,1383677237.482 [Onboard] Loaded 2013-11-05T18:47:17.482Z,1383677237.482 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2013-11-05T18:47:17.489Z,1383677237.489 [Radio_Freewave] Loaded 2013-11-05T18:47:17.489Z,1383677237.489 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread. 2013-11-05T18:47:17.636Z,1383677237.636 [DAT] Loaded 2013-11-05T18:47:17.637Z,1383677237.637 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2013-11-05T18:47:17.637Z,1383677237.637 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2013-11-05T18:47:17.638Z,1383677237.638 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2013-11-05T18:47:17.741Z,1383677237.741 [CTD_NeilBrown] Loaded 2013-11-05T18:47:17.741Z,1383677237.741 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2013-11-05T18:47:17.743Z,1383677237.743 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408B94E0 2013-11-05T18:47:17.775Z,1383677237.775 [WetLabsBB2FL] Loaded 2013-11-05T18:47:17.775Z,1383677237.775 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2013-11-05T18:47:17.777Z,1383677237.777 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408E94E0 2013-11-05T18:47:17.777Z,1383677237.777 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2013-11-05T18:47:17.780Z,1383677237.780 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2013-11-05T18:47:17.781Z,1383677237.781 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2013-11-05T18:47:17.788Z,1383677237.788 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2013-11-05T18:47:17.789Z,1383677237.789 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409194E0 2013-11-05T18:47:17.794Z,1383677237.794 [Supervisor](DEBUG): Running supervisor. 2013-11-05T18:47:17.795Z,1383677237.795 [CommandLine](INFO): Thread ID is 1584 2013-11-05T18:47:17.797Z,1383677237.797 [controlThread](INFO): Thread ID is 1583 2013-11-05T18:47:17.798Z,1383677237.798 [controlThread](DEBUG): Initializing ControlThread 2013-11-05T18:47:17.798Z,1383677237.798 [CycleStarter](INFO): Thread ID is 1582 2013-11-05T18:47:17.799Z,1383677237.799 [InternalSim](DEBUG): InternalSim initializing... 2013-11-05T18:47:17.834Z,1383677237.834 [logger](INFO): Thread ID is 1585 2013-11-05T18:47:17.851Z,1383677237.851 [SBIT](INFO): Initialize SBIT Component. 2013-11-05T18:47:17.851Z,1383677237.851 [SBIT](IMPORTANT): Tethys CM Info: $Rev:10713 2013-11-05T18:47:17.852Z,1383677237.852 [SBIT](IMPORTANT): Kernel Release:2.6.27.8 2013-11-05T18:47:17.852Z,1383677237.852 [SBIT](IMPORTANT): Kernel Version:#634 PREEMPT Wed Feb 13 10:21:48 PST 2013 2013-11-05T18:47:17.852Z,1383677237.852 [IBIT](INFO): Initialize IBIT Component. 2013-11-05T18:47:17.853Z,1383677237.853 [CBIT](DEBUG): Initialize CBIT Component. 2013-11-05T18:47:17.853Z,1383677237.853 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2013-11-05T18:47:17.863Z,1383677237.863 [AsyncPiEstimator](INFO): Thread ID is 1652 2013-11-05T18:47:17.863Z,1383677237.863 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator. 2013-11-05T18:47:17.884Z,1383677237.884 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2013-11-05T18:47:17.884Z,1383677237.884 [NavChart](DEBUG): Initialize NavChart Derivation. 2013-11-05T18:47:17.885Z,1383677237.885 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2013-11-05T18:47:17.885Z,1383677237.885 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2013-11-05T18:47:17.885Z,1383677237.885 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2013-11-05T18:47:17.886Z,1383677237.886 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2013-11-05T18:47:17.886Z,1383677237.886 [Navigation](DEBUG): Initializing Navigation. 2013-11-05T18:47:17.887Z,1383677237.887 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2013-11-05T18:47:17.888Z,1383677237.888 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2013-11-05T18:47:17.889Z,1383677237.889 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2013-11-05T18:47:17.889Z,1383677237.889 [LoopControl](DEBUG): Initialize LoopControlComponent. 2013-11-05T18:47:17.896Z,1383677237.896 [DVL_micro](INFO): Thread ID is 1653 2013-11-05T18:47:17.905Z,1383677237.905 [DVL_micro](INFO): Initializing 2013-11-05T18:47:17.905Z,1383677237.905 [DVL_micro](INFO): start:Powering up 2013-11-05T18:47:17.906Z,1383677237.906 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2013-11-05T18:47:17.907Z,1383677237.907 [DVL_micro](INFO): Cycling power to configure device. 2013-11-05T18:47:17.925Z,1383677237.925 [CTD_NeilBrown](INFO): Thread ID is 1654 2013-11-05T18:47:17.926Z,1383677237.926 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown. 2013-11-05T18:47:17.931Z,1383677237.931 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2013-11-05T18:47:17.947Z,1383677237.947 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4 2013-11-05T18:47:17.948Z,1383677237.948 [WetLabsBB2FL](INFO): Thread ID is 1655 2013-11-05T18:47:17.956Z,1383677237.956 [WetLabsBB2FL](INFO): Powering down 2013-11-05T18:47:17.981Z,1383677237.981 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2013-11-05T18:47:17.992Z,1383677237.992 [NavChartDb](INFO): Thread ID is 1656 2013-11-05T18:47:17.994Z,1383677237.994 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources 2013-11-05T18:47:17.996Z,1383677237.996 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2013-11-05T18:47:17.996Z,1383677237.996 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2013-11-05T18:47:17.996Z,1383677237.996 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2013-11-05T18:47:17.996Z,1383677237.996 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2013-11-05T18:47:17.997Z,1383677237.997 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2013-11-05T18:47:17.997Z,1383677237.997 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2013-11-05T18:47:17.997Z,1383677237.997 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2013-11-05T18:47:17.997Z,1383677237.997 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2013-11-05T18:47:18.021Z,1383677238.021 [MissionManager](DEBUG): 2013-11-05T18:47:18.022Z,1383677238.022 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2013-11-05T18:47:18.107Z,1383677238.107 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool 2013-11-05T18:47:18.121Z,1383677238.121 [Default:GPS:A.SetSpeed](DEBUG): Construct. 2013-11-05T18:47:18.124Z,1383677238.124 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface. 2013-11-05T18:47:18.156Z,1383677238.156 [Default:Iridium:A.SetSpeed](DEBUG): Construct. 2013-11-05T18:47:18.159Z,1383677238.159 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface. 2013-11-05T18:47:18.190Z,1383677238.190 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2013-11-05T18:47:18.206Z,1383677238.206 [Default:D.SetSpeed](DEBUG): Construct. 2013-11-05T18:47:18.209Z,1383677238.209 [Default:E.GoToSurface](DEBUG): Construct GoToSurface. 2013-11-05T18:47:18.214Z,1383677238.214 [Default:F.Wait](DEBUG): Construct Wait. 2013-11-05T18:47:18.230Z,1383677238.230 [MissionManager](DEBUG): 400 400 Burn 300 Dropped drop weight due to communications timeout 5.0 1.0 5 2013-11-05T18:47:18.234Z,1383677238.234 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,DataOverHttps,Depth_Keller,NAL9602,Onboard,Radio_Freewave,DAT,Depth_Keller,DepthRateCalculator,HFRadarModelCalc,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2013-11-05T18:47:18.300Z,1383677238.300 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D. 2013-11-05T18:47:18.314Z,1383677238.314 [DataOverHttps](INFO): run, dataState=0 2013-11-05T18:47:18.314Z,1383677238.314 [DataOverHttps](INFO): tcpConnect 2013-11-05T18:47:18.568Z,1383677238.568 [Radio_Freewave](INFO): Powering up 2013-11-05T18:47:18.586Z,1383677238.586 [DAT](INFO): Powering up 2013-11-05T18:47:18.586Z,1383677238.586 [DAT](DEBUG): Initializing DAT. 2013-11-05T18:47:18.805Z,1383677238.805 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2013-11-05T18:47:18.812Z,1383677238.812 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2013-11-05T18:47:18.822Z,1383677238.822 [ElevatorServo](DEBUG): Initializing EZServoServo. 2013-11-05T18:47:18.829Z,1383677238.829 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2013-11-05T18:47:18.848Z,1383677238.848 [MassServo](DEBUG): Initializing EZServoServo. 2013-11-05T18:47:18.856Z,1383677238.856 [MassServo](DEBUG): Initializing MassServo. 2013-11-05T18:47:18.874Z,1383677238.874 [RudderServo](DEBUG): Initializing EZServoServo. 2013-11-05T18:47:18.881Z,1383677238.881 [RudderServo](DEBUG): Initializing RudderServo. 2013-11-05T18:47:18.899Z,1383677238.899 [ThrusterServo](DEBUG): Initializing EZServoServo. 2013-11-05T18:47:18.904Z,1383677238.904 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2013-11-05T18:47:19.145Z,1383677239.145 [DataOverHttps](INFO): run, dataState=1 2013-11-05T18:47:19.146Z,1383677239.146 [DataOverHttps](INFO): tcpConnecting 2013-11-05T18:47:19.146Z,1383677239.146 [DataOverHttps](INFO): sslConnect 2013-11-05T18:47:19.150Z,1383677239.150 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:47:19.732Z,1383677239.732 [DataOverHttps](INFO): run, dataState=3 2013-11-05T18:47:19.732Z,1383677239.732 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:47:19.962Z,1383677239.962 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2013-11-05T18:47:19.963Z,1383677239.963 [DVL_micro](INFO): Querying output modes 2013-11-05T18:47:19.963Z,1383677239.963 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606 2013-11-05T18:47:19.974Z,1383677239.974 [DVL_micro](DEBUG): cmdResponse: 01 2013-11-05T18:47:19.974Z,1383677239.974 [DVL_micro](INFO): NQ1 output enabled 2013-11-05T18:47:19.975Z,1383677239.975 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525 2013-11-05T18:47:19.986Z,1383677239.986 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 2013-11-05T18:47:19.991Z,1383677239.991 [DVL_micro](INFO): pause:Powering down 2013-11-05T18:47:21.806Z,1383677241.806 [DataOverHttps](INFO): run, dataState=3 2013-11-05T18:47:21.807Z,1383677241.807 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:47:21.809Z,1383677241.809 [DataOverHttps](INFO): dataWrite 2013-11-05T18:47:22.248Z,1383677242.248 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:47:22.248Z,1383677242.248 [DataOverHttps](INFO): dataRead 2013-11-05T18:47:22.816Z,1383677242.816 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:47:22.816Z,1383677242.816 [DataOverHttps](INFO): dataRead 2013-11-05T18:47:22.824Z,1383677242.824 [NAL9602](INFO): Powering up NAL9602 2013-11-05T18:47:23.397Z,1383677243.397 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:47:23.397Z,1383677243.397 [DataOverHttps](INFO): dataRead 2013-11-05T18:47:23.397Z,1383677243.397 [DataOverHttps](INFO): disconnect 2013-11-05T18:47:23.850Z,1383677243.850 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:24.431Z,1383677244.431 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:25.408Z,1383677245.408 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:25.861Z,1383677245.861 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:26.309Z,1383677246.309 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:26.746Z,1383677246.746 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:27.219Z,1383677247.219 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:27.684Z,1383677247.684 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:28.140Z,1383677248.140 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:28.609Z,1383677248.609 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:29.094Z,1383677249.094 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:29.547Z,1383677249.547 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:29.983Z,1383677249.983 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:30.464Z,1383677250.464 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:30.908Z,1383677250.908 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:31.385Z,1383677251.385 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:31.802Z,1383677251.802 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:32.295Z,1383677252.295 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:32.719Z,1383677252.719 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:33.160Z,1383677253.160 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:33.633Z,1383677253.633 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:33.671Z,1383677253.671 [NAL9602](INFO): NAL9602 initialized 2013-11-05T18:47:33.757Z,1383677253.757 [SBIT](IMPORTANT): Beginning Startup BIT 2013-11-05T18:47:33.759Z,1383677253.759 [CBIT](IMPORTANT): Beginning GF scan 2013-11-05T18:47:34.121Z,1383677254.121 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:34.686Z,1383677254.686 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:35.341Z,1383677255.341 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:35.800Z,1383677255.800 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:36.341Z,1383677256.341 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:36.906Z,1383677256.906 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:37.459Z,1383677257.459 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:38.016Z,1383677258.016 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:38.636Z,1383677258.636 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:39.177Z,1383677259.177 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:39.210Z,1383677259.210 [DAT](INFO): Powering down 2013-11-05T18:47:39.922Z,1383677259.922 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:40.387Z,1383677260.387 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:40.880Z,1383677260.880 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:41.337Z,1383677261.337 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:41.805Z,1383677261.805 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:42.241Z,1383677262.241 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:42.640Z,1383677262.640 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:43.063Z,1383677263.063 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:43.468Z,1383677263.468 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:43.856Z,1383677263.856 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:44.301Z,1383677264.301 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:44.730Z,1383677264.730 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:45.231Z,1383677265.231 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:45.655Z,1383677265.655 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:46.056Z,1383677266.056 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:46.456Z,1383677266.456 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:46.881Z,1383677266.881 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:47.326Z,1383677267.326 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:47.750Z,1383677267.750 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:48.175Z,1383677268.175 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:48.592Z,1383677268.592 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:48.592Z,1383677268.592 [DataOverHttps](INFO): tcpConnect 2013-11-05T18:47:49.048Z,1383677269.048 [DataOverHttps](INFO): run, dataState=1 2013-11-05T18:47:49.049Z,1383677269.049 [DataOverHttps](INFO): tcpConnecting 2013-11-05T18:47:49.049Z,1383677269.049 [DataOverHttps](INFO): sslConnect 2013-11-05T18:47:49.050Z,1383677269.050 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:47:50.198Z,1383677270.198 [DataOverHttps](INFO): run, dataState=3 2013-11-05T18:47:50.198Z,1383677270.198 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:47:51.657Z,1383677271.657 [DataOverHttps](INFO): run, dataState=3 2013-11-05T18:47:51.657Z,1383677271.657 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:47:51.659Z,1383677271.659 [DataOverHttps](INFO): dataWrite 2013-11-05T18:47:52.149Z,1383677272.149 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:47:52.150Z,1383677272.150 [DataOverHttps](INFO): dataRead 2013-11-05T18:47:52.634Z,1383677272.634 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:47:52.634Z,1383677272.634 [DataOverHttps](INFO): dataRead 2013-11-05T18:47:53.127Z,1383677273.127 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:47:53.127Z,1383677273.127 [DataOverHttps](INFO): dataRead 2013-11-05T18:47:53.127Z,1383677273.127 [DataOverHttps](INFO): disconnect 2013-11-05T18:47:53.612Z,1383677273.612 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:54.112Z,1383677274.112 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:54.613Z,1383677274.613 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:55.274Z,1383677275.274 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:55.841Z,1383677275.841 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:56.300Z,1383677276.300 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:56.741Z,1383677276.741 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:57.297Z,1383677277.297 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:57.758Z,1383677277.758 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:58.201Z,1383677278.201 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:58.645Z,1383677278.645 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:59.044Z,1383677279.044 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:59.457Z,1383677279.457 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:47:59.885Z,1383677279.885 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:00.322Z,1383677280.322 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:00.417Z,1383677280.417 [CBIT](IMPORTANT): No ground fault detected 2013-11-05T18:48:00.751Z,1383677280.751 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:01.183Z,1383677281.183 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:01.664Z,1383677281.664 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:02.101Z,1383677282.101 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:02.497Z,1383677282.497 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:02.874Z,1383677282.874 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:03.295Z,1383677283.295 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:03.711Z,1383677283.711 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:04.112Z,1383677284.112 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:04.508Z,1383677284.508 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:04.913Z,1383677284.913 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:05.314Z,1383677285.314 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:05.710Z,1383677285.710 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:06.115Z,1383677286.115 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:06.520Z,1383677286.520 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:06.920Z,1383677286.920 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:07.321Z,1383677287.321 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:07.718Z,1383677287.718 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:08.110Z,1383677288.110 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:08.511Z,1383677288.511 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:08.911Z,1383677288.911 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:09.316Z,1383677289.316 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:09.729Z,1383677289.729 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:10.125Z,1383677290.125 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:10.522Z,1383677290.522 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:10.934Z,1383677290.934 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:11.311Z,1383677291.311 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:11.712Z,1383677291.712 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:12.112Z,1383677292.112 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:12.517Z,1383677292.517 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:12.918Z,1383677292.918 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:13.342Z,1383677293.342 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:13.727Z,1383677293.727 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:14.115Z,1383677294.115 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:14.512Z,1383677294.512 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:14.913Z,1383677294.913 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:15.317Z,1383677295.317 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:15.722Z,1383677295.722 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:16.275Z,1383677296.275 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:17.280Z,1383677297.280 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:17.742Z,1383677297.742 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:18.198Z,1383677298.198 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:18.674Z,1383677298.674 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:18.675Z,1383677298.675 [DataOverHttps](INFO): tcpConnect 2013-11-05T18:48:19.227Z,1383677299.227 [DataOverHttps](INFO): run, dataState=1 2013-11-05T18:48:19.228Z,1383677299.228 [DataOverHttps](INFO): tcpConnecting 2013-11-05T18:48:19.228Z,1383677299.228 [DataOverHttps](INFO): sslConnect 2013-11-05T18:48:19.229Z,1383677299.229 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:48:19.904Z,1383677299.904 [DataOverHttps](INFO): run, dataState=3 2013-11-05T18:48:19.905Z,1383677299.905 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:48:20.802Z,1383677300.802 [DataOverHttps](INFO): run, dataState=3 2013-11-05T18:48:20.802Z,1383677300.802 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:48:20.804Z,1383677300.804 [DataOverHttps](INFO): dataWrite 2013-11-05T18:48:21.210Z,1383677301.210 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:48:21.211Z,1383677301.211 [DataOverHttps](INFO): dataRead 2013-11-05T18:48:21.645Z,1383677301.645 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:48:21.645Z,1383677301.645 [DataOverHttps](INFO): dataRead 2013-11-05T18:48:22.076Z,1383677302.076 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:48:22.076Z,1383677302.076 [DataOverHttps](INFO): dataRead 2013-11-05T18:48:22.076Z,1383677302.076 [DataOverHttps](INFO): disconnect 2013-11-05T18:48:22.500Z,1383677302.500 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:22.917Z,1383677302.917 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:23.414Z,1383677303.414 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:23.822Z,1383677303.822 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:24.231Z,1383677304.231 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:24.632Z,1383677304.632 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:25.032Z,1383677305.032 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:25.425Z,1383677305.425 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:25.822Z,1383677305.822 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:26.218Z,1383677306.218 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:26.623Z,1383677306.623 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:27.019Z,1383677307.019 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:27.436Z,1383677307.436 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:27.837Z,1383677307.837 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:27.883Z,1383677307.883 [SBIT](IMPORTANT): SBIT PASSED 2013-11-05T18:48:28.233Z,1383677308.233 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:28.265Z,1383677308.265 [MissionManager](IMPORTANT): Started mission Startup 2013-11-05T18:48:28.265Z,1383677308.265 [Startup] Running Loop=1 2013-11-05T18:48:28.265Z,1383677308.265 [Startup](INFO): Aggregate::initialize Startup 2013-11-05T18:48:28.265Z,1383677308.265 [Startup:A.GoToSurface] Running Loop=1 2013-11-05T18:48:28.265Z,1383677308.265 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2013-11-05T18:48:28.271Z,1383677308.271 [Startup:StartupSatComms] Running Loop=1 2013-11-05T18:48:28.272Z,1383677308.272 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms 2013-11-05T18:48:28.272Z,1383677308.272 [Startup:StartupSatComms:A] Running Loop=1 2013-11-05T18:48:28.634Z,1383677308.634 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:28.665Z,1383677308.665 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2013-11-05T18:48:29.042Z,1383677309.042 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:29.435Z,1383677309.435 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:29.844Z,1383677309.844 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:30.805Z,1383677310.805 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:31.226Z,1383677311.226 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:31.644Z,1383677311.644 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:32.075Z,1383677312.075 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:32.539Z,1383677312.539 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:32.969Z,1383677312.969 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:33.405Z,1383677313.405 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:33.834Z,1383677313.834 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:34.283Z,1383677314.283 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:34.703Z,1383677314.703 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:35.108Z,1383677315.108 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:35.513Z,1383677315.513 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:35.944Z,1383677315.944 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:36.378Z,1383677316.378 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:36.811Z,1383677316.811 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:37.246Z,1383677317.246 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:37.652Z,1383677317.652 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:38.052Z,1383677318.052 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:38.457Z,1383677318.457 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:38.862Z,1383677318.862 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:39.294Z,1383677319.294 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:39.723Z,1383677319.723 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:40.144Z,1383677320.144 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:40.552Z,1383677320.552 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:40.957Z,1383677320.957 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:41.354Z,1383677321.354 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:41.762Z,1383677321.762 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:42.251Z,1383677322.251 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:42.652Z,1383677322.652 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:43.060Z,1383677323.060 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:43.449Z,1383677323.449 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:43.843Z,1383677323.843 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:44.250Z,1383677324.250 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:44.655Z,1383677324.655 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:45.051Z,1383677325.051 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:45.444Z,1383677325.444 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:45.849Z,1383677325.849 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:46.245Z,1383677326.245 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:46.646Z,1383677326.646 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:47.054Z,1383677327.054 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:47.459Z,1383677327.459 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:47.852Z,1383677327.852 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:48.252Z,1383677328.252 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:48.645Z,1383677328.645 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:49.046Z,1383677329.046 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:49.046Z,1383677329.046 [DataOverHttps](INFO): tcpConnect 2013-11-05T18:48:49.458Z,1383677329.458 [DataOverHttps](INFO): run, dataState=1 2013-11-05T18:48:49.458Z,1383677329.458 [DataOverHttps](INFO): tcpConnecting 2013-11-05T18:48:49.459Z,1383677329.459 [DataOverHttps](INFO): sslConnect 2013-11-05T18:48:49.459Z,1383677329.459 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:48:49.855Z,1383677329.855 [DataOverHttps](INFO): run, dataState=3 2013-11-05T18:48:49.855Z,1383677329.855 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:48:50.708Z,1383677330.708 [DataOverHttps](INFO): run, dataState=3 2013-11-05T18:48:50.708Z,1383677330.708 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:48:50.710Z,1383677330.710 [DataOverHttps](INFO): dataWrite 2013-11-05T18:48:51.113Z,1383677331.113 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:48:51.113Z,1383677331.113 [DataOverHttps](INFO): dataRead 2013-11-05T18:48:51.509Z,1383677331.509 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:48:51.510Z,1383677331.510 [DataOverHttps](INFO): dataRead 2013-11-05T18:48:51.922Z,1383677331.922 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:48:51.922Z,1383677331.922 [DataOverHttps](INFO): dataRead 2013-11-05T18:48:51.922Z,1383677331.922 [DataOverHttps](INFO): disconnect 2013-11-05T18:48:52.323Z,1383677332.323 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:52.731Z,1383677332.731 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:53.136Z,1383677333.136 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:53.529Z,1383677333.529 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:53.925Z,1383677333.925 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:54.326Z,1383677334.326 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:54.722Z,1383677334.722 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:55.135Z,1383677335.135 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:55.538Z,1383677335.538 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:55.932Z,1383677335.932 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:56.333Z,1383677336.333 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:56.733Z,1383677336.733 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:57.134Z,1383677337.134 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:57.527Z,1383677337.527 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:57.935Z,1383677337.935 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:58.336Z,1383677338.336 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:58.737Z,1383677338.737 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:59.137Z,1383677339.137 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:59.530Z,1383677339.530 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:48:59.930Z,1383677339.930 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:00.335Z,1383677340.335 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:00.728Z,1383677340.728 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:01.128Z,1383677341.128 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:01.525Z,1383677341.525 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:01.922Z,1383677341.922 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:02.322Z,1383677342.322 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:02.735Z,1383677342.735 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:03.123Z,1383677343.123 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:03.528Z,1383677343.528 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:03.933Z,1383677343.933 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:04.333Z,1383677344.333 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:04.734Z,1383677344.734 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:05.135Z,1383677345.135 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:05.547Z,1383677345.547 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:05.928Z,1383677345.928 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:06.336Z,1383677346.336 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:06.737Z,1383677346.737 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:07.144Z,1383677347.144 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:07.546Z,1383677347.546 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:07.923Z,1383677347.923 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:08.323Z,1383677348.323 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:08.724Z,1383677348.724 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:09.125Z,1383677349.125 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:09.529Z,1383677349.529 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:09.942Z,1383677349.942 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:10.327Z,1383677350.327 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:10.727Z,1383677350.727 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:11.136Z,1383677351.136 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:11.535Z,1383677351.535 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:11.929Z,1383677351.929 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:12.334Z,1383677352.334 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:12.746Z,1383677352.746 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:13.127Z,1383677353.127 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:13.538Z,1383677353.538 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:13.929Z,1383677353.929 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:14.329Z,1383677354.329 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:14.737Z,1383677354.737 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:15.141Z,1383677355.141 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:15.543Z,1383677355.543 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:15.923Z,1383677355.923 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:16.328Z,1383677356.328 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:16.728Z,1383677356.728 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:17.129Z,1383677357.129 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:17.540Z,1383677357.540 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:17.942Z,1383677357.942 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:18.336Z,1383677358.336 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:18.728Z,1383677358.728 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:19.128Z,1383677359.128 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:19.128Z,1383677359.128 [DataOverHttps](INFO): tcpConnect 2013-11-05T18:49:19.537Z,1383677359.537 [DataOverHttps](INFO): run, dataState=1 2013-11-05T18:49:19.537Z,1383677359.537 [DataOverHttps](INFO): tcpConnecting 2013-11-05T18:49:19.538Z,1383677359.538 [DataOverHttps](INFO): sslConnect 2013-11-05T18:49:19.539Z,1383677359.539 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:49:19.930Z,1383677359.930 [DataOverHttps](INFO): run, dataState=3 2013-11-05T18:49:19.930Z,1383677359.930 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:49:20.799Z,1383677360.799 [DataOverHttps](INFO): run, dataState=3 2013-11-05T18:49:20.799Z,1383677360.799 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:49:20.801Z,1383677360.801 [DataOverHttps](INFO): dataWrite 2013-11-05T18:49:21.187Z,1383677361.187 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:49:21.188Z,1383677361.188 [DataOverHttps](INFO): dataRead 2013-11-05T18:49:21.592Z,1383677361.592 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:49:21.592Z,1383677361.592 [DataOverHttps](INFO): dataRead 2013-11-05T18:49:22.017Z,1383677362.017 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:49:22.017Z,1383677362.017 [DataOverHttps](INFO): dataRead 2013-11-05T18:49:22.017Z,1383677362.017 [DataOverHttps](INFO): disconnect 2013-11-05T18:49:22.417Z,1383677362.417 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:22.814Z,1383677362.814 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:23.227Z,1383677363.227 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:23.627Z,1383677363.627 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:24.032Z,1383677364.032 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:24.424Z,1383677364.424 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:24.817Z,1383677364.817 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:25.218Z,1383677365.218 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:25.626Z,1383677365.626 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:26.019Z,1383677366.019 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:26.423Z,1383677366.423 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:26.816Z,1383677366.816 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:27.217Z,1383677367.217 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:27.621Z,1383677367.621 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:28.044Z,1383677368.044 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:28.423Z,1383677368.423 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:28.459Z,1383677368.459 [Startup:StartupSatComms:A](INFO): Timed out from 2013-11-05T18:48:28.3Z 2013-11-05T18:49:28.459Z,1383677368.459 [Startup:StartupSatComms:A:A_Timeout] Running Loop=1 2013-11-05T18:49:28.460Z,1383677368.460 [Startup:StartupSatComms:A:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:A:A_Timeout 2013-11-05T18:49:28.460Z,1383677368.460 [Startup:StartupSatComms:A:A_Timeout](INFO): Completed Startup:StartupSatComms:A:A_Timeout 2013-11-05T18:49:28.460Z,1383677368.460 [Startup:StartupSatComms:A] Stopped 2013-11-05T18:49:28.460Z,1383677368.460 [Startup:StartupSatComms:B] Running Loop=1 2013-11-05T18:49:28.827Z,1383677368.827 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:28.860Z,1383677368.860 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2013-11-05T18:49:29.656Z,1383677369.656 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:30.043Z,1383677370.043 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:30.454Z,1383677370.454 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:30.858Z,1383677370.858 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:31.251Z,1383677371.251 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:31.641Z,1383677371.641 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:32.056Z,1383677372.056 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:32.442Z,1383677372.442 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:32.853Z,1383677372.853 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:33.254Z,1383677373.254 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:33.641Z,1383677373.641 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:34.051Z,1383677374.051 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:34.460Z,1383677374.460 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:34.857Z,1383677374.857 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:35.249Z,1383677375.249 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:35.658Z,1383677375.658 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:36.050Z,1383677376.050 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:36.936Z,1383677376.936 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:37.593Z,1383677377.593 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:37.993Z,1383677377.993 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:38.414Z,1383677378.414 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:38.887Z,1383677378.887 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:39.336Z,1383677379.336 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:39.736Z,1383677379.736 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:40.169Z,1383677380.169 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:40.543Z,1383677380.543 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:40.942Z,1383677380.942 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:41.335Z,1383677381.335 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:41.745Z,1383677381.745 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:42.144Z,1383677382.144 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:42.542Z,1383677382.542 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:42.943Z,1383677382.943 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:43.350Z,1383677383.350 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:43.734Z,1383677383.734 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:44.144Z,1383677384.144 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:44.538Z,1383677384.538 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:45.437Z,1383677385.437 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:45.830Z,1383677385.830 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:46.258Z,1383677386.258 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:46.651Z,1383677386.651 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:46.663Z,1383677386.663 [NAL9602](IMPORTANT): GPS fix at: 1383677409.00 2013-11-05T18:49:47.041Z,1383677387.041 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:47.448Z,1383677387.448 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:47.837Z,1383677387.837 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:48.233Z,1383677388.233 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:48.644Z,1383677388.644 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:49.045Z,1383677389.045 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:49.944Z,1383677389.944 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:49.944Z,1383677389.944 [DataOverHttps](INFO): tcpConnect 2013-11-05T18:49:50.342Z,1383677390.342 [DataOverHttps](INFO): run, dataState=1 2013-11-05T18:49:50.343Z,1383677390.343 [DataOverHttps](INFO): tcpConnecting 2013-11-05T18:49:50.343Z,1383677390.343 [DataOverHttps](INFO): sslConnect 2013-11-05T18:49:50.344Z,1383677390.344 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:49:50.729Z,1383677390.729 [DataOverHttps](INFO): run, dataState=3 2013-11-05T18:49:50.730Z,1383677390.730 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:49:51.594Z,1383677391.594 [DataOverHttps](INFO): run, dataState=3 2013-11-05T18:49:51.595Z,1383677391.595 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:49:51.597Z,1383677391.597 [DataOverHttps](INFO): dataWrite 2013-11-05T18:49:52.015Z,1383677392.015 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:49:52.016Z,1383677392.016 [DataOverHttps](INFO): dataRead 2013-11-05T18:49:52.416Z,1383677392.416 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:49:52.416Z,1383677392.416 [DataOverHttps](INFO): dataRead 2013-11-05T18:49:52.816Z,1383677392.816 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:49:52.817Z,1383677392.817 [DataOverHttps](INFO): dataRead 2013-11-05T18:49:52.817Z,1383677392.817 [DataOverHttps](INFO): disconnect 2013-11-05T18:49:53.229Z,1383677393.229 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:54.122Z,1383677394.122 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:54.515Z,1383677394.515 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:54.912Z,1383677394.912 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:55.304Z,1383677395.304 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:55.717Z,1383677395.717 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:56.114Z,1383677396.114 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:56.514Z,1383677396.514 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:56.911Z,1383677396.911 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:57.311Z,1383677397.311 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:58.217Z,1383677398.217 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:49:58.658Z,1383677398.658 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:01.101Z,1383677401.101 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:01.478Z,1383677401.478 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:01.898Z,1383677401.898 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:02.315Z,1383677402.315 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:02.716Z,1383677402.716 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:03.116Z,1383677403.116 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:03.505Z,1383677403.505 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:03.918Z,1383677403.918 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:04.310Z,1383677404.310 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:04.711Z,1383677404.711 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:05.112Z,1383677405.112 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:05.520Z,1383677405.520 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:05.929Z,1383677405.929 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:06.313Z,1383677406.313 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:06.722Z,1383677406.722 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:07.135Z,1383677407.135 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:07.527Z,1383677407.527 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:07.932Z,1383677407.932 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:08.324Z,1383677408.324 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:08.729Z,1383677408.729 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:09.122Z,1383677409.122 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:09.537Z,1383677409.537 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:09.931Z,1383677409.931 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:10.336Z,1383677410.336 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:10.736Z,1383677410.736 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:11.137Z,1383677411.137 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:11.529Z,1383677411.529 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:11.934Z,1383677411.934 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:12.327Z,1383677412.327 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:12.727Z,1383677412.727 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:13.136Z,1383677413.136 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:13.535Z,1383677413.535 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:13.929Z,1383677413.929 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:13.938Z,1383677413.938 [NAL9602](INFO): SBD MO Status=2, MOMSN=20625, MT Status=2, MTMSN=0 2013-11-05T18:50:13.938Z,1383677413.938 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2013-11-05T18:50:14.334Z,1383677414.334 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:14.726Z,1383677414.726 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:15.123Z,1383677415.123 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:15.538Z,1383677415.538 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:15.928Z,1383677415.928 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:16.329Z,1383677416.329 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:16.743Z,1383677416.743 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:17.146Z,1383677417.146 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:17.527Z,1383677417.527 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:17.935Z,1383677417.935 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:18.328Z,1383677418.328 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:19.225Z,1383677419.225 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:19.690Z,1383677419.690 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:22.134Z,1383677422.134 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:22.134Z,1383677422.134 [DataOverHttps](INFO): tcpConnect 2013-11-05T18:50:22.515Z,1383677422.515 [DataOverHttps](INFO): run, dataState=1 2013-11-05T18:50:22.515Z,1383677422.515 [DataOverHttps](INFO): tcpConnecting 2013-11-05T18:50:22.515Z,1383677422.515 [DataOverHttps](INFO): sslConnect 2013-11-05T18:50:22.516Z,1383677422.516 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:50:22.919Z,1383677422.919 [DataOverHttps](INFO): run, dataState=3 2013-11-05T18:50:22.919Z,1383677422.919 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:50:23.764Z,1383677423.764 [DataOverHttps](INFO): run, dataState=3 2013-11-05T18:50:23.765Z,1383677423.765 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:50:23.766Z,1383677423.766 [DataOverHttps](INFO): dataWrite 2013-11-05T18:50:24.153Z,1383677424.153 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:50:24.153Z,1383677424.153 [DataOverHttps](INFO): dataRead 2013-11-05T18:50:24.554Z,1383677424.554 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:50:24.554Z,1383677424.554 [DataOverHttps](INFO): dataRead 2013-11-05T18:50:24.958Z,1383677424.958 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:50:24.958Z,1383677424.958 [DataOverHttps](INFO): dataRead 2013-11-05T18:50:24.959Z,1383677424.959 [DataOverHttps](INFO): disconnect 2013-11-05T18:50:25.347Z,1383677425.347 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:25.759Z,1383677425.759 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:26.160Z,1383677426.160 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:26.557Z,1383677426.557 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:26.957Z,1383677426.957 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:27.358Z,1383677427.358 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:27.747Z,1383677427.747 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:28.147Z,1383677428.147 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:28.556Z,1383677428.556 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:28.572Z,1383677428.572 [Startup:StartupSatComms:B](INFO): Timed out from 2013-11-05T18:49:28.5Z 2013-11-05T18:50:28.572Z,1383677428.572 [Startup:StartupSatComms:B:A_Timeout] Running Loop=1 2013-11-05T18:50:28.572Z,1383677428.572 [Startup:StartupSatComms:B:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:B:A_Timeout 2013-11-05T18:50:28.573Z,1383677428.573 [Startup:StartupSatComms:B:A_Timeout](INFO): Completed Startup:StartupSatComms:B:A_Timeout 2013-11-05T18:50:28.573Z,1383677428.573 [Startup:StartupSatComms:B] Stopped 2013-11-05T18:50:28.573Z,1383677428.573 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2013-11-05T18:50:28.573Z,1383677428.573 [Startup:StartupSatComms] Stopped 2013-11-05T18:50:28.573Z,1383677428.573 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms 2013-11-05T18:50:28.574Z,1383677428.574 [Startup](INFO): Completed Startup 2013-11-05T18:50:28.574Z,1383677428.574 [Startup] Stopped 2013-11-05T18:50:28.574Z,1383677428.574 [Startup](INFO): Aggregate::uninitialize Startup 2013-11-05T18:50:28.574Z,1383677428.574 [Startup:A.GoToSurface] Stopped 2013-11-05T18:50:28.574Z,1383677428.574 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2013-11-05T18:50:28.957Z,1383677428.957 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:28.977Z,1383677428.977 [MissionManager](IMPORTANT): Started mission Default 2013-11-05T18:50:28.978Z,1383677428.978 [Default] Running Loop=1 2013-11-05T18:50:28.978Z,1383677428.978 [Default](INFO): Aggregate::initialize Default 2013-11-05T18:50:28.978Z,1383677428.978 [Default:D.SetSpeed] Running Loop=1 2013-11-05T18:50:28.978Z,1383677428.978 [Default:D.SetSpeed](DEBUG): Initialize. 2013-11-05T18:50:28.978Z,1383677428.978 [Default:E.GoToSurface] Running Loop=1 2013-11-05T18:50:28.978Z,1383677428.978 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2013-11-05T18:50:28.978Z,1383677428.978 [Default:Iridium] Running Loop=1 2013-11-05T18:50:28.978Z,1383677428.978 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2013-11-05T18:50:28.978Z,1383677428.978 [Default:Iridium:A.SetSpeed] Running Loop=1 2013-11-05T18:50:28.979Z,1383677428.979 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2013-11-05T18:50:28.979Z,1383677428.979 [Default:Iridium:B.GoToSurface] Running Loop=1 2013-11-05T18:50:28.979Z,1383677428.979 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2013-11-05T18:50:28.979Z,1383677428.979 [Default:E.GoToSurface] Running Loop=1 2013-11-05T18:50:28.985Z,1383677428.985 [Default:D.SetSpeed] Running Loop=1 2013-11-05T18:50:28.990Z,1383677428.990 [Default:CallIridium] Running Loop=1 2013-11-05T18:50:28.990Z,1383677428.990 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium 2013-11-05T18:50:28.990Z,1383677428.990 [Default:CallIridium:A] Running Loop=1 2013-11-05T18:50:28.992Z,1383677428.992 [Default:CallIridium:A] Stopped 2013-11-05T18:50:28.992Z,1383677428.992 [Default:CallIridium:B] Running Loop=1 2013-11-05T18:50:28.992Z,1383677428.992 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B 2013-11-05T18:50:28.998Z,1383677428.998 [Default:Iridium:B.GoToSurface] Stopped 2013-11-05T18:50:28.998Z,1383677428.998 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2013-11-05T18:50:28.998Z,1383677428.998 [Default:Iridium:Read_Iridium] Running Loop=1 2013-11-05T18:50:28.998Z,1383677428.998 [Default:Iridium:A.SetSpeed] Running Loop=1 2013-11-05T18:50:29.003Z,1383677429.003 [Default:GPS] Running Loop=1 2013-11-05T18:50:29.004Z,1383677429.004 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2013-11-05T18:50:29.004Z,1383677429.004 [Default:GPS:A.SetSpeed] Running Loop=1 2013-11-05T18:50:29.004Z,1383677429.004 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2013-11-05T18:50:29.004Z,1383677429.004 [Default:GPS:B.GoToSurface] Running Loop=1 2013-11-05T18:50:29.004Z,1383677429.004 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2013-11-05T18:50:29.010Z,1383677429.010 [Default:GPS:B.GoToSurface] Stopped 2013-11-05T18:50:29.010Z,1383677429.010 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2013-11-05T18:50:29.010Z,1383677429.010 [Default:GPS:Read_GPS] Running Loop=1 2013-11-05T18:50:29.010Z,1383677429.010 [Default:GPS:A.SetSpeed] Running Loop=1 2013-11-05T18:50:29.393Z,1383677429.393 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:29.411Z,1383677429.411 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2013-11-05T18:50:29.414Z,1383677429.414 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2013-11-05T18:50:30.062Z,1383677430.062 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:30.463Z,1383677430.463 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:30.863Z,1383677430.863 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:30.871Z,1383677430.871 [NAL9602](INFO): SBD MO Status=2, MOMSN=20625, MT Status=2, MTMSN=0 2013-11-05T18:50:30.871Z,1383677430.871 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2013-11-05T18:50:31.300Z,1383677431.300 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:31.701Z,1383677431.701 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:32.113Z,1383677432.113 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:32.124Z,1383677432.124 [NAL9602](IMPORTANT): GPS fix at: 1383677454.00 2013-11-05T18:50:32.175Z,1383677432.175 [Default:GPS:Read_GPS] Stopped 2013-11-05T18:50:32.175Z,1383677432.175 [Default:GPS:D] Running Loop=1 2013-11-05T18:50:32.526Z,1383677432.526 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:32.576Z,1383677432.576 [Default:GPS:D] Stopped 2013-11-05T18:50:32.577Z,1383677432.577 [Default:GPS](INFO): Completed Default:GPS 2013-11-05T18:50:32.577Z,1383677432.577 [Default:GPS] Stopped 2013-11-05T18:50:32.577Z,1383677432.577 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2013-11-05T18:50:32.577Z,1383677432.577 [Default:GPS:A.SetSpeed] Stopped 2013-11-05T18:50:32.577Z,1383677432.577 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2013-11-05T18:50:32.955Z,1383677432.955 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:33.336Z,1383677433.336 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:33.744Z,1383677433.744 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:34.189Z,1383677434.189 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:34.613Z,1383677434.613 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:35.026Z,1383677435.026 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:35.924Z,1383677435.924 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:36.336Z,1383677436.336 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:36.736Z,1383677436.736 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:37.121Z,1383677437.121 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:37.544Z,1383677437.544 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:38.435Z,1383677438.435 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:38.832Z,1383677438.832 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:39.256Z,1383677439.256 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:39.645Z,1383677439.645 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:40.042Z,1383677440.042 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:40.430Z,1383677440.430 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:40.851Z,1383677440.851 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:41.251Z,1383677441.251 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:41.680Z,1383677441.680 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:42.618Z,1383677442.618 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:43.047Z,1383677443.047 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:43.455Z,1383677443.455 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:43.900Z,1383677443.900 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:44.300Z,1383677444.300 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:44.729Z,1383677444.729 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:45.166Z,1383677445.166 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:46.051Z,1383677446.051 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:46.448Z,1383677446.448 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:46.864Z,1383677446.864 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:47.237Z,1383677447.237 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:47.665Z,1383677447.665 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:48.062Z,1383677448.062 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:48.451Z,1383677448.451 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:48.847Z,1383677448.847 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:49.256Z,1383677449.256 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:49.657Z,1383677449.657 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:50.043Z,1383677450.043 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:50.450Z,1383677450.450 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:50.846Z,1383677450.846 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:51.243Z,1383677451.243 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:51.660Z,1383677451.660 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:52.052Z,1383677452.052 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:52.457Z,1383677452.457 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:52.457Z,1383677452.457 [DataOverHttps](INFO): tcpConnect 2013-11-05T18:50:52.849Z,1383677452.849 [DataOverHttps](INFO): run, dataState=1 2013-11-05T18:50:52.850Z,1383677452.850 [DataOverHttps](INFO): tcpConnecting 2013-11-05T18:50:52.850Z,1383677452.850 [DataOverHttps](INFO): sslConnect 2013-11-05T18:50:52.851Z,1383677452.851 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:50:53.246Z,1383677453.246 [DataOverHttps](INFO): run, dataState=3 2013-11-05T18:50:53.246Z,1383677453.246 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:50:54.620Z,1383677454.620 [DataOverHttps](INFO): run, dataState=3 2013-11-05T18:50:54.620Z,1383677454.620 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:50:54.622Z,1383677454.622 [DataOverHttps](INFO): dataWrite 2013-11-05T18:50:55.033Z,1383677455.033 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:50:55.033Z,1383677455.033 [DataOverHttps](INFO): dataRead 2013-11-05T18:50:55.454Z,1383677455.454 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:50:55.454Z,1383677455.454 [DataOverHttps](INFO): dataRead 2013-11-05T18:50:55.834Z,1383677455.834 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:50:55.834Z,1383677455.834 [DataOverHttps](INFO): dataRead 2013-11-05T18:50:55.834Z,1383677455.834 [DataOverHttps](INFO): disconnect 2013-11-05T18:50:56.235Z,1383677456.235 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:56.652Z,1383677456.652 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:57.152Z,1383677457.152 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:57.529Z,1383677457.529 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:57.929Z,1383677457.929 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:58.835Z,1383677458.835 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:59.242Z,1383677459.242 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:50:59.624Z,1383677459.624 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:00.037Z,1383677460.037 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:00.425Z,1383677460.425 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:00.826Z,1383677460.826 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:01.246Z,1383677461.246 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:01.635Z,1383677461.635 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:02.036Z,1383677462.036 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:02.424Z,1383677462.424 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:03.322Z,1383677463.322 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:03.730Z,1383677463.730 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:04.147Z,1383677464.147 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:04.528Z,1383677464.528 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:04.920Z,1383677464.920 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:05.329Z,1383677465.329 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:05.722Z,1383677465.722 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:06.122Z,1383677466.122 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:06.545Z,1383677466.545 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:07.436Z,1383677467.436 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:07.833Z,1383677467.833 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:08.237Z,1383677468.237 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:08.646Z,1383677468.646 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:09.044Z,1383677469.044 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:09.447Z,1383677469.447 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:09.842Z,1383677469.842 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:10.243Z,1383677470.243 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:10.649Z,1383677470.649 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:11.044Z,1383677471.044 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:11.943Z,1383677471.943 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:12.332Z,1383677472.332 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:12.744Z,1383677472.744 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:13.145Z,1383677473.145 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:13.542Z,1383677473.542 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:13.942Z,1383677473.942 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:14.359Z,1383677474.359 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:14.731Z,1383677474.731 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:15.642Z,1383677475.642 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:16.030Z,1383677476.030 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:16.446Z,1383677476.446 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:16.835Z,1383677476.835 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:17.231Z,1383677477.231 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:18.149Z,1383677478.149 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:18.529Z,1383677478.529 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:18.946Z,1383677478.946 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:19.359Z,1383677479.359 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:19.759Z,1383677479.759 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:20.148Z,1383677480.148 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:20.572Z,1383677480.572 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:20.943Z,1383677480.943 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:21.354Z,1383677481.354 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:22.243Z,1383677482.243 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:22.642Z,1383677482.642 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:22.642Z,1383677482.642 [DataOverHttps](INFO): tcpConnect 2013-11-05T18:51:23.028Z,1383677483.028 [DataOverHttps](INFO): run, dataState=1 2013-11-05T18:51:23.028Z,1383677483.028 [DataOverHttps](INFO): tcpConnecting 2013-11-05T18:51:23.029Z,1383677483.029 [DataOverHttps](INFO): sslConnect 2013-11-05T18:51:23.030Z,1383677483.030 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:51:23.437Z,1383677483.437 [DataOverHttps](INFO): run, dataState=3 2013-11-05T18:51:23.438Z,1383677483.438 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:51:24.318Z,1383677484.318 [DataOverHttps](INFO): run, dataState=3 2013-11-05T18:51:24.319Z,1383677484.319 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:51:24.321Z,1383677484.321 [DataOverHttps](INFO): dataWrite 2013-11-05T18:51:25.220Z,1383677485.220 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:51:25.220Z,1383677485.220 [DataOverHttps](INFO): dataRead 2013-11-05T18:51:25.616Z,1383677485.616 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:51:25.617Z,1383677485.617 [DataOverHttps](INFO): dataRead 2013-11-05T18:51:26.021Z,1383677486.021 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:51:26.021Z,1383677486.021 [DataOverHttps](INFO): dataRead 2013-11-05T18:51:26.021Z,1383677486.021 [DataOverHttps](INFO): disconnect 2013-11-05T18:51:26.426Z,1383677486.426 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:26.826Z,1383677486.826 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:27.215Z,1383677487.215 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:27.615Z,1383677487.615 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:28.020Z,1383677488.020 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:28.429Z,1383677488.429 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:29.362Z,1383677489.362 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:29.751Z,1383677489.751 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:30.145Z,1383677490.145 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:30.542Z,1383677490.542 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:30.943Z,1383677490.943 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:31.850Z,1383677491.850 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:32.243Z,1383677492.243 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:32.645Z,1383677492.645 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:33.044Z,1383677493.044 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:33.437Z,1383677493.437 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:33.843Z,1383677493.843 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:34.234Z,1383677494.234 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:34.644Z,1383677494.644 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:35.045Z,1383677495.045 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:35.942Z,1383677495.942 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:36.329Z,1383677496.329 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:36.743Z,1383677496.743 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:37.134Z,1383677497.134 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:37.543Z,1383677497.543 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:37.931Z,1383677497.931 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:38.341Z,1383677498.341 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:38.729Z,1383677498.729 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:39.129Z,1383677499.129 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:39.544Z,1383677499.544 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:39.930Z,1383677499.930 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:40.323Z,1383677500.323 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:40.736Z,1383677500.736 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:41.136Z,1383677501.136 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:41.535Z,1383677501.535 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:41.934Z,1383677501.934 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:42.344Z,1383677502.344 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:42.747Z,1383677502.747 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:43.135Z,1383677503.135 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:43.535Z,1383677503.535 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:43.929Z,1383677503.929 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:44.343Z,1383677504.343 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:44.740Z,1383677504.740 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:45.143Z,1383677505.143 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:46.028Z,1383677506.028 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:46.437Z,1383677506.437 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:46.829Z,1383677506.829 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:47.226Z,1383677507.226 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:47.630Z,1383677507.630 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:48.031Z,1383677508.031 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:48.448Z,1383677508.448 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:48.836Z,1383677508.836 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:49.213Z,1383677509.213 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:49.634Z,1383677509.634 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:50.515Z,1383677510.515 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:50.928Z,1383677510.928 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:51.320Z,1383677511.320 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:51.725Z,1383677511.725 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:52.137Z,1383677512.137 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:52.550Z,1383677512.550 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:52.931Z,1383677512.931 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:52.931Z,1383677512.931 [DataOverHttps](INFO): tcpConnect 2013-11-05T18:51:53.340Z,1383677513.340 [DataOverHttps](INFO): run, dataState=1 2013-11-05T18:51:53.341Z,1383677513.341 [DataOverHttps](INFO): tcpConnecting 2013-11-05T18:51:53.341Z,1383677513.341 [DataOverHttps](INFO): sslConnect 2013-11-05T18:51:53.342Z,1383677513.342 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:51:53.736Z,1383677513.736 [DataOverHttps](INFO): run, dataState=3 2013-11-05T18:51:53.736Z,1383677513.736 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:51:54.613Z,1383677514.613 [DataOverHttps](INFO): run, dataState=3 2013-11-05T18:51:54.614Z,1383677514.614 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:51:54.616Z,1383677514.616 [DataOverHttps](INFO): dataWrite 2013-11-05T18:51:55.026Z,1383677515.026 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:51:55.026Z,1383677515.026 [DataOverHttps](INFO): dataRead 2013-11-05T18:51:55.422Z,1383677515.422 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:51:55.423Z,1383677515.423 [DataOverHttps](INFO): dataRead 2013-11-05T18:51:55.823Z,1383677515.823 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:51:55.823Z,1383677515.823 [DataOverHttps](INFO): dataRead 2013-11-05T18:51:55.823Z,1383677515.823 [DataOverHttps](INFO): disconnect 2013-11-05T18:51:56.242Z,1383677516.242 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:56.648Z,1383677516.648 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:57.043Z,1383677517.043 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:57.442Z,1383677517.442 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:57.842Z,1383677517.842 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:58.246Z,1383677518.246 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:58.635Z,1383677518.635 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:59.537Z,1383677519.537 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:51:59.930Z,1383677519.930 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:00.342Z,1383677520.342 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:00.747Z,1383677520.747 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:01.147Z,1383677521.147 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:01.528Z,1383677521.528 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:01.937Z,1383677521.937 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:02.329Z,1383677522.329 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:03.227Z,1383677523.227 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:03.619Z,1383677523.619 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:04.028Z,1383677524.028 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:04.433Z,1383677524.433 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:04.809Z,1383677524.809 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:05.226Z,1383677525.226 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:05.674Z,1383677525.674 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:06.042Z,1383677526.042 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:06.448Z,1383677526.448 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:06.872Z,1383677526.872 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:07.243Z,1383677527.243 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:07.642Z,1383677527.642 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:08.050Z,1383677528.050 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:08.451Z,1383677528.451 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:08.855Z,1383677528.855 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:09.252Z,1383677529.252 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:09.642Z,1383677529.642 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:10.042Z,1383677530.042 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:10.450Z,1383677530.450 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:10.850Z,1383677530.850 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:11.255Z,1383677531.255 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:12.164Z,1383677532.164 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:12.549Z,1383677532.549 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:12.954Z,1383677532.954 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:13.358Z,1383677533.358 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:13.742Z,1383677533.742 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:14.152Z,1383677534.152 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:14.560Z,1383677534.560 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:14.957Z,1383677534.957 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:15.353Z,1383677535.353 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:16.251Z,1383677536.251 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:16.644Z,1383677536.644 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:17.056Z,1383677537.056 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:17.433Z,1383677537.433 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:17.833Z,1383677537.833 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:18.245Z,1383677538.245 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:18.646Z,1383677538.646 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:19.045Z,1383677539.045 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:19.444Z,1383677539.444 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:19.828Z,1383677539.828 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:20.734Z,1383677540.734 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:21.150Z,1383677541.150 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:21.555Z,1383677541.555 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:21.932Z,1383677541.932 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:22.328Z,1383677542.328 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:22.742Z,1383677542.742 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:23.130Z,1383677543.130 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:23.130Z,1383677543.130 [DataOverHttps](INFO): tcpConnect 2013-11-05T18:52:23.543Z,1383677543.543 [DataOverHttps](INFO): run, dataState=1 2013-11-05T18:52:23.543Z,1383677543.543 [DataOverHttps](INFO): tcpConnecting 2013-11-05T18:52:23.543Z,1383677543.543 [DataOverHttps](INFO): sslConnect 2013-11-05T18:52:23.544Z,1383677543.544 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:52:23.955Z,1383677543.955 [DataOverHttps](INFO): run, dataState=3 2013-11-05T18:52:23.955Z,1383677543.955 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:52:25.329Z,1383677545.329 [DataOverHttps](INFO): run, dataState=3 2013-11-05T18:52:25.329Z,1383677545.329 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:52:25.331Z,1383677545.331 [DataOverHttps](INFO): dataWrite 2013-11-05T18:52:25.745Z,1383677545.745 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:52:25.746Z,1383677545.746 [DataOverHttps](INFO): dataRead 2013-11-05T18:52:26.146Z,1383677546.146 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:52:26.146Z,1383677546.146 [DataOverHttps](INFO): dataRead 2013-11-05T18:52:26.537Z,1383677546.537 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:52:26.538Z,1383677546.538 [DataOverHttps](INFO): dataRead 2013-11-05T18:52:26.538Z,1383677546.538 [DataOverHttps](INFO): disconnect 2013-11-05T18:52:26.935Z,1383677546.935 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:27.352Z,1383677547.352 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:27.742Z,1383677547.742 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:28.654Z,1383677548.654 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:29.042Z,1383677549.042 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:29.447Z,1383677549.447 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:29.842Z,1383677549.842 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:30.229Z,1383677550.229 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:30.634Z,1383677550.634 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:31.044Z,1383677551.044 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:31.441Z,1383677551.441 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:31.845Z,1383677551.845 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:32.760Z,1383677552.760 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:33.145Z,1383677553.145 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:33.542Z,1383677553.542 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:33.966Z,1383677553.966 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:34.359Z,1383677554.359 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:34.751Z,1383677554.751 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:35.176Z,1383677555.176 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:35.553Z,1383677555.553 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:35.953Z,1383677555.953 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:36.398Z,1383677556.398 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:36.795Z,1383677556.795 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:37.202Z,1383677557.202 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:37.624Z,1383677557.624 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:38.517Z,1383677558.517 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:39.002Z,1383677559.002 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:41.444Z,1383677561.444 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:41.811Z,1383677561.811 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:42.215Z,1383677562.215 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:42.620Z,1383677562.620 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:43.012Z,1383677563.012 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:43.413Z,1383677563.413 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:43.814Z,1383677563.814 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:44.222Z,1383677564.222 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:44.623Z,1383677564.623 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:45.045Z,1383677565.045 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:45.424Z,1383677565.424 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:45.825Z,1383677565.825 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:46.225Z,1383677566.225 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:46.626Z,1383677566.626 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:47.026Z,1383677567.026 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:47.435Z,1383677567.435 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:47.828Z,1383677567.828 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:48.220Z,1383677568.220 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:48.642Z,1383677568.642 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:49.102Z,1383677569.102 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:49.498Z,1383677569.498 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:49.907Z,1383677569.907 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:50.292Z,1383677570.292 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:50.704Z,1383677570.704 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:51.109Z,1383677571.109 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:51.514Z,1383677571.514 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:51.930Z,1383677571.930 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:51.948Z,1383677571.948 [NAL9602](INFO): SBD MO Status=2, MOMSN=20625, MT Status=2, MTMSN=0 2013-11-05T18:52:51.949Z,1383677571.949 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2013-11-05T18:52:52.363Z,1383677572.363 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:52.728Z,1383677572.728 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:53.141Z,1383677573.141 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:53.142Z,1383677573.142 [DataOverHttps](INFO): tcpConnect 2013-11-05T18:52:53.561Z,1383677573.561 [DataOverHttps](INFO): run, dataState=1 2013-11-05T18:52:53.561Z,1383677573.561 [DataOverHttps](INFO): tcpConnecting 2013-11-05T18:52:53.561Z,1383677573.561 [DataOverHttps](INFO): sslConnect 2013-11-05T18:52:53.562Z,1383677573.562 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:52:53.929Z,1383677573.929 [DataOverHttps](INFO): run, dataState=3 2013-11-05T18:52:53.929Z,1383677573.929 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:52:54.795Z,1383677574.795 [DataOverHttps](INFO): run, dataState=3 2013-11-05T18:52:54.795Z,1383677574.795 [DataOverHttps](INFO): sslConnecting 2013-11-05T18:52:54.797Z,1383677574.797 [DataOverHttps](INFO): dataWrite 2013-11-05T18:52:55.195Z,1383677575.195 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:52:55.195Z,1383677575.195 [DataOverHttps](INFO): dataRead 2013-11-05T18:52:55.604Z,1383677575.604 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:52:55.604Z,1383677575.604 [DataOverHttps](INFO): dataRead 2013-11-05T18:52:56.016Z,1383677576.016 [DataOverHttps](INFO): run, dataState=5 2013-11-05T18:52:56.017Z,1383677576.017 [DataOverHttps](INFO): dataRead 2013-11-05T18:52:56.017Z,1383677576.017 [DataOverHttps](INFO): disconnect 2013-11-05T18:52:56.922Z,1383677576.922 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:57.399Z,1383677577.399 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:52:59.858Z,1383677579.858 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:00.227Z,1383677580.227 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:00.628Z,1383677580.628 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:01.042Z,1383677581.042 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:01.429Z,1383677581.429 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:01.829Z,1383677581.829 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:02.234Z,1383677582.234 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:02.627Z,1383677582.627 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:03.027Z,1383677583.027 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:03.436Z,1383677583.436 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:03.829Z,1383677583.829 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:04.229Z,1383677584.229 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:04.650Z,1383677584.650 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:05.147Z,1383677585.147 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:05.537Z,1383677585.537 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:05.920Z,1383677585.920 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:06.329Z,1383677586.329 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:06.717Z,1383677586.717 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:07.126Z,1383677587.126 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:07.523Z,1383677587.523 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:07.931Z,1383677587.931 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:08.345Z,1383677588.345 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:08.725Z,1383677588.725 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:09.129Z,1383677589.129 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:09.537Z,1383677589.537 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:09.930Z,1383677589.930 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:10.335Z,1383677590.335 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:10.747Z,1383677590.747 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:10.894Z,1383677590.894 [CommandLine](IMPORTANT): got command quit 2013-11-05T18:53:11.129Z,1383677591.129 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:11.520Z,1383677591.520 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:11.930Z,1383677591.930 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:11.964Z,1383677591.964 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2013-11-05T18:53:11.964Z,1383677591.964 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2013-11-05T18:53:12.181Z,1383677592.181 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2013-11-05T18:53:12.326Z,1383677592.326 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:12.489Z,1383677592.489 [WetLabsBB2FL](INFO): Powering down 2013-11-05T18:53:12.504Z,1383677592.504 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2013-11-05T18:53:12.729Z,1383677592.729 [CTD_NeilBrown](INFO): Powering down 2013-11-05T18:53:12.734Z,1383677592.734 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:12.745Z,1383677592.745 [ComponentRegistry](INFO): Shutting down DVL_micro ThreadHandler 2013-11-05T18:53:13.014Z,1383677593.014 [DVL_micro](INFO): uninitialize:Powering down 2013-11-05T18:53:13.015Z,1383677593.015 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler 2013-11-05T18:53:13.140Z,1383677593.140 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:13.198Z,1383677593.198 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator. 2013-11-05T18:53:13.214Z,1383677593.214 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2013-11-05T18:53:13.254Z,1383677593.254 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2013-11-05T18:53:13.256Z,1383677593.256 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2013-11-05T18:53:13.520Z,1383677593.520 [DataOverHttps](INFO): run, dataState=7 2013-11-05T18:53:13.553Z,1383677593.553 [controlThread](DEBUG): Uninitializing ControlThread 2013-11-05T18:53:13.553Z,1383677593.553 [AHRS_sp3003D](INFO): Powering down 2013-11-05T18:53:13.639Z,1383677593.639 [DataOverHttps](INFO): disconnect 2013-11-05T18:53:13.639Z,1383677593.639 [NAL9602](INFO): Powering down 2013-11-05T18:53:13.641Z,1383677593.641 [DAT](INFO): Powering down 2013-11-05T18:53:13.642Z,1383677593.642 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2013-11-05T18:53:13.645Z,1383677593.645 [NavChart](DEBUG): Uninitialize NavChart Derivation. 2013-11-05T18:53:13.646Z,1383677593.646 [Default] Stopped 2013-11-05T18:53:13.646Z,1383677593.646 [Default](INFO): Aggregate::uninitialize Default 2013-11-05T18:53:13.646Z,1383677593.646 [Default:Iridium] Stopped 2013-11-05T18:53:13.646Z,1383677593.646 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2013-11-05T18:53:13.646Z,1383677593.646 [Default:Iridium:A.SetSpeed] Stopped 2013-11-05T18:53:13.646Z,1383677593.646 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2013-11-05T18:53:13.647Z,1383677593.647 [Default:Iridium:Read_Iridium] Stopped 2013-11-05T18:53:13.647Z,1383677593.647 [Default:CallIridium] Stopped 2013-11-05T18:53:13.647Z,1383677593.647 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium 2013-11-05T18:53:13.647Z,1383677593.647 [Default:CallIridium:B] Stopped 2013-11-05T18:53:13.647Z,1383677593.647 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B 2013-11-05T18:53:13.647Z,1383677593.647 [Default:D.SetSpeed] Stopped 2013-11-05T18:53:13.647Z,1383677593.647 [Default:D.SetSpeed](DEBUG): Uninitialize. 2013-11-05T18:53:13.647Z,1383677593.647 [Default:E.GoToSurface] Stopped 2013-11-05T18:53:13.647Z,1383677593.647 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2013-11-05T18:53:13.651Z,1383677593.651 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2013-11-05T18:53:13.652Z,1383677593.652 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2013-11-05T18:53:13.652Z,1383677593.652 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2013-11-05T18:53:13.652Z,1383677593.652 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2013-11-05T18:53:13.653Z,1383677593.653 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2013-11-05T18:53:13.653Z,1383677593.653 [BuoyancyServo](INFO): Powering down 2013-11-05T18:53:13.666Z,1383677593.666 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2013-11-05T18:53:13.667Z,1383677593.667 [ElevatorServo](INFO): Powering down 2013-11-05T18:53:13.667Z,1383677593.667 [MassServo](DEBUG): Uninitialize Mass Servo. 2013-11-05T18:53:13.667Z,1383677593.667 [MassServo](INFO): Powering down 2013-11-05T18:53:13.668Z,1383677593.668 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2013-11-05T18:53:13.668Z,1383677593.668 [RudderServo](INFO): Powering down 2013-11-05T18:53:13.669Z,1383677593.669 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2013-11-05T18:53:13.669Z,1383677593.669 [ThrusterServo](INFO): Powering down 2013-11-05T18:53:13.670Z,1383677593.670 [SBIT](DEBUG): Uninitialize SBIT Component. 2013-11-05T18:53:13.671Z,1383677593.671 [IBIT](DEBUG): Uninitialize IBIT Component. 2013-11-05T18:53:13.671Z,1383677593.671 [CBIT](DEBUG): Uninitialize CBIT Component.