2013-11-08T23:00:07.648Z,1383951607.648 [Supervisor](DEBUG): Initializing supervisor.
2013-11-08T23:00:07.651Z,1383951607.651 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2013-11-08T23:00:07.651Z,1383951607.651 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2013-11-08T23:00:07.652Z,1383951607.652 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0
2013-11-08T23:00:07.656Z,1383951607.656 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2013-11-08T23:00:07.667Z,1383951607.667 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2013-11-08T23:00:07.668Z,1383951607.668 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0
2013-11-08T23:00:07.669Z,1383951607.669 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2013-11-08T23:00:07.670Z,1383951607.670 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403CB4E0
2013-11-08T23:00:07.672Z,1383951607.672 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2013-11-08T23:00:07.672Z,1383951607.672 [Supervisor](INFO): Looking for Config files in directory: Config/
2013-11-08T23:00:07.674Z,1383951607.674 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2013-11-08T23:00:07.955Z,1383951607.955 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2013-11-08T23:00:07.956Z,1383951607.956 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2013-11-08T23:00:08.154Z,1383951608.154 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2013-11-08T23:00:08.155Z,1383951608.155 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2013-11-08T23:00:08.241Z,1383951608.241 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2013-11-08T23:00:08.241Z,1383951608.241 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2013-11-08T23:00:08.357Z,1383951608.357 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2013-11-08T23:00:08.358Z,1383951608.358 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2013-11-08T23:00:08.497Z,1383951608.497 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2013-11-08T23:00:08.497Z,1383951608.497 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2013-11-08T23:00:08.739Z,1383951608.739 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2013-11-08T23:00:08.740Z,1383951608.740 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2013-11-08T23:00:08.908Z,1383951608.908 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2013-11-08T23:00:08.909Z,1383951608.909 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2013-11-08T23:00:09.173Z,1383951609.173 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2013-11-08T23:00:09.173Z,1383951609.173 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2013-11-08T23:00:09.275Z,1383951609.275 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2013-11-08T23:00:09.275Z,1383951609.275 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2013-11-08T23:00:09.708Z,1383951609.708 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2013-11-08T23:00:09.709Z,1383951609.709 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2013-11-08T23:00:09.820Z,1383951609.820 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2013-11-08T23:00:09.821Z,1383951609.821 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2013-11-08T23:00:09.906Z,1383951609.906 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2013-11-08T23:00:10.007Z,1383951610.007 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2013-11-08T23:00:10.009Z,1383951610.009 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2013-11-08T23:00:10.010Z,1383951610.010 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2013-11-08T23:00:10.106Z,1383951610.106 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2013-11-08T23:00:10.233Z,1383951610.233 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2013-11-08T23:00:10.334Z,1383951610.334 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2013-11-08T23:00:10.438Z,1383951610.438 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2013-11-08T23:00:10.534Z,1383951610.534 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2013-11-08T23:00:10.648Z,1383951610.648 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2013-11-08T23:00:10.743Z,1383951610.743 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2013-11-08T23:00:10.829Z,1383951610.829 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2013-11-08T23:00:10.923Z,1383951610.923 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/LOGIN/
2013-11-08T23:00:10.923Z,1383951610.923 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2013-11-08T23:00:10.924Z,1383951610.924 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2013-11-08T23:00:11.091Z,1383951611.091 [InternalSim] Loaded
2013-11-08T23:00:11.091Z,1383951611.091 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2013-11-08T23:00:11.092Z,1383951611.092 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2013-11-08T23:00:11.093Z,1383951611.093 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2013-11-08T23:00:11.179Z,1383951611.179 [SBIT](DEBUG): Construct Startup Built In Test.
2013-11-08T23:00:11.209Z,1383951611.209 [SBIT] Loaded
2013-11-08T23:00:11.210Z,1383951611.210 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2013-11-08T23:00:11.210Z,1383951611.210 [IBIT](DEBUG): Construct Initiated Built In Test.
2013-11-08T23:00:11.240Z,1383951611.240 [IBIT] Loaded
2013-11-08T23:00:11.240Z,1383951611.240 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2013-11-08T23:00:11.242Z,1383951611.242 [CBIT](DEBUG): Construct CBIT Built In Test.
2013-11-08T23:00:11.360Z,1383951611.360 [CBIT] Loaded
2013-11-08T23:00:11.360Z,1383951611.360 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2013-11-08T23:00:11.360Z,1383951611.360 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2013-11-08T23:00:11.361Z,1383951611.361 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2013-11-08T23:00:11.530Z,1383951611.530 [BuoyancyServo] Loaded
2013-11-08T23:00:11.530Z,1383951611.530 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2013-11-08T23:00:11.542Z,1383951611.542 [ElevatorServo] Loaded
2013-11-08T23:00:11.543Z,1383951611.543 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2013-11-08T23:00:11.554Z,1383951611.554 [MassServo] Loaded
2013-11-08T23:00:11.554Z,1383951611.554 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2013-11-08T23:00:11.566Z,1383951611.566 [RudderServo] Loaded
2013-11-08T23:00:11.566Z,1383951611.566 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2013-11-08T23:00:11.577Z,1383951611.577 [ThrusterServo] Loaded
2013-11-08T23:00:11.578Z,1383951611.578 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2013-11-08T23:00:11.578Z,1383951611.578 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2013-11-08T23:00:11.579Z,1383951611.579 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2013-11-08T23:00:11.613Z,1383951611.613 [DepthRateCalculator] Loaded
2013-11-08T23:00:11.613Z,1383951611.613 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2013-11-08T23:00:15.996Z,1383951615.996 [HFRadarModelCalc] Loaded
2013-11-08T23:00:15.996Z,1383951615.996 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2013-11-08T23:00:16.012Z,1383951616.012 [NavChart] Loaded
2013-11-08T23:00:16.012Z,1383951616.012 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2013-11-08T23:00:16.018Z,1383951616.018 [PitchRateCalculator] Loaded
2013-11-08T23:00:16.018Z,1383951616.018 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2013-11-08T23:00:16.029Z,1383951616.029 [SpeedCalculator] Loaded
2013-11-08T23:00:16.029Z,1383951616.029 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2013-11-08T23:00:16.046Z,1383951616.046 [TempGradientCalculator] Loaded
2013-11-08T23:00:16.046Z,1383951616.046 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2013-11-08T23:00:16.052Z,1383951616.052 [YawRateCalculator] Loaded
2013-11-08T23:00:16.052Z,1383951616.052 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2013-11-08T23:00:16.099Z,1383951616.099 [Navigation] Loaded
2013-11-08T23:00:16.099Z,1383951616.099 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread.
2013-11-08T23:00:16.100Z,1383951616.100 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2013-11-08T23:00:16.101Z,1383951616.101 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2013-11-08T23:00:16.341Z,1383951616.341 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2013-11-08T23:00:16.342Z,1383951616.342 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2013-11-08T23:00:16.365Z,1383951616.365 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2013-11-08T23:00:16.366Z,1383951616.366 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2013-11-08T23:00:16.414Z,1383951616.414 [VerticalControl](DEBUG): Construct VerticalControl.
2013-11-08T23:00:16.510Z,1383951616.510 [VerticalControl] Loaded
2013-11-08T23:00:16.510Z,1383951616.510 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2013-11-08T23:00:16.511Z,1383951616.511 [HorizontalControl](DEBUG): Construct HorizontalControl.
2013-11-08T23:00:16.569Z,1383951616.569 [HorizontalControl] Loaded
2013-11-08T23:00:16.569Z,1383951616.569 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2013-11-08T23:00:16.570Z,1383951616.570 [SpeedControl](DEBUG): Construct SpeedControl.
2013-11-08T23:00:16.572Z,1383951616.572 [SpeedControl] Loaded
2013-11-08T23:00:16.572Z,1383951616.572 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2013-11-08T23:00:16.573Z,1383951616.573 [LoopControl](DEBUG): Construct LoopControl.
2013-11-08T23:00:16.573Z,1383951616.573 [LoopControl] Loaded
2013-11-08T23:00:16.574Z,1383951616.574 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2013-11-08T23:00:16.574Z,1383951616.574 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2013-11-08T23:00:16.575Z,1383951616.575 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2013-11-08T23:00:16.580Z,1383951616.580 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2013-11-08T23:00:16.585Z,1383951616.585 [AsyncPiEstimator] Loaded
2013-11-08T23:00:16.585Z,1383951616.585 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2013-11-08T23:00:16.587Z,1383951616.587 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 406474E0
2013-11-08T23:00:16.587Z,1383951616.587 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2013-11-08T23:00:16.588Z,1383951616.588 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2013-11-08T23:00:16.805Z,1383951616.805 [AHRS_sp3003D] Loaded
2013-11-08T23:00:16.805Z,1383951616.805 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2013-11-08T23:00:16.880Z,1383951616.880 [DataOverHttps] Loaded
2013-11-08T23:00:16.880Z,1383951616.880 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2013-11-08T23:00:16.894Z,1383951616.894 [Depth_Keller] Loaded
2013-11-08T23:00:16.894Z,1383951616.894 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2013-11-08T23:00:17.080Z,1383951617.080 [DVL_micro] Loaded
2013-11-08T23:00:17.080Z,1383951617.080 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread.
2013-11-08T23:00:17.082Z,1383951617.082 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 408554E0
2013-11-08T23:00:17.172Z,1383951617.172 [NAL9602] Loaded
2013-11-08T23:00:17.173Z,1383951617.173 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2013-11-08T23:00:17.214Z,1383951617.214 [Onboard] Loaded
2013-11-08T23:00:17.215Z,1383951617.215 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2013-11-08T23:00:17.221Z,1383951617.221 [Radio_Freewave] Loaded
2013-11-08T23:00:17.221Z,1383951617.221 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread.
2013-11-08T23:00:17.369Z,1383951617.369 [DAT] Loaded
2013-11-08T23:00:17.369Z,1383951617.369 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2013-11-08T23:00:17.370Z,1383951617.370 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2013-11-08T23:00:17.371Z,1383951617.371 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2013-11-08T23:00:17.471Z,1383951617.471 [CTD_NeilBrown] Loaded
2013-11-08T23:00:17.471Z,1383951617.471 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2013-11-08T23:00:17.472Z,1383951617.472 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408B24E0
2013-11-08T23:00:17.505Z,1383951617.505 [WetLabsBB2FL] Loaded
2013-11-08T23:00:17.505Z,1383951617.505 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2013-11-08T23:00:17.506Z,1383951617.506 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408E24E0
2013-11-08T23:00:17.507Z,1383951617.507 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2013-11-08T23:00:17.509Z,1383951617.509 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2013-11-08T23:00:17.510Z,1383951617.510 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2013-11-08T23:00:17.517Z,1383951617.517 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2013-11-08T23:00:17.518Z,1383951617.518 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409124E0
2013-11-08T23:00:17.523Z,1383951617.523 [Supervisor](DEBUG): Running supervisor.
2013-11-08T23:00:17.524Z,1383951617.524 [CommandLine](INFO): Thread ID is 5086
2013-11-08T23:00:17.526Z,1383951617.526 [controlThread](INFO): Thread ID is 5085
2013-11-08T23:00:17.526Z,1383951617.526 [controlThread](DEBUG): Initializing ControlThread
2013-11-08T23:00:17.527Z,1383951617.527 [CycleStarter](INFO): Thread ID is 5084
2013-11-08T23:00:17.528Z,1383951617.528 [InternalSim](DEBUG):  InternalSim initializing...
2013-11-08T23:00:17.555Z,1383951617.555 [logger](INFO): Thread ID is 5087
2013-11-08T23:00:17.586Z,1383951617.586 [SBIT](INFO): Initialize SBIT Component.
2013-11-08T23:00:17.586Z,1383951617.586 [SBIT](IMPORTANT): Tethys CM Info: 
 $Rev:10713
2013-11-08T23:00:17.587Z,1383951617.587 [SBIT](IMPORTANT): Kernel Release:2.6.27.8
2013-11-08T23:00:17.587Z,1383951617.587 [SBIT](IMPORTANT): Kernel Version:#634 PREEMPT Wed Feb 13 10:21:48 PST 2013
2013-11-08T23:00:17.595Z,1383951617.595 [AsyncPiEstimator](INFO): Thread ID is 5154
2013-11-08T23:00:17.595Z,1383951617.595 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2013-11-08T23:00:17.598Z,1383951617.598 [IBIT](INFO): Initialize IBIT Component.
2013-11-08T23:00:17.599Z,1383951617.599 [CBIT](DEBUG): Initialize CBIT Component.
2013-11-08T23:00:17.599Z,1383951617.599 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2013-11-08T23:00:17.608Z,1383951617.608 [DVL_micro](INFO): Thread ID is 5155
2013-11-08T23:00:17.617Z,1383951617.617 [DVL_micro](INFO): Initializing
2013-11-08T23:00:17.617Z,1383951617.617 [DVL_micro](INFO): start:Powering up
2013-11-08T23:00:17.618Z,1383951617.618 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-11-08T23:00:17.619Z,1383951617.619 [DVL_micro](INFO): Cycling power to configure device.
2013-11-08T23:00:17.632Z,1383951617.632 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2013-11-08T23:00:17.632Z,1383951617.632 [NavChart](DEBUG): Initialize NavChart Derivation.
2013-11-08T23:00:17.633Z,1383951617.633 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2013-11-08T23:00:17.633Z,1383951617.633 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2013-11-08T23:00:17.633Z,1383951617.633 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2013-11-08T23:00:17.634Z,1383951617.634 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2013-11-08T23:00:17.634Z,1383951617.634 [Navigation](DEBUG): Initializing Navigation.
2013-11-08T23:00:17.635Z,1383951617.635 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2013-11-08T23:00:17.636Z,1383951617.636 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2013-11-08T23:00:17.637Z,1383951617.637 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2013-11-08T23:00:17.637Z,1383951617.637 [LoopControl](DEBUG): Initialize LoopControlComponent.
2013-11-08T23:00:17.642Z,1383951617.642 [CTD_NeilBrown](INFO): Thread ID is 5156
2013-11-08T23:00:17.642Z,1383951617.642 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2013-11-08T23:00:17.644Z,1383951617.644 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2013-11-08T23:00:17.652Z,1383951617.652 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2013-11-08T23:00:17.675Z,1383951617.675 [WetLabsBB2FL](INFO): Thread ID is 5157
2013-11-08T23:00:17.676Z,1383951617.676 [WetLabsBB2FL](INFO): Powering down
2013-11-08T23:00:17.705Z,1383951617.705 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2013-11-08T23:00:17.707Z,1383951617.707 [NavChartDb](INFO): Thread ID is 5158
2013-11-08T23:00:17.710Z,1383951617.710 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2013-11-08T23:00:17.711Z,1383951617.711 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2013-11-08T23:00:17.711Z,1383951617.711 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2013-11-08T23:00:17.712Z,1383951617.712 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2013-11-08T23:00:17.712Z,1383951617.712 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2013-11-08T23:00:17.712Z,1383951617.712 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2013-11-08T23:00:17.712Z,1383951617.712 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2013-11-08T23:00:17.713Z,1383951617.713 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2013-11-08T23:00:17.713Z,1383951617.713 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2013-11-08T23:00:17.748Z,1383951617.748 [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:00:17.749Z,1383951617.749 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2013-11-08T23:00:17.833Z,1383951617.833 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2013-11-08T23:00:17.835Z,1383951617.835 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2013-11-08T23:00:17.838Z,1383951617.838 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-11-08T23:00:17.858Z,1383951617.858 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2013-11-08T23:00:17.881Z,1383951617.881 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-11-08T23:00:17.904Z,1383951617.904 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2013-11-08T23:00:17.928Z,1383951617.928 [Default:D.SetSpeed](DEBUG): Construct.
2013-11-08T23:00:17.939Z,1383951617.939 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2013-11-08T23:00:17.944Z,1383951617.944 [Default:F.Wait](DEBUG): Construct Wait.
2013-11-08T23:00:17.947Z,1383951617.947 [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:00:17.955Z,1383951617.955 [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:00:18.023Z,1383951618.023 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-11-08T23:00:18.037Z,1383951618.037 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:00:18.304Z,1383951618.304 [Radio_Freewave](INFO): Powering up
2013-11-08T23:00:18.312Z,1383951618.312 [DAT](INFO): Powering up
2013-11-08T23:00:18.312Z,1383951618.312 [DAT](DEBUG): Initializing DAT.
2013-11-08T23:00:18.515Z,1383951618.515 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-11-08T23:00:18.524Z,1383951618.524 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-11-08T23:00:18.530Z,1383951618.530 [ElevatorServo](DEBUG): Initializing EZServoServo.
2013-11-08T23:00:18.536Z,1383951618.536 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2013-11-08T23:00:18.542Z,1383951618.542 [MassServo](DEBUG): Initializing EZServoServo.
2013-11-08T23:00:18.548Z,1383951618.548 [MassServo](DEBUG): Initializing MassServo.
2013-11-08T23:00:18.554Z,1383951618.554 [RudderServo](DEBUG): Initializing EZServoServo.
2013-11-08T23:00:18.588Z,1383951618.588 [RudderServo](DEBUG): Initializing RudderServo.
2013-11-08T23:00:18.594Z,1383951618.594 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-11-08T23:00:18.600Z,1383951618.600 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-11-08T23:00:18.837Z,1383951618.837 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:00:18.837Z,1383951618.837 [DataOverHttps](INFO): sslConnect
2013-11-08T23:00:18.841Z,1383951618.841 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:00:19.427Z,1383951619.427 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:00:19.734Z,1383951619.734 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-11-08T23:00:19.734Z,1383951619.734 [DVL_micro](INFO): Querying output modes
2013-11-08T23:00:19.735Z,1383951619.735 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

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

2013-11-08T23:00:19.758Z,1383951619.758 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2013-11-08T23:00:19.766Z,1383951619.766 [DVL_micro](INFO): pause:Powering down
2013-11-08T23:00:21.518Z,1383951621.518 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:00:21.520Z,1383951621.520 [DataOverHttps](INFO): dataWrite
2013-11-08T23:00:21.521Z,1383951621.521 [DataOverHttps](INFO): dataWriting
2013-11-08T23:00:21.522Z,1383951621.522 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:00:21.951Z,1383951621.951 [DataOverHttps](INFO): dataRead
2013-11-08T23:00:22.480Z,1383951622.480 [DataOverHttps](INFO): dataRead
2013-11-08T23:00:22.481Z,1383951622.481 [DataOverHttps](IMPORTANT): SBD MTMSN=20131108T230045
2013-11-08T23:00:22.481Z,1383951622.481 [DataOverHttps](INFO): disconnect
2013-11-08T23:00:22.485Z,1383951622.485 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004006
2013-11-08T23:00:22.487Z,1383951622.487 [NAL9602](INFO): Powering up NAL9602
2013-11-08T23:00:23.016Z,1383951623.016 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:00:23.429Z,1383951623.429 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:00:23.429Z,1383951623.429 [DataOverHttps](INFO): sslConnect
2013-11-08T23:00:23.430Z,1383951623.430 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:00:24.014Z,1383951624.014 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:00:26.178Z,1383951626.178 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:00:26.180Z,1383951626.180 [DataOverHttps](INFO): dataWrite
2013-11-08T23:00:26.180Z,1383951626.180 [DataOverHttps](INFO): dataWriting
2013-11-08T23:00:26.181Z,1383951626.181 [DataOverHttps](INFO): Wrote 228 bytes.
2013-11-08T23:00:26.182Z,1383951626.182 [DataOverHttps](INFO): Received command:get cake
2013-11-08T23:00:26.434Z,1383951626.434 [CommandLine](IMPORTANT): got command get 
2013-11-08T23:00:26.434Z,1383951626.434 [CommandLine](FAULT): Incomplete syntax. Try: help get
2013-11-08T23:00:27.091Z,1383951627.091 [DataOverHttps](INFO): dataRead
2013-11-08T23:00:27.564Z,1383951627.564 [DataOverHttps](INFO): dataRead
2013-11-08T23:00:27.564Z,1383951627.564 [DataOverHttps](INFO): disconnect
2013-11-08T23:00:33.211Z,1383951633.211 [NAL9602](INFO): NAL9602 initialized
2013-11-08T23:00:33.284Z,1383951633.284 [SBIT](IMPORTANT): Beginning Startup BIT
2013-11-08T23:00:33.287Z,1383951633.287 [CBIT](IMPORTANT): Beginning GF scan
2013-11-08T23:00:38.634Z,1383951638.634 [DAT](INFO): Powering down
2013-11-08T23:00:41.668Z,1383951641.668 [CommandLine](IMPORTANT): got command failComponent hardware DataOverHttps
2013-11-08T23:00:41.668Z,1383951641.668 [DataOverHttps] Hardware Fault, FailCount= 1
2013-11-08T23:00:41.668Z,1383951641.668 [DataOverHttps](ERROR): Hardware Fault
2013-11-08T23:00:41.669Z,1383951641.669 [CommandLine](IMPORTANT): DataOverHttps failureMode is Hardware Fault
2013-11-08T23:00:41.673Z,1383951641.673 [DataOverHttps](INFO): disconnect
2013-11-08T23:00:41.781Z,1383951641.781 [CBIT](ERROR): Hardware Fault in component: DataOverHttps
2013-11-08T23:00:41.782Z,1383951641.782 [CBIT](CRITICAL): Hardware Fault in component: DataOverHttps
2013-11-08T23:00:42.200Z,1383951642.200 [CommandLine](FAULT): Scheduling is paused
2013-11-08T23:01:00.030Z,1383951660.030 [NAL9602](IMPORTANT): GPS fix at: 1383951682.00
2013-11-08T23:01:00.105Z,1383951660.105 [CBIT](IMPORTANT): No ground fault detected
2013-11-08T23:01:07.367Z,1383951667.367 [NAL9602](INFO): Powering down
2013-11-08T23:01:12.752Z,1383951672.752 [CommandLine](IMPORTANT): got command failComponent
2013-11-08T23:01:12.752Z,1383951672.752 [CommandLine](IMPORTANT): Failed components:
2013-11-08T23:01:12.752Z,1383951672.752 [CommandLine](IMPORTANT): DataOverHttps: Hardware Fault
2013-11-08T23:01:27.087Z,1383951687.087 [SBIT](IMPORTANT): SBIT PASSED
2013-11-08T23:01:27.479Z,1383951687.479 [MissionManager](IMPORTANT): Started mission Startup
2013-11-08T23:01:27.479Z,1383951687.479 [Startup] Running Loop=1
2013-11-08T23:01:27.479Z,1383951687.479 [Startup](INFO): Aggregate::initialize Startup
2013-11-08T23:01:27.479Z,1383951687.479 [Startup:A.GoToSurface] Running Loop=1
2013-11-08T23:01:27.479Z,1383951687.479 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-11-08T23:01:27.485Z,1383951687.485 [Startup:StartupSatComms] Running Loop=1
2013-11-08T23:01:27.485Z,1383951687.485 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2013-11-08T23:01:27.485Z,1383951687.485 [Startup:StartupSatComms:A] Running Loop=1
2013-11-08T23:01:27.876Z,1383951687.876 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-11-08T23:01:28.249Z,1383951688.249 [NAL9602](INFO): Powering up
2013-11-08T23:01:38.965Z,1383951698.965 [NAL9602](INFO): NAL9602 initialized
2013-11-08T23:01:42.562Z,1383951702.562 [CommandLine](IMPORTANT): got command show variable quality
2013-11-08T23:01:42.606Z,1383951702.606 [CommandLine](INFO): NAL9602.sigQuality (count)
2013-11-08T23:01:47.983Z,1383951707.983 [CommandLine](IMPORTANT): got command report mod NAL9602.sigQuality
2013-11-08T23:01:48.112Z,1383951708.112 [Reporter](INFO): NAL9602.sigQuality 0 count
2013-11-08T23:01:51.500Z,1383951711.500 [CommandLine](IMPORTANT): got command failComponent
2013-11-08T23:01:51.500Z,1383951711.500 [CommandLine](IMPORTANT): Failed components:
2013-11-08T23:01:51.500Z,1383951711.500 [CommandLine](IMPORTANT): DataOverHttps: Hardware Fault
2013-11-08T23:02:10.072Z,1383951730.072 [NAL9602](IMPORTANT): GPS fix at: 1383951752.00
2013-11-08T23:02:10.086Z,1383951730.086 [Startup:StartupSatComms:A] Stopped
2013-11-08T23:02:10.086Z,1383951730.086 [Startup:StartupSatComms:B] Running Loop=1
2013-11-08T23:02:10.495Z,1383951730.495 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2013-11-08T23:02:12.809Z,1383951732.809 [CommandLine](IMPORTANT): got command failComponent
2013-11-08T23:02:12.809Z,1383951732.809 [CommandLine](IMPORTANT): Failed components:
2013-11-08T23:02:12.810Z,1383951732.810 [CommandLine](IMPORTANT): DataOverHttps: Hardware Fault
2013-11-08T23:02:21.350Z,1383951741.350 [Reporter](INFO): NAL9602.sigQuality 5 count
2013-11-08T23:02:30.142Z,1383951750.142 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=20627, MT Status=1, MTMSN=1408
2013-11-08T23:02:30.200Z,1383951750.200 [NAL9602](INFO): Sent 207 bytes from file Logs/20131108T213212/Courier0004.lzma
2013-11-08T23:02:30.200Z,1383951750.200 [NAL9602](INFO): Packets left to send: 0
2013-11-08T23:02:30.202Z,1383951750.202 [NAL9602](INFO): Stored copy of sent data in Logs/20131108T213212/Courier0004.lzma.parts/0000.sbd
2013-11-08T23:02:30.665Z,1383951750.665 [NAL9602](INFO): Received command:get nothing
2013-11-08T23:02:30.835Z,1383951750.835 [CommandLine](IMPORTANT): got command get 
2013-11-08T23:02:30.836Z,1383951750.836 [CommandLine](FAULT): Incomplete syntax. Try: help get
2013-11-08T23:02:56.653Z,1383951776.653 [NAL9602](INFO): SBD MO Status=2, MOMSN=20628, MT Status=2, MTMSN=0
2013-11-08T23:02:56.654Z,1383951776.654 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-11-08T23:03:01.593Z,1383951781.593 [Reporter](INFO): NAL9602.sigQuality 0 count
2013-11-08T23:03:06.040Z,1383951786.040 [CommandLine](IMPORTANT): got command failComponent
2013-11-08T23:03:06.040Z,1383951786.040 [CommandLine](IMPORTANT): Failed components:
2013-11-08T23:03:06.041Z,1383951786.041 [CommandLine](IMPORTANT): DataOverHttps: Hardware Fault
2013-11-08T23:03:10.786Z,1383951790.786 [Startup:StartupSatComms:B](INFO): Timed out from 2013-11-08T23:02:10.1Z
2013-11-08T23:03:10.787Z,1383951790.787 [Startup:StartupSatComms:B:A_Timeout] Running Loop=1
2013-11-08T23:03:10.787Z,1383951790.787 [Startup:StartupSatComms:B:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:B:A_Timeout
2013-11-08T23:03:10.787Z,1383951790.787 [Startup:StartupSatComms:B:A_Timeout](INFO): Completed Startup:StartupSatComms:B:A_Timeout
2013-11-08T23:03:10.787Z,1383951790.787 [Startup:StartupSatComms:B] Stopped
2013-11-08T23:03:10.787Z,1383951790.787 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2013-11-08T23:03:10.787Z,1383951790.787 [Startup:StartupSatComms] Stopped
2013-11-08T23:03:10.787Z,1383951790.787 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2013-11-08T23:03:10.788Z,1383951790.788 [Startup](INFO): Completed Startup
2013-11-08T23:03:10.788Z,1383951790.788 [Startup] Stopped
2013-11-08T23:03:10.789Z,1383951790.789 [Startup](INFO): Aggregate::uninitialize Startup
2013-11-08T23:03:10.789Z,1383951790.789 [Startup:A.GoToSurface] Stopped
2013-11-08T23:03:10.789Z,1383951790.789 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-11-08T23:03:11.194Z,1383951791.194 [MissionManager](IMPORTANT): Started mission Default
2013-11-08T23:03:11.194Z,1383951791.194 [Default] Running Loop=1
2013-11-08T23:03:11.194Z,1383951791.194 [Default](INFO): Aggregate::initialize Default
2013-11-08T23:03:11.194Z,1383951791.194 [Default:D.SetSpeed] Running Loop=1
2013-11-08T23:03:11.194Z,1383951791.194 [Default:D.SetSpeed](DEBUG): Initialize.
2013-11-08T23:03:11.194Z,1383951791.194 [Default:E.GoToSurface] Running Loop=1
2013-11-08T23:03:11.194Z,1383951791.194 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-11-08T23:03:11.195Z,1383951791.195 [Default:Iridium] Running Loop=1
2013-11-08T23:03:11.195Z,1383951791.195 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-11-08T23:03:11.195Z,1383951791.195 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-11-08T23:03:11.195Z,1383951791.195 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-11-08T23:03:11.195Z,1383951791.195 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-11-08T23:03:11.195Z,1383951791.195 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-11-08T23:03:11.196Z,1383951791.196 [Default:E.GoToSurface] Running Loop=1
2013-11-08T23:03:11.201Z,1383951791.201 [Default:D.SetSpeed] Running Loop=1
2013-11-08T23:03:11.207Z,1383951791.207 [Default:CallIridium] Running Loop=1
2013-11-08T23:03:11.207Z,1383951791.207 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-11-08T23:03:11.207Z,1383951791.207 [Default:CallIridium:A] Running Loop=1
2013-11-08T23:03:11.209Z,1383951791.209 [Default:CallIridium:A] Stopped
2013-11-08T23:03:11.209Z,1383951791.209 [Default:CallIridium:B] Running Loop=1
2013-11-08T23:03:11.209Z,1383951791.209 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-11-08T23:03:11.215Z,1383951791.215 [Default:Iridium:B.GoToSurface] Stopped
2013-11-08T23:03:11.215Z,1383951791.215 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-11-08T23:03:11.215Z,1383951791.215 [Default:Iridium:Read_Iridium] Running Loop=1
2013-11-08T23:03:11.215Z,1383951791.215 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-11-08T23:03:11.220Z,1383951791.220 [Default:GPS] Running Loop=1
2013-11-08T23:03:11.221Z,1383951791.221 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-11-08T23:03:11.221Z,1383951791.221 [Default:GPS:A.SetSpeed] Running Loop=1
2013-11-08T23:03:11.221Z,1383951791.221 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-11-08T23:03:11.221Z,1383951791.221 [Default:GPS:B.GoToSurface] Running Loop=1
2013-11-08T23:03:11.221Z,1383951791.221 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-11-08T23:03:11.227Z,1383951791.227 [Default:GPS:B.GoToSurface] Stopped
2013-11-08T23:03:11.227Z,1383951791.227 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-11-08T23:03:11.227Z,1383951791.227 [Default:GPS:Read_GPS] Running Loop=1
2013-11-08T23:03:11.227Z,1383951791.227 [Default:GPS:A.SetSpeed] Running Loop=1
2013-11-08T23:03:11.650Z,1383951791.650 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2013-11-08T23:03:11.653Z,1383951791.653 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-11-08T23:03:13.970Z,1383951793.970 [NAL9602](IMPORTANT): GPS fix at: 1383951816.00
2013-11-08T23:03:13.986Z,1383951793.986 [Default:GPS:Read_GPS] Stopped
2013-11-08T23:03:13.986Z,1383951793.986 [Default:GPS:D] Running Loop=1
2013-11-08T23:03:14.403Z,1383951794.403 [Default:GPS:D] Stopped
2013-11-08T23:03:14.404Z,1383951794.404 [Default:GPS](INFO): Completed Default:GPS
2013-11-08T23:03:14.404Z,1383951794.404 [Default:GPS] Stopped
2013-11-08T23:03:14.404Z,1383951794.404 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-11-08T23:03:14.404Z,1383951794.404 [Default:GPS:A.SetSpeed] Stopped
2013-11-08T23:03:14.404Z,1383951794.404 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-11-08T23:03:24.629Z,1383951804.629 [CommandLine](IMPORTANT): got command failComponent none DataOverHttps
2013-11-08T23:03:24.629Z,1383951804.629 [DataOverHttps] No Fault, FailCount= 1
2013-11-08T23:03:24.629Z,1383951804.629 [CommandLine](IMPORTANT): DataOverHttps failureMode is No Fault
2013-11-08T23:03:25.766Z,1383951805.766 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:03:25.766Z,1383951805.766 [DataOverHttps](INFO): disconnect
2013-11-08T23:03:25.767Z,1383951805.767 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=178.202286
2013-11-08T23:03:37.897Z,1383951817.897 [CommandLine](IMPORTANT): got command failComponent hardware DataOverHttps
2013-11-08T23:03:37.897Z,1383951817.897 [DataOverHttps] Hardware Fault, FailCount= 2
2013-11-08T23:03:37.897Z,1383951817.897 [DataOverHttps](ERROR): Hardware Fault
2013-11-08T23:03:37.898Z,1383951817.898 [CommandLine](IMPORTANT): DataOverHttps failureMode is Hardware Fault
2013-11-08T23:03:37.971Z,1383951817.971 [DataOverHttps](INFO): disconnect
2013-11-08T23:03:38.021Z,1383951818.021 [CBIT](ERROR): Hardware Fault in component: DataOverHttps
2013-11-08T23:03:38.021Z,1383951818.021 [CBIT](CRITICAL): Hardware Fault in component: DataOverHttps
2013-11-08T23:03:40.377Z,1383951820.377 [CommandLine](IMPORTANT): got command failComponent
2013-11-08T23:03:40.377Z,1383951820.377 [CommandLine](IMPORTANT): Failed components:
2013-11-08T23:03:40.377Z,1383951820.377 [CommandLine](IMPORTANT): DataOverHttps: Hardware Fault
2013-11-08T23:04:22.206Z,1383951862.206 [CommandLine](IMPORTANT): got command failComponent
2013-11-08T23:04:22.206Z,1383951862.206 [CommandLine](IMPORTANT): Failed components:
2013-11-08T23:04:22.206Z,1383951862.206 [CommandLine](IMPORTANT): DataOverHttps: Hardware Fault
2013-11-08T23:04:23.636Z,1383951863.636 [CommandLine](IMPORTANT): got command show stack
2013-11-08T23:04:23.636Z,1383951863.636 [CommandLine](INFO): Behavior Stack:

2013-11-08T23:04:23.637Z,1383951863.637 [Default:Iridium](INFO): Priority 0: Default:Iridium:A.SetSpeed
2013-11-08T23:04:23.637Z,1383951863.637 [Default:Iridium](INFO): Priority 1: Default:Iridium:Read_Iridium
2013-11-08T23:04:23.637Z,1383951863.637 [Default](INFO): Priority 2: Default:D.SetSpeed
2013-11-08T23:04:23.638Z,1383951863.638 [Default](INFO): Priority 3: Default:E.GoToSurface
2013-11-08T23:04:28.027Z,1383951868.027 [CommandLine](IMPORTANT): got command report
2013-11-08T23:04:28.027Z,1383951868.027 [Reporter](INFO): NAL9602.sigQuality reporting changes
2013-11-08T23:04:30.290Z,1383951870.290 [CommandLine](IMPORTANT): got command show stack
2013-11-08T23:04:30.290Z,1383951870.290 [CommandLine](INFO): Behavior Stack:

2013-11-08T23:04:30.291Z,1383951870.291 [Default:Iridium](INFO): Priority 0: Default:Iridium:A.SetSpeed
2013-11-08T23:04:30.291Z,1383951870.291 [Default:Iridium](INFO): Priority 1: Default:Iridium:Read_Iridium
2013-11-08T23:04:30.292Z,1383951870.292 [Default](INFO): Priority 2: Default:D.SetSpeed
2013-11-08T23:04:30.292Z,1383951870.292 [Default](INFO): Priority 3: Default:E.GoToSurface
2013-11-08T23:04:37.421Z,1383951877.421 [CommandLine](IMPORTANT): got command run ./Missions/Default.xml
2013-11-08T23:04:37.422Z,1383951877.422 [MissionManager](INFO): Loading Mission: ./Missions/Default.xml
2013-11-08T23:04:37.447Z,1383951877.447 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2013-11-08T23:04:37.448Z,1383951877.448 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2013-11-08T23:04:37.451Z,1383951877.451 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-11-08T23:04:37.456Z,1383951877.456 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2013-11-08T23:04:37.479Z,1383951877.479 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-11-08T23:04:37.483Z,1383951877.483 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2013-11-08T23:04:37.495Z,1383951877.495 [Default:D.SetSpeed](DEBUG): Construct.
2013-11-08T23:04:37.497Z,1383951877.497 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2013-11-08T23:04:37.500Z,1383951877.500 [Default:F.Wait](DEBUG): Construct Wait.
2013-11-08T23:04:37.504Z,1383951877.504 [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:04:37.514Z,1383951877.514 [CommandLine](IMPORTANT): Running ./Missions/Default.xml
2013-11-08T23:04:37.522Z,1383951877.522 [Default] Stopped
2013-11-08T23:04:37.522Z,1383951877.522 [Default](INFO): Aggregate::uninitialize Default
2013-11-08T23:04:37.523Z,1383951877.523 [Default:Iridium] Stopped
2013-11-08T23:04:37.523Z,1383951877.523 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-11-08T23:04:37.523Z,1383951877.523 [Default:Iridium:A.SetSpeed] Stopped
2013-11-08T23:04:37.523Z,1383951877.523 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-11-08T23:04:37.523Z,1383951877.523 [Default:Iridium:Read_Iridium] Stopped
2013-11-08T23:04:37.523Z,1383951877.523 [Default:CallIridium] Stopped
2013-11-08T23:04:37.523Z,1383951877.523 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-11-08T23:04:37.523Z,1383951877.523 [Default:CallIridium:B] Stopped
2013-11-08T23:04:37.523Z,1383951877.523 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-11-08T23:04:37.523Z,1383951877.523 [Default:D.SetSpeed] Stopped
2013-11-08T23:04:37.523Z,1383951877.523 [Default:D.SetSpeed](DEBUG): Uninitialize.
2013-11-08T23:04:37.524Z,1383951877.524 [Default:E.GoToSurface] Stopped
2013-11-08T23:04:37.524Z,1383951877.524 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-11-08T23:04:37.524Z,1383951877.524 [MissionManager](IMPORTANT): Started mission Default
2013-11-08T23:04:37.524Z,1383951877.524 [Default] Running Loop=1
2013-11-08T23:04:37.524Z,1383951877.524 [Default](INFO): Aggregate::initialize Default
2013-11-08T23:04:37.524Z,1383951877.524 [Default:D.SetSpeed] Running Loop=1
2013-11-08T23:04:37.524Z,1383951877.524 [Default:D.SetSpeed](DEBUG): Initialize.
2013-11-08T23:04:37.524Z,1383951877.524 [Default:E.GoToSurface] Running Loop=1
2013-11-08T23:04:37.524Z,1383951877.524 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-11-08T23:04:37.525Z,1383951877.525 [Default:Iridium] Running Loop=1
2013-11-08T23:04:37.525Z,1383951877.525 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-11-08T23:04:37.525Z,1383951877.525 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-11-08T23:04:37.525Z,1383951877.525 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-11-08T23:04:37.525Z,1383951877.525 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-11-08T23:04:37.525Z,1383951877.525 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-11-08T23:04:37.526Z,1383951877.526 [Default:E.GoToSurface] Running Loop=1
2013-11-08T23:04:37.527Z,1383951877.527 [Default:D.SetSpeed] Running Loop=1
2013-11-08T23:04:37.528Z,1383951877.528 [Default:CallIridium] Running Loop=1
2013-11-08T23:04:37.528Z,1383951877.528 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-11-08T23:04:37.528Z,1383951877.528 [Default:CallIridium:A] Running Loop=1
2013-11-08T23:04:37.529Z,1383951877.529 [Default:CallIridium:A] Stopped
2013-11-08T23:04:37.529Z,1383951877.529 [Default:CallIridium:B] Running Loop=1
2013-11-08T23:04:37.529Z,1383951877.529 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-11-08T23:04:37.531Z,1383951877.531 [Default:Iridium:B.GoToSurface] Stopped
2013-11-08T23:04:37.531Z,1383951877.531 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-11-08T23:04:37.531Z,1383951877.531 [Default:Iridium:Read_Iridium] Running Loop=1
2013-11-08T23:04:37.531Z,1383951877.531 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-11-08T23:04:37.532Z,1383951877.532 [Default:GPS] Running Loop=1
2013-11-08T23:04:37.532Z,1383951877.532 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-11-08T23:04:37.532Z,1383951877.532 [Default:GPS:A.SetSpeed] Running Loop=1
2013-11-08T23:04:37.532Z,1383951877.532 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-11-08T23:04:37.532Z,1383951877.532 [Default:GPS:B.GoToSurface] Running Loop=1
2013-11-08T23:04:37.532Z,1383951877.532 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-11-08T23:04:37.534Z,1383951877.534 [Default:GPS:B.GoToSurface] Stopped
2013-11-08T23:04:37.534Z,1383951877.534 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-11-08T23:04:37.534Z,1383951877.534 [Default:GPS:Read_GPS] Running Loop=1
2013-11-08T23:04:37.534Z,1383951877.534 [Default:GPS:A.SetSpeed] Running Loop=1
2013-11-08T23:04:37.984Z,1383951877.984 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2013-11-08T23:04:37.985Z,1383951877.985 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-11-08T23:04:39.410Z,1383951879.410 [NAL9602](IMPORTANT): GPS fix at: 1383951902.00
2013-11-08T23:04:39.426Z,1383951879.426 [Default:GPS:Read_GPS] Stopped
2013-11-08T23:04:39.426Z,1383951879.426 [Default:GPS:D] Running Loop=1
2013-11-08T23:04:39.855Z,1383951879.855 [Default:GPS:D] Stopped
2013-11-08T23:04:39.855Z,1383951879.855 [Default:GPS](INFO): Completed Default:GPS
2013-11-08T23:04:39.856Z,1383951879.856 [Default:GPS] Stopped
2013-11-08T23:04:39.856Z,1383951879.856 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-11-08T23:04:39.856Z,1383951879.856 [Default:GPS:A.SetSpeed] Stopped
2013-11-08T23:04:39.856Z,1383951879.856 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-11-08T23:04:50.015Z,1383951890.015 [Reporter](INFO): NAL9602.sigQuality 2 count
2013-11-08T23:05:04.568Z,1383951904.568 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=20628, MT Status=1, MTMSN=1409
2013-11-08T23:05:04.616Z,1383951904.616 [NAL9602](INFO): Sent 60 bytes from file Logs/20131108T213212/Courier0008.lzma
2013-11-08T23:05:04.616Z,1383951904.616 [NAL9602](INFO): Packets left to send: 0
2013-11-08T23:05:04.617Z,1383951904.617 [NAL9602](INFO): Stored copy of sent data in Logs/20131108T213212/Courier0008.lzma.parts/0000.sbd
2013-11-08T23:05:05.112Z,1383951905.112 [NAL9602](INFO): Received command:get unicorns
2013-11-08T23:05:05.332Z,1383951905.332 [CommandLine](IMPORTANT): got command get 
2013-11-08T23:05:05.332Z,1383951905.332 [CommandLine](FAULT): Incomplete syntax. Try: help get
2013-11-08T23:05:09.493Z,1383951909.493 [Reporter](INFO): NAL9602.sigQuality 4 count
2013-11-08T23:05:24.154Z,1383951924.154 [CommandLine](IMPORTANT): got command failComponent none DataOverHttps
2013-11-08T23:05:24.154Z,1383951924.154 [DataOverHttps] No Fault, FailCount= 2
2013-11-08T23:05:24.155Z,1383951924.155 [CommandLine](IMPORTANT): DataOverHttps failureMode is No Fault
2013-11-08T23:05:24.636Z,1383951924.636 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:05:25.040Z,1383951925.040 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:05:25.041Z,1383951925.041 [DataOverHttps](INFO): sslConnect
2013-11-08T23:05:25.042Z,1383951925.042 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:05:25.449Z,1383951925.449 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:05:26.318Z,1383951926.318 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:05:26.320Z,1383951926.320 [DataOverHttps](INFO): dataWrite
2013-11-08T23:05:26.321Z,1383951926.321 [DataOverHttps](INFO): dataWriting
2013-11-08T23:05:26.322Z,1383951926.322 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:05:26.727Z,1383951926.727 [DataOverHttps](INFO): dataRead
2013-11-08T23:05:27.144Z,1383951927.144 [DataOverHttps](INFO): dataRead
2013-11-08T23:05:27.145Z,1383951927.145 [DataOverHttps](IMPORTANT): SBD MTMSN=20131108T230550
2013-11-08T23:05:27.145Z,1383951927.145 [DataOverHttps](INFO): disconnect
2013-11-08T23:05:27.148Z,1383951927.148 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002807
2013-11-08T23:05:27.813Z,1383951927.813 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:05:28.198Z,1383951928.198 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:05:28.198Z,1383951928.198 [DataOverHttps](INFO): sslConnect
2013-11-08T23:05:28.199Z,1383951928.199 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:05:28.606Z,1383951928.606 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:05:29.471Z,1383951929.471 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:05:29.473Z,1383951929.473 [DataOverHttps](INFO): dataWrite
2013-11-08T23:05:29.475Z,1383951929.475 [DataOverHttps](INFO): encoded 650 bytes: %5d%00%00%04%00%24%02%00%00%00%00%00%00%00%15%1d%80%03%0cVb%01%11H%e2%fa%22%18%8bj8%8e%ec%c3%a20I7%c6%0d%a56%fa%f3s%eb%dc%ebSp%86%09%0b%07%b1%5ce%5c%86v2%8eo%fd%5b%c7s%15%00%dbAD%b8%a2%93%cf%8bihyk%ed%9a3%e3%a2%d4%ab%0c%9d5%94%07%5e%40%05Izw%91%bbPg%cd%d93%a9%fee%d2D%03N%91%29%d9%3f%82%9cw%bd%0b%ac%10%00v%0a%80%8d%81%0d%ba%8f%11%aa%27%be%1a%01%d7%c7%16%f4%10%ac%e0T%c5%60%df%cc%fc%24%3c%19%e0R%3f%f6%e5RN%3b%1dv%ba%7d%25%06%fc8_%fd%7c%22D%0bUg%11%5bsD%3d%14%14%1b-Wa%f7%2f%df%f7%1cy%13%d9%c5K_E%3d0%bd%fe%1eP%04%c3%d1%e6%07%a2K%a8%b5%90%25%03%f3%a44%099%bf%a3%2fH%ce%2b%e4%5d%e8%96%3e%ce%21%f3%15m%17%da%cb%85s%28S%7bwX%cc%5e%a1%05%c2%b8%b1D%10%c8X
2013-11-08T23:05:29.476Z,1383951929.476 [DataOverHttps](INFO): Sending 262 bytes from file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:05:29.476Z,1383951929.476 [DataOverHttps](INFO): dataWriting
2013-11-08T23:05:29.479Z,1383951929.479 [DataOverHttps](INFO): Wrote 945 bytes.
2013-11-08T23:05:29.479Z,1383951929.479 [DataOverHttps](INFO): Received command:get mo problems
2013-11-08T23:05:29.763Z,1383951929.763 [CommandLine](IMPORTANT): got command get 
2013-11-08T23:05:29.763Z,1383951929.763 [CommandLine](FAULT): Incomplete syntax. Try: help get
2013-11-08T23:05:30.343Z,1383951930.343 [DataOverHttps](INFO): dataRead
2013-11-08T23:05:30.745Z,1383951930.745 [DataOverHttps](INFO): dataRead
2013-11-08T23:05:31.142Z,1383951931.142 [DataOverHttps](INFO): dataRead
2013-11-08T23:05:31.142Z,1383951931.142 [DataOverHttps](INFO): disconnect
2013-11-08T23:05:31.564Z,1383951931.564 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:05:31.951Z,1383951931.951 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:05:31.951Z,1383951931.951 [DataOverHttps](INFO): sslConnect
2013-11-08T23:05:31.952Z,1383951931.952 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:05:32.352Z,1383951932.352 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:05:33.273Z,1383951933.273 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:05:33.275Z,1383951933.275 [DataOverHttps](INFO): dataWrite
2013-11-08T23:05:33.277Z,1383951933.277 [DataOverHttps](INFO): encoded 650 bytes: %5d%00%00%04%00%24%02%00%00%00%00%00%00%00%15%1d%80%03%0cVb%01%11H%e2%fa%22%18%8bj8%8e%ec%c3%a20I7%c6%0d%a56%fa%f3s%eb%dc%ebSp%86%09%0b%07%b1%5ce%5c%86v2%8eo%fd%5b%c7s%15%00%dbAD%b8%a2%93%cf%8bihyk%ed%9a3%e3%a2%d4%ab%0c%9d5%94%07%5e%40%05Izw%91%bbPg%cd%d93%a9%fee%d2D%03N%91%29%d9%3f%82%9cw%bd%0b%ac%10%00v%0a%80%8d%81%0d%ba%8f%11%aa%27%be%1a%01%d7%c7%16%f4%10%ac%e0T%c5%60%df%cc%fc%24%3c%19%e0R%3f%f6%e5RN%3b%1dv%ba%7d%25%06%fc8_%fd%7c%22D%0bUg%11%5bsD%3d%14%14%1b-Wa%f7%2f%df%f7%1cy%13%d9%c5K_E%3d0%bd%fe%1eP%04%c3%d1%e6%07%a2K%a8%b5%90%25%03%f3%a44%099%bf%a3%2fH%ce%2b%e4%5d%e8%96%3e%ce%21%f3%15m%17%da%cb%85s%28S%7bwX%cc%5e%a1%05%c2%b8%b1D%10%c8X
2013-11-08T23:05:33.278Z,1383951933.278 [DataOverHttps](INFO): Sending 262 bytes from file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:05:33.278Z,1383951933.278 [DataOverHttps](INFO): dataWriting
2013-11-08T23:05:33.281Z,1383951933.281 [DataOverHttps](INFO): Wrote 923 bytes.
2013-11-08T23:05:33.651Z,1383951933.651 [DataOverHttps](INFO): dataRead
2013-11-08T23:05:34.064Z,1383951934.064 [DataOverHttps](INFO): dataRead
2013-11-08T23:05:34.467Z,1383951934.467 [DataOverHttps](INFO): dataRead
2013-11-08T23:05:34.904Z,1383951934.904 [DataOverHttps](INFO): dataRead
2013-11-08T23:05:34.904Z,1383951934.904 [DataOverHttps](INFO): disconnect
2013-11-08T23:05:35.304Z,1383951935.304 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:05:35.737Z,1383951935.737 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:05:35.737Z,1383951935.737 [DataOverHttps](INFO): sslConnect
2013-11-08T23:05:35.738Z,1383951935.738 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:05:36.130Z,1383951936.130 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:05:37.007Z,1383951937.007 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:05:37.009Z,1383951937.009 [DataOverHttps](INFO): dataWrite
2013-11-08T23:05:37.011Z,1383951937.011 [DataOverHttps](INFO): encoded 650 bytes: %5d%00%00%04%00%24%02%00%00%00%00%00%00%00%15%1d%80%03%0cVb%01%11H%e2%fa%22%18%8bj8%8e%ec%c3%a20I7%c6%0d%a56%fa%f3s%eb%dc%ebSp%86%09%0b%07%b1%5ce%5c%86v2%8eo%fd%5b%c7s%15%00%dbAD%b8%a2%93%cf%8bihyk%ed%9a3%e3%a2%d4%ab%0c%9d5%94%07%5e%40%05Izw%91%bbPg%cd%d93%a9%fee%d2D%03N%91%29%d9%3f%82%9cw%bd%0b%ac%10%00v%0a%80%8d%81%0d%ba%8f%11%aa%27%be%1a%01%d7%c7%16%f4%10%ac%e0T%c5%60%df%cc%fc%24%3c%19%e0R%3f%f6%e5RN%3b%1dv%ba%7d%25%06%fc8_%fd%7c%22D%0bUg%11%5bsD%3d%14%14%1b-Wa%f7%2f%df%f7%1cy%13%d9%c5K_E%3d0%bd%fe%1eP%04%c3%d1%e6%07%a2K%a8%b5%90%25%03%f3%a44%099%bf%a3%2fH%ce%2b%e4%5d%e8%96%3e%ce%21%f3%15m%17%da%cb%85s%28S%7bwX%cc%5e%a1%05%c2%b8%b1D%10%c8X
2013-11-08T23:05:37.012Z,1383951937.012 [DataOverHttps](INFO): Sending 262 bytes from file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:05:37.012Z,1383951937.012 [DataOverHttps](INFO): dataWriting
2013-11-08T23:05:37.014Z,1383951937.014 [DataOverHttps](INFO): Wrote 923 bytes.
2013-11-08T23:05:37.400Z,1383951937.400 [DataOverHttps](INFO): dataRead
2013-11-08T23:05:37.797Z,1383951937.797 [DataOverHttps](INFO): dataRead
2013-11-08T23:05:38.201Z,1383951938.201 [DataOverHttps](INFO): dataRead
2013-11-08T23:05:38.590Z,1383951938.590 [DataOverHttps](INFO): dataRead
2013-11-08T23:05:38.590Z,1383951938.590 [DataOverHttps](INFO): disconnect
2013-11-08T23:05:39.010Z,1383951939.010 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:05:39.399Z,1383951939.399 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:05:39.399Z,1383951939.399 [DataOverHttps](INFO): sslConnect
2013-11-08T23:05:39.400Z,1383951939.400 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:05:39.795Z,1383951939.795 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:05:40.653Z,1383951940.653 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:05:40.655Z,1383951940.655 [DataOverHttps](INFO): dataWrite
2013-11-08T23:05:40.656Z,1383951940.656 [DataOverHttps](INFO): encoded 650 bytes: %5d%00%00%04%00%24%02%00%00%00%00%00%00%00%15%1d%80%03%0cVb%01%11H%e2%fa%22%18%8bj8%8e%ec%c3%a20I7%c6%0d%a56%fa%f3s%eb%dc%ebSp%86%09%0b%07%b1%5ce%5c%86v2%8eo%fd%5b%c7s%15%00%dbAD%b8%a2%93%cf%8bihyk%ed%9a3%e3%a2%d4%ab%0c%9d5%94%07%5e%40%05Izw%91%bbPg%cd%d93%a9%fee%d2D%03N%91%29%d9%3f%82%9cw%bd%0b%ac%10%00v%0a%80%8d%81%0d%ba%8f%11%aa%27%be%1a%01%d7%c7%16%f4%10%ac%e0T%c5%60%df%cc%fc%24%3c%19%e0R%3f%f6%e5RN%3b%1dv%ba%7d%25%06%fc8_%fd%7c%22D%0bUg%11%5bsD%3d%14%14%1b-Wa%f7%2f%df%f7%1cy%13%d9%c5K_E%3d0%bd%fe%1eP%04%c3%d1%e6%07%a2K%a8%b5%90%25%03%f3%a44%099%bf%a3%2fH%ce%2b%e4%5d%e8%96%3e%ce%21%f3%15m%17%da%cb%85s%28S%7bwX%cc%5e%a1%05%c2%b8%b1D%10%c8X
2013-11-08T23:05:40.657Z,1383951940.657 [DataOverHttps](INFO): Sending 262 bytes from file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:05:40.657Z,1383951940.657 [DataOverHttps](INFO): dataWriting
2013-11-08T23:05:40.660Z,1383951940.660 [DataOverHttps](INFO): Wrote 923 bytes.
2013-11-08T23:05:41.060Z,1383951941.060 [DataOverHttps](INFO): dataRead
2013-11-08T23:05:41.474Z,1383951941.474 [DataOverHttps](INFO): dataRead
2013-11-08T23:05:41.483Z,1383951941.483 [NAL9602](INFO): SBD MO Status=1, MOMSN=20629, MT Status=0, MTMSN=0
2013-11-08T23:05:41.533Z,1383951941.533 [NAL9602](INFO): Sent 262 bytes from file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:05:41.533Z,1383951941.533 [NAL9602](INFO): Packets left to send: 0
2013-11-08T23:05:41.535Z,1383951941.535 [NAL9602](INFO): Stored copy of sent data in Logs/20131108T225718/Courier0000.lzma.parts/0000.sbd
2013-11-08T23:05:41.947Z,1383951941.947 [DataOverHttps](INFO): dataRead
2013-11-08T23:05:42.676Z,1383951942.676 [DataOverHttps](INFO): dataRead
2013-11-08T23:05:42.676Z,1383951942.676 [DataOverHttps](INFO): disconnect
2013-11-08T23:05:45.132Z,1383951945.132 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:05:45.536Z,1383951945.536 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:05:45.537Z,1383951945.537 [DataOverHttps](INFO): sslConnect
2013-11-08T23:05:45.538Z,1383951945.538 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:05:45.953Z,1383951945.953 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:05:46.834Z,1383951946.834 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:05:46.836Z,1383951946.836 [DataOverHttps](INFO): dataWrite
2013-11-08T23:05:46.837Z,1383951946.837 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:05:46.837Z,1383951946.837 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:05:46.837Z,1383951946.837 [DataOverHttps](INFO): dataWriting
2013-11-08T23:05:46.838Z,1383951946.838 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:05:47.231Z,1383951947.231 [DataOverHttps](INFO): dataRead
2013-11-08T23:05:47.254Z,1383951947.254 [Default] Stopped
2013-11-08T23:05:47.254Z,1383951947.254 [Default](INFO): Aggregate::uninitialize Default
2013-11-08T23:05:47.254Z,1383951947.254 [Default:Iridium] Stopped
2013-11-08T23:05:47.255Z,1383951947.255 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-11-08T23:05:47.255Z,1383951947.255 [Default:Iridium:A.SetSpeed] Stopped
2013-11-08T23:05:47.255Z,1383951947.255 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-11-08T23:05:47.255Z,1383951947.255 [Default:Iridium:Read_Iridium] Stopped
2013-11-08T23:05:47.255Z,1383951947.255 [Default:CallIridium] Stopped
2013-11-08T23:05:47.255Z,1383951947.255 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-11-08T23:05:47.255Z,1383951947.255 [Default:CallIridium:B] Stopped
2013-11-08T23:05:47.255Z,1383951947.255 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-11-08T23:05:47.255Z,1383951947.255 [Default:D.SetSpeed] Stopped
2013-11-08T23:05:47.255Z,1383951947.255 [Default:D.SetSpeed](DEBUG): Uninitialize.
2013-11-08T23:05:47.255Z,1383951947.255 [Default:E.GoToSurface] Stopped
2013-11-08T23:05:47.256Z,1383951947.256 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-11-08T23:05:47.656Z,1383951947.656 [DataOverHttps](INFO): dataRead
2013-11-08T23:05:47.656Z,1383951947.656 [DataOverHttps](IMPORTANT): SBD MTMSN=20131108T230610
2013-11-08T23:05:47.657Z,1383951947.657 [DataOverHttps](INFO): disconnect
2013-11-08T23:05:47.703Z,1383951947.703 [MissionManager](IMPORTANT): Started mission Default
2013-11-08T23:05:47.703Z,1383951947.703 [Default] Running Loop=1
2013-11-08T23:05:47.703Z,1383951947.703 [Default](INFO): Aggregate::initialize Default
2013-11-08T23:05:47.704Z,1383951947.704 [Default:D.SetSpeed] Running Loop=1
2013-11-08T23:05:47.704Z,1383951947.704 [Default:D.SetSpeed](DEBUG): Initialize.
2013-11-08T23:05:47.704Z,1383951947.704 [Default:E.GoToSurface] Running Loop=1
2013-11-08T23:05:47.704Z,1383951947.704 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-11-08T23:05:47.704Z,1383951947.704 [Default:Iridium] Running Loop=1
2013-11-08T23:05:47.704Z,1383951947.704 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-11-08T23:05:47.705Z,1383951947.705 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-11-08T23:05:47.705Z,1383951947.705 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-11-08T23:05:47.705Z,1383951947.705 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-11-08T23:05:47.705Z,1383951947.705 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-11-08T23:05:47.706Z,1383951947.706 [Default:CallIridium] Running Loop=1
2013-11-08T23:05:47.706Z,1383951947.706 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-11-08T23:05:47.707Z,1383951947.707 [Default:CallIridium:A] Running Loop=1
2013-11-08T23:05:47.707Z,1383951947.707 [Default:CallIridium:A] Stopped
2013-11-08T23:05:47.707Z,1383951947.707 [Default:CallIridium:B] Running Loop=1
2013-11-08T23:05:47.707Z,1383951947.707 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-11-08T23:05:47.708Z,1383951947.708 [Default:Iridium:B.GoToSurface] Stopped
2013-11-08T23:05:47.708Z,1383951947.708 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-11-08T23:05:47.708Z,1383951947.708 [Default:Iridium:Read_Iridium] Running Loop=1
2013-11-08T23:05:48.068Z,1383951948.068 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:05:48.089Z,1383951948.089 [Default:GPS] Running Loop=1
2013-11-08T23:05:48.089Z,1383951948.089 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-11-08T23:05:48.089Z,1383951948.089 [Default:GPS:A.SetSpeed] Running Loop=1
2013-11-08T23:05:48.089Z,1383951948.089 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-11-08T23:05:48.089Z,1383951948.089 [Default:GPS:B.GoToSurface] Running Loop=1
2013-11-08T23:05:48.090Z,1383951948.090 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-11-08T23:05:48.090Z,1383951948.090 [Default:GPS:B.GoToSurface] Stopped
2013-11-08T23:05:48.091Z,1383951948.091 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-11-08T23:05:48.091Z,1383951948.091 [Default:GPS:Read_GPS] Running Loop=1
2013-11-08T23:05:48.685Z,1383951948.685 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:05:48.685Z,1383951948.685 [DataOverHttps](INFO): sslConnect
2013-11-08T23:05:48.686Z,1383951948.686 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:05:49.118Z,1383951949.118 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:05:50.031Z,1383951950.031 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:05:50.033Z,1383951950.033 [DataOverHttps](INFO): dataWrite
2013-11-08T23:05:50.034Z,1383951950.034 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:05:50.034Z,1383951950.034 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:05:50.034Z,1383951950.034 [DataOverHttps](INFO): dataWriting
2013-11-08T23:05:50.036Z,1383951950.036 [DataOverHttps](INFO): Wrote 228 bytes.
2013-11-08T23:05:50.036Z,1383951950.036 [DataOverHttps](INFO): Received command:get mo problems
2013-11-08T23:05:50.316Z,1383951950.316 [CommandLine](IMPORTANT): got command get 
2013-11-08T23:05:50.317Z,1383951950.317 [CommandLine](FAULT): Incomplete syntax. Try: help get
2013-11-08T23:05:50.853Z,1383951950.853 [DataOverHttps](INFO): dataRead
2013-11-08T23:05:51.241Z,1383951951.241 [DataOverHttps](INFO): dataRead
2013-11-08T23:05:51.242Z,1383951951.242 [DataOverHttps](INFO): disconnect
2013-11-08T23:05:51.638Z,1383951951.638 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:05:52.043Z,1383951952.043 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:05:52.043Z,1383951952.043 [DataOverHttps](INFO): sslConnect
2013-11-08T23:05:52.044Z,1383951952.044 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:05:52.443Z,1383951952.443 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:05:53.321Z,1383951953.321 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:05:53.323Z,1383951953.323 [DataOverHttps](INFO): dataWrite
2013-11-08T23:05:53.323Z,1383951953.323 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:05:53.324Z,1383951953.324 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:05:53.324Z,1383951953.324 [DataOverHttps](INFO): dataWriting
2013-11-08T23:05:53.325Z,1383951953.325 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:05:53.733Z,1383951953.733 [DataOverHttps](INFO): dataRead
2013-11-08T23:05:53.742Z,1383951953.742 [NAL9602](INFO): SBD MO Status=1, MOMSN=20630, MT Status=0, MTMSN=0
2013-11-08T23:05:53.804Z,1383951953.804 [NAL9602](INFO): Sent 25 bytes from file Logs/20131108T225718/Courier0004.lzma
2013-11-08T23:05:53.804Z,1383951953.804 [NAL9602](INFO): Packets left to send: 0
2013-11-08T23:05:53.826Z,1383951953.826 [NAL9602](INFO): Stored copy of sent data in Logs/20131108T225718/Courier0004.lzma.parts/0000.sbd
2013-11-08T23:05:54.246Z,1383951954.246 [DataOverHttps](INFO): dataRead
2013-11-08T23:05:54.246Z,1383951954.246 [DataOverHttps](INFO): disconnect
2013-11-08T23:05:54.975Z,1383951954.975 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:05:57.439Z,1383951957.439 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:05:57.439Z,1383951957.439 [DataOverHttps](INFO): sslConnect
2013-11-08T23:05:57.440Z,1383951957.440 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:05:57.844Z,1383951957.844 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:05:58.705Z,1383951958.705 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:05:58.707Z,1383951958.707 [DataOverHttps](INFO): dataWrite
2013-11-08T23:05:58.707Z,1383951958.707 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:05:58.707Z,1383951958.707 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:05:58.707Z,1383951958.707 [DataOverHttps](INFO): dataWriting
2013-11-08T23:05:58.709Z,1383951958.709 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:05:59.109Z,1383951959.109 [DataOverHttps](INFO): dataRead
2013-11-08T23:05:59.514Z,1383951959.514 [DataOverHttps](INFO): dataRead
2013-11-08T23:05:59.515Z,1383951959.515 [DataOverHttps](INFO): disconnect
2013-11-08T23:05:59.895Z,1383951959.895 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:06:00.307Z,1383951960.307 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:06:00.307Z,1383951960.307 [DataOverHttps](INFO): sslConnect
2013-11-08T23:06:00.309Z,1383951960.309 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:00.736Z,1383951960.736 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:01.573Z,1383951961.573 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:01.575Z,1383951961.575 [DataOverHttps](INFO): dataWrite
2013-11-08T23:06:01.575Z,1383951961.575 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:01.576Z,1383951961.576 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:01.576Z,1383951961.576 [DataOverHttps](INFO): dataWriting
2013-11-08T23:06:01.577Z,1383951961.577 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:06:01.960Z,1383951961.960 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:02.398Z,1383951962.398 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:02.399Z,1383951962.399 [DataOverHttps](INFO): disconnect
2013-11-08T23:06:02.771Z,1383951962.771 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:06:03.168Z,1383951963.168 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:06:03.168Z,1383951963.168 [DataOverHttps](INFO): sslConnect
2013-11-08T23:06:03.169Z,1383951963.169 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:03.580Z,1383951963.580 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:04.060Z,1383951964.060 [NAL9602](INFO): SBD MO Status=1, MOMSN=20631, MT Status=0, MTMSN=0
2013-11-08T23:06:04.108Z,1383951964.108 [NAL9602](INFO): Sent 272 bytes from file Logs/20131108T230007/Courier0000.lzma
2013-11-08T23:06:04.108Z,1383951964.108 [NAL9602](INFO): Packets left to send: 0
2013-11-08T23:06:04.110Z,1383951964.110 [NAL9602](INFO): Stored copy of sent data in Logs/20131108T230007/Courier0000.lzma.parts/0000.sbd
2013-11-08T23:06:04.510Z,1383951964.510 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:04.512Z,1383951964.512 [DataOverHttps](INFO): dataWrite
2013-11-08T23:06:04.512Z,1383951964.512 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:04.512Z,1383951964.512 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:04.512Z,1383951964.512 [DataOverHttps](INFO): dataWriting
2013-11-08T23:06:04.514Z,1383951964.514 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:06:05.264Z,1383951965.264 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:07.719Z,1383951967.719 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:07.719Z,1383951967.719 [DataOverHttps](INFO): disconnect
2013-11-08T23:06:08.121Z,1383951968.121 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:06:08.520Z,1383951968.520 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:06:08.520Z,1383951968.520 [DataOverHttps](INFO): sslConnect
2013-11-08T23:06:08.521Z,1383951968.521 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:08.937Z,1383951968.937 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:09.795Z,1383951969.795 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:09.797Z,1383951969.797 [DataOverHttps](INFO): dataWrite
2013-11-08T23:06:09.797Z,1383951969.797 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:09.797Z,1383951969.797 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:09.797Z,1383951969.797 [DataOverHttps](INFO): dataWriting
2013-11-08T23:06:09.799Z,1383951969.799 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:06:10.178Z,1383951970.178 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:10.579Z,1383951970.579 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:10.580Z,1383951970.580 [DataOverHttps](INFO): disconnect
2013-11-08T23:06:10.976Z,1383951970.976 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:06:11.376Z,1383951971.376 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:06:11.377Z,1383951971.377 [DataOverHttps](INFO): sslConnect
2013-11-08T23:06:11.378Z,1383951971.378 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:11.781Z,1383951971.781 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:12.634Z,1383951972.634 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:12.636Z,1383951972.636 [DataOverHttps](INFO): dataWrite
2013-11-08T23:06:12.637Z,1383951972.637 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:12.637Z,1383951972.637 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:12.637Z,1383951972.637 [DataOverHttps](INFO): dataWriting
2013-11-08T23:06:12.638Z,1383951972.638 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:06:13.043Z,1383951973.043 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:13.452Z,1383951973.452 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:13.452Z,1383951973.452 [DataOverHttps](INFO): disconnect
2013-11-08T23:06:13.852Z,1383951973.852 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:06:14.245Z,1383951974.245 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:06:14.245Z,1383951974.245 [DataOverHttps](INFO): sslConnect
2013-11-08T23:06:14.246Z,1383951974.246 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:14.662Z,1383951974.662 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:15.531Z,1383951975.531 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:15.533Z,1383951975.533 [DataOverHttps](INFO): dataWrite
2013-11-08T23:06:15.533Z,1383951975.533 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:15.534Z,1383951975.534 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:15.534Z,1383951975.534 [DataOverHttps](INFO): dataWriting
2013-11-08T23:06:15.535Z,1383951975.535 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:06:15.934Z,1383951975.934 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:16.341Z,1383951976.341 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:16.341Z,1383951976.341 [DataOverHttps](INFO): disconnect
2013-11-08T23:06:16.737Z,1383951976.737 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:06:17.133Z,1383951977.133 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:06:17.133Z,1383951977.133 [DataOverHttps](INFO): sslConnect
2013-11-08T23:06:17.135Z,1383951977.135 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:17.542Z,1383951977.542 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:18.431Z,1383951978.431 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:18.433Z,1383951978.433 [DataOverHttps](INFO): dataWrite
2013-11-08T23:06:18.434Z,1383951978.434 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:18.434Z,1383951978.434 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:18.434Z,1383951978.434 [DataOverHttps](INFO): dataWriting
2013-11-08T23:06:18.436Z,1383951978.436 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:06:18.868Z,1383951978.868 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:19.253Z,1383951979.253 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:19.253Z,1383951979.253 [DataOverHttps](INFO): disconnect
2013-11-08T23:06:19.661Z,1383951979.661 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:06:20.063Z,1383951980.063 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:06:20.063Z,1383951980.063 [DataOverHttps](INFO): sslConnect
2013-11-08T23:06:20.064Z,1383951980.064 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:20.462Z,1383951980.462 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:21.348Z,1383951981.348 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:21.350Z,1383951981.350 [DataOverHttps](INFO): dataWrite
2013-11-08T23:06:21.350Z,1383951981.350 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:21.351Z,1383951981.351 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:21.351Z,1383951981.351 [DataOverHttps](INFO): dataWriting
2013-11-08T23:06:21.352Z,1383951981.352 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:06:21.769Z,1383951981.769 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:22.173Z,1383951982.173 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:22.174Z,1383951982.174 [DataOverHttps](INFO): disconnect
2013-11-08T23:06:22.554Z,1383951982.554 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:06:22.974Z,1383951982.974 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:06:22.974Z,1383951982.974 [DataOverHttps](INFO): sslConnect
2013-11-08T23:06:22.976Z,1383951982.976 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:23.375Z,1383951983.375 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:24.244Z,1383951984.244 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:24.246Z,1383951984.246 [DataOverHttps](INFO): dataWrite
2013-11-08T23:06:24.247Z,1383951984.247 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:24.247Z,1383951984.247 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:24.247Z,1383951984.247 [DataOverHttps](INFO): dataWriting
2013-11-08T23:06:24.249Z,1383951984.249 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:06:24.664Z,1383951984.664 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:25.062Z,1383951985.062 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:25.062Z,1383951985.062 [DataOverHttps](INFO): disconnect
2013-11-08T23:06:25.474Z,1383951985.474 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:06:25.875Z,1383951985.875 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:06:25.875Z,1383951985.875 [DataOverHttps](INFO): sslConnect
2013-11-08T23:06:25.876Z,1383951985.876 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:26.291Z,1383951986.291 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:27.137Z,1383951987.137 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:27.139Z,1383951987.139 [DataOverHttps](INFO): dataWrite
2013-11-08T23:06:27.139Z,1383951987.139 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:27.140Z,1383951987.140 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:27.140Z,1383951987.140 [DataOverHttps](INFO): dataWriting
2013-11-08T23:06:27.141Z,1383951987.141 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:06:27.545Z,1383951987.545 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:27.960Z,1383951987.960 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:27.961Z,1383951987.961 [DataOverHttps](INFO): disconnect
2013-11-08T23:06:27.967Z,1383951987.967 [NAL9602](INFO): SBD MO Status=2, MOMSN=20632, MT Status=2, MTMSN=0
2013-11-08T23:06:27.967Z,1383951987.967 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-11-08T23:06:28.361Z,1383951988.361 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:06:28.747Z,1383951988.747 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:06:28.747Z,1383951988.747 [DataOverHttps](INFO): sslConnect
2013-11-08T23:06:28.748Z,1383951988.748 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:29.168Z,1383951989.168 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:29.664Z,1383951989.664 [NAL9602](IMPORTANT): GPS fix at: 1383952012.00
2013-11-08T23:06:29.680Z,1383951989.680 [Default:GPS:Read_GPS] Stopped
2013-11-08T23:06:29.681Z,1383951989.681 [Default:GPS:D] Running Loop=1
2013-11-08T23:06:30.059Z,1383951990.059 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:30.061Z,1383951990.061 [DataOverHttps](INFO): dataWrite
2013-11-08T23:06:30.061Z,1383951990.061 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:30.062Z,1383951990.062 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:30.062Z,1383951990.062 [DataOverHttps](INFO): dataWriting
2013-11-08T23:06:30.063Z,1383951990.063 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:06:30.114Z,1383951990.114 [Default:GPS:D] Stopped
2013-11-08T23:06:30.114Z,1383951990.114 [Default:GPS](INFO): Completed Default:GPS
2013-11-08T23:06:30.114Z,1383951990.114 [Default:GPS] Stopped
2013-11-08T23:06:30.114Z,1383951990.114 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-11-08T23:06:30.114Z,1383951990.114 [Default:GPS:A.SetSpeed] Stopped
2013-11-08T23:06:30.115Z,1383951990.115 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-11-08T23:06:30.594Z,1383951990.594 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:31.035Z,1383951991.035 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:31.036Z,1383951991.036 [DataOverHttps](INFO): disconnect
2013-11-08T23:06:31.436Z,1383951991.436 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:06:31.840Z,1383951991.840 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:06:31.840Z,1383951991.840 [DataOverHttps](INFO): sslConnect
2013-11-08T23:06:31.841Z,1383951991.841 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:32.233Z,1383951992.233 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:33.082Z,1383951993.082 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:33.084Z,1383951993.084 [DataOverHttps](INFO): dataWrite
2013-11-08T23:06:33.084Z,1383951993.084 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:33.085Z,1383951993.085 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:33.085Z,1383951993.085 [DataOverHttps](INFO): dataWriting
2013-11-08T23:06:33.086Z,1383951993.086 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:06:33.483Z,1383951993.483 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:33.879Z,1383951993.879 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:33.880Z,1383951993.880 [DataOverHttps](INFO): disconnect
2013-11-08T23:06:34.292Z,1383951994.292 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:06:34.669Z,1383951994.669 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:06:34.669Z,1383951994.669 [DataOverHttps](INFO): sslConnect
2013-11-08T23:06:34.670Z,1383951994.670 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:35.085Z,1383951995.085 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:35.982Z,1383951995.982 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:35.984Z,1383951995.984 [DataOverHttps](INFO): dataWrite
2013-11-08T23:06:35.985Z,1383951995.985 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:35.985Z,1383951995.985 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:35.985Z,1383951995.985 [DataOverHttps](INFO): dataWriting
2013-11-08T23:06:35.987Z,1383951995.987 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:06:36.371Z,1383951996.371 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:36.378Z,1383951996.378 [NAL9602](INFO): Powering down
2013-11-08T23:06:36.405Z,1383951996.405 [Reporter](INFO): NAL9602.sigQuality 0 count
2013-11-08T23:06:36.784Z,1383951996.784 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:36.784Z,1383951996.784 [DataOverHttps](INFO): disconnect
2013-11-08T23:06:37.172Z,1383951997.172 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:06:37.581Z,1383951997.581 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:06:37.581Z,1383951997.581 [DataOverHttps](INFO): sslConnect
2013-11-08T23:06:37.582Z,1383951997.582 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:37.978Z,1383951997.978 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:38.843Z,1383951998.843 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:38.845Z,1383951998.845 [DataOverHttps](INFO): dataWrite
2013-11-08T23:06:38.845Z,1383951998.845 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:38.846Z,1383951998.846 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:38.846Z,1383951998.846 [DataOverHttps](INFO): dataWriting
2013-11-08T23:06:38.847Z,1383951998.847 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:06:39.256Z,1383951999.256 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:39.664Z,1383951999.664 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:39.665Z,1383951999.665 [DataOverHttps](INFO): disconnect
2013-11-08T23:06:40.062Z,1383952000.062 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:06:40.462Z,1383952000.462 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:06:40.462Z,1383952000.462 [DataOverHttps](INFO): sslConnect
2013-11-08T23:06:40.463Z,1383952000.463 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:40.866Z,1383952000.866 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:41.739Z,1383952001.739 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:41.741Z,1383952001.741 [DataOverHttps](INFO): dataWrite
2013-11-08T23:06:41.742Z,1383952001.742 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:41.742Z,1383952001.742 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:41.742Z,1383952001.742 [DataOverHttps](INFO): dataWriting
2013-11-08T23:06:41.743Z,1383952001.743 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:06:42.144Z,1383952002.144 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:42.549Z,1383952002.549 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:42.549Z,1383952002.549 [DataOverHttps](INFO): disconnect
2013-11-08T23:06:42.945Z,1383952002.945 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:06:43.346Z,1383952003.346 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:06:43.346Z,1383952003.346 [DataOverHttps](INFO): sslConnect
2013-11-08T23:06:43.347Z,1383952003.347 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:43.783Z,1383952003.783 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:44.640Z,1383952004.640 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:44.642Z,1383952004.642 [DataOverHttps](INFO): dataWrite
2013-11-08T23:06:44.642Z,1383952004.642 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:44.643Z,1383952004.643 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:44.643Z,1383952004.643 [DataOverHttps](INFO): dataWriting
2013-11-08T23:06:44.644Z,1383952004.644 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:06:45.053Z,1383952005.053 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:45.453Z,1383952005.453 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:45.454Z,1383952005.454 [DataOverHttps](INFO): disconnect
2013-11-08T23:06:45.854Z,1383952005.854 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:06:46.262Z,1383952006.262 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:06:46.262Z,1383952006.262 [DataOverHttps](INFO): sslConnect
2013-11-08T23:06:46.263Z,1383952006.263 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:46.667Z,1383952006.667 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:47.528Z,1383952007.528 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:47.530Z,1383952007.530 [DataOverHttps](INFO): dataWrite
2013-11-08T23:06:47.531Z,1383952007.531 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:47.531Z,1383952007.531 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:47.531Z,1383952007.531 [DataOverHttps](INFO): dataWriting
2013-11-08T23:06:47.532Z,1383952007.532 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:06:47.945Z,1383952007.945 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:48.366Z,1383952008.366 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:48.366Z,1383952008.366 [DataOverHttps](INFO): disconnect
2013-11-08T23:06:48.746Z,1383952008.746 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:06:49.143Z,1383952009.143 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:06:49.143Z,1383952009.143 [DataOverHttps](INFO): sslConnect
2013-11-08T23:06:49.144Z,1383952009.144 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:49.540Z,1383952009.540 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:50.413Z,1383952010.413 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:50.415Z,1383952010.415 [DataOverHttps](INFO): dataWrite
2013-11-08T23:06:50.415Z,1383952010.415 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:50.415Z,1383952010.415 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:50.416Z,1383952010.416 [DataOverHttps](INFO): dataWriting
2013-11-08T23:06:50.417Z,1383952010.417 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:06:50.817Z,1383952010.817 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:51.218Z,1383952011.218 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:51.219Z,1383952011.219 [DataOverHttps](INFO): disconnect
2013-11-08T23:06:51.639Z,1383952011.639 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:06:52.035Z,1383952012.035 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:06:52.036Z,1383952012.036 [DataOverHttps](INFO): sslConnect
2013-11-08T23:06:52.037Z,1383952012.037 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:52.448Z,1383952012.448 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:53.329Z,1383952013.329 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:53.331Z,1383952013.331 [DataOverHttps](INFO): dataWrite
2013-11-08T23:06:53.332Z,1383952013.332 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:53.332Z,1383952013.332 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:53.332Z,1383952013.332 [DataOverHttps](INFO): dataWriting
2013-11-08T23:06:53.334Z,1383952013.334 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:06:53.746Z,1383952013.746 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:54.155Z,1383952014.155 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:54.155Z,1383952014.155 [DataOverHttps](INFO): disconnect
2013-11-08T23:06:54.547Z,1383952014.547 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:06:54.948Z,1383952014.948 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:06:54.948Z,1383952014.948 [DataOverHttps](INFO): sslConnect
2013-11-08T23:06:54.949Z,1383952014.949 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:55.352Z,1383952015.352 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:56.226Z,1383952016.226 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:56.228Z,1383952016.228 [DataOverHttps](INFO): dataWrite
2013-11-08T23:06:56.228Z,1383952016.228 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:56.228Z,1383952016.228 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:56.229Z,1383952016.229 [DataOverHttps](INFO): dataWriting
2013-11-08T23:06:56.230Z,1383952016.230 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:06:56.638Z,1383952016.638 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:57.071Z,1383952017.071 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:57.072Z,1383952017.072 [DataOverHttps](INFO): disconnect
2013-11-08T23:06:57.452Z,1383952017.452 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:06:57.852Z,1383952017.852 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:06:57.853Z,1383952017.853 [DataOverHttps](INFO): sslConnect
2013-11-08T23:06:57.854Z,1383952017.854 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:58.249Z,1383952018.249 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:59.118Z,1383952019.118 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:06:59.120Z,1383952019.120 [DataOverHttps](INFO): dataWrite
2013-11-08T23:06:59.121Z,1383952019.121 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:59.121Z,1383952019.121 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:06:59.121Z,1383952019.121 [DataOverHttps](INFO): dataWriting
2013-11-08T23:06:59.123Z,1383952019.123 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:06:59.527Z,1383952019.527 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:59.934Z,1383952019.934 [DataOverHttps](INFO): dataRead
2013-11-08T23:06:59.935Z,1383952019.935 [DataOverHttps](INFO): disconnect
2013-11-08T23:07:00.328Z,1383952020.328 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:07:00.733Z,1383952020.733 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:07:00.733Z,1383952020.733 [DataOverHttps](INFO): sslConnect
2013-11-08T23:07:00.734Z,1383952020.734 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:01.141Z,1383952021.141 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:02.007Z,1383952022.007 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:02.009Z,1383952022.009 [DataOverHttps](INFO): dataWrite
2013-11-08T23:07:02.009Z,1383952022.009 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:07:02.009Z,1383952022.009 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:07:02.010Z,1383952022.010 [DataOverHttps](INFO): dataWriting
2013-11-08T23:07:02.011Z,1383952022.011 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:07:02.415Z,1383952022.415 [DataOverHttps](INFO): dataRead
2013-11-08T23:07:02.824Z,1383952022.824 [DataOverHttps](INFO): dataRead
2013-11-08T23:07:02.825Z,1383952022.825 [DataOverHttps](INFO): disconnect
2013-11-08T23:07:03.233Z,1383952023.233 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:07:03.641Z,1383952023.641 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:07:03.642Z,1383952023.642 [DataOverHttps](INFO): sslConnect
2013-11-08T23:07:03.643Z,1383952023.643 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:04.050Z,1383952024.050 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:04.934Z,1383952024.934 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:04.936Z,1383952024.936 [DataOverHttps](INFO): dataWrite
2013-11-08T23:07:04.936Z,1383952024.936 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:07:04.936Z,1383952024.936 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:07:04.937Z,1383952024.937 [DataOverHttps](INFO): dataWriting
2013-11-08T23:07:04.938Z,1383952024.938 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:07:05.348Z,1383952025.348 [DataOverHttps](INFO): dataRead
2013-11-08T23:07:05.762Z,1383952025.762 [DataOverHttps](INFO): dataRead
2013-11-08T23:07:05.763Z,1383952025.763 [DataOverHttps](INFO): disconnect
2013-11-08T23:07:06.161Z,1383952026.161 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:07:06.562Z,1383952026.562 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:07:06.562Z,1383952026.562 [DataOverHttps](INFO): sslConnect
2013-11-08T23:07:06.563Z,1383952026.563 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:06.966Z,1383952026.966 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:07.861Z,1383952027.861 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:07.863Z,1383952027.863 [DataOverHttps](INFO): dataWrite
2013-11-08T23:07:07.863Z,1383952027.863 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:07:07.864Z,1383952027.864 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:07:07.864Z,1383952027.864 [DataOverHttps](INFO): dataWriting
2013-11-08T23:07:07.865Z,1383952027.865 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:07:08.253Z,1383952028.253 [DataOverHttps](INFO): dataRead
2013-11-08T23:07:08.653Z,1383952028.653 [DataOverHttps](INFO): dataRead
2013-11-08T23:07:08.654Z,1383952028.654 [DataOverHttps](INFO): disconnect
2013-11-08T23:07:09.054Z,1383952029.054 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:07:09.450Z,1383952029.450 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:07:09.451Z,1383952029.451 [DataOverHttps](INFO): sslConnect
2013-11-08T23:07:09.452Z,1383952029.452 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:09.871Z,1383952029.871 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:10.748Z,1383952030.748 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:10.750Z,1383952030.750 [DataOverHttps](INFO): dataWrite
2013-11-08T23:07:10.751Z,1383952030.751 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:07:10.751Z,1383952030.751 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:07:10.751Z,1383952030.751 [DataOverHttps](INFO): dataWriting
2013-11-08T23:07:10.753Z,1383952030.753 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:07:11.163Z,1383952031.163 [DataOverHttps](INFO): dataRead
2013-11-08T23:07:11.564Z,1383952031.564 [DataOverHttps](INFO): dataRead
2013-11-08T23:07:11.564Z,1383952031.564 [DataOverHttps](INFO): disconnect
2013-11-08T23:07:11.965Z,1383952031.965 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:07:12.363Z,1383952032.363 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:07:12.363Z,1383952032.363 [DataOverHttps](INFO): sslConnect
2013-11-08T23:07:12.364Z,1383952032.364 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:12.764Z,1383952032.764 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:13.649Z,1383952033.649 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:13.651Z,1383952033.651 [DataOverHttps](INFO): dataWrite
2013-11-08T23:07:13.651Z,1383952033.651 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:07:13.652Z,1383952033.652 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:07:13.652Z,1383952033.652 [DataOverHttps](INFO): dataWriting
2013-11-08T23:07:13.653Z,1383952033.653 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:07:14.054Z,1383952034.054 [DataOverHttps](INFO): dataRead
2013-11-08T23:07:14.462Z,1383952034.462 [DataOverHttps](INFO): dataRead
2013-11-08T23:07:14.463Z,1383952034.463 [DataOverHttps](INFO): disconnect
2013-11-08T23:07:14.864Z,1383952034.864 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:07:15.256Z,1383952035.256 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:07:15.256Z,1383952035.256 [DataOverHttps](INFO): sslConnect
2013-11-08T23:07:15.257Z,1383952035.257 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:15.656Z,1383952035.656 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:16.533Z,1383952036.533 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:16.535Z,1383952036.535 [DataOverHttps](INFO): dataWrite
2013-11-08T23:07:16.536Z,1383952036.536 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:07:16.536Z,1383952036.536 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:07:16.536Z,1383952036.536 [DataOverHttps](INFO): dataWriting
2013-11-08T23:07:16.538Z,1383952036.538 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:07:16.965Z,1383952036.965 [DataOverHttps](INFO): dataRead
2013-11-08T23:07:17.379Z,1383952037.379 [DataOverHttps](INFO): dataRead
2013-11-08T23:07:17.379Z,1383952037.379 [DataOverHttps](INFO): disconnect
2013-11-08T23:07:17.761Z,1383952037.761 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:07:18.148Z,1383952038.148 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:07:18.148Z,1383952038.148 [DataOverHttps](INFO): sslConnect
2013-11-08T23:07:18.149Z,1383952038.149 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:18.562Z,1383952038.562 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:19.534Z,1383952039.534 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:19.536Z,1383952039.536 [DataOverHttps](INFO): dataWrite
2013-11-08T23:07:19.536Z,1383952039.536 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:07:19.537Z,1383952039.537 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:07:19.537Z,1383952039.537 [DataOverHttps](INFO): dataWriting
2013-11-08T23:07:19.538Z,1383952039.538 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:07:19.961Z,1383952039.961 [DataOverHttps](INFO): dataRead
2013-11-08T23:07:20.363Z,1383952040.363 [DataOverHttps](INFO): dataRead
2013-11-08T23:07:20.364Z,1383952040.364 [DataOverHttps](INFO): disconnect
2013-11-08T23:07:20.761Z,1383952040.761 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:07:21.149Z,1383952041.149 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:07:21.149Z,1383952041.149 [DataOverHttps](INFO): sslConnect
2013-11-08T23:07:21.150Z,1383952041.150 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:21.565Z,1383952041.565 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:22.451Z,1383952042.451 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:22.453Z,1383952042.453 [DataOverHttps](INFO): dataWrite
2013-11-08T23:07:22.453Z,1383952042.453 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:07:22.453Z,1383952042.453 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:07:22.453Z,1383952042.453 [DataOverHttps](INFO): dataWriting
2013-11-08T23:07:22.455Z,1383952042.455 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:07:22.867Z,1383952042.867 [DataOverHttps](INFO): dataRead
2013-11-08T23:07:23.268Z,1383952043.268 [DataOverHttps](INFO): dataRead
2013-11-08T23:07:23.269Z,1383952043.269 [DataOverHttps](INFO): disconnect
2013-11-08T23:07:23.656Z,1383952043.656 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:07:24.085Z,1383952044.085 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:07:24.085Z,1383952044.085 [DataOverHttps](INFO): sslConnect
2013-11-08T23:07:24.086Z,1383952044.086 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:24.478Z,1383952044.478 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:25.343Z,1383952045.343 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:25.345Z,1383952045.345 [DataOverHttps](INFO): dataWrite
2013-11-08T23:07:25.345Z,1383952045.345 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:07:25.346Z,1383952045.346 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:07:25.346Z,1383952045.346 [DataOverHttps](INFO): dataWriting
2013-11-08T23:07:25.347Z,1383952045.347 [DataOverHttps](INFO): Wrote 206 bytes.
2013-11-08T23:07:25.748Z,1383952045.748 [DataOverHttps](INFO): dataRead
2013-11-08T23:07:26.156Z,1383952046.156 [DataOverHttps](INFO): dataRead
2013-11-08T23:07:26.157Z,1383952046.157 [DataOverHttps](IMPORTANT): SBD MTMSN=20131108T230749
2013-11-08T23:07:26.157Z,1383952046.157 [DataOverHttps](INFO): disconnect
2013-11-08T23:07:26.549Z,1383952046.549 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:07:26.964Z,1383952046.964 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:07:26.964Z,1383952046.964 [DataOverHttps](INFO): sslConnect
2013-11-08T23:07:26.965Z,1383952046.965 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:27.378Z,1383952047.378 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:28.239Z,1383952048.239 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:28.242Z,1383952048.242 [DataOverHttps](INFO): dataWrite
2013-11-08T23:07:28.242Z,1383952048.242 [DataOverHttps](IMPORTANT): Could not stat file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:07:28.242Z,1383952048.242 [DataOverHttps](CRITICAL): Could not open file Logs/20131108T225718/Courier0000.lzma
2013-11-08T23:07:28.243Z,1383952048.243 [DataOverHttps](INFO): dataWriting
2013-11-08T23:07:28.244Z,1383952048.244 [DataOverHttps](INFO): Wrote 228 bytes.
2013-11-08T23:07:28.245Z,1383952048.245 [DataOverHttps](INFO): Received command:restart app
2013-11-08T23:07:28.294Z,1383952048.294 [CommandLine](IMPORTANT): got command restart application
2013-11-08T23:07:29.078Z,1383952049.078 [DataOverHttps](INFO): dataRead
2013-11-08T23:07:29.428Z,1383952049.428 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2013-11-08T23:07:29.428Z,1383952049.428 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2013-11-08T23:07:29.462Z,1383952049.462 [DataOverHttps](INFO): dataRead
2013-11-08T23:07:29.462Z,1383952049.462 [DataOverHttps](INFO): disconnect
2013-11-08T23:07:29.728Z,1383952049.728 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2013-11-08T23:07:29.846Z,1383952049.846 [DataOverHttps](INFO): tcpConnect
2013-11-08T23:07:29.985Z,1383952049.985 [WetLabsBB2FL](INFO): Powering down
2013-11-08T23:07:29.997Z,1383952049.997 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2013-11-08T23:07:30.255Z,1383952050.255 [DataOverHttps](INFO): tcpConnecting
2013-11-08T23:07:30.255Z,1383952050.255 [DataOverHttps](INFO): sslConnect
2013-11-08T23:07:30.256Z,1383952050.256 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:30.365Z,1383952050.365 [CTD_NeilBrown](INFO): Powering down
2013-11-08T23:07:30.377Z,1383952050.377 [ComponentRegistry](INFO): Shutting down DVL_micro ThreadHandler
2013-11-08T23:07:30.638Z,1383952050.638 [DVL_micro](INFO): uninitialize:Powering down
2013-11-08T23:07:30.640Z,1383952050.640 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler
2013-11-08T23:07:30.651Z,1383952050.651 [DataOverHttps](INFO): sslConnecting
2013-11-08T23:07:30.930Z,1383952050.930 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator.
2013-11-08T23:07:30.947Z,1383952050.947 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2013-11-08T23:07:30.960Z,1383952050.960 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2013-11-08T23:07:30.971Z,1383952050.971 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2013-11-08T23:07:31.146Z,1383952051.146 [controlThread](DEBUG): Uninitializing ControlThread
2013-11-08T23:07:31.147Z,1383952051.147 [AHRS_sp3003D](INFO): Powering down
2013-11-08T23:07:31.235Z,1383952051.235 [DataOverHttps](INFO): disconnect
2013-11-08T23:07:31.238Z,1383952051.238 [NAL9602](INFO): Powering down
2013-11-08T23:07:31.239Z,1383952051.239 [DAT](INFO): Powering down
2013-11-08T23:07:31.240Z,1383952051.240 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2013-11-08T23:07:31.241Z,1383952051.241 [NavChart](DEBUG): Uninitialize NavChart Derivation.
2013-11-08T23:07:31.242Z,1383952051.242 [Default] Stopped
2013-11-08T23:07:31.242Z,1383952051.242 [Default](INFO): Aggregate::uninitialize Default
2013-11-08T23:07:31.242Z,1383952051.242 [Default:Iridium] Stopped
2013-11-08T23:07:31.242Z,1383952051.242 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-11-08T23:07:31.242Z,1383952051.242 [Default:Iridium:A.SetSpeed] Stopped
2013-11-08T23:07:31.242Z,1383952051.242 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-11-08T23:07:31.243Z,1383952051.243 [Default:Iridium:Read_Iridium] Stopped
2013-11-08T23:07:31.243Z,1383952051.243 [Default:CallIridium] Stopped
2013-11-08T23:07:31.243Z,1383952051.243 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-11-08T23:07:31.243Z,1383952051.243 [Default:CallIridium:B] Stopped
2013-11-08T23:07:31.243Z,1383952051.243 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-11-08T23:07:31.243Z,1383952051.243 [Default:D.SetSpeed] Stopped
2013-11-08T23:07:31.243Z,1383952051.243 [Default:D.SetSpeed](DEBUG): Uninitialize.
2013-11-08T23:07:31.243Z,1383952051.243 [Default:E.GoToSurface] Stopped
2013-11-08T23:07:31.243Z,1383952051.243 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-11-08T23:07:31.248Z,1383952051.248 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2013-11-08T23:07:31.248Z,1383952051.248 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2013-11-08T23:07:31.248Z,1383952051.248 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2013-11-08T23:07:31.249Z,1383952051.249 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2013-11-08T23:07:31.249Z,1383952051.249 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-11-08T23:07:31.249Z,1383952051.249 [BuoyancyServo](INFO): Powering down
2013-11-08T23:07:31.263Z,1383952051.263 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2013-11-08T23:07:31.263Z,1383952051.263 [ElevatorServo](INFO): Powering down
2013-11-08T23:07:31.264Z,1383952051.264 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-11-08T23:07:31.264Z,1383952051.264 [MassServo](INFO): Powering down
2013-11-08T23:07:31.265Z,1383952051.265 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2013-11-08T23:07:31.265Z,1383952051.265 [RudderServo](INFO): Powering down
2013-11-08T23:07:31.266Z,1383952051.266 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-11-08T23:07:31.266Z,1383952051.266 [ThrusterServo](INFO): Powering down
2013-11-08T23:07:31.267Z,1383952051.267 [SBIT](DEBUG): Uninitialize SBIT Component.
2013-11-08T23:07:31.267Z,1383952051.267 [IBIT](DEBUG): Uninitialize IBIT Component.
2013-11-08T23:07:31.267Z,1383952051.267 [CBIT](DEBUG): Uninitialize CBIT Component.