2013-11-08T23:07:32.141Z,1383952052.141 [Supervisor](DEBUG): Initializing supervisor.
2013-11-08T23:07:32.143Z,1383952052.143 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2013-11-08T23:07:32.144Z,1383952052.144 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2013-11-08T23:07:32.145Z,1383952052.145 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0
2013-11-08T23:07:32.148Z,1383952052.148 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2013-11-08T23:07:32.159Z,1383952052.159 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2013-11-08T23:07:32.160Z,1383952052.160 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0
2013-11-08T23:07:32.161Z,1383952052.161 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2013-11-08T23:07:32.162Z,1383952052.162 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403CB4E0
2013-11-08T23:07:32.164Z,1383952052.164 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2013-11-08T23:07:32.165Z,1383952052.165 [Supervisor](INFO): Looking for Config files in directory: Config/
2013-11-08T23:07:32.166Z,1383952052.166 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2013-11-08T23:07:32.442Z,1383952052.442 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2013-11-08T23:07:32.443Z,1383952052.443 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2013-11-08T23:07:32.641Z,1383952052.641 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2013-11-08T23:07:32.642Z,1383952052.642 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2013-11-08T23:07:32.727Z,1383952052.727 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2013-11-08T23:07:32.728Z,1383952052.728 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2013-11-08T23:07:32.844Z,1383952052.844 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2013-11-08T23:07:32.844Z,1383952052.844 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2013-11-08T23:07:32.983Z,1383952052.983 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2013-11-08T23:07:32.984Z,1383952052.984 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2013-11-08T23:07:33.250Z,1383952053.250 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2013-11-08T23:07:33.250Z,1383952053.250 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2013-11-08T23:07:33.418Z,1383952053.418 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2013-11-08T23:07:33.419Z,1383952053.419 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2013-11-08T23:07:33.681Z,1383952053.681 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2013-11-08T23:07:33.682Z,1383952053.682 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2013-11-08T23:07:33.782Z,1383952053.782 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2013-11-08T23:07:33.783Z,1383952053.783 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2013-11-08T23:07:34.211Z,1383952054.211 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2013-11-08T23:07:34.212Z,1383952054.212 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2013-11-08T23:07:34.323Z,1383952054.323 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2013-11-08T23:07:34.323Z,1383952054.323 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2013-11-08T23:07:34.409Z,1383952054.409 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2013-11-08T23:07:34.509Z,1383952054.509 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2013-11-08T23:07:34.511Z,1383952054.511 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2013-11-08T23:07:34.512Z,1383952054.512 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2013-11-08T23:07:34.608Z,1383952054.608 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2013-11-08T23:07:34.736Z,1383952054.736 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2013-11-08T23:07:34.835Z,1383952054.835 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2013-11-08T23:07:34.940Z,1383952054.940 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2013-11-08T23:07:35.035Z,1383952055.035 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2013-11-08T23:07:35.150Z,1383952055.150 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2013-11-08T23:07:35.244Z,1383952055.244 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2013-11-08T23:07:35.330Z,1383952055.330 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2013-11-08T23:07:35.424Z,1383952055.424 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/LOGIN/
2013-11-08T23:07:35.424Z,1383952055.424 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2013-11-08T23:07:35.426Z,1383952055.426 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2013-11-08T23:07:35.591Z,1383952055.591 [InternalSim] Loaded
2013-11-08T23:07:35.592Z,1383952055.592 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2013-11-08T23:07:35.592Z,1383952055.592 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2013-11-08T23:07:35.593Z,1383952055.593 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2013-11-08T23:07:35.680Z,1383952055.680 [SBIT](DEBUG): Construct Startup Built In Test.
2013-11-08T23:07:35.710Z,1383952055.710 [SBIT] Loaded
2013-11-08T23:07:35.711Z,1383952055.711 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2013-11-08T23:07:35.711Z,1383952055.711 [IBIT](DEBUG): Construct Initiated Built In Test.
2013-11-08T23:07:35.741Z,1383952055.741 [IBIT] Loaded
2013-11-08T23:07:35.741Z,1383952055.741 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2013-11-08T23:07:35.743Z,1383952055.743 [CBIT](DEBUG): Construct CBIT Built In Test.
2013-11-08T23:07:35.859Z,1383952055.859 [CBIT] Loaded
2013-11-08T23:07:35.859Z,1383952055.859 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2013-11-08T23:07:35.860Z,1383952055.860 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2013-11-08T23:07:35.860Z,1383952055.860 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2013-11-08T23:07:36.027Z,1383952056.027 [BuoyancyServo] Loaded
2013-11-08T23:07:36.028Z,1383952056.028 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2013-11-08T23:07:36.039Z,1383952056.039 [ElevatorServo] Loaded
2013-11-08T23:07:36.040Z,1383952056.040 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2013-11-08T23:07:36.051Z,1383952056.051 [MassServo] Loaded
2013-11-08T23:07:36.051Z,1383952056.051 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2013-11-08T23:07:36.063Z,1383952056.063 [RudderServo] Loaded
2013-11-08T23:07:36.064Z,1383952056.064 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2013-11-08T23:07:36.075Z,1383952056.075 [ThrusterServo] Loaded
2013-11-08T23:07:36.075Z,1383952056.075 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2013-11-08T23:07:36.076Z,1383952056.076 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2013-11-08T23:07:36.076Z,1383952056.076 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2013-11-08T23:07:36.111Z,1383952056.111 [DepthRateCalculator] Loaded
2013-11-08T23:07:36.111Z,1383952056.111 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2013-11-08T23:07:38.717Z,1383952058.717 [HFRadarModelCalc] Loaded
2013-11-08T23:07:38.717Z,1383952058.717 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2013-11-08T23:07:38.733Z,1383952058.733 [NavChart] Loaded
2013-11-08T23:07:38.734Z,1383952058.734 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2013-11-08T23:07:38.739Z,1383952058.739 [PitchRateCalculator] Loaded
2013-11-08T23:07:38.740Z,1383952058.740 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2013-11-08T23:07:38.750Z,1383952058.750 [SpeedCalculator] Loaded
2013-11-08T23:07:38.751Z,1383952058.751 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2013-11-08T23:07:38.767Z,1383952058.767 [TempGradientCalculator] Loaded
2013-11-08T23:07:38.768Z,1383952058.768 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2013-11-08T23:07:38.773Z,1383952058.773 [YawRateCalculator] Loaded
2013-11-08T23:07:38.773Z,1383952058.773 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2013-11-08T23:07:38.820Z,1383952058.820 [Navigation] Loaded
2013-11-08T23:07:38.821Z,1383952058.821 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread.
2013-11-08T23:07:38.821Z,1383952058.821 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2013-11-08T23:07:38.822Z,1383952058.822 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2013-11-08T23:07:39.060Z,1383952059.060 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2013-11-08T23:07:39.060Z,1383952059.060 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2013-11-08T23:07:39.084Z,1383952059.084 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2013-11-08T23:07:39.085Z,1383952059.085 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2013-11-08T23:07:39.133Z,1383952059.133 [VerticalControl](DEBUG): Construct VerticalControl.
2013-11-08T23:07:39.229Z,1383952059.229 [VerticalControl] Loaded
2013-11-08T23:07:39.229Z,1383952059.229 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2013-11-08T23:07:39.230Z,1383952059.230 [HorizontalControl](DEBUG): Construct HorizontalControl.
2013-11-08T23:07:39.288Z,1383952059.288 [HorizontalControl] Loaded
2013-11-08T23:07:39.288Z,1383952059.288 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2013-11-08T23:07:39.289Z,1383952059.289 [SpeedControl](DEBUG): Construct SpeedControl.
2013-11-08T23:07:39.291Z,1383952059.291 [SpeedControl] Loaded
2013-11-08T23:07:39.291Z,1383952059.291 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2013-11-08T23:07:39.292Z,1383952059.292 [LoopControl](DEBUG): Construct LoopControl.
2013-11-08T23:07:39.292Z,1383952059.292 [LoopControl] Loaded
2013-11-08T23:07:39.293Z,1383952059.293 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2013-11-08T23:07:39.293Z,1383952059.293 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2013-11-08T23:07:39.294Z,1383952059.294 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2013-11-08T23:07:39.299Z,1383952059.299 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2013-11-08T23:07:39.304Z,1383952059.304 [AsyncPiEstimator] Loaded
2013-11-08T23:07:39.304Z,1383952059.304 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2013-11-08T23:07:39.306Z,1383952059.306 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 406474E0
2013-11-08T23:07:39.306Z,1383952059.306 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2013-11-08T23:07:39.307Z,1383952059.307 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2013-11-08T23:07:39.523Z,1383952059.523 [AHRS_sp3003D] Loaded
2013-11-08T23:07:39.524Z,1383952059.524 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2013-11-08T23:07:39.599Z,1383952059.599 [DataOverHttps] Loaded
2013-11-08T23:07:39.599Z,1383952059.599 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2013-11-08T23:07:39.613Z,1383952059.613 [Depth_Keller] Loaded
2013-11-08T23:07:39.613Z,1383952059.613 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2013-11-08T23:07:39.801Z,1383952059.801 [DVL_micro] Loaded
2013-11-08T23:07:39.802Z,1383952059.802 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread.
2013-11-08T23:07:39.803Z,1383952059.803 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 408554E0
2013-11-08T23:07:39.893Z,1383952059.893 [NAL9602] Loaded
2013-11-08T23:07:39.894Z,1383952059.894 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2013-11-08T23:07:39.936Z,1383952059.936 [Onboard] Loaded
2013-11-08T23:07:39.936Z,1383952059.936 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2013-11-08T23:07:39.942Z,1383952059.942 [Radio_Freewave] Loaded
2013-11-08T23:07:39.943Z,1383952059.943 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread.
2013-11-08T23:07:40.091Z,1383952060.091 [DAT] Loaded
2013-11-08T23:07:40.091Z,1383952060.091 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2013-11-08T23:07:40.091Z,1383952060.091 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2013-11-08T23:07:40.092Z,1383952060.092 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2013-11-08T23:07:40.192Z,1383952060.192 [CTD_NeilBrown] Loaded
2013-11-08T23:07:40.192Z,1383952060.192 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2013-11-08T23:07:40.193Z,1383952060.193 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408B24E0
2013-11-08T23:07:40.226Z,1383952060.226 [WetLabsBB2FL] Loaded
2013-11-08T23:07:40.226Z,1383952060.226 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2013-11-08T23:07:40.227Z,1383952060.227 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408E24E0
2013-11-08T23:07:40.228Z,1383952060.228 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2013-11-08T23:07:40.231Z,1383952060.231 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2013-11-08T23:07:40.231Z,1383952060.231 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2013-11-08T23:07:40.239Z,1383952060.239 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2013-11-08T23:07:40.240Z,1383952060.240 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409124E0
2013-11-08T23:07:40.244Z,1383952060.244 [Supervisor](DEBUG): Running supervisor.
2013-11-08T23:07:40.245Z,1383952060.245 [CommandLine](INFO): Thread ID is 5196
2013-11-08T23:07:40.248Z,1383952060.248 [controlThread](INFO): Thread ID is 5195
2013-11-08T23:07:40.248Z,1383952060.248 [controlThread](DEBUG): Initializing ControlThread
2013-11-08T23:07:40.249Z,1383952060.249 [CycleStarter](INFO): Thread ID is 5194
2013-11-08T23:07:40.249Z,1383952060.249 [InternalSim](DEBUG):  InternalSim initializing...
2013-11-08T23:07:40.284Z,1383952060.284 [logger](INFO): Thread ID is 5197
2013-11-08T23:07:40.305Z,1383952060.305 [SBIT](INFO): Initialize SBIT Component.
2013-11-08T23:07:40.305Z,1383952060.305 [SBIT](IMPORTANT): Tethys CM Info: 
 $Rev:10713
2013-11-08T23:07:40.306Z,1383952060.306 [SBIT](IMPORTANT): Kernel Release:2.6.27.8
2013-11-08T23:07:40.306Z,1383952060.306 [SBIT](IMPORTANT): Kernel Version:#634 PREEMPT Wed Feb 13 10:21:48 PST 2013
2013-11-08T23:07:40.306Z,1383952060.306 [IBIT](INFO): Initialize IBIT Component.
2013-11-08T23:07:40.307Z,1383952060.307 [CBIT](DEBUG): Initialize CBIT Component.
2013-11-08T23:07:40.307Z,1383952060.307 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2013-11-08T23:07:40.309Z,1383952060.309 [AsyncPiEstimator](INFO): Thread ID is 5264
2013-11-08T23:07:40.310Z,1383952060.310 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2013-11-08T23:07:40.334Z,1383952060.334 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2013-11-08T23:07:40.335Z,1383952060.335 [NavChart](DEBUG): Initialize NavChart Derivation.
2013-11-08T23:07:40.335Z,1383952060.335 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2013-11-08T23:07:40.335Z,1383952060.335 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2013-11-08T23:07:40.336Z,1383952060.336 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2013-11-08T23:07:40.336Z,1383952060.336 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2013-11-08T23:07:40.336Z,1383952060.336 [Navigation](DEBUG): Initializing Navigation.
2013-11-08T23:07:40.337Z,1383952060.337 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2013-11-08T23:07:40.338Z,1383952060.338 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2013-11-08T23:07:40.339Z,1383952060.339 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2013-11-08T23:07:40.340Z,1383952060.340 [LoopControl](DEBUG): Initialize LoopControlComponent.
2013-11-08T23:07:40.346Z,1383952060.346 [DVL_micro](INFO): Thread ID is 5265
2013-11-08T23:07:40.354Z,1383952060.354 [DVL_micro](INFO): Initializing
2013-11-08T23:07:40.354Z,1383952060.354 [DVL_micro](INFO): start:Powering up
2013-11-08T23:07:40.355Z,1383952060.355 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-11-08T23:07:40.356Z,1383952060.356 [DVL_micro](INFO): Cycling power to configure device.
2013-11-08T23:07:40.369Z,1383952060.369 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2013-11-08T23:07:40.378Z,1383952060.378 [CTD_NeilBrown](INFO): Thread ID is 5266
2013-11-08T23:07:40.378Z,1383952060.378 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2013-11-08T23:07:40.394Z,1383952060.394 [WetLabsBB2FL](INFO): Thread ID is 5267
2013-11-08T23:07:40.394Z,1383952060.394 [WetLabsBB2FL](INFO): Powering down
2013-11-08T23:07:40.413Z,1383952060.413 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2013-11-08T23:07:40.426Z,1383952060.426 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2013-11-08T23:07:40.431Z,1383952060.431 [NavChartDb](INFO): Thread ID is 5268
2013-11-08T23:07:40.434Z,1383952060.434 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2013-11-08T23:07:40.434Z,1383952060.434 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2013-11-08T23:07:40.435Z,1383952060.435 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2013-11-08T23:07:40.435Z,1383952060.435 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2013-11-08T23:07:40.435Z,1383952060.435 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2013-11-08T23:07:40.435Z,1383952060.435 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2013-11-08T23:07:40.436Z,1383952060.436 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2013-11-08T23:07:40.436Z,1383952060.436 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2013-11-08T23:07:40.436Z,1383952060.436 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2013-11-08T23:07:40.474Z,1383952060.474 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface RunIn="Progression"/>

    <Aggregate Id="StartupSatComms" >

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

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

    </Aggregate>

</Mission>


2013-11-08T23:07:40.475Z,1383952060.475 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2013-11-08T23:07:40.549Z,1383952060.549 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2013-11-08T23:07:40.551Z,1383952060.551 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2013-11-08T23:07:40.566Z,1383952060.566 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-11-08T23:07:40.574Z,1383952060.574 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2013-11-08T23:07:40.606Z,1383952060.606 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-11-08T23:07:40.619Z,1383952060.619 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2013-11-08T23:07:40.636Z,1383952060.636 [Default:D.SetSpeed](DEBUG): Construct.
2013-11-08T23:07:40.652Z,1383952060.652 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2013-11-08T23:07:40.657Z,1383952060.657 [Default:F.Wait](DEBUG): Construct Wait.
2013-11-08T23:07:40.660Z,1383952060.660 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
xmlns:Control="Tethys/Control"
xmlns:Guidance="Tethys/Guidance" 
xmlns:Units="Tethys/Units"
xmlns:Universal="Tethys/Universal"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

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

    <Aggregate Id="GPS" >

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

<!-- Run at high speed -->

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

<!-- Get to the surface -->

        <Guidance:GoToSurface RunIn="Sequence"/>

<!-- Get GPS fix -->

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

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

    </Aggregate>

    <Aggregate Id="Iridium" >

<!-- Run at high speed -->

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

<!-- Get to the surface -->

        <Guidance:GoToSurface RunIn="Sequence"/>

<!-- Do data comms -->

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

    </Aggregate>

    <Aggregate Id="CallIridium" >

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

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

        <Call RefId="Iridium"/>

    </Aggregate>

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

<!-- Run at low speed -->

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

    <Guidance:GoToSurface RunIn="Parallel"/>

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

</Mission>


2013-11-08T23:07:40.677Z,1383952060.677 [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-08T23:07:40.739Z,1383952060.739 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-11-08T23:07:40.756Z,1383952060.756 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:07:40.918Z,1383952060.918 [Radio_Freewave](INFO): Powering up
2013-11-08T23:07:40.926Z,1383952060.926 [DAT](INFO): Powering up
2013-11-08T23:07:40.927Z,1383952060.927 [DAT](DEBUG): Initializing DAT.
2013-11-08T23:07:41.151Z,1383952061.151 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-11-08T23:07:41.158Z,1383952061.158 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-11-08T23:07:41.165Z,1383952061.165 [ElevatorServo](DEBUG): Initializing EZServoServo.
2013-11-08T23:07:41.170Z,1383952061.170 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2013-11-08T23:07:41.176Z,1383952061.176 [MassServo](DEBUG): Initializing EZServoServo.
2013-11-08T23:07:41.182Z,1383952061.182 [MassServo](DEBUG): Initializing MassServo.
2013-11-08T23:07:41.204Z,1383952061.204 [RudderServo](DEBUG): Initializing EZServoServo.
2013-11-08T23:07:41.210Z,1383952061.210 [RudderServo](DEBUG): Initializing RudderServo.
2013-11-08T23:07:41.227Z,1383952061.227 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-11-08T23:07:41.234Z,1383952061.234 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-11-08T23:07:41.451Z,1383952061.451 [AHRS_sp3003D](ERROR): readHeadingMagBin got 0x42360D0AA4
2013-11-08T23:07:41.451Z,1383952061.451 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-11-08T23:07:41.451Z,1383952061.451 [AHRS_sp3003D] Hardware Fault, FailCount= 1
2013-11-08T23:07:41.451Z,1383952061.451 [AHRS_sp3003D](ERROR): Hardware Fault
2013-11-08T23:07:41.452Z,1383952061.452 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:07:41.452Z,1383952061.452 [DataOverHttps](INFO): sslConnect
2013-11-08T23:07:41.456Z,1383952061.456 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:41.546Z,1383952061.546 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-11-08T23:07:41.731Z,1383952061.731 [AHRS_sp3003D](INFO): Powering down
2013-11-08T23:07:41.820Z,1383952061.820 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:42.476Z,1383952062.476 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-11-08T23:07:42.477Z,1383952062.477 [DVL_micro](INFO): Querying output modes
2013-11-08T23:07:42.477Z,1383952062.477 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-11-08T23:07:42.488Z,1383952062.488 [DVL_micro](DEBUG): cmdResponse: 01
2013-11-08T23:07:42.488Z,1383952062.488 [DVL_micro](INFO): NQ1 output enabled
2013-11-08T23:07:42.489Z,1383952062.489 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-11-08T23:07:42.500Z,1383952062.500 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2013-11-08T23:07:42.506Z,1383952062.506 [DVL_micro](INFO): pause:Powering down
2013-11-08T23:07:42.508Z,1383952062.508 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-11-08T23:07:42.508Z,1383952062.508 [DVL_micro](INFO): resume:Powering up
2013-11-08T23:07:42.508Z,1383952062.508 [DVL_micro](INFO): Cycling power to configure device.
2013-11-08T23:07:43.579Z,1383952063.579 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:43.581Z,1383952063.581 [DataOverHttps](INFO): dataWrite
2013-11-08T23:07:43.582Z,1383952063.582 [DataOverHttps](INFO): dataWriting
2013-11-08T23:07:43.583Z,1383952063.583 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:07:43.934Z,1383952063.934 [DataOverHttps](INFO): dataRead
2013-11-08T23:07:44.002Z,1383952064.002 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-11-08T23:07:44.003Z,1383952064.003 [AHRS_sp3003D] No Fault, FailCount= 1
2013-11-08T23:07:44.334Z,1383952064.334 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-11-08T23:07:44.336Z,1383952064.336 [DataOverHttps](INFO): dataRead
2013-11-08T23:07:44.337Z,1383952064.337 [DataOverHttps](INFO): disconnect
2013-11-08T23:07:44.341Z,1383952064.341 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003906
2013-11-08T23:07:44.512Z,1383952064.512 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-11-08T23:07:44.512Z,1383952064.512 [DVL_micro](INFO): Querying output modes
2013-11-08T23:07:44.512Z,1383952064.512 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-11-08T23:07:45.016Z,1383952065.016 [DVL_micro](DEBUG): cmdResponse: 01
2013-11-08T23:07:45.016Z,1383952065.016 [DVL_micro](INFO): NQ1 output enabled
2013-11-08T23:07:45.016Z,1383952065.016 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-11-08T23:07:45.028Z,1383952065.028 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2013-11-08T23:07:45.031Z,1383952065.031 [DVL_micro](INFO): pause:Powering down
2013-11-08T23:07:45.226Z,1383952065.226 [NAL9602](INFO): Powering up NAL9602
2013-11-08T23:07:55.869Z,1383952075.869 [NAL9602](INFO): NAL9602 initialized
2013-11-08T23:07:56.043Z,1383952076.043 [SBIT](IMPORTANT): Beginning Startup BIT
2013-11-08T23:07:56.046Z,1383952076.046 [CBIT](IMPORTANT): Beginning GF scan
2013-11-08T23:08:01.380Z,1383952081.380 [DAT](INFO): Powering down
2013-11-08T23:08:11.014Z,1383952091.014 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:08:11.454Z,1383952091.454 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:08:11.455Z,1383952091.455 [DataOverHttps](INFO): sslConnect
2013-11-08T23:08:11.456Z,1383952091.456 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:08:12.616Z,1383952092.616 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:08:14.103Z,1383952094.103 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:08:14.105Z,1383952094.105 [DataOverHttps](INFO): dataWrite
2013-11-08T23:08:14.105Z,1383952094.105 [DataOverHttps](INFO): dataWriting
2013-11-08T23:08:14.106Z,1383952094.106 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:08:14.116Z,1383952094.116 [NAL9602](IMPORTANT): GPS fix at: 1383952116.00
2013-11-08T23:08:14.636Z,1383952094.636 [DataOverHttps](INFO): dataRead
2013-11-08T23:08:15.148Z,1383952095.148 [DataOverHttps](INFO): dataRead
2013-11-08T23:08:15.149Z,1383952095.149 [DataOverHttps](INFO): disconnect
2013-11-08T23:08:21.476Z,1383952101.476 [NAL9602](INFO): Powering down
2013-11-08T23:08:23.268Z,1383952103.268 [CBIT](IMPORTANT): No ground fault detected
2013-11-08T23:08:41.076Z,1383952121.076 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:08:41.629Z,1383952121.629 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:08:41.630Z,1383952121.630 [DataOverHttps](INFO): sslConnect
2013-11-08T23:08:41.631Z,1383952121.631 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:08:42.282Z,1383952122.282 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:08:43.193Z,1383952123.193 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:08:43.195Z,1383952123.195 [DataOverHttps](INFO): dataWrite
2013-11-08T23:08:43.195Z,1383952123.195 [DataOverHttps](INFO): dataWriting
2013-11-08T23:08:43.196Z,1383952123.196 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:08:43.592Z,1383952123.592 [DataOverHttps](INFO): dataRead
2013-11-08T23:08:44.009Z,1383952124.009 [DataOverHttps](INFO): dataRead
2013-11-08T23:08:44.010Z,1383952124.010 [DataOverHttps](INFO): disconnect
2013-11-08T23:08:50.224Z,1383952130.224 [SBIT](IMPORTANT): SBIT PASSED
2013-11-08T23:08:50.628Z,1383952130.628 [MissionManager](IMPORTANT): Started mission Startup
2013-11-08T23:08:50.628Z,1383952130.628 [Startup] Running Loop=1
2013-11-08T23:08:50.628Z,1383952130.628 [Startup](INFO): Aggregate::initialize Startup
2013-11-08T23:08:50.628Z,1383952130.628 [Startup:A.GoToSurface] Running Loop=1
2013-11-08T23:08:50.628Z,1383952130.628 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-11-08T23:08:50.634Z,1383952130.634 [Startup:StartupSatComms] Running Loop=1
2013-11-08T23:08:50.634Z,1383952130.634 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2013-11-08T23:08:50.634Z,1383952130.634 [Startup:StartupSatComms:A] Running Loop=1
2013-11-08T23:08:51.029Z,1383952131.029 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-11-08T23:08:51.418Z,1383952131.418 [NAL9602](INFO): Powering up
2013-11-08T23:09:02.152Z,1383952142.152 [NAL9602](INFO): NAL9602 initialized
2013-11-08T23:09:11.304Z,1383952151.304 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:09:11.692Z,1383952151.692 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:09:11.692Z,1383952151.692 [DataOverHttps](INFO): sslConnect
2013-11-08T23:09:11.693Z,1383952151.693 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:09:12.117Z,1383952152.117 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:09:12.966Z,1383952152.966 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:09:12.968Z,1383952152.968 [DataOverHttps](INFO): dataWrite
2013-11-08T23:09:12.968Z,1383952152.968 [DataOverHttps](INFO): dataWriting
2013-11-08T23:09:12.969Z,1383952152.969 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:09:13.376Z,1383952153.376 [DataOverHttps](INFO): dataRead
2013-11-08T23:09:13.775Z,1383952153.775 [DataOverHttps](INFO): dataRead
2013-11-08T23:09:13.776Z,1383952153.776 [DataOverHttps](INFO): disconnect
2013-11-08T23:09:29.802Z,1383952169.802 [NAL9602](IMPORTANT): GPS fix at: 1383952191.00
2013-11-08T23:09:29.816Z,1383952169.816 [Startup:StartupSatComms:A] Stopped
2013-11-08T23:09:29.816Z,1383952169.816 [Startup:StartupSatComms:B] Running Loop=1
2013-11-08T23:09:30.233Z,1383952170.233 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2013-11-08T23:09:31.252Z,1383952171.252 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:09:31.650Z,1383952171.650 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:09:31.650Z,1383952171.650 [DataOverHttps](INFO): sslConnect
2013-11-08T23:09:31.651Z,1383952171.651 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:09:32.067Z,1383952172.067 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:09:33.498Z,1383952173.498 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:09:33.500Z,1383952173.500 [DataOverHttps](INFO): dataWrite
2013-11-08T23:09:33.501Z,1383952173.501 [DataOverHttps](INFO): encoded 71 bytes: %5d%00%00%04%00%08%00%00%00%00%00%00%00%00%15%1d%80%03%0cVb%00%b8%f0%00
2013-11-08T23:09:33.502Z,1383952173.502 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:09:33.502Z,1383952173.502 [DataOverHttps](INFO): dataWriting
2013-11-08T23:09:33.504Z,1383952173.504 [DataOverHttps](INFO): Wrote 343 bytes.
2013-11-08T23:09:33.947Z,1383952173.947 [DataOverHttps](INFO): dataRead
2013-11-08T23:09:34.403Z,1383952174.403 [DataOverHttps](INFO): dataRead
2013-11-08T23:09:34.868Z,1383952174.868 [DataOverHttps](INFO): dataRead
2013-11-08T23:09:35.261Z,1383952175.261 [DataOverHttps](INFO): dataRead
2013-11-08T23:09:35.261Z,1383952175.261 [DataOverHttps](INFO): disconnect
2013-11-08T23:09:35.677Z,1383952175.677 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:09:36.082Z,1383952176.082 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:09:36.082Z,1383952176.082 [DataOverHttps](INFO): sslConnect
2013-11-08T23:09:36.083Z,1383952176.083 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:09:36.474Z,1383952176.474 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:09:37.340Z,1383952177.340 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:09:37.342Z,1383952177.342 [DataOverHttps](INFO): dataWrite
2013-11-08T23:09:37.343Z,1383952177.343 [DataOverHttps](INFO): encoded 71 bytes: %5d%00%00%04%00%08%00%00%00%00%00%00%00%00%15%1d%80%03%0cVb%00%b8%f0%00
2013-11-08T23:09:37.344Z,1383952177.344 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:09:37.344Z,1383952177.344 [DataOverHttps](INFO): dataWriting
2013-11-08T23:09:37.345Z,1383952177.345 [DataOverHttps](INFO): Wrote 343 bytes.
2013-11-08T23:09:37.749Z,1383952177.749 [DataOverHttps](INFO): dataRead
2013-11-08T23:09:38.205Z,1383952178.205 [DataOverHttps](INFO): dataRead
2013-11-08T23:09:38.212Z,1383952178.212 [NAL9602](INFO): Powering down
2013-11-08T23:09:38.714Z,1383952178.714 [DataOverHttps](INFO): dataRead
2013-11-08T23:09:38.714Z,1383952178.714 [DataOverHttps](INFO): disconnect
2013-11-08T23:09:39.204Z,1383952179.204 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:09:39.623Z,1383952179.623 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:09:39.623Z,1383952179.623 [DataOverHttps](INFO): sslConnect
2013-11-08T23:09:39.624Z,1383952179.624 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:09:40.040Z,1383952180.040 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:09:41.186Z,1383952181.186 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:09:41.188Z,1383952181.188 [DataOverHttps](INFO): dataWrite
2013-11-08T23:09:41.189Z,1383952181.189 [DataOverHttps](INFO): encoded 71 bytes: %5d%00%00%04%00%08%00%00%00%00%00%00%00%00%15%1d%80%03%0cVb%00%b8%f0%00
2013-11-08T23:09:41.190Z,1383952181.190 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:09:41.190Z,1383952181.190 [DataOverHttps](INFO): dataWriting
2013-11-08T23:09:41.192Z,1383952181.192 [DataOverHttps](INFO): Wrote 343 bytes.
2013-11-08T23:09:41.606Z,1383952181.606 [DataOverHttps](INFO): dataRead
2013-11-08T23:09:41.995Z,1383952181.995 [DataOverHttps](INFO): dataRead
2013-11-08T23:09:42.392Z,1383952182.392 [DataOverHttps](INFO): dataRead
2013-11-08T23:09:42.392Z,1383952182.392 [DataOverHttps](INFO): disconnect
2013-11-08T23:09:42.804Z,1383952182.804 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:09:43.249Z,1383952183.249 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:09:43.249Z,1383952183.249 [DataOverHttps](INFO): sslConnect
2013-11-08T23:09:43.250Z,1383952183.250 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:09:43.678Z,1383952183.678 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:09:44.639Z,1383952184.639 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:09:44.641Z,1383952184.641 [DataOverHttps](INFO): dataWrite
2013-11-08T23:09:44.642Z,1383952184.642 [DataOverHttps](INFO): encoded 71 bytes: %5d%00%00%04%00%08%00%00%00%00%00%00%00%00%15%1d%80%03%0cVb%00%b8%f0%00
2013-11-08T23:09:44.643Z,1383952184.643 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:09:44.643Z,1383952184.643 [DataOverHttps](INFO): dataWriting
2013-11-08T23:09:44.645Z,1383952184.645 [DataOverHttps](INFO): Wrote 343 bytes.
2013-11-08T23:09:45.048Z,1383952185.048 [DataOverHttps](INFO): dataRead
2013-11-08T23:09:45.448Z,1383952185.448 [DataOverHttps](INFO): dataRead
2013-11-08T23:09:45.861Z,1383952185.861 [DataOverHttps](INFO): dataRead
2013-11-08T23:09:45.861Z,1383952185.861 [DataOverHttps](INFO): disconnect
2013-11-08T23:09:46.262Z,1383952186.262 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:09:46.666Z,1383952186.666 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:09:46.666Z,1383952186.666 [DataOverHttps](INFO): sslConnect
2013-11-08T23:09:46.667Z,1383952186.667 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:09:47.079Z,1383952187.079 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:09:47.957Z,1383952187.957 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:09:47.959Z,1383952187.959 [DataOverHttps](INFO): dataWrite
2013-11-08T23:09:47.960Z,1383952187.960 [DataOverHttps](INFO): encoded 71 bytes: %5d%00%00%04%00%08%00%00%00%00%00%00%00%00%15%1d%80%03%0cVb%00%b8%f0%00
2013-11-08T23:09:47.961Z,1383952187.961 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:09:47.961Z,1383952187.961 [DataOverHttps](INFO): dataWriting
2013-11-08T23:09:47.963Z,1383952187.963 [DataOverHttps](INFO): Wrote 343 bytes.
2013-11-08T23:09:48.377Z,1383952188.377 [DataOverHttps](INFO): dataRead
2013-11-08T23:09:48.781Z,1383952188.781 [DataOverHttps](INFO): dataRead
2013-11-08T23:09:49.189Z,1383952189.189 [DataOverHttps](INFO): dataRead
2013-11-08T23:09:49.189Z,1383952189.189 [DataOverHttps](INFO): disconnect
2013-11-08T23:09:49.591Z,1383952189.591 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:09:49.994Z,1383952189.994 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:09:49.994Z,1383952189.994 [DataOverHttps](INFO): sslConnect
2013-11-08T23:09:49.995Z,1383952189.995 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:09:50.391Z,1383952190.391 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:09:51.261Z,1383952191.261 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:09:51.263Z,1383952191.263 [DataOverHttps](INFO): dataWrite
2013-11-08T23:09:51.265Z,1383952191.265 [DataOverHttps](INFO): encoded 71 bytes: %5d%00%00%04%00%08%00%00%00%00%00%00%00%00%15%1d%80%03%0cVb%00%b8%f0%00
2013-11-08T23:09:51.265Z,1383952191.265 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:09:51.266Z,1383952191.266 [DataOverHttps](INFO): dataWriting
2013-11-08T23:09:51.267Z,1383952191.267 [DataOverHttps](INFO): Wrote 343 bytes.
2013-11-08T23:09:51.662Z,1383952191.662 [DataOverHttps](INFO): dataRead
2013-11-08T23:09:52.070Z,1383952192.070 [DataOverHttps](INFO): dataRead
2013-11-08T23:09:52.471Z,1383952192.471 [DataOverHttps](INFO): dataRead
2013-11-08T23:09:52.471Z,1383952192.471 [DataOverHttps](INFO): disconnect
2013-11-08T23:09:52.860Z,1383952192.860 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:09:53.260Z,1383952193.260 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:09:53.261Z,1383952193.261 [DataOverHttps](INFO): sslConnect
2013-11-08T23:09:53.262Z,1383952193.262 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:09:53.677Z,1383952193.677 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:09:54.575Z,1383952194.575 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:09:54.577Z,1383952194.577 [DataOverHttps](INFO): dataWrite
2013-11-08T23:09:54.578Z,1383952194.578 [DataOverHttps](INFO): encoded 71 bytes: %5d%00%00%04%00%08%00%00%00%00%00%00%00%00%15%1d%80%03%0cVb%00%b8%f0%00
2013-11-08T23:09:54.579Z,1383952194.579 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:09:54.579Z,1383952194.579 [DataOverHttps](INFO): dataWriting
2013-11-08T23:09:54.580Z,1383952194.580 [DataOverHttps](INFO): Wrote 343 bytes.
2013-11-08T23:09:55.007Z,1383952195.007 [DataOverHttps](INFO): dataRead
2013-11-08T23:09:55.404Z,1383952195.404 [DataOverHttps](INFO): dataRead
2013-11-08T23:09:55.789Z,1383952195.789 [DataOverHttps](INFO): dataRead
2013-11-08T23:09:56.185Z,1383952196.185 [DataOverHttps](INFO): dataRead
2013-11-08T23:09:56.586Z,1383952196.586 [DataOverHttps](INFO): dataRead
2013-11-08T23:09:56.981Z,1383952196.981 [DataOverHttps](INFO): dataRead
2013-11-08T23:09:56.981Z,1383952196.981 [DataOverHttps](INFO): disconnect
2013-11-08T23:09:57.383Z,1383952197.383 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:09:57.783Z,1383952197.783 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:09:57.784Z,1383952197.784 [DataOverHttps](INFO): sslConnect
2013-11-08T23:09:57.785Z,1383952197.785 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:09:58.184Z,1383952198.184 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:09:59.069Z,1383952199.069 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:09:59.071Z,1383952199.071 [DataOverHttps](INFO): dataWrite
2013-11-08T23:09:59.073Z,1383952199.073 [DataOverHttps](INFO): encoded 71 bytes: %5d%00%00%04%00%08%00%00%00%00%00%00%00%00%15%1d%80%03%0cVb%00%b8%f0%00
2013-11-08T23:09:59.073Z,1383952199.073 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:09:59.074Z,1383952199.074 [DataOverHttps](INFO): dataWriting
2013-11-08T23:09:59.075Z,1383952199.075 [DataOverHttps](INFO): Wrote 343 bytes.
2013-11-08T23:09:59.470Z,1383952199.470 [DataOverHttps](INFO): dataRead
2013-11-08T23:09:59.883Z,1383952199.883 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:00.303Z,1383952200.303 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:00.303Z,1383952200.303 [DataOverHttps](INFO): disconnect
2013-11-08T23:10:00.676Z,1383952200.676 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:10:01.085Z,1383952201.085 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:10:01.085Z,1383952201.085 [DataOverHttps](INFO): sslConnect
2013-11-08T23:10:01.086Z,1383952201.086 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:01.485Z,1383952201.485 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:02.367Z,1383952202.367 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:02.368Z,1383952202.368 [DataOverHttps](INFO): dataWrite
2013-11-08T23:10:02.370Z,1383952202.370 [DataOverHttps](INFO): encoded 71 bytes: %5d%00%00%04%00%08%00%00%00%00%00%00%00%00%15%1d%80%03%0cVb%00%b8%f0%00
2013-11-08T23:10:02.371Z,1383952202.371 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:10:02.371Z,1383952202.371 [DataOverHttps](INFO): dataWriting
2013-11-08T23:10:02.372Z,1383952202.372 [DataOverHttps](INFO): Wrote 343 bytes.
2013-11-08T23:10:02.779Z,1383952202.779 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:03.193Z,1383952203.193 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:03.580Z,1383952203.580 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:03.581Z,1383952203.581 [DataOverHttps](INFO): disconnect
2013-11-08T23:10:03.985Z,1383952203.985 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:10:04.382Z,1383952204.382 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:10:04.382Z,1383952204.382 [DataOverHttps](INFO): sslConnect
2013-11-08T23:10:04.383Z,1383952204.383 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:04.782Z,1383952204.782 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:05.656Z,1383952205.656 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:05.658Z,1383952205.658 [DataOverHttps](INFO): dataWrite
2013-11-08T23:10:05.659Z,1383952205.659 [DataOverHttps](INFO): encoded 71 bytes: %5d%00%00%04%00%08%00%00%00%00%00%00%00%00%15%1d%80%03%0cVb%00%b8%f0%00
2013-11-08T23:10:05.660Z,1383952205.660 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:10:05.660Z,1383952205.660 [DataOverHttps](INFO): dataWriting
2013-11-08T23:10:05.661Z,1383952205.661 [DataOverHttps](INFO): Wrote 343 bytes.
2013-11-08T23:10:06.064Z,1383952206.064 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:06.465Z,1383952206.465 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:06.865Z,1383952206.865 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:06.866Z,1383952206.866 [DataOverHttps](INFO): disconnect
2013-11-08T23:10:07.270Z,1383952207.270 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:10:07.671Z,1383952207.671 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:10:07.671Z,1383952207.671 [DataOverHttps](INFO): sslConnect
2013-11-08T23:10:07.672Z,1383952207.672 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:08.079Z,1383952208.079 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:08.933Z,1383952208.933 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:08.935Z,1383952208.935 [DataOverHttps](INFO): dataWrite
2013-11-08T23:10:08.936Z,1383952208.936 [DataOverHttps](INFO): encoded 71 bytes: %5d%00%00%04%00%08%00%00%00%00%00%00%00%00%15%1d%80%03%0cVb%00%b8%f0%00
2013-11-08T23:10:08.937Z,1383952208.937 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:10:08.937Z,1383952208.937 [DataOverHttps](INFO): dataWriting
2013-11-08T23:10:08.938Z,1383952208.938 [DataOverHttps](INFO): Wrote 343 bytes.
2013-11-08T23:10:09.341Z,1383952209.341 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:09.746Z,1383952209.746 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:10.127Z,1383952210.127 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:10.127Z,1383952210.127 [DataOverHttps](INFO): disconnect
2013-11-08T23:10:10.543Z,1383952210.543 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:10:10.944Z,1383952210.944 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:10:10.944Z,1383952210.944 [DataOverHttps](INFO): sslConnect
2013-11-08T23:10:10.945Z,1383952210.945 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:11.352Z,1383952211.352 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:12.254Z,1383952212.254 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:12.256Z,1383952212.256 [DataOverHttps](INFO): dataWrite
2013-11-08T23:10:12.257Z,1383952212.257 [DataOverHttps](INFO): encoded 71 bytes: %5d%00%00%04%00%08%00%00%00%00%00%00%00%00%15%1d%80%03%0cVb%00%b8%f0%00
2013-11-08T23:10:12.258Z,1383952212.258 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:10:12.258Z,1383952212.258 [DataOverHttps](INFO): dataWriting
2013-11-08T23:10:12.259Z,1383952212.259 [DataOverHttps](INFO): Wrote 343 bytes.
2013-11-08T23:10:12.655Z,1383952212.655 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:13.055Z,1383952213.055 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:13.464Z,1383952213.464 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:13.464Z,1383952213.464 [DataOverHttps](INFO): disconnect
2013-11-08T23:10:13.860Z,1383952213.860 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:10:14.269Z,1383952214.269 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:10:14.269Z,1383952214.269 [DataOverHttps](INFO): sslConnect
2013-11-08T23:10:14.270Z,1383952214.270 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:14.674Z,1383952214.674 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:15.571Z,1383952215.571 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:15.573Z,1383952215.573 [DataOverHttps](INFO): dataWrite
2013-11-08T23:10:15.575Z,1383952215.575 [DataOverHttps](INFO): encoded 71 bytes: %5d%00%00%04%00%08%00%00%00%00%00%00%00%00%15%1d%80%03%0cVb%00%b8%f0%00
2013-11-08T23:10:15.576Z,1383952215.576 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:10:15.576Z,1383952215.576 [DataOverHttps](INFO): dataWriting
2013-11-08T23:10:15.578Z,1383952215.578 [DataOverHttps](INFO): Wrote 343 bytes.
2013-11-08T23:10:15.996Z,1383952215.996 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:16.392Z,1383952216.392 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:16.801Z,1383952216.801 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:16.801Z,1383952216.801 [DataOverHttps](INFO): disconnect
2013-11-08T23:10:17.182Z,1383952217.182 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:10:17.614Z,1383952217.614 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:10:17.614Z,1383952217.614 [DataOverHttps](INFO): sslConnect
2013-11-08T23:10:17.615Z,1383952217.615 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:18.007Z,1383952218.007 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:18.884Z,1383952218.884 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:18.886Z,1383952218.886 [DataOverHttps](INFO): dataWrite
2013-11-08T23:10:18.887Z,1383952218.887 [DataOverHttps](INFO): encoded 71 bytes: %5d%00%00%04%00%08%00%00%00%00%00%00%00%00%15%1d%80%03%0cVb%00%b8%f0%00
2013-11-08T23:10:18.888Z,1383952218.888 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:10:18.888Z,1383952218.888 [DataOverHttps](INFO): dataWriting
2013-11-08T23:10:18.890Z,1383952218.890 [DataOverHttps](INFO): Wrote 343 bytes.
2013-11-08T23:10:19.291Z,1383952219.291 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:19.697Z,1383952219.697 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:20.092Z,1383952220.092 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:20.092Z,1383952220.092 [DataOverHttps](INFO): disconnect
2013-11-08T23:10:20.495Z,1383952220.495 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:10:20.907Z,1383952220.907 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:10:20.908Z,1383952220.908 [DataOverHttps](INFO): sslConnect
2013-11-08T23:10:20.909Z,1383952220.909 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:21.296Z,1383952221.296 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:22.169Z,1383952222.169 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:22.171Z,1383952222.171 [DataOverHttps](INFO): dataWrite
2013-11-08T23:10:22.173Z,1383952222.173 [DataOverHttps](INFO): encoded 71 bytes: %5d%00%00%04%00%08%00%00%00%00%00%00%00%00%15%1d%80%03%0cVb%00%b8%f0%00
2013-11-08T23:10:22.173Z,1383952222.173 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:10:22.174Z,1383952222.174 [DataOverHttps](INFO): dataWriting
2013-11-08T23:10:22.175Z,1383952222.175 [DataOverHttps](INFO): Wrote 343 bytes.
2013-11-08T23:10:22.578Z,1383952222.578 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:22.983Z,1383952222.983 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:23.380Z,1383952223.380 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:23.380Z,1383952223.380 [DataOverHttps](INFO): disconnect
2013-11-08T23:10:23.780Z,1383952223.780 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:10:24.180Z,1383952224.180 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:10:24.180Z,1383952224.180 [DataOverHttps](INFO): sslConnect
2013-11-08T23:10:24.182Z,1383952224.182 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:24.569Z,1383952224.569 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:25.439Z,1383952225.439 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:25.441Z,1383952225.441 [DataOverHttps](INFO): dataWrite
2013-11-08T23:10:25.442Z,1383952225.442 [DataOverHttps](INFO): encoded 71 bytes: %5d%00%00%04%00%08%00%00%00%00%00%00%00%00%15%1d%80%03%0cVb%00%b8%f0%00
2013-11-08T23:10:25.443Z,1383952225.443 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:10:25.443Z,1383952225.443 [DataOverHttps](INFO): dataWriting
2013-11-08T23:10:25.444Z,1383952225.444 [DataOverHttps](INFO): Wrote 343 bytes.
2013-11-08T23:10:25.835Z,1383952225.835 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:26.235Z,1383952226.235 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:26.624Z,1383952226.624 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:27.029Z,1383952227.029 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:27.029Z,1383952227.029 [DataOverHttps](INFO): disconnect
2013-11-08T23:10:27.441Z,1383952227.441 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:10:27.838Z,1383952227.838 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:10:27.838Z,1383952227.838 [DataOverHttps](INFO): sslConnect
2013-11-08T23:10:27.839Z,1383952227.839 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:28.251Z,1383952228.251 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:29.100Z,1383952229.100 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:29.102Z,1383952229.102 [DataOverHttps](INFO): dataWrite
2013-11-08T23:10:29.103Z,1383952229.103 [DataOverHttps](INFO): encoded 71 bytes: %5d%00%00%04%00%08%00%00%00%00%00%00%00%00%15%1d%80%03%0cVb%00%b8%f0%00
2013-11-08T23:10:29.104Z,1383952229.104 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:10:29.104Z,1383952229.104 [DataOverHttps](INFO): dataWriting
2013-11-08T23:10:29.106Z,1383952229.106 [DataOverHttps](INFO): Wrote 343 bytes.
2013-11-08T23:10:29.513Z,1383952229.513 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:29.893Z,1383952229.893 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:29.919Z,1383952229.919 [Startup:StartupSatComms:B](INFO): Timed out from 2013-11-08T23:09:29.8Z
2013-11-08T23:10:29.919Z,1383952229.919 [Startup:StartupSatComms:B:A_Timeout] Running Loop=1
2013-11-08T23:10:29.919Z,1383952229.919 [Startup:StartupSatComms:B:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:B:A_Timeout
2013-11-08T23:10:29.919Z,1383952229.919 [Startup:StartupSatComms:B:A_Timeout](INFO): Completed Startup:StartupSatComms:B:A_Timeout
2013-11-08T23:10:29.919Z,1383952229.919 [Startup:StartupSatComms:B] Stopped
2013-11-08T23:10:29.919Z,1383952229.919 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2013-11-08T23:10:29.920Z,1383952229.920 [Startup:StartupSatComms] Stopped
2013-11-08T23:10:29.920Z,1383952229.920 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2013-11-08T23:10:29.921Z,1383952229.921 [Startup](INFO): Completed Startup
2013-11-08T23:10:29.921Z,1383952229.921 [Startup] Stopped
2013-11-08T23:10:29.921Z,1383952229.921 [Startup](INFO): Aggregate::uninitialize Startup
2013-11-08T23:10:29.921Z,1383952229.921 [Startup:A.GoToSurface] Stopped
2013-11-08T23:10:29.921Z,1383952229.921 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-11-08T23:10:30.298Z,1383952230.298 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:30.298Z,1383952230.298 [DataOverHttps](INFO): disconnect
2013-11-08T23:10:30.319Z,1383952230.319 [MissionManager](IMPORTANT): Started mission Default
2013-11-08T23:10:30.319Z,1383952230.319 [Default] Running Loop=1
2013-11-08T23:10:30.319Z,1383952230.319 [Default](INFO): Aggregate::initialize Default
2013-11-08T23:10:30.319Z,1383952230.319 [Default:D.SetSpeed] Running Loop=1
2013-11-08T23:10:30.319Z,1383952230.319 [Default:D.SetSpeed](DEBUG): Initialize.
2013-11-08T23:10:30.320Z,1383952230.320 [Default:E.GoToSurface] Running Loop=1
2013-11-08T23:10:30.320Z,1383952230.320 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-11-08T23:10:30.320Z,1383952230.320 [Default:Iridium] Running Loop=1
2013-11-08T23:10:30.320Z,1383952230.320 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-11-08T23:10:30.320Z,1383952230.320 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-11-08T23:10:30.320Z,1383952230.320 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-11-08T23:10:30.320Z,1383952230.320 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-11-08T23:10:30.321Z,1383952230.321 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-11-08T23:10:30.321Z,1383952230.321 [Default:E.GoToSurface] Running Loop=1
2013-11-08T23:10:30.326Z,1383952230.326 [Default:D.SetSpeed] Running Loop=1
2013-11-08T23:10:30.331Z,1383952230.332 [Default:CallIridium] Running Loop=1
2013-11-08T23:10:30.332Z,1383952230.332 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-11-08T23:10:30.332Z,1383952230.332 [Default:CallIridium:A] Running Loop=1
2013-11-08T23:10:30.334Z,1383952230.334 [Default:CallIridium:A] Stopped
2013-11-08T23:10:30.334Z,1383952230.334 [Default:CallIridium:B] Running Loop=1
2013-11-08T23:10:30.334Z,1383952230.334 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-11-08T23:10:30.340Z,1383952230.340 [Default:Iridium:B.GoToSurface] Stopped
2013-11-08T23:10:30.340Z,1383952230.340 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-11-08T23:10:30.340Z,1383952230.340 [Default:Iridium:Read_Iridium] Running Loop=1
2013-11-08T23:10:30.340Z,1383952230.340 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-11-08T23:10:30.345Z,1383952230.345 [Default:GPS] Running Loop=1
2013-11-08T23:10:30.345Z,1383952230.345 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-11-08T23:10:30.345Z,1383952230.345 [Default:GPS:A.SetSpeed] Running Loop=1
2013-11-08T23:10:30.345Z,1383952230.345 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-11-08T23:10:30.345Z,1383952230.345 [Default:GPS:B.GoToSurface] Running Loop=1
2013-11-08T23:10:30.346Z,1383952230.346 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-11-08T23:10:30.351Z,1383952230.351 [Default:GPS:B.GoToSurface] Stopped
2013-11-08T23:10:30.351Z,1383952230.351 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-11-08T23:10:30.351Z,1383952230.351 [Default:GPS:Read_GPS] Running Loop=1
2013-11-08T23:10:30.351Z,1383952230.351 [Default:GPS:A.SetSpeed] Running Loop=1
2013-11-08T23:10:30.722Z,1383952230.722 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:10:30.745Z,1383952230.745 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2013-11-08T23:10:30.748Z,1383952230.748 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-11-08T23:10:31.456Z,1383952231.456 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:10:31.456Z,1383952231.456 [DataOverHttps](INFO): sslConnect
2013-11-08T23:10:31.457Z,1383952231.457 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:31.460Z,1383952231.460 [NAL9602](INFO): Powering up
2013-11-08T23:10:31.860Z,1383952231.860 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:32.734Z,1383952232.734 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:32.736Z,1383952232.736 [DataOverHttps](INFO): dataWrite
2013-11-08T23:10:32.739Z,1383952232.739 [DataOverHttps](INFO): encoded 71 bytes: %5d%00%00%04%00%08%00%00%00%00%00%00%00%00%15%1d%80%03%0cVb%00%b8%f0%00
2013-11-08T23:10:32.740Z,1383952232.740 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:10:32.744Z,1383952232.744 [DataOverHttps](INFO): dataWriting
2013-11-08T23:10:32.746Z,1383952232.746 [DataOverHttps](INFO): Wrote 343 bytes.
2013-11-08T23:10:33.158Z,1383952233.158 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:33.567Z,1383952233.567 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:33.971Z,1383952233.971 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:34.364Z,1383952234.364 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:34.364Z,1383952234.364 [DataOverHttps](INFO): disconnect
2013-11-08T23:10:34.769Z,1383952234.769 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:10:35.173Z,1383952235.173 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:10:35.174Z,1383952235.174 [DataOverHttps](INFO): sslConnect
2013-11-08T23:10:35.175Z,1383952235.175 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:35.570Z,1383952235.570 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:36.435Z,1383952236.435 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:36.437Z,1383952236.437 [DataOverHttps](INFO): dataWrite
2013-11-08T23:10:36.439Z,1383952236.439 [DataOverHttps](INFO): encoded 71 bytes: %5d%00%00%04%00%08%00%00%00%00%00%00%00%00%15%1d%80%03%0cVb%00%b8%f0%00
2013-11-08T23:10:36.439Z,1383952236.439 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:10:36.440Z,1383952236.440 [DataOverHttps](INFO): dataWriting
2013-11-08T23:10:36.441Z,1383952236.441 [DataOverHttps](INFO): Wrote 343 bytes.
2013-11-08T23:10:36.834Z,1383952236.834 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:37.234Z,1383952237.234 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:37.635Z,1383952237.635 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:37.635Z,1383952237.635 [DataOverHttps](INFO): disconnect
2013-11-08T23:10:38.022Z,1383952238.022 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:10:38.442Z,1383952238.442 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:10:38.443Z,1383952238.443 [DataOverHttps](INFO): sslConnect
2013-11-08T23:10:38.444Z,1383952238.444 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:38.848Z,1383952238.848 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:39.691Z,1383952239.691 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:39.693Z,1383952239.693 [DataOverHttps](INFO): dataWrite
2013-11-08T23:10:39.694Z,1383952239.694 [DataOverHttps](INFO): encoded 71 bytes: %5d%00%00%04%00%08%00%00%00%00%00%00%00%00%15%1d%80%03%0cVb%00%b8%f0%00
2013-11-08T23:10:39.695Z,1383952239.695 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:10:39.695Z,1383952239.695 [DataOverHttps](INFO): dataWriting
2013-11-08T23:10:39.697Z,1383952239.697 [DataOverHttps](INFO): Wrote 343 bytes.
2013-11-08T23:10:40.101Z,1383952240.101 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:40.482Z,1383952240.482 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:40.894Z,1383952240.894 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:40.894Z,1383952240.894 [DataOverHttps](INFO): disconnect
2013-11-08T23:10:41.291Z,1383952241.291 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:10:41.696Z,1383952241.696 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:10:41.696Z,1383952241.696 [DataOverHttps](INFO): sslConnect
2013-11-08T23:10:41.697Z,1383952241.697 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:42.084Z,1383952242.084 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:42.592Z,1383952242.592 [NAL9602](INFO): NAL9602 initialized
2013-11-08T23:10:42.965Z,1383952242.965 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:42.967Z,1383952242.967 [DataOverHttps](INFO): dataWrite
2013-11-08T23:10:42.969Z,1383952242.969 [DataOverHttps](INFO): encoded 71 bytes: %5d%00%00%04%00%08%00%00%00%00%00%00%00%00%15%1d%80%03%0cVb%00%b8%f0%00
2013-11-08T23:10:42.969Z,1383952242.969 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:10:42.970Z,1383952242.970 [DataOverHttps](INFO): dataWriting
2013-11-08T23:10:42.971Z,1383952242.971 [DataOverHttps](INFO): Wrote 343 bytes.
2013-11-08T23:10:43.389Z,1383952243.389 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:43.827Z,1383952243.827 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:43.829Z,1383952243.829 [DataOverHttps](INFO): disconnect
2013-11-08T23:10:43.832Z,1383952243.832 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=90.056122
2013-11-08T23:10:44.263Z,1383952244.263 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:10:44.664Z,1383952244.664 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:10:44.664Z,1383952244.664 [DataOverHttps](INFO): sslConnect
2013-11-08T23:10:44.666Z,1383952244.666 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:45.077Z,1383952245.077 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:45.966Z,1383952245.966 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:45.968Z,1383952245.968 [DataOverHttps](INFO): dataWrite
2013-11-08T23:10:45.968Z,1383952245.968 [DataOverHttps](INFO): dataWriting
2013-11-08T23:10:45.970Z,1383952245.970 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:10:46.383Z,1383952246.383 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:46.791Z,1383952246.791 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:46.792Z,1383952246.792 [DataOverHttps](INFO): disconnect
2013-11-08T23:10:46.795Z,1383952246.795 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002606
2013-11-08T23:10:47.494Z,1383952247.494 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:10:47.873Z,1383952247.873 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:10:47.873Z,1383952247.873 [DataOverHttps](INFO): sslConnect
2013-11-08T23:10:47.874Z,1383952247.874 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:48.286Z,1383952248.286 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:49.211Z,1383952249.211 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:49.213Z,1383952249.213 [DataOverHttps](INFO): dataWrite
2013-11-08T23:10:49.214Z,1383952249.214 [DataOverHttps](INFO): encoded 71 bytes: %5d%00%00%04%00%08%00%00%00%00%00%00%00%00%15%1d%80%03%0cVb%00%b8%f0%00
2013-11-08T23:10:49.215Z,1383952249.215 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:10:49.215Z,1383952249.215 [DataOverHttps](INFO): dataWriting
2013-11-08T23:10:49.217Z,1383952249.217 [DataOverHttps](INFO): Wrote 343 bytes.
2013-11-08T23:10:49.612Z,1383952249.612 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:50.000Z,1383952250.000 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:50.413Z,1383952250.413 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:50.413Z,1383952250.413 [DataOverHttps](INFO): disconnect
2013-11-08T23:10:50.806Z,1383952250.806 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:10:51.194Z,1383952251.194 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:10:51.194Z,1383952251.194 [DataOverHttps](INFO): sslConnect
2013-11-08T23:10:51.195Z,1383952251.195 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:51.611Z,1383952251.611 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:52.464Z,1383952252.464 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:52.466Z,1383952252.466 [DataOverHttps](INFO): dataWrite
2013-11-08T23:10:52.468Z,1383952252.468 [DataOverHttps](INFO): encoded 71 bytes: %5d%00%00%04%00%08%00%00%00%00%00%00%00%00%15%1d%80%03%0cVb%00%b8%f0%00
2013-11-08T23:10:52.468Z,1383952252.468 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:10:52.469Z,1383952252.469 [DataOverHttps](INFO): dataWriting
2013-11-08T23:10:52.470Z,1383952252.470 [DataOverHttps](INFO): Wrote 343 bytes.
2013-11-08T23:10:52.873Z,1383952252.873 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:53.269Z,1383952253.269 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:53.674Z,1383952253.674 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:53.674Z,1383952253.674 [DataOverHttps](INFO): disconnect
2013-11-08T23:10:54.596Z,1383952254.596 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:10:55.321Z,1383952255.321 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:10:55.321Z,1383952255.321 [DataOverHttps](INFO): sslConnect
2013-11-08T23:10:55.322Z,1383952255.322 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:57.784Z,1383952257.784 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:58.662Z,1383952258.662 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:10:58.664Z,1383952258.664 [DataOverHttps](INFO): dataWrite
2013-11-08T23:10:58.665Z,1383952258.665 [DataOverHttps](INFO): encoded 71 bytes: %5d%00%00%04%00%08%00%00%00%00%00%00%00%00%15%1d%80%03%0cVb%00%b8%f0%00
2013-11-08T23:10:58.666Z,1383952258.666 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:10:58.666Z,1383952258.666 [DataOverHttps](INFO): dataWriting
2013-11-08T23:10:58.668Z,1383952258.668 [DataOverHttps](INFO): Wrote 343 bytes.
2013-11-08T23:10:59.078Z,1383952259.078 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:59.493Z,1383952259.493 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:59.889Z,1383952259.889 [DataOverHttps](INFO): dataRead
2013-11-08T23:10:59.889Z,1383952259.889 [DataOverHttps](INFO): disconnect
2013-11-08T23:11:00.272Z,1383952260.272 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:11:00.685Z,1383952260.685 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:11:00.685Z,1383952260.685 [DataOverHttps](INFO): sslConnect
2013-11-08T23:11:00.686Z,1383952260.686 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:11:01.078Z,1383952261.078 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:11:01.955Z,1383952261.955 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:11:01.957Z,1383952261.957 [DataOverHttps](INFO): dataWrite
2013-11-08T23:11:01.958Z,1383952261.958 [DataOverHttps](INFO): encoded 71 bytes: %5d%00%00%04%00%08%00%00%00%00%00%00%00%00%15%1d%80%03%0cVb%00%b8%f0%00
2013-11-08T23:11:01.959Z,1383952261.959 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:11:01.959Z,1383952261.959 [DataOverHttps](INFO): dataWriting
2013-11-08T23:11:01.961Z,1383952261.961 [DataOverHttps](INFO): Wrote 343 bytes.
2013-11-08T23:11:02.375Z,1383952262.375 [DataOverHttps](INFO): dataRead
2013-11-08T23:11:02.385Z,1383952262.385 [NAL9602](INFO): SBD MO Status=1, MOMSN=20632, MT Status=0, MTMSN=0
2013-11-08T23:11:02.438Z,1383952262.438 [NAL9602](INFO): Sent 25 bytes from file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:11:02.439Z,1383952262.439 [NAL9602](INFO): Packets left to send: 0
2013-11-08T23:11:02.440Z,1383952262.440 [NAL9602](INFO): Stored copy of sent data in Logs/20131108T230007/Courier0004.lzma.parts/0000.sbd
2013-11-08T23:11:02.812Z,1383952262.812 [DataOverHttps](INFO): dataRead
2013-11-08T23:11:03.593Z,1383952263.593 [DataOverHttps](INFO): dataRead
2013-11-08T23:11:03.594Z,1383952263.594 [DataOverHttps](INFO): disconnect
2013-11-08T23:11:06.061Z,1383952266.061 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:11:06.462Z,1383952266.462 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:11:06.462Z,1383952266.462 [DataOverHttps](INFO): sslConnect
2013-11-08T23:11:06.463Z,1383952266.463 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:11:06.882Z,1383952266.882 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:11:07.756Z,1383952267.756 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:11:07.758Z,1383952267.758 [DataOverHttps](INFO): dataWrite
2013-11-08T23:11:07.758Z,1383952267.758 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:11:07.759Z,1383952267.759 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:11:07.759Z,1383952267.759 [DataOverHttps](INFO): dataWriting
2013-11-08T23:11:07.760Z,1383952267.760 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:11:07.816Z,1383952267.816 [CommandLine](FAULT): Scheduling is paused
2013-11-08T23:11:08.161Z,1383952268.161 [DataOverHttps](INFO): dataRead
2013-11-08T23:11:08.573Z,1383952268.573 [DataOverHttps](INFO): dataRead
2013-11-08T23:11:08.574Z,1383952268.574 [DataOverHttps](INFO): disconnect
2013-11-08T23:11:08.970Z,1383952268.970 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:11:09.378Z,1383952269.378 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:11:09.379Z,1383952269.379 [DataOverHttps](INFO): sslConnect
2013-11-08T23:11:09.380Z,1383952269.380 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:11:09.807Z,1383952269.807 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:11:10.668Z,1383952270.668 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:11:10.670Z,1383952270.670 [DataOverHttps](INFO): dataWrite
2013-11-08T23:11:10.671Z,1383952270.671 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:11:10.671Z,1383952270.671 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:11:10.671Z,1383952270.671 [DataOverHttps](INFO): dataWriting
2013-11-08T23:11:10.673Z,1383952270.673 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:11:11.077Z,1383952271.077 [DataOverHttps](INFO): dataRead
2013-11-08T23:11:11.491Z,1383952271.491 [DataOverHttps](INFO): dataRead
2013-11-08T23:11:11.492Z,1383952271.492 [DataOverHttps](INFO): disconnect
2013-11-08T23:11:11.894Z,1383952271.894 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:11:12.291Z,1383952272.291 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:11:12.291Z,1383952272.291 [DataOverHttps](INFO): sslConnect
2013-11-08T23:11:12.292Z,1383952272.292 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:11:12.699Z,1383952272.699 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:11:13.573Z,1383952273.573 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:11:13.575Z,1383952273.575 [DataOverHttps](INFO): dataWrite
2013-11-08T23:11:13.575Z,1383952273.575 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:11:13.576Z,1383952273.576 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:11:13.576Z,1383952273.576 [DataOverHttps](INFO): dataWriting
2013-11-08T23:11:13.577Z,1383952273.577 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:11:13.985Z,1383952273.985 [DataOverHttps](INFO): dataRead
2013-11-08T23:11:14.392Z,1383952274.392 [DataOverHttps](INFO): dataRead
2013-11-08T23:11:14.393Z,1383952274.393 [DataOverHttps](INFO): disconnect
2013-11-08T23:11:14.783Z,1383952274.783 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:11:15.193Z,1383952275.193 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:11:15.193Z,1383952275.193 [DataOverHttps](INFO): sslConnect
2013-11-08T23:11:15.194Z,1383952275.194 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:11:15.204Z,1383952275.204 [NAL9602](INFO): SBD MO Status=1, MOMSN=20633, MT Status=0, MTMSN=0
2013-11-08T23:11:15.258Z,1383952275.258 [NAL9602](INFO): Sent 60 bytes from file Logs/20131108T230007/Courier0008.lzma
2013-11-08T23:11:15.259Z,1383952275.259 [NAL9602](INFO): Packets left to send: 0
2013-11-08T23:11:15.260Z,1383952275.260 [NAL9602](INFO): Stored copy of sent data in Logs/20131108T230007/Courier0008.lzma.parts/0000.sbd
2013-11-08T23:11:15.690Z,1383952275.690 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:11:16.982Z,1383952276.982 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:11:16.984Z,1383952276.984 [DataOverHttps](INFO): dataWrite
2013-11-08T23:11:16.985Z,1383952276.985 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:11:16.985Z,1383952276.985 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T230007/Courier0004.lzma
2013-11-08T23:11:16.985Z,1383952276.985 [DataOverHttps](INFO): dataWriting
2013-11-08T23:11:16.987Z,1383952276.987 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:11:18.203Z,1383952278.203 [CommandLine](IMPORTANT): got command quit
2013-11-08T23:11:19.360Z,1383952279.360 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2013-11-08T23:11:19.360Z,1383952279.360 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2013-11-08T23:11:19.462Z,1383952279.462 [DataOverHttps](INFO): dataRead
2013-11-08T23:11:19.641Z,1383952279.641 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2013-11-08T23:11:19.859Z,1383952279.859 [DataOverHttps](INFO): dataRead
2013-11-08T23:11:19.859Z,1383952279.859 [DataOverHttps](INFO): disconnect
2013-11-08T23:11:19.869Z,1383952279.869 [WetLabsBB2FL](INFO): Powering down
2013-11-08T23:11:19.881Z,1383952279.881 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2013-11-08T23:11:20.174Z,1383952280.174 [CTD_NeilBrown](INFO): Powering down
2013-11-08T23:11:20.177Z,1383952280.177 [ComponentRegistry](INFO): Shutting down DVL_micro ThreadHandler
2013-11-08T23:11:20.222Z,1383952280.222 [DVL_micro](INFO): uninitialize:Powering down
2013-11-08T23:11:20.238Z,1383952280.238 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler
2013-11-08T23:11:20.267Z,1383952280.267 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:11:20.310Z,1383952280.310 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator.
2013-11-08T23:11:20.319Z,1383952280.319 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2013-11-08T23:11:20.399Z,1383952280.399 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2013-11-08T23:11:20.400Z,1383952280.400 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2013-11-08T23:11:20.660Z,1383952280.660 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:11:20.660Z,1383952280.660 [DataOverHttps](INFO): sslConnect
2013-11-08T23:11:20.661Z,1383952280.661 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:11:20.692Z,1383952280.692 [controlThread](DEBUG): Uninitializing ControlThread
2013-11-08T23:11:20.692Z,1383952280.692 [AHRS_sp3003D](INFO): Powering down
2013-11-08T23:11:20.779Z,1383952280.779 [DataOverHttps](INFO): disconnect
2013-11-08T23:11:20.780Z,1383952280.780 [NAL9602](INFO): Powering down
2013-11-08T23:11:20.782Z,1383952280.782 [DAT](INFO): Powering down
2013-11-08T23:11:20.783Z,1383952280.783 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2013-11-08T23:11:20.783Z,1383952280.783 [NavChart](DEBUG): Uninitialize NavChart Derivation.
2013-11-08T23:11:20.784Z,1383952280.784 [Default] Stopped
2013-11-08T23:11:20.784Z,1383952280.784 [Default](INFO): Aggregate::uninitialize Default
2013-11-08T23:11:20.784Z,1383952280.784 [Default:GPS] Stopped
2013-11-08T23:11:20.785Z,1383952280.785 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-11-08T23:11:20.785Z,1383952280.785 [Default:GPS:A.SetSpeed] Stopped
2013-11-08T23:11:20.785Z,1383952280.785 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-11-08T23:11:20.785Z,1383952280.785 [Default:GPS:Read_GPS] Stopped
2013-11-08T23:11:20.785Z,1383952280.785 [Default:Iridium] Stopped
2013-11-08T23:11:20.785Z,1383952280.785 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-11-08T23:11:20.785Z,1383952280.785 [Default:Iridium:A.SetSpeed] Stopped
2013-11-08T23:11:20.785Z,1383952280.785 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-11-08T23:11:20.785Z,1383952280.785 [Default:Iridium:Read_Iridium] Stopped
2013-11-08T23:11:20.785Z,1383952280.785 [Default:CallIridium] Stopped
2013-11-08T23:11:20.785Z,1383952280.785 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-11-08T23:11:20.786Z,1383952280.786 [Default:CallIridium:B] Stopped
2013-11-08T23:11:20.786Z,1383952280.786 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-11-08T23:11:20.786Z,1383952280.786 [Default:D.SetSpeed] Stopped
2013-11-08T23:11:20.786Z,1383952280.786 [Default:D.SetSpeed](DEBUG): Uninitialize.
2013-11-08T23:11:20.786Z,1383952280.786 [Default:E.GoToSurface] Stopped
2013-11-08T23:11:20.786Z,1383952280.786 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-11-08T23:11:20.790Z,1383952280.790 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2013-11-08T23:11:20.791Z,1383952280.791 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2013-11-08T23:11:20.791Z,1383952280.791 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2013-11-08T23:11:20.791Z,1383952280.791 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2013-11-08T23:11:20.791Z,1383952280.791 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-11-08T23:11:20.792Z,1383952280.792 [BuoyancyServo](INFO): Powering down
2013-11-08T23:11:20.807Z,1383952280.807 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2013-11-08T23:11:20.807Z,1383952280.807 [ElevatorServo](INFO): Powering down
2013-11-08T23:11:20.808Z,1383952280.808 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-11-08T23:11:20.808Z,1383952280.808 [MassServo](INFO): Powering down
2013-11-08T23:11:20.809Z,1383952280.809 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2013-11-08T23:11:20.809Z,1383952280.809 [RudderServo](INFO): Powering down
2013-11-08T23:11:20.809Z,1383952280.809 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-11-08T23:11:20.810Z,1383952280.810 [ThrusterServo](INFO): Powering down
2013-11-08T23:11:20.810Z,1383952280.810 [SBIT](DEBUG): Uninitialize SBIT Component.
2013-11-08T23:11:20.811Z,1383952280.811 [IBIT](DEBUG): Uninitialize IBIT Component.
2013-11-08T23:11:20.811Z,1383952280.811 [CBIT](DEBUG): Uninitialize CBIT Component.