2013-04-24T20:07:54.020Z,1366834074.020 [Supervisor](DEBUG): Initializing supervisor.
2013-04-24T20:07:54.026Z,1366834074.026 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2013-04-24T20:07:54.026Z,1366834074.026 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2013-04-24T20:07:54.030Z,1366834074.030 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0
2013-04-24T20:07:54.033Z,1366834074.033 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2013-04-24T20:07:54.043Z,1366834074.043 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2013-04-24T20:07:54.046Z,1366834074.046 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0
2013-04-24T20:07:54.047Z,1366834074.047 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2013-04-24T20:07:54.050Z,1366834074.050 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403CB4E0
2013-04-24T20:07:54.050Z,1366834074.050 [Supervisor](INFO): Looking for Config files in directory: Config/
2013-04-24T20:07:54.055Z,1366834074.055 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2013-04-24T20:07:54.365Z,1366834074.365 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2013-04-24T20:07:54.366Z,1366834074.366 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2013-04-24T20:07:54.573Z,1366834074.573 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2013-04-24T20:07:54.574Z,1366834074.574 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2013-04-24T20:07:54.669Z,1366834074.669 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2013-04-24T20:07:54.671Z,1366834074.671 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2013-04-24T20:07:54.895Z,1366834074.895 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2013-04-24T20:07:54.895Z,1366834074.895 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2013-04-24T20:07:55.045Z,1366834075.045 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2013-04-24T20:07:55.047Z,1366834075.047 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2013-04-24T20:07:55.301Z,1366834075.301 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2013-04-24T20:07:55.302Z,1366834075.302 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2013-04-24T20:07:55.490Z,1366834075.490 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2013-04-24T20:07:55.492Z,1366834075.492 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2013-04-24T20:07:55.775Z,1366834075.775 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2013-04-24T20:07:55.777Z,1366834075.777 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2013-04-24T20:07:55.890Z,1366834075.890 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2013-04-24T20:07:55.891Z,1366834075.891 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2013-04-24T20:07:56.335Z,1366834076.335 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2013-04-24T20:07:56.336Z,1366834076.336 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2013-04-24T20:07:56.463Z,1366834076.463 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2013-04-24T20:07:56.463Z,1366834076.464 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2013-04-24T20:07:56.560Z,1366834076.560 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2013-04-24T20:07:56.564Z,1366834076.564 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2013-04-24T20:07:56.684Z,1366834076.684 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2013-04-24T20:07:56.827Z,1366834076.827 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2013-04-24T20:07:56.922Z,1366834076.922 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2013-04-24T20:07:57.043Z,1366834077.043 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2013-04-24T20:07:57.154Z,1366834077.154 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2013-04-24T20:07:57.291Z,1366834077.291 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2013-04-24T20:07:57.400Z,1366834077.400 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2013-04-24T20:07:57.503Z,1366834077.503 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2013-04-24T20:07:57.631Z,1366834077.631 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2013-04-24T20:07:57.840Z,1366834077.839 [InternalSim] Loaded
2013-04-24T20:07:57.840Z,1366834077.840 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2013-04-24T20:07:57.841Z,1366834077.841 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2013-04-24T20:07:57.841Z,1366834077.841 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2013-04-24T20:07:57.928Z,1366834077.928 [SBIT](DEBUG): Construct Startup Built In Test.
2013-04-24T20:07:57.961Z,1366834077.961 [SBIT] Loaded
2013-04-24T20:07:57.961Z,1366834077.961 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2013-04-24T20:07:57.962Z,1366834077.962 [IBIT](DEBUG): Construct Initiated Built In Test.
2013-04-24T20:07:57.995Z,1366834077.995 [IBIT] Loaded
2013-04-24T20:07:57.995Z,1366834077.995 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2013-04-24T20:07:57.999Z,1366834077.999 [CBIT](DEBUG): Construct CBIT Built In Test.
2013-04-24T20:07:58.133Z,1366834078.134 [CBIT] Loaded
2013-04-24T20:07:58.134Z,1366834078.134 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2013-04-24T20:07:58.134Z,1366834078.134 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2013-04-24T20:07:58.135Z,1366834078.135 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2013-04-24T20:07:58.315Z,1366834078.315 [BuoyancyServo] Loaded
2013-04-24T20:07:58.315Z,1366834078.315 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2013-04-24T20:07:58.328Z,1366834078.328 [ElevatorServo] Loaded
2013-04-24T20:07:58.328Z,1366834078.328 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2013-04-24T20:07:58.344Z,1366834078.344 [MassServo] Loaded
2013-04-24T20:07:58.344Z,1366834078.344 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2013-04-24T20:07:58.356Z,1366834078.356 [RudderServo] Loaded
2013-04-24T20:07:58.357Z,1366834078.357 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2013-04-24T20:07:58.369Z,1366834078.369 [ThrusterServo] Loaded
2013-04-24T20:07:58.369Z,1366834078.369 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2013-04-24T20:07:58.369Z,1366834078.369 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2013-04-24T20:07:58.370Z,1366834078.370 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2013-04-24T20:07:58.403Z,1366834078.403 [DepthRateCalculator] Loaded
2013-04-24T20:07:58.403Z,1366834078.403 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2013-04-24T20:08:01.664Z,1366834081.664 [HFRadarModelCalc] Loaded
2013-04-24T20:08:01.664Z,1366834081.664 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2013-04-24T20:08:01.684Z,1366834081.684 [NavChart] Loaded
2013-04-24T20:08:01.684Z,1366834081.684 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2013-04-24T20:08:01.690Z,1366834081.690 [PitchRateCalculator] Loaded
2013-04-24T20:08:01.691Z,1366834081.691 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2013-04-24T20:08:01.697Z,1366834081.697 [SpeedCalculator] Loaded
2013-04-24T20:08:01.697Z,1366834081.697 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2013-04-24T20:08:01.712Z,1366834081.712 [TempGradientCalculator] Loaded
2013-04-24T20:08:01.713Z,1366834081.713 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2013-04-24T20:08:01.719Z,1366834081.719 [YawRateCalculator] Loaded
2013-04-24T20:08:01.719Z,1366834081.719 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2013-04-24T20:08:01.768Z,1366834081.768 [Navigation] Loaded
2013-04-24T20:08:01.768Z,1366834081.768 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread.
2013-04-24T20:08:01.768Z,1366834081.769 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2013-04-24T20:08:01.769Z,1366834081.769 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2013-04-24T20:08:02.049Z,1366834082.049 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2013-04-24T20:08:02.049Z,1366834082.049 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2013-04-24T20:08:02.089Z,1366834082.089 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2013-04-24T20:08:02.094Z,1366834082.094 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2013-04-24T20:08:02.162Z,1366834082.162 [VerticalControl](DEBUG): Construct VerticalControl.
2013-04-24T20:08:02.263Z,1366834082.263 [VerticalControl] Loaded
2013-04-24T20:08:02.263Z,1366834082.263 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2013-04-24T20:08:02.264Z,1366834082.264 [HorizontalControl](DEBUG): Construct HorizontalControl.
2013-04-24T20:08:02.324Z,1366834082.324 [HorizontalControl] Loaded
2013-04-24T20:08:02.325Z,1366834082.325 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2013-04-24T20:08:02.326Z,1366834082.326 [SpeedControl](DEBUG): Construct SpeedControl.
2013-04-24T20:08:02.327Z,1366834082.327 [SpeedControl] Loaded
2013-04-24T20:08:02.328Z,1366834082.328 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2013-04-24T20:08:02.329Z,1366834082.329 [LoopControl](DEBUG): Construct LoopControl.
2013-04-24T20:08:02.329Z,1366834082.329 [LoopControl] Loaded
2013-04-24T20:08:02.329Z,1366834082.329 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2013-04-24T20:08:02.330Z,1366834082.330 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2013-04-24T20:08:02.330Z,1366834082.330 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2013-04-24T20:08:02.339Z,1366834082.339 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2013-04-24T20:08:02.344Z,1366834082.344 [AsyncPiEstimator] Loaded
2013-04-24T20:08:02.344Z,1366834082.344 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2013-04-24T20:08:02.346Z,1366834082.346 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 4063B4E0
2013-04-24T20:08:02.346Z,1366834082.346 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2013-04-24T20:08:02.347Z,1366834082.347 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2013-04-24T20:08:02.531Z,1366834082.532 [AHRS_sp3003D] Loaded
2013-04-24T20:08:02.532Z,1366834082.532 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2013-04-24T20:08:02.824Z,1366834082.824 [Batt_Ocean_Server] Loaded
2013-04-24T20:08:02.824Z,1366834082.824 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread.
2013-04-24T20:08:02.838Z,1366834082.838 [Depth_Keller] Loaded
2013-04-24T20:08:02.838Z,1366834082.838 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2013-04-24T20:08:02.844Z,1366834082.844 [DropWeight] Loaded
2013-04-24T20:08:02.844Z,1366834082.844 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2013-04-24T20:08:03.039Z,1366834083.039 [DVL_micro] Loaded
2013-04-24T20:08:03.039Z,1366834083.039 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread.
2013-04-24T20:08:03.042Z,1366834083.042 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406BF4E0
2013-04-24T20:08:03.133Z,1366834083.133 [NAL9602] Loaded
2013-04-24T20:08:03.133Z,1366834083.133 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2013-04-24T20:08:03.189Z,1366834083.189 [Onboard] Loaded
2013-04-24T20:08:03.189Z,1366834083.189 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2013-04-24T20:08:03.200Z,1366834083.200 [Radio_Freewave] Loaded
2013-04-24T20:08:03.201Z,1366834083.201 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread.
2013-04-24T20:08:03.303Z,1366834083.303 [DAT] Loaded
2013-04-24T20:08:03.303Z,1366834083.303 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2013-04-24T20:08:03.303Z,1366834083.303 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2013-04-24T20:08:03.304Z,1366834083.304 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2013-04-24T20:08:03.405Z,1366834083.405 [CTD_NeilBrown] Loaded
2013-04-24T20:08:03.405Z,1366834083.405 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2013-04-24T20:08:03.410Z,1366834083.410 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 407114E0
2013-04-24T20:08:03.441Z,1366834083.441 [WetLabsBB2FL] Loaded
2013-04-24T20:08:03.441Z,1366834083.441 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2013-04-24T20:08:03.446Z,1366834083.446 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407414E0
2013-04-24T20:08:03.446Z,1366834083.446 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2013-04-24T20:08:03.449Z,1366834083.449 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2013-04-24T20:08:03.450Z,1366834083.450 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2013-04-24T20:08:03.461Z,1366834083.461 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2013-04-24T20:08:03.466Z,1366834083.466 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407714E0
2013-04-24T20:08:03.470Z,1366834083.470 [Supervisor](DEBUG): Running supervisor.
2013-04-24T20:08:03.471Z,1366834083.471 [CommandLine](INFO): Thread ID is 746
2013-04-24T20:08:03.474Z,1366834083.474 [controlThread](INFO): Thread ID is 745
2013-04-24T20:08:03.475Z,1366834083.475 [controlThread](DEBUG): Initializing ControlThread
2013-04-24T20:08:03.475Z,1366834083.475 [CycleStarter](INFO): Thread ID is 744
2013-04-24T20:08:03.475Z,1366834083.476 [InternalSim](DEBUG):  InternalSim initializing...
2013-04-24T20:08:03.582Z,1366834083.582 [logger](INFO): Thread ID is 747
2013-04-24T20:08:03.699Z,1366834083.699 [AsyncPiEstimator](INFO): Thread ID is 808
2013-04-24T20:08:03.699Z,1366834083.699 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2013-04-24T20:08:03.783Z,1366834083.783 [DVL_micro](INFO): Thread ID is 809
2013-04-24T20:08:03.802Z,1366834083.802 [DVL_micro](INFO): Initializing
2013-04-24T20:08:03.802Z,1366834083.802 [DVL_micro](INFO): start:Powering up
2013-04-24T20:08:03.803Z,1366834083.803 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-04-24T20:08:03.804Z,1366834083.804 [DVL_micro](INFO): Cycling power to configure device.
2013-04-24T20:08:03.819Z,1366834083.819 [CTD_NeilBrown](INFO): Thread ID is 810
2013-04-24T20:08:03.819Z,1366834083.819 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2013-04-24T20:08:03.826Z,1366834083.826 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2013-04-24T20:08:03.836Z,1366834083.836 [WetLabsBB2FL](INFO): Thread ID is 811
2013-04-24T20:08:03.836Z,1366834083.836 [WetLabsBB2FL](INFO): Powering down
2013-04-24T20:08:03.903Z,1366834083.903 [NavChartDb](INFO): Thread ID is 812
2013-04-24T20:08:03.913Z,1366834083.913 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2013-04-24T20:08:03.914Z,1366834083.914 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2013-04-24T20:08:03.914Z,1366834083.914 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2013-04-24T20:08:03.914Z,1366834083.914 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2013-04-24T20:08:03.915Z,1366834083.915 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2013-04-24T20:08:03.915Z,1366834083.915 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2013-04-24T20:08:03.915Z,1366834083.915 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2013-04-24T20:08:03.915Z,1366834083.915 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2013-04-24T20:08:03.916Z,1366834083.916 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2013-04-24T20:08:03.978Z,1366834083.978 [SBIT](INFO): Initialize SBIT Component.
2013-04-24T20:08:03.978Z,1366834083.978 [SBIT](IMPORTANT): Tethys CM Info:
$Rev: 10307
2013-04-24T20:08:03.979Z,1366834083.979 [SBIT](IMPORTANT): Kernel Release:2.6.27.8
2013-04-24T20:08:03.979Z,1366834083.979 [SBIT](FAULT): Kernel Reporting Different Version From Configuration.
Expected:#634 PREEMPT Wed Feb 13 10:21:48 PST 2013
Kernel Reported:#625 PREEMPT Mon Nov 5 10:42:26 PST 2012
2013-04-24T20:08:03.980Z,1366834083.980 [IBIT](INFO): Initialize IBIT Component.
2013-04-24T20:08:03.981Z,1366834083.981 [CBIT](DEBUG): Initialize CBIT Component.
2013-04-24T20:08:03.981Z,1366834083.981 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2013-04-24T20:08:03.981Z,1366834083.981 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2013-04-24T20:08:04.007Z,1366834084.007 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2013-04-24T20:08:04.008Z,1366834084.008 [NavChart](DEBUG): Initialize NavChart Derivation.
2013-04-24T20:08:04.008Z,1366834084.008 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2013-04-24T20:08:04.008Z,1366834084.008 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2013-04-24T20:08:04.009Z,1366834084.009 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2013-04-24T20:08:04.010Z,1366834084.010 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2013-04-24T20:08:04.011Z,1366834084.011 [Navigation](DEBUG): Initializing Navigation.
2013-04-24T20:08:04.011Z,1366834084.011 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2013-04-24T20:08:04.013Z,1366834084.013 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2013-04-24T20:08:04.014Z,1366834084.014 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2013-04-24T20:08:04.014Z,1366834084.014 [LoopControl](DEBUG): Initialize LoopControlComponent.
2013-04-24T20:08:05.886Z,1366834085.886 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2013-04-24T20:08:05.932Z,1366834085.932 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2013-04-24T20:08:05.954Z,1366834085.954 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-04-24T20:08:05.955Z,1366834085.954 [DVL_micro](INFO): Querying output modes
2013-04-24T20:08:05.955Z,1366834085.955 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-04-24T20:08:05.966Z,1366834085.966 [DVL_micro](DEBUG): cmdResponse: 01 03 16
2013-04-24T20:08:05.966Z,1366834085.966 [DVL_micro](INFO): NQ1 output enabled
2013-04-24T20:08:05.966Z,1366834085.967 [DVL_micro](INFO): RSSI output enabled
2013-04-24T20:08:05.967Z,1366834085.967 [DVL_micro](INFO): ADCP output enabled
2013-04-24T20:08:05.967Z,1366834085.967 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-04-24T20:08:05.976Z,1366834085.976 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2013-04-24T20:08:05.978Z,1366834085.978 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2013-04-24T20:08:05.995Z,1366834085.995 [DVL_micro](INFO): pause:Powering down
2013-04-24T20:08:06.017Z,1366834086.017 [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-04-24T20:08:06.042Z,1366834086.042 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2013-04-24T20:08:06.088Z,1366834086.088 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2013-04-24T20:08:06.103Z,1366834086.103 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2013-04-24T20:08:06.106Z,1366834086.106 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-04-24T20:08:06.150Z,1366834086.150 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2013-04-24T20:08:06.153Z,1366834086.153 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-04-24T20:08:06.180Z,1366834086.180 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2013-04-24T20:08:06.200Z,1366834086.200 [Default:D.SetSpeed](DEBUG): Construct.
2013-04-24T20:08:06.203Z,1366834086.203 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2013-04-24T20:08:06.208Z,1366834086.208 [Default:F.Wait](DEBUG): Construct Wait.
2013-04-24T20:08:06.235Z,1366834086.236 [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-04-24T20:08:06.240Z,1366834086.240 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,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,
2013-04-24T20:08:06.294Z,1366834086.294 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-04-24T20:08:06.315Z,1366834086.316 [Depth_Keller](ERROR): Pressure reading out of range: 1831.078247 decibar
2013-04-24T20:08:06.407Z,1366834086.407 [Radio_Freewave](INFO): Powering up
2013-04-24T20:08:06.416Z,1366834086.416 [DAT](INFO): Powering up
2013-04-24T20:08:06.417Z,1366834086.417 [DAT](DEBUG): Initializing DAT.
2013-04-24T20:08:06.608Z,1366834086.608 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-04-24T20:08:06.614Z,1366834086.614 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-04-24T20:08:06.641Z,1366834086.641 [ElevatorServo](DEBUG): Initializing EZServoServo.
2013-04-24T20:08:06.654Z,1366834086.654 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2013-04-24T20:08:06.672Z,1366834086.672 [MassServo](DEBUG): Initializing EZServoServo.
2013-04-24T20:08:06.678Z,1366834086.678 [MassServo](DEBUG): Initializing MassServo.
2013-04-24T20:08:06.684Z,1366834086.684 [RudderServo](DEBUG): Initializing EZServoServo.
2013-04-24T20:08:06.690Z,1366834086.690 [RudderServo](DEBUG): Initializing RudderServo.
2013-04-24T20:08:06.696Z,1366834086.696 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-04-24T20:08:06.702Z,1366834086.702 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-04-24T20:08:08.306Z,1366834088.306 [NAL9602](INFO): Powering up NAL9602
2013-04-24T20:08:19.018Z,1366834099.018 [NAL9602](INFO): NAL9602 initialized
2013-04-24T20:08:19.445Z,1366834099.445 [SBIT](IMPORTANT): Beginning Startup BIT
2013-04-24T20:08:19.459Z,1366834099.459 [CBIT](IMPORTANT): Beginning GF scan
2013-04-24T20:08:26.469Z,1366834106.469 [DAT](INFO): Powering down
2013-04-24T20:08:45.964Z,1366834125.964 [CBIT](FAULT): Chan 4 High side GF detected
mA:
CHAN 5 (24V): 0.047348
CHAN 4 (Batt): 0.025348
CHAN 2 (12V): 0.017026
CHAN 1 (5V): 0.002200
CHAN 0 (3.3V): 0.002200
OPEN: 0.000670
Full Scale Calc: 0.392
2013-04-24T20:09:13.246Z,1366834153.246 [SBIT](IMPORTANT): SBIT PASSED
2013-04-24T20:09:13.640Z,1366834153.640 [MissionManager](IMPORTANT): Started mission Startup
2013-04-24T20:09:13.640Z,1366834153.640 [Startup] Running Loop=1
2013-04-24T20:09:13.640Z,1366834153.640 [Startup](INFO): Aggregate::initialize Startup
2013-04-24T20:09:13.640Z,1366834153.640 [Startup:A.GoToSurface] Running Loop=1
2013-04-24T20:09:13.640Z,1366834153.640 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T20:09:13.646Z,1366834153.646 [Startup:StartupSatComms] Running Loop=1
2013-04-24T20:09:13.646Z,1366834153.646 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2013-04-24T20:09:13.646Z,1366834153.646 [Startup:StartupSatComms:A] Running Loop=1
2013-04-24T20:09:14.029Z,1366834154.029 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-04-24T20:10:14.063Z,1366834214.063 [Startup:StartupSatComms:A](INFO): Timed out from 2013-04-24T20:09:13.6Z
2013-04-24T20:10:14.063Z,1366834214.063 [Startup:StartupSatComms:A:A_Timeout] Running Loop=1
2013-04-24T20:10:14.063Z,1366834214.063 [Startup:StartupSatComms:A:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:A:A_Timeout
2013-04-24T20:10:14.063Z,1366834214.063 [Startup:StartupSatComms:A:A_Timeout](INFO): Completed Startup:StartupSatComms:A:A_Timeout
2013-04-24T20:10:14.063Z,1366834214.063 [Startup:StartupSatComms:A] Stopped
2013-04-24T20:10:14.064Z,1366834214.063 [Startup:StartupSatComms:B] Running Loop=1
2013-04-24T20:10:14.415Z,1366834214.415 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2013-04-24T20:11:14.360Z,1366834274.360 [Startup:StartupSatComms:B](INFO): Timed out from 2013-04-24T20:10:14.1Z
2013-04-24T20:11:14.360Z,1366834274.360 [Startup:StartupSatComms:B:A_Timeout] Running Loop=1
2013-04-24T20:11:14.360Z,1366834274.360 [Startup:StartupSatComms:B:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:B:A_Timeout
2013-04-24T20:11:14.361Z,1366834274.361 [Startup:StartupSatComms:B:A_Timeout](INFO): Completed Startup:StartupSatComms:B:A_Timeout
2013-04-24T20:11:14.361Z,1366834274.361 [Startup:StartupSatComms:B] Stopped
2013-04-24T20:11:14.361Z,1366834274.361 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2013-04-24T20:11:14.361Z,1366834274.361 [Startup:StartupSatComms] Stopped
2013-04-24T20:11:14.361Z,1366834274.361 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2013-04-24T20:11:14.362Z,1366834274.362 [Startup](INFO): Completed Startup
2013-04-24T20:11:14.362Z,1366834274.362 [Startup] Stopped
2013-04-24T20:11:14.362Z,1366834274.362 [Startup](INFO): Aggregate::uninitialize Startup
2013-04-24T20:11:14.362Z,1366834274.362 [Startup:A.GoToSurface] Stopped
2013-04-24T20:11:14.363Z,1366834274.362 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T20:11:15.200Z,1366834275.200 [MissionManager](IMPORTANT): Started mission Default
2013-04-24T20:11:15.200Z,1366834275.200 [Default] Running Loop=1
2013-04-24T20:11:15.200Z,1366834275.200 [Default](INFO): Aggregate::initialize Default
2013-04-24T20:11:15.200Z,1366834275.201 [Default:D.SetSpeed] Running Loop=1
2013-04-24T20:11:15.200Z,1366834275.201 [Default:D.SetSpeed](DEBUG): Initialize.
2013-04-24T20:11:15.201Z,1366834275.201 [Default:E.GoToSurface] Running Loop=1
2013-04-24T20:11:15.201Z,1366834275.201 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T20:11:15.201Z,1366834275.201 [Default:Iridium] Running Loop=1
2013-04-24T20:11:15.201Z,1366834275.201 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-24T20:11:15.201Z,1366834275.201 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-24T20:11:15.201Z,1366834275.201 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-24T20:11:15.202Z,1366834275.202 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-24T20:11:15.202Z,1366834275.202 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T20:11:15.202Z,1366834275.202 [Default:E.GoToSurface] Running Loop=1
2013-04-24T20:11:15.208Z,1366834275.208 [Default:D.SetSpeed] Running Loop=1
2013-04-24T20:11:15.213Z,1366834275.213 [Default:CallIridium] Running Loop=1
2013-04-24T20:11:15.213Z,1366834275.213 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-24T20:11:15.213Z,1366834275.213 [Default:CallIridium:A] Running Loop=1
2013-04-24T20:11:15.215Z,1366834275.215 [Default:CallIridium:A] Stopped
2013-04-24T20:11:15.215Z,1366834275.215 [Default:CallIridium:B] Running Loop=1
2013-04-24T20:11:15.215Z,1366834275.216 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-24T20:11:15.241Z,1366834275.241 [Default:Iridium:B.GoToSurface] Stopped
2013-04-24T20:11:15.241Z,1366834275.241 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T20:11:15.241Z,1366834275.241 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-24T20:11:15.241Z,1366834275.241 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-24T20:11:15.246Z,1366834275.246 [Default:GPS] Running Loop=1
2013-04-24T20:11:15.246Z,1366834275.246 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-24T20:11:15.246Z,1366834275.246 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-24T20:11:15.246Z,1366834275.246 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-24T20:11:15.247Z,1366834275.247 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-24T20:11:15.247Z,1366834275.247 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T20:11:15.265Z,1366834275.265 [Default:GPS:B.GoToSurface] Stopped
2013-04-24T20:11:15.265Z,1366834275.265 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T20:11:15.265Z,1366834275.265 [Default:GPS:Read_GPS] Running Loop=1
2013-04-24T20:11:15.265Z,1366834275.265 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-24T20:11:15.539Z,1366834275.539 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2013-04-24T20:11:15.541Z,1366834275.541 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-04-24T20:12:05.859Z,1366834325.859 [NAL9602](IMPORTANT): GPS fix at: 1366834324.00
2013-04-24T20:12:05.876Z,1366834325.875 [Default:GPS:Read_GPS] Stopped
2013-04-24T20:12:05.876Z,1366834325.876 [Default:GPS:D] Running Loop=1
2013-04-24T20:12:06.283Z,1366834326.283 [Default:GPS:D] Stopped
2013-04-24T20:12:06.283Z,1366834326.283 [Default:GPS](INFO): Completed Default:GPS
2013-04-24T20:12:06.283Z,1366834326.283 [Default:GPS] Stopped
2013-04-24T20:12:06.283Z,1366834326.283 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-24T20:12:06.283Z,1366834326.283 [Default:GPS:A.SetSpeed] Stopped
2013-04-24T20:12:06.283Z,1366834326.283 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T20:13:01.114Z,1366834381.114 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=9809, MT Status=1, MTMSN=740
2013-04-24T20:13:01.170Z,1366834381.170 [NAL9602](INFO): Sent 332 bytes from file Logs/20130424T200753/shore0000.lzma
2013-04-24T20:13:01.170Z,1366834381.170 [NAL9602](INFO): Packets left to send: 2
2013-04-24T20:13:01.172Z,1366834381.172 [NAL9602](INFO): Stored copy of sent data in Logs/20130424T200753/shore0000.lzma.parts/0002.sbd
2013-04-24T20:13:01.551Z,1366834381.551 [NAL9602](INFO): Received command:echo 1 > /dev/loadA2
2013-04-24T20:13:01.566Z,1366834381.566 [CommandLine](IMPORTANT): got command 
2013-04-24T20:13:01.566Z,1366834381.566 [CommandLine](FAULT): Incomplete syntax. Try: help
2013-04-24T20:13:22.061Z,1366834402.061 [NAL9602](INFO): SBD MO Status=1, MOMSN=9810, MT Status=0, MTMSN=0
2013-04-24T20:13:22.114Z,1366834402.114 [NAL9602](INFO): Sent 332 bytes from file Logs/20130424T200753/shore0000.lzma
2013-04-24T20:13:22.114Z,1366834402.114 [NAL9602](INFO): Packets left to send: 1
2013-04-24T20:13:22.115Z,1366834402.115 [NAL9602](INFO): Stored copy of sent data in Logs/20130424T200753/shore0000.lzma.parts/0001.sbd
2013-04-24T20:13:28.785Z,1366834408.785 [NAL9602](INFO): SBD MO Status=1, MOMSN=9811, MT Status=0, MTMSN=0
2013-04-24T20:13:28.838Z,1366834408.838 [NAL9602](INFO): Sent 68 bytes from file Logs/20130424T200753/shore0000.lzma
2013-04-24T20:13:28.838Z,1366834408.838 [NAL9602](INFO): Packets left to send: 0
2013-04-24T20:13:28.839Z,1366834408.839 [NAL9602](INFO): Stored copy of sent data in Logs/20130424T200753/shore0000.lzma.parts/0000.sbd
2013-04-24T20:13:36.383Z,1366834416.383 [NAL9602](INFO): SBD MO Status=0, MOMSN=9812, MT Status=0, MTMSN=0
2013-04-24T20:13:52.134Z,1366834432.134 [CommandLine](IMPORTANT): got command failComponent
2013-04-24T20:13:52.134Z,1366834432.134 [CommandLine](IMPORTANT): Failed components:
2013-04-24T20:13:52.134Z,1366834432.135 [CommandLine](IMPORTANT): No failed Components.
2013-04-24T20:14:02.193Z,1366834442.193 [NAL9602](INFO): SBD MO Status=1, MOMSN=9813, MT Status=0, MTMSN=0
2013-04-24T20:14:02.246Z,1366834442.246 [NAL9602](INFO): Sent 332 bytes from file Logs/20130424T200753/shore0001.lzma
2013-04-24T20:14:02.246Z,1366834442.246 [NAL9602](INFO): Packets left to send: 1
2013-04-24T20:14:02.248Z,1366834442.248 [NAL9602](INFO): Stored copy of sent data in Logs/20130424T200753/shore0001.lzma.parts/0001.sbd
2013-04-24T20:14:13.627Z,1366834453.627 [NAL9602](INFO): SBD MO Status=1, MOMSN=9814, MT Status=0, MTMSN=0
2013-04-24T20:14:13.674Z,1366834453.674 [NAL9602](INFO): Sent 197 bytes from file Logs/20130424T200753/shore0001.lzma
2013-04-24T20:14:13.674Z,1366834453.674 [NAL9602](INFO): Packets left to send: 0
2013-04-24T20:14:13.675Z,1366834453.675 [NAL9602](INFO): Stored copy of sent data in Logs/20130424T200753/shore0001.lzma.parts/0000.sbd
2013-04-24T20:14:29.627Z,1366834469.627 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=9815, MT Status=1, MTMSN=741
2013-04-24T20:14:30.278Z,1366834470.278 [NAL9602](INFO): Received command:ibit
2013-04-24T20:14:30.334Z,1366834470.334 [CommandLine](IMPORTANT): got command ibit
2013-04-24T20:14:30.539Z,1366834470.539 [IBIT](IMPORTANT): Beginning Initiated BIT
2013-04-24T20:14:30.539Z,1366834470.539 [IBIT](IMPORTANT): Beginning control surface checks.

2013-04-24T20:14:30.540Z,1366834470.540 [CBIT](IMPORTANT): Beginning GF scan
2013-04-24T20:14:31.311Z,1366834471.311 [Radio_Freewave](INFO): Powering down
2013-04-24T20:14:31.704Z,1366834471.704 [NAL9602](IMPORTANT): GPS fix at: 1366834471.00
2013-04-24T20:14:32.132Z,1366834472.132 [Radio_Freewave](INFO): Powering up
2013-04-24T20:14:37.941Z,1366834477.941 [NAL9602](INFO): SBD MO Status=0, MOMSN=9816, MT Status=0, MTMSN=0
2013-04-24T20:14:39.082Z,1366834479.082 [NAL9602](IMPORTANT): GPS fix at: 1366834478.00
2013-04-24T20:14:50.896Z,1366834490.897 [NAL9602](INFO): SBD MO Status=1, MOMSN=9817, MT Status=0, MTMSN=0
2013-04-24T20:14:50.946Z,1366834490.946 [NAL9602](INFO): Sent 332 bytes from file Logs/20130424T200753/shore0002.lzma
2013-04-24T20:14:50.946Z,1366834490.946 [NAL9602](INFO): Packets left to send: 1
2013-04-24T20:14:50.948Z,1366834490.948 [NAL9602](INFO): Stored copy of sent data in Logs/20130424T200753/shore0002.lzma.parts/0001.sbd
2013-04-24T20:14:56.697Z,1366834496.697 [NAL9602](INFO): SBD MO Status=1, MOMSN=9818, MT Status=0, MTMSN=0
2013-04-24T20:14:56.750Z,1366834496.750 [NAL9602](INFO): Sent 30 bytes from file Logs/20130424T200753/shore0002.lzma
2013-04-24T20:14:56.750Z,1366834496.750 [NAL9602](INFO): Packets left to send: 0
2013-04-24T20:14:56.751Z,1366834496.751 [NAL9602](INFO): Stored copy of sent data in Logs/20130424T200753/shore0002.lzma.parts/0000.sbd
2013-04-24T20:14:57.141Z,1366834497.141 [CBIT](FAULT): Chan 4 High side GF detected
mA:
CHAN 5 (24V): 0.043235
CHAN 4 (Batt): 0.024104
CHAN 2 (12V): 0.016548
CHAN 1 (5V): 0.004496
CHAN 0 (3.3V): 0.004878
OPEN: 0.000670
Full Scale Calc: 0.392
2013-04-24T20:15:06.296Z,1366834506.296 [NAL9602](INFO): SBD MO Status=0, MOMSN=9819, MT Status=0, MTMSN=0
2013-04-24T20:15:10.531Z,1366834510.531 [Depth_Keller](ERROR): Pressure reading out of range: 1830.470703 decibar
2013-04-24T20:15:11.228Z,1366834511.228 [NAL9602](IMPORTANT): GPS fix at: 1366834510.00
2013-04-24T20:15:16.517Z,1366834516.517 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802429 Longitude: -121.787506
2013-04-24T20:15:16.909Z,1366834516.909 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 237.660004
Voltage: 16.304937
2013-04-24T20:15:16.909Z,1366834516.909 [IBIT](IMPORTANT): batteryCapacityThreshold: 5.000000 Ah
2013-04-24T20:15:16.909Z,1366834516.909 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.500000 V
2013-04-24T20:15:17.312Z,1366834517.312 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2013-04-24T20:15:17.312Z,1366834517.312 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2013-04-24T20:15:17.313Z,1366834517.313 [IBIT](IMPORTANT): Pressure:18.699255 PSI
2013-04-24T20:15:17.313Z,1366834517.313 [IBIT](IMPORTANT): Humidity:31.949087 %
2013-04-24T20:15:17.709Z,1366834517.709 [IBIT](IMPORTANT): surfaceThreshold: 0.500000 m
2013-04-24T20:15:17.709Z,1366834517.709 [IBIT](IMPORTANT): buoyancyNeutral: 450.000000 cc
2013-04-24T20:15:17.712Z,1366834517.712 [IBIT](IMPORTANT): massDefault: 0.000000 cm
2013-04-24T20:15:17.713Z,1366834517.713 [IBIT](IMPORTANT): stopDepth: 95.000000 m
2013-04-24T20:15:17.713Z,1366834517.713 [IBIT](IMPORTANT): abortDepth: 125.000000 m
2013-04-24T20:15:17.713Z,1366834517.713 [IBIT](IMPORTANT): IBIT PASSED
2013-04-24T20:15:18.043Z,1366834518.043 [Default:Iridium:Read_Iridium] Stopped
2013-04-24T20:15:18.043Z,1366834518.043 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-24T20:15:18.043Z,1366834518.043 [Default:Iridium] Stopped
2013-04-24T20:15:18.043Z,1366834518.043 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-24T20:15:18.043Z,1366834518.043 [Default:Iridium:A.SetSpeed] Stopped
2013-04-24T20:15:18.043Z,1366834518.043 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T20:15:18.043Z,1366834518.043 [Default:F.Wait] Running Loop=1
2013-04-24T20:15:18.043Z,1366834518.043 [Default:F.Wait](DEBUG): Initialize Wait Component.
2013-04-24T20:15:18.545Z,1366834518.545 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-24T20:15:18.545Z,1366834518.545 [Default:CallIridium:B] Stopped
2013-04-24T20:15:18.545Z,1366834518.545 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-24T20:15:18.545Z,1366834518.545 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-24T20:15:18.545Z,1366834518.545 [Default:CallIridium] Stopped
2013-04-24T20:15:18.545Z,1366834518.546 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-24T20:15:48.589Z,1366834548.589 [NAL9602](INFO): SBD MO Status=1, MOMSN=9820, MT Status=0, MTMSN=0
2013-04-24T20:15:48.638Z,1366834548.638 [NAL9602](INFO): Sent 300 bytes from file Logs/20130424T200753/shore0003.lzma
2013-04-24T20:15:48.638Z,1366834548.638 [NAL9602](INFO): Packets left to send: 0
2013-04-24T20:15:48.640Z,1366834548.640 [NAL9602](INFO): Stored copy of sent data in Logs/20130424T200753/shore0003.lzma.parts/0000.sbd
2013-04-24T20:15:58.600Z,1366834558.601 [NAL9602](INFO): SBD MO Status=0, MOMSN=9821, MT Status=0, MTMSN=0
2013-04-24T20:16:13.565Z,1366834573.565 [NAL9602](INFO): Powering down
2013-04-24T20:21:03.610Z,1366834863.610 [Default:CallIridium] Running Loop=1
2013-04-24T20:21:03.610Z,1366834863.610 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-24T20:21:03.610Z,1366834863.610 [Default:CallIridium:A] Running Loop=1
2013-04-24T20:21:03.611Z,1366834863.611 [Default:CallIridium:A] Stopped
2013-04-24T20:21:03.611Z,1366834863.611 [Default:CallIridium:B] Running Loop=1
2013-04-24T20:21:03.611Z,1366834863.611 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-24T20:21:08.599Z,1366834868.599 [Default:Iridium] Running Loop=1
2013-04-24T20:21:08.599Z,1366834868.599 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-24T20:21:08.599Z,1366834868.599 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-24T20:21:08.599Z,1366834868.599 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-24T20:21:08.599Z,1366834868.599 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-24T20:21:08.599Z,1366834868.599 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T20:21:08.600Z,1366834868.600 [Default:Iridium:B.GoToSurface] Stopped
2013-04-24T20:21:08.600Z,1366834868.600 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T20:21:08.600Z,1366834868.600 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-24T20:21:08.601Z,1366834868.601 [Default:GPS] Running Loop=1
2013-04-24T20:21:08.601Z,1366834868.601 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-24T20:21:08.601Z,1366834868.601 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-24T20:21:08.601Z,1366834868.601 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-24T20:21:08.601Z,1366834868.601 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-24T20:21:08.601Z,1366834868.601 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T20:21:08.602Z,1366834868.602 [Default:GPS:B.GoToSurface] Stopped
2013-04-24T20:21:08.602Z,1366834868.602 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T20:21:08.602Z,1366834868.602 [Default:GPS:Read_GPS] Running Loop=1
2013-04-24T20:21:09.283Z,1366834869.283 [NAL9602](INFO): Powering up
2013-04-24T20:21:20.266Z,1366834880.266 [NAL9602](INFO): NAL9602 initialized
2013-04-24T20:21:43.556Z,1366834903.556 [NAL9602](INFO): SBD MO Status=1, MOMSN=9822, MT Status=0, MTMSN=0
2013-04-24T20:21:43.606Z,1366834903.606 [NAL9602](INFO): Sent 332 bytes from file Logs/20130424T200753/shore0004.lzma
2013-04-24T20:21:43.606Z,1366834903.606 [NAL9602](INFO): Packets left to send: 1
2013-04-24T20:21:43.608Z,1366834903.608 [NAL9602](INFO): Stored copy of sent data in Logs/20130424T200753/shore0004.lzma.parts/0001.sbd
2013-04-24T20:21:55.936Z,1366834915.936 [NAL9602](INFO): SBD MO Status=1, MOMSN=9823, MT Status=0, MTMSN=0
2013-04-24T20:21:55.986Z,1366834915.986 [NAL9602](INFO): Sent 129 bytes from file Logs/20130424T200753/shore0004.lzma
2013-04-24T20:21:55.986Z,1366834915.986 [NAL9602](INFO): Packets left to send: 0
2013-04-24T20:21:55.987Z,1366834915.987 [NAL9602](INFO): Stored copy of sent data in Logs/20130424T200753/shore0004.lzma.parts/0000.sbd
2013-04-24T20:22:07.940Z,1366834927.940 [NAL9602](INFO): SBD MO Status=0, MOMSN=9824, MT Status=0, MTMSN=0
2013-04-24T20:22:11.715Z,1366834931.715 [Default:Iridium:Read_Iridium] Stopped
2013-04-24T20:22:11.715Z,1366834931.715 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-24T20:22:11.715Z,1366834931.715 [Default:Iridium] Stopped
2013-04-24T20:22:11.715Z,1366834931.715 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-24T20:22:11.715Z,1366834931.715 [Default:Iridium:A.SetSpeed] Stopped
2013-04-24T20:22:11.715Z,1366834931.715 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T20:22:12.159Z,1366834932.159 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-24T20:22:12.159Z,1366834932.159 [Default:CallIridium:B] Stopped
2013-04-24T20:22:12.159Z,1366834932.159 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-24T20:22:12.160Z,1366834932.159 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-24T20:22:12.160Z,1366834932.159 [Default:CallIridium] Stopped
2013-04-24T20:22:12.160Z,1366834932.160 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-24T20:22:12.846Z,1366834932.846 [NAL9602](IMPORTANT): GPS fix at: 1366834931.00
2013-04-24T20:22:12.862Z,1366834932.862 [Default:GPS:Read_GPS] Stopped
2013-04-24T20:22:12.862Z,1366834932.862 [Default:GPS:D] Running Loop=1
2013-04-24T20:22:13.289Z,1366834933.289 [Default:GPS:D] Stopped
2013-04-24T20:22:13.290Z,1366834933.290 [Default:GPS](INFO): Completed Default:GPS
2013-04-24T20:22:13.290Z,1366834933.290 [Default:GPS] Stopped
2013-04-24T20:22:13.290Z,1366834933.290 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-24T20:22:13.290Z,1366834933.290 [Default:GPS:A.SetSpeed] Stopped
2013-04-24T20:22:13.290Z,1366834933.290 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T20:22:33.831Z,1366834953.831 [NAL9602](INFO): Powering down
2013-04-24T20:27:08.842Z,1366835228.842 [Default:CallIridium] Running Loop=1
2013-04-24T20:27:08.842Z,1366835228.842 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-24T20:27:08.842Z,1366835228.842 [Default:CallIridium:A] Running Loop=1
2013-04-24T20:27:08.842Z,1366835228.842 [Default:CallIridium:A] Stopped
2013-04-24T20:27:08.842Z,1366835228.842 [Default:CallIridium:B] Running Loop=1
2013-04-24T20:27:08.843Z,1366835228.843 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-24T20:27:13.846Z,1366835233.846 [Default:Iridium] Running Loop=1
2013-04-24T20:27:13.846Z,1366835233.846 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-24T20:27:13.846Z,1366835233.846 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-24T20:27:13.846Z,1366835233.846 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-24T20:27:13.847Z,1366835233.847 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-24T20:27:13.847Z,1366835233.847 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T20:27:13.848Z,1366835233.848 [Default:Iridium:B.GoToSurface] Stopped
2013-04-24T20:27:13.848Z,1366835233.848 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T20:27:13.848Z,1366835233.848 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-24T20:27:13.848Z,1366835233.848 [Default:GPS] Running Loop=1
2013-04-24T20:27:13.848Z,1366835233.848 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-24T20:27:13.848Z,1366835233.848 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-24T20:27:13.848Z,1366835233.848 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-24T20:27:13.849Z,1366835233.849 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-24T20:27:13.849Z,1366835233.849 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T20:27:13.849Z,1366835233.849 [Default:GPS:B.GoToSurface] Stopped
2013-04-24T20:27:13.850Z,1366835233.850 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T20:27:13.850Z,1366835233.850 [Default:GPS:Read_GPS] Running Loop=1
2013-04-24T20:27:14.525Z,1366835234.525 [NAL9602](INFO): Powering up
2013-04-24T20:27:25.426Z,1366835245.426 [NAL9602](INFO): NAL9602 initialized
2013-04-24T20:27:56.582Z,1366835276.582 [NAL9602](IMPORTANT): GPS fix at: 1366835276.00
2013-04-24T20:27:56.598Z,1366835276.598 [Default:GPS:Read_GPS] Stopped
2013-04-24T20:27:56.598Z,1366835276.598 [Default:GPS:D] Running Loop=1
2013-04-24T20:27:57.011Z,1366835277.011 [Default:GPS:D] Stopped
2013-04-24T20:27:57.012Z,1366835277.012 [Default:GPS](INFO): Completed Default:GPS
2013-04-24T20:27:57.012Z,1366835277.012 [Default:GPS] Stopped
2013-04-24T20:27:57.012Z,1366835277.012 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-24T20:27:57.012Z,1366835277.012 [Default:GPS:A.SetSpeed] Stopped
2013-04-24T20:27:57.012Z,1366835277.012 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T20:28:17.753Z,1366835297.753 [NAL9602](INFO): SBD MO Status=1, MOMSN=9825, MT Status=0, MTMSN=0
2013-04-24T20:28:17.802Z,1366835297.802 [NAL9602](INFO): Sent 137 bytes from file Logs/20130424T200753/shore0005.lzma
2013-04-24T20:28:17.802Z,1366835297.802 [NAL9602](INFO): Packets left to send: 0
2013-04-24T20:28:17.804Z,1366835297.804 [NAL9602](INFO): Stored copy of sent data in Logs/20130424T200753/shore0005.lzma.parts/0000.sbd
2013-04-24T20:28:26.976Z,1366835306.977 [NAL9602](INFO): SBD MO Status=0, MOMSN=9826, MT Status=0, MTMSN=0
2013-04-24T20:28:30.753Z,1366835310.753 [Default:Iridium:Read_Iridium] Stopped
2013-04-24T20:28:30.753Z,1366835310.753 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-24T20:28:30.753Z,1366835310.753 [Default:Iridium] Stopped
2013-04-24T20:28:30.753Z,1366835310.753 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-24T20:28:30.754Z,1366835310.754 [Default:Iridium:A.SetSpeed] Stopped
2013-04-24T20:28:30.754Z,1366835310.754 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T20:28:31.170Z,1366835311.170 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-24T20:28:31.170Z,1366835311.170 [Default:CallIridium:B] Stopped
2013-04-24T20:28:31.170Z,1366835311.170 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-24T20:28:31.171Z,1366835311.171 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-24T20:28:31.171Z,1366835311.171 [Default:CallIridium] Stopped
2013-04-24T20:28:31.171Z,1366835311.171 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-24T20:28:41.256Z,1366835321.256 [NAL9602](INFO): Powering down
2013-04-24T20:33:31.195Z,1366835611.195 [Default:CallIridium] Running Loop=1
2013-04-24T20:33:31.195Z,1366835611.195 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-24T20:33:31.195Z,1366835611.195 [Default:CallIridium:A] Running Loop=1
2013-04-24T20:33:31.196Z,1366835611.196 [Default:CallIridium:A] Stopped
2013-04-24T20:33:31.196Z,1366835611.196 [Default:CallIridium:B] Running Loop=1
2013-04-24T20:33:31.196Z,1366835611.196 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-24T20:33:36.240Z,1366835616.240 [Default:Iridium] Running Loop=1
2013-04-24T20:33:36.241Z,1366835616.240 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-24T20:33:36.241Z,1366835616.241 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-24T20:33:36.241Z,1366835616.241 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-24T20:33:36.241Z,1366835616.241 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-24T20:33:36.241Z,1366835616.241 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T20:33:36.242Z,1366835616.242 [Default:Iridium:B.GoToSurface] Stopped
2013-04-24T20:33:36.242Z,1366835616.242 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T20:33:36.242Z,1366835616.242 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-24T20:33:36.242Z,1366835616.242 [Default:GPS] Running Loop=1
2013-04-24T20:33:36.242Z,1366835616.242 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-24T20:33:36.243Z,1366835616.243 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-24T20:33:36.243Z,1366835616.243 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-24T20:33:36.243Z,1366835616.243 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-24T20:33:36.243Z,1366835616.243 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T20:33:36.244Z,1366835616.244 [Default:GPS:B.GoToSurface] Stopped
2013-04-24T20:33:36.244Z,1366835616.244 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T20:33:36.244Z,1366835616.244 [Default:GPS:Read_GPS] Running Loop=1
2013-04-24T20:33:36.931Z,1366835616.931 [NAL9602](INFO): Powering up
2013-04-24T20:33:47.918Z,1366835627.918 [NAL9602](INFO): NAL9602 initialized
2013-04-24T20:34:02.751Z,1366835642.751 [NAL9602](IMPORTANT): GPS fix at: 1366835642.00
2013-04-24T20:34:02.769Z,1366835642.769 [Default:GPS:Read_GPS] Stopped
2013-04-24T20:34:02.770Z,1366835642.770 [Default:GPS:D] Running Loop=1
2013-04-24T20:34:03.087Z,1366835643.087 [Default:GPS:D] Stopped
2013-04-24T20:34:03.087Z,1366835643.087 [Default:GPS](INFO): Completed Default:GPS
2013-04-24T20:34:03.087Z,1366835643.087 [Default:GPS] Stopped
2013-04-24T20:34:03.087Z,1366835643.088 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-24T20:34:03.087Z,1366835643.088 [Default:GPS:A.SetSpeed] Stopped
2013-04-24T20:34:03.088Z,1366835643.088 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T20:36:33.853Z,1366835793.853 [NAL9602](INFO): SBD MO Status=1, MOMSN=9827, MT Status=0, MTMSN=0
2013-04-24T20:36:33.902Z,1366835793.902 [NAL9602](INFO): Sent 139 bytes from file Logs/20130424T200753/shore0006.lzma
2013-04-24T20:36:33.902Z,1366835793.902 [NAL9602](INFO): Packets left to send: 0
2013-04-24T20:36:33.904Z,1366835793.904 [NAL9602](INFO): Stored copy of sent data in Logs/20130424T200753/shore0006.lzma.parts/0000.sbd
2013-04-24T20:36:41.817Z,1366835801.816 [NAL9602](INFO): SBD MO Status=0, MOMSN=9828, MT Status=0, MTMSN=0
2013-04-24T20:36:45.581Z,1366835805.581 [Default:Iridium:Read_Iridium] Stopped
2013-04-24T20:36:45.582Z,1366835805.582 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-24T20:36:45.582Z,1366835805.582 [Default:Iridium] Stopped
2013-04-24T20:36:45.582Z,1366835805.582 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-24T20:36:45.582Z,1366835805.582 [Default:Iridium:A.SetSpeed] Stopped
2013-04-24T20:36:45.582Z,1366835805.582 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T20:36:45.966Z,1366835805.967 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-24T20:36:45.967Z,1366835805.967 [Default:CallIridium:B] Stopped
2013-04-24T20:36:45.967Z,1366835805.967 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-24T20:36:45.967Z,1366835805.967 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-24T20:36:45.967Z,1366835805.967 [Default:CallIridium] Stopped
2013-04-24T20:36:45.967Z,1366835805.967 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-24T20:36:55.932Z,1366835815.932 [NAL9602](INFO): Powering down
2013-04-24T20:41:45.980Z,1366836105.980 [Default:CallIridium] Running Loop=1
2013-04-24T20:41:45.980Z,1366836105.980 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-24T20:41:45.980Z,1366836105.980 [Default:CallIridium:A] Running Loop=1
2013-04-24T20:41:45.981Z,1366836105.981 [Default:CallIridium:A] Stopped
2013-04-24T20:41:45.981Z,1366836105.981 [Default:CallIridium:B] Running Loop=1
2013-04-24T20:41:45.981Z,1366836105.981 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-24T20:41:50.946Z,1366836110.946 [Default:Iridium] Running Loop=1
2013-04-24T20:41:50.947Z,1366836110.947 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-24T20:41:50.947Z,1366836110.947 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-24T20:41:50.947Z,1366836110.947 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-24T20:41:50.947Z,1366836110.947 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-24T20:41:50.947Z,1366836110.947 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T20:41:50.948Z,1366836110.948 [Default:Iridium:B.GoToSurface] Stopped
2013-04-24T20:41:50.948Z,1366836110.948 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T20:41:50.948Z,1366836110.948 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-24T20:41:50.948Z,1366836110.948 [Default:GPS] Running Loop=1
2013-04-24T20:41:50.948Z,1366836110.948 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-24T20:41:50.948Z,1366836110.948 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-24T20:41:50.948Z,1366836110.948 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-24T20:41:50.949Z,1366836110.949 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-24T20:41:50.949Z,1366836110.949 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T20:41:50.950Z,1366836110.950 [Default:GPS:B.GoToSurface] Stopped
2013-04-24T20:41:50.950Z,1366836110.950 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T20:41:50.950Z,1366836110.950 [Default:GPS:Read_GPS] Running Loop=1
2013-04-24T20:41:51.579Z,1366836111.579 [NAL9602](INFO): Powering up
2013-04-24T20:42:02.422Z,1366836122.422 [NAL9602](INFO): NAL9602 initialized
2013-04-24T20:42:26.973Z,1366836146.973 [NAL9602](INFO): SBD MO Status=1, MOMSN=9829, MT Status=0, MTMSN=0
2013-04-24T20:42:27.024Z,1366836147.024 [NAL9602](INFO): Sent 137 bytes from file Logs/20130424T200753/shore0007.lzma
2013-04-24T20:42:27.024Z,1366836147.024 [NAL9602](INFO): Packets left to send: 0
2013-04-24T20:42:27.026Z,1366836147.026 [NAL9602](INFO): Stored copy of sent data in Logs/20130424T200753/shore0007.lzma.parts/0000.sbd
2013-04-24T20:42:34.571Z,1366836154.571 [NAL9602](INFO): SBD MO Status=0, MOMSN=9830, MT Status=0, MTMSN=0
2013-04-24T20:42:38.355Z,1366836158.355 [Default:Iridium:Read_Iridium] Stopped
2013-04-24T20:42:38.356Z,1366836158.356 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-24T20:42:38.356Z,1366836158.356 [Default:Iridium] Stopped
2013-04-24T20:42:38.356Z,1366836158.356 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-24T20:42:38.356Z,1366836158.356 [Default:Iridium:A.SetSpeed] Stopped
2013-04-24T20:42:38.356Z,1366836158.356 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T20:42:38.765Z,1366836158.765 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-24T20:42:38.765Z,1366836158.765 [Default:CallIridium:B] Stopped
2013-04-24T20:42:38.765Z,1366836158.765 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-24T20:42:38.766Z,1366836158.766 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-24T20:42:38.766Z,1366836158.766 [Default:CallIridium] Stopped
2013-04-24T20:42:38.766Z,1366836158.766 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-24T20:43:14.966Z,1366836194.966 [NAL9602](IMPORTANT): GPS fix at: 1366836196.00
2013-04-24T20:43:14.982Z,1366836194.982 [Default:GPS:Read_GPS] Stopped
2013-04-24T20:43:14.982Z,1366836194.982 [Default:GPS:D] Running Loop=1
2013-04-24T20:43:15.391Z,1366836195.391 [Default:GPS:D] Stopped
2013-04-24T20:43:15.392Z,1366836195.392 [Default:GPS](INFO): Completed Default:GPS
2013-04-24T20:43:15.392Z,1366836195.392 [Default:GPS] Stopped
2013-04-24T20:43:15.392Z,1366836195.392 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-24T20:43:15.392Z,1366836195.392 [Default:GPS:A.SetSpeed] Stopped
2013-04-24T20:43:15.392Z,1366836195.392 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T20:43:35.944Z,1366836215.944 [NAL9602](INFO): Powering down
2013-04-24T20:47:35.946Z,1366836455.946 [Default:CallIridium] Running Loop=1
2013-04-24T20:47:35.946Z,1366836455.946 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-24T20:47:35.946Z,1366836455.946 [Default:CallIridium:A] Running Loop=1
2013-04-24T20:47:35.946Z,1366836455.946 [Default:CallIridium:A] Stopped
2013-04-24T20:47:35.947Z,1366836455.947 [Default:CallIridium:B] Running Loop=1
2013-04-24T20:47:35.947Z,1366836455.947 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-24T20:47:40.907Z,1366836460.907 [Default:Iridium] Running Loop=1
2013-04-24T20:47:40.907Z,1366836460.907 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-24T20:47:40.907Z,1366836460.907 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-24T20:47:40.907Z,1366836460.907 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-24T20:47:40.907Z,1366836460.907 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-24T20:47:40.907Z,1366836460.907 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T20:47:40.908Z,1366836460.908 [Default:Iridium:B.GoToSurface] Stopped
2013-04-24T20:47:40.908Z,1366836460.908 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T20:47:40.908Z,1366836460.908 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-24T20:47:40.909Z,1366836460.909 [Default:GPS] Running Loop=1
2013-04-24T20:47:40.909Z,1366836460.909 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-24T20:47:40.909Z,1366836460.909 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-24T20:47:40.909Z,1366836460.909 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-24T20:47:40.909Z,1366836460.909 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-24T20:47:40.909Z,1366836460.909 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T20:47:40.910Z,1366836460.910 [Default:GPS:B.GoToSurface] Stopped
2013-04-24T20:47:40.910Z,1366836460.910 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T20:47:40.910Z,1366836460.910 [Default:GPS:Read_GPS] Running Loop=1
2013-04-24T20:47:41.565Z,1366836461.565 [NAL9602](INFO): Powering up
2013-04-24T20:47:52.478Z,1366836472.478 [NAL9602](INFO): NAL9602 initialized
2013-04-24T20:48:11.823Z,1366836491.823 [NAL9602](INFO): SBD MO Status=1, MOMSN=9831, MT Status=0, MTMSN=0
2013-04-24T20:48:11.870Z,1366836491.870 [NAL9602](INFO): Sent 164 bytes from file Logs/20130424T200753/shore0008.lzma
2013-04-24T20:48:11.870Z,1366836491.870 [NAL9602](INFO): Packets left to send: 0
2013-04-24T20:48:11.872Z,1366836491.872 [NAL9602](INFO): Stored copy of sent data in Logs/20130424T200753/shore0008.lzma.parts/0000.sbd
2013-04-24T20:48:19.823Z,1366836499.823 [NAL9602](INFO): SBD MO Status=0, MOMSN=9832, MT Status=0, MTMSN=0
2013-04-24T20:48:23.599Z,1366836503.599 [Default:Iridium:Read_Iridium] Stopped
2013-04-24T20:48:23.599Z,1366836503.599 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-24T20:48:23.599Z,1366836503.599 [Default:Iridium] Stopped
2013-04-24T20:48:23.600Z,1366836503.600 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-24T20:48:23.600Z,1366836503.600 [Default:Iridium:A.SetSpeed] Stopped
2013-04-24T20:48:23.600Z,1366836503.600 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T20:48:24.004Z,1366836504.004 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-24T20:48:24.004Z,1366836504.004 [Default:CallIridium:B] Stopped
2013-04-24T20:48:24.004Z,1366836504.004 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-24T20:48:24.004Z,1366836504.004 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-24T20:48:24.004Z,1366836504.004 [Default:CallIridium] Stopped
2013-04-24T20:48:24.005Z,1366836504.005 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-24T20:48:24.635Z,1366836504.635 [NAL9602](IMPORTANT): GPS fix at: 1366836506.00
2013-04-24T20:48:24.650Z,1366836504.650 [Default:GPS:Read_GPS] Stopped
2013-04-24T20:48:24.650Z,1366836504.650 [Default:GPS:D] Running Loop=1
2013-04-24T20:48:25.063Z,1366836505.063 [Default:GPS:D] Stopped
2013-04-24T20:48:25.064Z,1366836505.064 [Default:GPS](INFO): Completed Default:GPS
2013-04-24T20:48:25.064Z,1366836505.064 [Default:GPS] Stopped
2013-04-24T20:48:25.064Z,1366836505.064 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-24T20:48:25.064Z,1366836505.064 [Default:GPS:A.SetSpeed] Stopped
2013-04-24T20:48:25.064Z,1366836505.064 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T20:48:45.630Z,1366836525.630 [NAL9602](INFO): Powering down
2013-04-24T20:53:20.607Z,1366836800.607 [Default:CallIridium] Running Loop=1
2013-04-24T20:53:20.607Z,1366836800.607 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-24T20:53:20.607Z,1366836800.607 [Default:CallIridium:A] Running Loop=1
2013-04-24T20:53:20.607Z,1366836800.607 [Default:CallIridium:A] Stopped
2013-04-24T20:53:20.608Z,1366836800.608 [Default:CallIridium:B] Running Loop=1
2013-04-24T20:53:20.608Z,1366836800.608 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-24T20:53:25.645Z,1366836805.645 [Default:Iridium] Running Loop=1
2013-04-24T20:53:25.645Z,1366836805.645 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-24T20:53:25.645Z,1366836805.645 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-24T20:53:25.645Z,1366836805.645 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-24T20:53:25.645Z,1366836805.645 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-24T20:53:25.646Z,1366836805.646 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T20:53:25.646Z,1366836805.647 [Default:Iridium:B.GoToSurface] Stopped
2013-04-24T20:53:25.647Z,1366836805.647 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T20:53:25.647Z,1366836805.647 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-24T20:53:25.647Z,1366836805.647 [Default:GPS] Running Loop=1
2013-04-24T20:53:25.647Z,1366836805.647 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-24T20:53:25.647Z,1366836805.647 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-24T20:53:25.647Z,1366836805.647 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-24T20:53:25.647Z,1366836805.648 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-24T20:53:25.648Z,1366836805.648 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T20:53:25.648Z,1366836805.648 [Default:GPS:B.GoToSurface] Stopped
2013-04-24T20:53:25.648Z,1366836805.648 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T20:53:25.649Z,1366836805.649 [Default:GPS:Read_GPS] Running Loop=1
2013-04-24T20:53:26.240Z,1366836806.240 [NAL9602](INFO): Powering up
2013-04-24T20:53:37.238Z,1366836817.238 [NAL9602](INFO): NAL9602 initialized
2013-04-24T20:53:54.169Z,1366836834.169 [NAL9602](INFO): SBD MO Status=1, MOMSN=9833, MT Status=0, MTMSN=0
2013-04-24T20:53:54.218Z,1366836834.218 [NAL9602](INFO): Sent 162 bytes from file Logs/20130424T200753/shore0009.lzma
2013-04-24T20:53:54.218Z,1366836834.218 [NAL9602](INFO): Packets left to send: 0
2013-04-24T20:53:54.220Z,1366836834.220 [NAL9602](INFO): Stored copy of sent data in Logs/20130424T200753/shore0009.lzma.parts/0000.sbd
2013-04-24T20:54:03.774Z,1366836843.773 [NAL9602](INFO): SBD MO Status=0, MOMSN=9834, MT Status=0, MTMSN=0
2013-04-24T20:54:07.432Z,1366836847.432 [Default:Iridium:Read_Iridium] Stopped
2013-04-24T20:54:07.433Z,1366836847.433 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-24T20:54:07.433Z,1366836847.433 [Default:Iridium] Stopped
2013-04-24T20:54:07.433Z,1366836847.433 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-24T20:54:07.433Z,1366836847.433 [Default:Iridium:A.SetSpeed] Stopped
2013-04-24T20:54:07.433Z,1366836847.433 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T20:54:07.755Z,1366836847.755 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-24T20:54:07.755Z,1366836847.755 [Default:CallIridium:B] Stopped
2013-04-24T20:54:07.755Z,1366836847.755 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-24T20:54:07.755Z,1366836847.755 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-24T20:54:07.755Z,1366836847.755 [Default:CallIridium] Stopped
2013-04-24T20:54:07.755Z,1366836847.755 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-24T20:54:08.479Z,1366836848.479 [NAL9602](IMPORTANT): GPS fix at: 1366836850.00
2013-04-24T20:54:08.494Z,1366836848.494 [Default:GPS:Read_GPS] Stopped
2013-04-24T20:54:08.494Z,1366836848.494 [Default:GPS:D] Running Loop=1
2013-04-24T20:54:08.859Z,1366836848.859 [Default:GPS:D] Stopped
2013-04-24T20:54:08.859Z,1366836848.859 [Default:GPS](INFO): Completed Default:GPS
2013-04-24T20:54:08.859Z,1366836848.859 [Default:GPS] Stopped
2013-04-24T20:54:08.859Z,1366836848.859 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-24T20:54:08.859Z,1366836848.859 [Default:GPS:A.SetSpeed] Stopped
2013-04-24T20:54:08.859Z,1366836848.859 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T20:54:29.408Z,1366836869.408 [NAL9602](INFO): Powering down
2013-04-24T20:59:04.445Z,1366837144.445 [Default:CallIridium] Running Loop=1
2013-04-24T20:59:04.445Z,1366837144.445 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-24T20:59:04.446Z,1366837144.446 [Default:CallIridium:A] Running Loop=1
2013-04-24T20:59:04.446Z,1366837144.446 [Default:CallIridium:A] Stopped
2013-04-24T20:59:04.446Z,1366837144.446 [Default:CallIridium:B] Running Loop=1
2013-04-24T20:59:04.446Z,1366837144.446 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-24T20:59:09.399Z,1366837149.399 [Default:Iridium] Running Loop=1
2013-04-24T20:59:09.399Z,1366837149.399 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-24T20:59:09.399Z,1366837149.399 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-24T20:59:09.399Z,1366837149.399 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-24T20:59:09.399Z,1366837149.399 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-24T20:59:09.399Z,1366837149.399 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T20:59:09.400Z,1366837149.400 [Default:Iridium:B.GoToSurface] Stopped
2013-04-24T20:59:09.400Z,1366837149.400 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T20:59:09.400Z,1366837149.400 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-24T20:59:09.401Z,1366837149.401 [Default:GPS] Running Loop=1
2013-04-24T20:59:09.401Z,1366837149.401 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-24T20:59:09.401Z,1366837149.401 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-24T20:59:09.401Z,1366837149.401 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-24T20:59:09.401Z,1366837149.401 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-24T20:59:09.401Z,1366837149.401 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T20:59:09.402Z,1366837149.402 [Default:GPS:B.GoToSurface] Stopped
2013-04-24T20:59:09.402Z,1366837149.402 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T20:59:09.402Z,1366837149.402 [Default:GPS:Read_GPS] Running Loop=1
2013-04-24T20:59:10.064Z,1366837150.064 [NAL9602](INFO): Powering up
2013-04-24T20:59:20.986Z,1366837160.986 [NAL9602](INFO): NAL9602 initialized
2013-04-24T20:59:30.274Z,1366837170.274 [NAL9602](IMPORTANT): GPS fix at: 1366836850.00
2013-04-24T20:59:30.290Z,1366837170.290 [Default:GPS:Read_GPS] Stopped
2013-04-24T20:59:30.290Z,1366837170.290 [Default:GPS:D] Running Loop=1
2013-04-24T20:59:30.703Z,1366837170.703 [Default:GPS:D] Stopped
2013-04-24T20:59:30.703Z,1366837170.704 [Default:GPS](INFO): Completed Default:GPS
2013-04-24T20:59:30.704Z,1366837170.704 [Default:GPS] Stopped
2013-04-24T20:59:30.704Z,1366837170.704 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-24T20:59:30.704Z,1366837170.704 [Default:GPS:A.SetSpeed] Stopped
2013-04-24T20:59:30.704Z,1366837170.704 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T21:04:12.264Z,1366837452.264 [NAL9602](INFO): SBD MO Status=2, MOMSN=9835, MT Status=2, MTMSN=0
2013-04-24T21:04:12.265Z,1366837452.265 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-24T21:04:45.322Z,1366837485.322 [NAL9602](INFO): SBD MO Status=2, MOMSN=9835, MT Status=2, MTMSN=0
2013-04-24T21:04:45.323Z,1366837485.323 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-24T21:05:05.988Z,1366837505.988 [NAL9602](INFO): SBD MO Status=2, MOMSN=9835, MT Status=2, MTMSN=0
2013-04-24T21:05:05.988Z,1366837505.989 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-24T21:05:23.808Z,1366837523.809 [NAL9602](INFO): SBD MO Status=2, MOMSN=9835, MT Status=2, MTMSN=0
2013-04-24T21:05:23.809Z,1366837523.809 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-24T21:06:05.681Z,1366837565.681 [NAL9602](INFO): SBD MO Status=2, MOMSN=9835, MT Status=2, MTMSN=0
2013-04-24T21:06:05.681Z,1366837565.681 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-24T21:06:28.700Z,1366837588.700 [NAL9602](INFO): SBD MO Status=1, MOMSN=9835, MT Status=0, MTMSN=0
2013-04-24T21:06:28.750Z,1366837588.750 [NAL9602](INFO): Sent 141 bytes from file Logs/20130424T200753/shore0010.lzma
2013-04-24T21:06:28.750Z,1366837588.750 [NAL9602](INFO): Packets left to send: 0
2013-04-24T21:06:28.756Z,1366837588.756 [NAL9602](INFO): Stored copy of sent data in Logs/20130424T200753/shore0010.lzma.parts/0000.sbd
2013-04-24T21:06:49.905Z,1366837609.905 [NAL9602](INFO): SBD MO Status=0, MOMSN=9836, MT Status=0, MTMSN=0
2013-04-24T21:06:53.512Z,1366837613.512 [Default:Iridium:Read_Iridium] Stopped
2013-04-24T21:06:53.512Z,1366837613.512 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-24T21:06:53.512Z,1366837613.513 [Default:Iridium] Stopped
2013-04-24T21:06:53.513Z,1366837613.513 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-24T21:06:53.513Z,1366837613.513 [Default:Iridium:A.SetSpeed] Stopped
2013-04-24T21:06:53.513Z,1366837613.513 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T21:06:53.879Z,1366837613.879 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-24T21:06:53.879Z,1366837613.879 [Default:CallIridium:B] Stopped
2013-04-24T21:06:53.879Z,1366837613.879 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-24T21:06:53.879Z,1366837613.879 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-24T21:06:53.879Z,1366837613.879 [Default:CallIridium] Stopped
2013-04-24T21:06:53.879Z,1366837613.879 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-24T21:07:03.931Z,1366837623.931 [NAL9602](INFO): Powering down
2013-04-24T21:11:53.903Z,1366837913.903 [Default:CallIridium] Running Loop=1
2013-04-24T21:11:53.903Z,1366837913.903 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-24T21:11:53.903Z,1366837913.903 [Default:CallIridium:A] Running Loop=1
2013-04-24T21:11:53.904Z,1366837913.904 [Default:CallIridium:A] Stopped
2013-04-24T21:11:53.904Z,1366837913.904 [Default:CallIridium:B] Running Loop=1
2013-04-24T21:11:53.904Z,1366837913.904 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-24T21:11:58.938Z,1366837918.938 [Default:Iridium] Running Loop=1
2013-04-24T21:11:58.938Z,1366837918.938 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-24T21:11:58.939Z,1366837918.939 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-24T21:11:58.939Z,1366837918.939 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-24T21:11:58.939Z,1366837918.939 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-24T21:11:58.939Z,1366837918.939 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T21:11:58.940Z,1366837918.940 [Default:Iridium:B.GoToSurface] Stopped
2013-04-24T21:11:58.940Z,1366837918.940 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T21:11:58.940Z,1366837918.940 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-24T21:11:58.940Z,1366837918.940 [Default:GPS] Running Loop=1
2013-04-24T21:11:58.940Z,1366837918.940 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-24T21:11:58.941Z,1366837918.941 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-24T21:11:58.941Z,1366837918.941 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-24T21:11:58.941Z,1366837918.941 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-24T21:11:58.941Z,1366837918.941 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T21:11:58.942Z,1366837918.942 [Default:GPS:B.GoToSurface] Stopped
2013-04-24T21:11:58.942Z,1366837918.942 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T21:11:58.942Z,1366837918.942 [Default:GPS:Read_GPS] Running Loop=1
2013-04-24T21:11:59.530Z,1366837919.530 [NAL9602](INFO): Powering up
2013-04-24T21:12:10.058Z,1366837930.058 [NAL9602](INFO): NAL9602 initialized
2013-04-24T21:12:39.023Z,1366837959.023 [NAL9602](INFO): SBD MO Status=2, MOMSN=9837, MT Status=0, MTMSN=0
2013-04-24T21:12:39.023Z,1366837959.023 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-24T21:12:40.234Z,1366837960.234 [NAL9602](IMPORTANT): GPS fix at: 1366837964.00
2013-04-24T21:12:40.250Z,1366837960.250 [Default:GPS:Read_GPS] Stopped
2013-04-24T21:12:40.251Z,1366837960.251 [Default:GPS:D] Running Loop=1
2013-04-24T21:12:40.691Z,1366837960.691 [Default:GPS:D] Stopped
2013-04-24T21:12:40.691Z,1366837960.691 [Default:GPS](INFO): Completed Default:GPS
2013-04-24T21:12:40.692Z,1366837960.691 [Default:GPS] Stopped
2013-04-24T21:12:40.692Z,1366837960.692 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-24T21:12:40.692Z,1366837960.692 [Default:GPS:A.SetSpeed] Stopped
2013-04-24T21:12:40.692Z,1366837960.692 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T21:13:08.827Z,1366837988.828 [NAL9602](INFO): SBD MO Status=1, MOMSN=9837, MT Status=0, MTMSN=0
2013-04-24T21:13:08.878Z,1366837988.878 [NAL9602](INFO): Sent 139 bytes from file Logs/20130424T200753/shore0011.lzma
2013-04-24T21:13:08.878Z,1366837988.878 [NAL9602](INFO): Packets left to send: 0
2013-04-24T21:13:08.880Z,1366837988.880 [NAL9602](INFO): Stored copy of sent data in Logs/20130424T200753/shore0011.lzma.parts/0000.sbd
2013-04-24T21:13:25.228Z,1366838005.228 [NAL9602](INFO): SBD MO Status=2, MOMSN=9838, MT Status=2, MTMSN=0
2013-04-24T21:13:25.228Z,1366838005.228 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-24T21:13:29.994Z,1366838009.994 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-04-24T21:13:39.524Z,1366838019.524 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-04-24T21:13:59.393Z,1366838039.392 [NAL9602](INFO): SBD MO Status=2, MOMSN=9838, MT Status=2, MTMSN=0
2013-04-24T21:13:59.393Z,1366838039.393 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-24T21:14:04.533Z,1366838044.533 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-04-24T21:14:06.848Z,1366838046.848 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-04-24T21:14:14.384Z,1366838054.384 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-04-24T21:14:18.397Z,1366838058.397 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-04-24T21:14:27.060Z,1366838067.060 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-04-24T21:14:31.380Z,1366838071.379 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-04-24T21:14:35.750Z,1366838075.750 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-04-24T21:14:59.273Z,1366838099.273 [NAL9602](INFO): SBD MO Status=0, MOMSN=9838, MT Status=0, MTMSN=0
2013-04-24T21:15:02.926Z,1366838102.926 [Default:Iridium:Read_Iridium] Stopped
2013-04-24T21:15:02.927Z,1366838102.927 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-24T21:15:02.927Z,1366838102.927 [Default:Iridium] Stopped
2013-04-24T21:15:02.927Z,1366838102.927 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-24T21:15:02.927Z,1366838102.927 [Default:Iridium:A.SetSpeed] Stopped
2013-04-24T21:15:02.927Z,1366838102.927 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T21:15:03.246Z,1366838103.246 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-24T21:15:03.246Z,1366838103.246 [Default:CallIridium:B] Stopped
2013-04-24T21:15:03.246Z,1366838103.246 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-24T21:15:03.247Z,1366838103.247 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-24T21:15:03.247Z,1366838103.247 [Default:CallIridium] Stopped
2013-04-24T21:15:03.247Z,1366838103.247 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-24T21:15:13.284Z,1366838113.284 [NAL9602](INFO): Powering down
2013-04-24T21:20:03.286Z,1366838403.286 [Default:CallIridium] Running Loop=1
2013-04-24T21:20:03.287Z,1366838403.287 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-24T21:20:03.287Z,1366838403.287 [Default:CallIridium:A] Running Loop=1
2013-04-24T21:20:03.287Z,1366838403.287 [Default:CallIridium:A] Stopped
2013-04-24T21:20:03.287Z,1366838403.287 [Default:CallIridium:B] Running Loop=1
2013-04-24T21:20:03.287Z,1366838403.287 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-24T21:20:08.306Z,1366838408.306 [Default:Iridium] Running Loop=1
2013-04-24T21:20:08.306Z,1366838408.306 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-24T21:20:08.306Z,1366838408.306 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-24T21:20:08.306Z,1366838408.306 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-24T21:20:08.307Z,1366838408.307 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-24T21:20:08.307Z,1366838408.307 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T21:20:08.308Z,1366838408.308 [Default:Iridium:B.GoToSurface] Stopped
2013-04-24T21:20:08.308Z,1366838408.308 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T21:20:08.308Z,1366838408.308 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-24T21:20:08.308Z,1366838408.308 [Default:GPS] Running Loop=1
2013-04-24T21:20:08.308Z,1366838408.308 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-24T21:20:08.308Z,1366838408.308 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-24T21:20:08.308Z,1366838408.309 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-24T21:20:08.309Z,1366838408.309 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-24T21:20:08.309Z,1366838408.309 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T21:20:08.310Z,1366838408.310 [Default:GPS:B.GoToSurface] Stopped
2013-04-24T21:20:08.310Z,1366838408.310 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T21:20:08.310Z,1366838408.310 [Default:GPS:Read_GPS] Running Loop=1
2013-04-24T21:20:08.956Z,1366838408.956 [NAL9602](INFO): Powering up
2013-04-24T21:20:19.762Z,1366838419.762 [NAL9602](INFO): NAL9602 initialized
2013-04-24T21:20:52.288Z,1366838452.288 [NAL9602](INFO): SBD MO Status=1, MOMSN=9839, MT Status=0, MTMSN=0
2013-04-24T21:20:52.338Z,1366838452.338 [NAL9602](INFO): Sent 165 bytes from file Logs/20130424T200753/shore0012.lzma
2013-04-24T21:20:52.338Z,1366838452.338 [NAL9602](INFO): Packets left to send: 0
2013-04-24T21:20:52.340Z,1366838452.340 [NAL9602](INFO): Stored copy of sent data in Logs/20130424T200753/shore0012.lzma.parts/0000.sbd
2013-04-24T21:21:03.117Z,1366838463.117 [NAL9602](INFO): SBD MO Status=0, MOMSN=9840, MT Status=0, MTMSN=0
2013-04-24T21:21:06.754Z,1366838466.754 [Default:Iridium:Read_Iridium] Stopped
2013-04-24T21:21:06.754Z,1366838466.754 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-24T21:21:06.754Z,1366838466.754 [Default:Iridium] Stopped
2013-04-24T21:21:06.755Z,1366838466.755 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-24T21:21:06.755Z,1366838466.755 [Default:Iridium:A.SetSpeed] Stopped
2013-04-24T21:21:06.755Z,1366838466.755 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T21:21:07.144Z,1366838467.144 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-24T21:21:07.144Z,1366838467.144 [Default:CallIridium:B] Stopped
2013-04-24T21:21:07.145Z,1366838467.145 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-24T21:21:07.145Z,1366838467.145 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-24T21:21:07.145Z,1366838467.145 [Default:CallIridium] Stopped
2013-04-24T21:21:07.145Z,1366838467.145 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-24T21:21:07.758Z,1366838467.758 [NAL9602](IMPORTANT): GPS fix at: 1366838472.00
2013-04-24T21:21:07.773Z,1366838467.773 [Default:GPS:Read_GPS] Stopped
2013-04-24T21:21:07.774Z,1366838467.773 [Default:GPS:D] Running Loop=1
2013-04-24T21:21:08.177Z,1366838468.177 [Default:GPS:D] Stopped
2013-04-24T21:21:08.178Z,1366838468.178 [Default:GPS](INFO): Completed Default:GPS
2013-04-24T21:21:08.178Z,1366838468.178 [Default:GPS] Stopped
2013-04-24T21:21:08.178Z,1366838468.178 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-24T21:21:08.178Z,1366838468.178 [Default:GPS:A.SetSpeed] Stopped
2013-04-24T21:21:08.178Z,1366838468.178 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T21:21:28.756Z,1366838488.756 [NAL9602](INFO): Powering down
2013-04-24T21:26:03.702Z,1366838763.702 [Default:CallIridium] Running Loop=1
2013-04-24T21:26:03.702Z,1366838763.702 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-24T21:26:03.703Z,1366838763.703 [Default:CallIridium:A] Running Loop=1
2013-04-24T21:26:03.703Z,1366838763.703 [Default:CallIridium:A] Stopped
2013-04-24T21:26:03.703Z,1366838763.703 [Default:CallIridium:B] Running Loop=1
2013-04-24T21:26:03.703Z,1366838763.703 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-24T21:26:08.736Z,1366838768.736 [Default:Iridium] Running Loop=1
2013-04-24T21:26:08.736Z,1366838768.736 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-24T21:26:08.737Z,1366838768.737 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-24T21:26:08.737Z,1366838768.737 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-24T21:26:08.737Z,1366838768.737 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-24T21:26:08.737Z,1366838768.737 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T21:26:08.738Z,1366838768.738 [Default:Iridium:B.GoToSurface] Stopped
2013-04-24T21:26:08.738Z,1366838768.738 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T21:26:08.738Z,1366838768.738 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-24T21:26:08.738Z,1366838768.738 [Default:GPS] Running Loop=1
2013-04-24T21:26:08.739Z,1366838768.739 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-24T21:26:08.739Z,1366838768.739 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-24T21:26:08.739Z,1366838768.739 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-24T21:26:08.739Z,1366838768.739 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-24T21:26:08.739Z,1366838768.739 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T21:26:08.740Z,1366838768.740 [Default:GPS:B.GoToSurface] Stopped
2013-04-24T21:26:08.740Z,1366838768.740 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T21:26:08.740Z,1366838768.740 [Default:GPS:Read_GPS] Running Loop=1
2013-04-24T21:26:09.378Z,1366838769.378 [NAL9602](INFO): Powering up
2013-04-24T21:26:19.922Z,1366838779.922 [NAL9602](INFO): NAL9602 initialized
2013-04-24T21:26:37.233Z,1366838797.233 [NAL9602](INFO): SBD MO Status=1, MOMSN=9841, MT Status=0, MTMSN=0
2013-04-24T21:26:37.282Z,1366838797.282 [NAL9602](INFO): Sent 139 bytes from file Logs/20130424T200753/shore0013.lzma
2013-04-24T21:26:37.282Z,1366838797.282 [NAL9602](INFO): Packets left to send: 0
2013-04-24T21:26:37.287Z,1366838797.287 [NAL9602](INFO): Stored copy of sent data in Logs/20130424T200753/shore0013.lzma.parts/0000.sbd
2013-04-24T21:26:45.637Z,1366838805.637 [NAL9602](INFO): SBD MO Status=0, MOMSN=9842, MT Status=0, MTMSN=0
2013-04-24T21:26:49.414Z,1366838809.414 [Default:Iridium:Read_Iridium] Stopped
2013-04-24T21:26:49.415Z,1366838809.415 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-24T21:26:49.415Z,1366838809.415 [Default:Iridium] Stopped
2013-04-24T21:26:49.415Z,1366838809.415 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-24T21:26:49.415Z,1366838809.415 [Default:Iridium:A.SetSpeed] Stopped
2013-04-24T21:26:49.415Z,1366838809.415 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T21:26:49.803Z,1366838809.803 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-24T21:26:49.803Z,1366838809.803 [Default:CallIridium:B] Stopped
2013-04-24T21:26:49.803Z,1366838809.803 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-24T21:26:49.804Z,1366838809.804 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-24T21:26:49.804Z,1366838809.804 [Default:CallIridium] Stopped
2013-04-24T21:26:49.804Z,1366838809.804 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-24T21:26:50.429Z,1366838810.429 [NAL9602](IMPORTANT): GPS fix at: 1366838815.00
2013-04-24T21:26:50.444Z,1366838810.444 [Default:GPS:Read_GPS] Stopped
2013-04-24T21:26:50.444Z,1366838810.444 [Default:GPS:D] Running Loop=1
2013-04-24T21:26:50.859Z,1366838810.859 [Default:GPS:D] Stopped
2013-04-24T21:26:50.859Z,1366838810.859 [Default:GPS](INFO): Completed Default:GPS
2013-04-24T21:26:50.859Z,1366838810.859 [Default:GPS] Stopped
2013-04-24T21:26:50.860Z,1366838810.860 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-24T21:26:50.860Z,1366838810.860 [Default:GPS:A.SetSpeed] Stopped
2013-04-24T21:26:50.860Z,1366838810.860 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T21:27:11.431Z,1366838831.431 [NAL9602](INFO): Powering down
2013-04-24T21:31:46.407Z,1366839106.407 [Default:CallIridium] Running Loop=1
2013-04-24T21:31:46.407Z,1366839106.407 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-24T21:31:46.407Z,1366839106.407 [Default:CallIridium:A] Running Loop=1
2013-04-24T21:31:46.407Z,1366839106.407 [Default:CallIridium:A] Stopped
2013-04-24T21:31:46.407Z,1366839106.408 [Default:CallIridium:B] Running Loop=1
2013-04-24T21:31:46.408Z,1366839106.408 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-24T21:31:51.446Z,1366839111.446 [Default:Iridium] Running Loop=1
2013-04-24T21:31:51.447Z,1366839111.447 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-24T21:31:51.447Z,1366839111.447 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-24T21:31:51.447Z,1366839111.447 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-24T21:31:51.447Z,1366839111.447 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-24T21:31:51.447Z,1366839111.447 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T21:31:51.448Z,1366839111.448 [Default:Iridium:B.GoToSurface] Stopped
2013-04-24T21:31:51.448Z,1366839111.448 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T21:31:51.448Z,1366839111.448 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-24T21:31:51.448Z,1366839111.448 [Default:GPS] Running Loop=1
2013-04-24T21:31:51.448Z,1366839111.448 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-24T21:31:51.449Z,1366839111.448 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-24T21:31:51.449Z,1366839111.448 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-24T21:31:51.449Z,1366839111.449 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-24T21:31:51.449Z,1366839111.449 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T21:31:51.450Z,1366839111.450 [Default:GPS:B.GoToSurface] Stopped
2013-04-24T21:31:51.450Z,1366839111.450 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T21:31:51.450Z,1366839111.450 [Default:GPS:Read_GPS] Running Loop=1
2013-04-24T21:31:52.044Z,1366839112.044 [NAL9602](INFO): Powering up
2013-04-24T21:32:02.646Z,1366839122.646 [NAL9602](INFO): NAL9602 initialized
2013-04-24T21:32:27.952Z,1366839147.953 [NAL9602](INFO): SBD MO Status=1, MOMSN=9843, MT Status=0, MTMSN=0
2013-04-24T21:32:28.014Z,1366839148.014 [NAL9602](INFO): Sent 136 bytes from file Logs/20130424T200753/shore0014.lzma
2013-04-24T21:32:28.014Z,1366839148.014 [NAL9602](INFO): Packets left to send: 0
2013-04-24T21:32:28.016Z,1366839148.016 [NAL9602](INFO): Stored copy of sent data in Logs/20130424T200753/shore0014.lzma.parts/0000.sbd
2013-04-24T21:32:37.557Z,1366839157.556 [NAL9602](INFO): SBD MO Status=0, MOMSN=9844, MT Status=0, MTMSN=0
2013-04-24T21:32:41.142Z,1366839161.142 [Default:Iridium:Read_Iridium] Stopped
2013-04-24T21:32:41.143Z,1366839161.143 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-24T21:32:41.143Z,1366839161.143 [Default:Iridium] Stopped
2013-04-24T21:32:41.143Z,1366839161.143 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-24T21:32:41.143Z,1366839161.143 [Default:Iridium:A.SetSpeed] Stopped
2013-04-24T21:32:41.143Z,1366839161.143 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T21:32:41.511Z,1366839161.511 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-24T21:32:41.511Z,1366839161.511 [Default:CallIridium:B] Stopped
2013-04-24T21:32:41.511Z,1366839161.511 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-24T21:32:41.511Z,1366839161.511 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-24T21:32:41.511Z,1366839161.511 [Default:CallIridium] Stopped
2013-04-24T21:32:41.512Z,1366839161.511 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-24T21:32:42.154Z,1366839162.154 [NAL9602](IMPORTANT): GPS fix at: 1366839167.00
2013-04-24T21:32:42.169Z,1366839162.169 [Default:GPS:Read_GPS] Stopped
2013-04-24T21:32:42.169Z,1366839162.169 [Default:GPS:D] Running Loop=1
2013-04-24T21:32:42.569Z,1366839162.569 [Default:GPS:D] Stopped
2013-04-24T21:32:42.569Z,1366839162.569 [Default:GPS](INFO): Completed Default:GPS
2013-04-24T21:32:42.570Z,1366839162.570 [Default:GPS] Stopped
2013-04-24T21:32:42.570Z,1366839162.570 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-24T21:32:42.570Z,1366839162.570 [Default:GPS:A.SetSpeed] Stopped
2013-04-24T21:32:42.570Z,1366839162.570 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T21:33:03.180Z,1366839183.180 [NAL9602](INFO): Powering down
2013-04-24T21:37:38.137Z,1366839458.137 [Default:CallIridium] Running Loop=1
2013-04-24T21:37:38.137Z,1366839458.137 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-24T21:37:38.138Z,1366839458.138 [Default:CallIridium:A] Running Loop=1
2013-04-24T21:37:38.138Z,1366839458.138 [Default:CallIridium:A] Stopped
2013-04-24T21:37:38.138Z,1366839458.138 [Default:CallIridium:B] Running Loop=1
2013-04-24T21:37:38.139Z,1366839458.138 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-24T21:37:43.172Z,1366839463.172 [Default:Iridium] Running Loop=1
2013-04-24T21:37:43.172Z,1366839463.172 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-24T21:37:43.172Z,1366839463.172 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-24T21:37:43.172Z,1366839463.172 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-24T21:37:43.173Z,1366839463.173 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-24T21:37:43.173Z,1366839463.173 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T21:37:43.174Z,1366839463.174 [Default:Iridium:B.GoToSurface] Stopped
2013-04-24T21:37:43.174Z,1366839463.174 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T21:37:43.174Z,1366839463.174 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-24T21:37:43.174Z,1366839463.174 [Default:GPS] Running Loop=1
2013-04-24T21:37:43.174Z,1366839463.174 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-24T21:37:43.175Z,1366839463.174 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-24T21:37:43.175Z,1366839463.175 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-24T21:37:43.175Z,1366839463.175 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-24T21:37:43.175Z,1366839463.175 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T21:37:43.176Z,1366839463.176 [Default:GPS:B.GoToSurface] Stopped
2013-04-24T21:37:43.176Z,1366839463.176 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T21:37:43.176Z,1366839463.176 [Default:GPS:Read_GPS] Running Loop=1
2013-04-24T21:37:43.774Z,1366839463.774 [NAL9602](INFO): Powering up
2013-04-24T21:37:54.342Z,1366839474.342 [NAL9602](INFO): NAL9602 initialized
2013-04-24T21:38:19.201Z,1366839499.201 [NAL9602](INFO): SBD MO Status=2, MOMSN=9845, MT Status=2, MTMSN=0
2013-04-24T21:38:19.201Z,1366839499.201 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-24T21:38:20.394Z,1366839500.394 [NAL9602](IMPORTANT): GPS fix at: 1366839506.00
2013-04-24T21:38:20.410Z,1366839500.410 [Default:GPS:Read_GPS] Stopped
2013-04-24T21:38:20.410Z,1366839500.411 [Default:GPS:D] Running Loop=1
2013-04-24T21:38:20.951Z,1366839500.951 [Default:GPS:D] Stopped
2013-04-24T21:38:20.952Z,1366839500.952 [Default:GPS](INFO): Completed Default:GPS
2013-04-24T21:38:20.952Z,1366839500.952 [Default:GPS] Stopped
2013-04-24T21:38:20.952Z,1366839500.952 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-24T21:38:20.952Z,1366839500.952 [Default:GPS:A.SetSpeed] Stopped
2013-04-24T21:38:20.952Z,1366839500.952 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T21:38:38.664Z,1366839518.664 [NAL9602](INFO): SBD MO Status=1, MOMSN=9845, MT Status=0, MTMSN=0
2013-04-24T21:38:38.714Z,1366839518.714 [NAL9602](INFO): Sent 137 bytes from file Logs/20130424T200753/shore0015.lzma
2013-04-24T21:38:38.714Z,1366839518.714 [NAL9602](INFO): Packets left to send: 0
2013-04-24T21:38:38.716Z,1366839518.716 [NAL9602](INFO): Stored copy of sent data in Logs/20130424T200753/shore0015.lzma.parts/0000.sbd
2013-04-24T21:38:49.459Z,1366839529.459 [NAL9602](INFO): SBD MO Status=0, MOMSN=9846, MT Status=0, MTMSN=0
2013-04-24T21:38:53.048Z,1366839533.048 [Default:Iridium:Read_Iridium] Stopped
2013-04-24T21:38:53.049Z,1366839533.049 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-24T21:38:53.049Z,1366839533.049 [Default:Iridium] Stopped
2013-04-24T21:38:53.049Z,1366839533.049 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-24T21:38:53.049Z,1366839533.049 [Default:Iridium:A.SetSpeed] Stopped
2013-04-24T21:38:53.049Z,1366839533.049 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T21:38:53.444Z,1366839533.444 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-24T21:38:53.444Z,1366839533.444 [Default:CallIridium:B] Stopped
2013-04-24T21:38:53.444Z,1366839533.444 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-24T21:38:53.445Z,1366839533.445 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-24T21:38:53.445Z,1366839533.445 [Default:CallIridium] Stopped
2013-04-24T21:38:53.445Z,1366839533.445 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-24T21:39:03.430Z,1366839543.430 [NAL9602](INFO): Powering down
2013-04-24T21:43:53.451Z,1366839833.451 [Default:CallIridium] Running Loop=1
2013-04-24T21:43:53.452Z,1366839833.452 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-24T21:43:53.452Z,1366839833.452 [Default:CallIridium:A] Running Loop=1
2013-04-24T21:43:53.452Z,1366839833.452 [Default:CallIridium:A] Stopped
2013-04-24T21:43:53.452Z,1366839833.452 [Default:CallIridium:B] Running Loop=1
2013-04-24T21:43:53.452Z,1366839833.452 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-24T21:43:58.489Z,1366839838.489 [Default:Iridium] Running Loop=1
2013-04-24T21:43:58.489Z,1366839838.489 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-24T21:43:58.489Z,1366839838.489 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-24T21:43:58.489Z,1366839838.489 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-24T21:43:58.489Z,1366839838.489 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-24T21:43:58.489Z,1366839838.489 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T21:43:58.490Z,1366839838.490 [Default:Iridium:B.GoToSurface] Stopped
2013-04-24T21:43:58.490Z,1366839838.490 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T21:43:58.490Z,1366839838.490 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-24T21:43:58.491Z,1366839838.491 [Default:GPS] Running Loop=1
2013-04-24T21:43:58.491Z,1366839838.491 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-24T21:43:58.491Z,1366839838.491 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-24T21:43:58.491Z,1366839838.491 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-24T21:43:58.491Z,1366839838.491 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-24T21:43:58.491Z,1366839838.491 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T21:43:58.492Z,1366839838.492 [Default:GPS:B.GoToSurface] Stopped
2013-04-24T21:43:58.492Z,1366839838.492 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T21:43:58.492Z,1366839838.492 [Default:GPS:Read_GPS] Running Loop=1
2013-04-24T21:43:59.132Z,1366839839.132 [NAL9602](INFO): Powering up
2013-04-24T21:44:10.102Z,1366839850.102 [NAL9602](INFO): NAL9602 initialized
2013-04-24T21:44:29.025Z,1366839869.025 [NAL9602](INFO): SBD MO Status=1, MOMSN=9847, MT Status=0, MTMSN=0
2013-04-24T21:44:29.082Z,1366839869.082 [NAL9602](INFO): Sent 138 bytes from file Logs/20130424T200753/shore0016.lzma
2013-04-24T21:44:29.082Z,1366839869.082 [NAL9602](INFO): Packets left to send: 0
2013-04-24T21:44:29.084Z,1366839869.084 [NAL9602](INFO): Stored copy of sent data in Logs/20130424T200753/shore0016.lzma.parts/0000.sbd
2013-04-24T21:44:39.419Z,1366839879.419 [NAL9602](INFO): SBD MO Status=0, MOMSN=9848, MT Status=0, MTMSN=0
2013-04-24T21:44:43.005Z,1366839883.005 [Default:Iridium:Read_Iridium] Stopped
2013-04-24T21:44:43.005Z,1366839883.005 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-24T21:44:43.006Z,1366839883.006 [Default:Iridium] Stopped
2013-04-24T21:44:43.006Z,1366839883.006 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-24T21:44:43.006Z,1366839883.006 [Default:Iridium:A.SetSpeed] Stopped
2013-04-24T21:44:43.006Z,1366839883.006 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T21:44:43.395Z,1366839883.395 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-24T21:44:43.395Z,1366839883.395 [Default:CallIridium:B] Stopped
2013-04-24T21:44:43.395Z,1366839883.395 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-24T21:44:43.395Z,1366839883.395 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-24T21:44:43.395Z,1366839883.395 [Default:CallIridium] Stopped
2013-04-24T21:44:43.395Z,1366839883.395 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-24T21:44:44.002Z,1366839884.002 [NAL9602](IMPORTANT): GPS fix at: 1366839890.00
2013-04-24T21:44:44.017Z,1366839884.017 [Default:GPS:Read_GPS] Stopped
2013-04-24T21:44:44.017Z,1366839884.017 [Default:GPS:D] Running Loop=1
2013-04-24T21:44:44.447Z,1366839884.447 [Default:GPS:D] Stopped
2013-04-24T21:44:44.447Z,1366839884.447 [Default:GPS](INFO): Completed Default:GPS
2013-04-24T21:44:44.447Z,1366839884.447 [Default:GPS] Stopped
2013-04-24T21:44:44.447Z,1366839884.447 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-24T21:44:44.447Z,1366839884.447 [Default:GPS:A.SetSpeed] Stopped
2013-04-24T21:44:44.448Z,1366839884.448 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T21:45:05.008Z,1366839905.008 [NAL9602](INFO): Powering down
2013-04-24T21:49:40.007Z,1366840180.007 [Default:CallIridium] Running Loop=1
2013-04-24T21:49:40.007Z,1366840180.007 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-24T21:49:40.007Z,1366840180.007 [Default:CallIridium:A] Running Loop=1
2013-04-24T21:49:40.007Z,1366840180.007 [Default:CallIridium:A] Stopped
2013-04-24T21:49:40.008Z,1366840180.008 [Default:CallIridium:B] Running Loop=1
2013-04-24T21:49:40.008Z,1366840180.008 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-24T21:49:44.980Z,1366840184.980 [Default:Iridium] Running Loop=1
2013-04-24T21:49:44.980Z,1366840184.980 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-24T21:49:44.980Z,1366840184.980 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-24T21:49:44.981Z,1366840184.981 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-24T21:49:44.981Z,1366840184.981 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-24T21:49:44.981Z,1366840184.981 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T21:49:44.982Z,1366840184.982 [Default:Iridium:B.GoToSurface] Stopped
2013-04-24T21:49:44.982Z,1366840184.982 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T21:49:44.982Z,1366840184.982 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-24T21:49:44.982Z,1366840184.982 [Default:GPS] Running Loop=1
2013-04-24T21:49:44.983Z,1366840184.983 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-24T21:49:44.983Z,1366840184.983 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-24T21:49:44.983Z,1366840184.983 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-24T21:49:44.983Z,1366840184.983 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-24T21:49:44.983Z,1366840184.983 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T21:49:44.984Z,1366840184.984 [Default:GPS:B.GoToSurface] Stopped
2013-04-24T21:49:44.984Z,1366840184.984 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T21:49:44.984Z,1366840184.984 [Default:GPS:Read_GPS] Running Loop=1
2013-04-24T21:49:45.594Z,1366840185.594 [NAL9602](INFO): Powering up
2013-04-24T21:49:56.090Z,1366840196.090 [NAL9602](INFO): NAL9602 initialized
2013-04-24T21:50:28.611Z,1366840228.611 [NAL9602](INFO): SBD MO Status=1, MOMSN=9849, MT Status=0, MTMSN=0
2013-04-24T21:50:28.662Z,1366840228.662 [NAL9602](INFO): Sent 137 bytes from file Logs/20130424T200753/shore0017.lzma
2013-04-24T21:50:28.662Z,1366840228.662 [NAL9602](INFO): Packets left to send: 0
2013-04-24T21:50:28.665Z,1366840228.665 [NAL9602](INFO): Stored copy of sent data in Logs/20130424T200753/shore0017.lzma.parts/0000.sbd
2013-04-24T21:50:47.420Z,1366840247.420 [NAL9602](INFO): SBD MO Status=2, MOMSN=9850, MT Status=2, MTMSN=0
2013-04-24T21:50:47.421Z,1366840247.421 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-24T21:50:48.622Z,1366840248.622 [NAL9602](IMPORTANT): GPS fix at: 1366840256.00
2013-04-24T21:50:48.638Z,1366840248.638 [Default:GPS:Read_GPS] Stopped
2013-04-24T21:50:48.638Z,1366840248.638 [Default:GPS:D] Running Loop=1
2013-04-24T21:50:49.054Z,1366840249.054 [Default:GPS:D] Stopped
2013-04-24T21:50:49.054Z,1366840249.054 [Default:GPS](INFO): Completed Default:GPS
2013-04-24T21:50:49.054Z,1366840249.054 [Default:GPS] Stopped
2013-04-24T21:50:49.054Z,1366840249.054 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-24T21:50:49.054Z,1366840249.055 [Default:GPS:A.SetSpeed] Stopped
2013-04-24T21:50:49.055Z,1366840249.055 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T21:51:05.785Z,1366840265.785 [NAL9602](INFO): SBD MO Status=0, MOMSN=9850, MT Status=0, MTMSN=0
2013-04-24T21:51:09.620Z,1366840269.620 [Default:Iridium:Read_Iridium] Stopped
2013-04-24T21:51:09.620Z,1366840269.620 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-24T21:51:09.621Z,1366840269.621 [Default:Iridium] Stopped
2013-04-24T21:51:09.621Z,1366840269.621 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-24T21:51:09.621Z,1366840269.621 [Default:Iridium:A.SetSpeed] Stopped
2013-04-24T21:51:09.621Z,1366840269.621 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T21:51:10.011Z,1366840270.011 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-24T21:51:10.012Z,1366840270.011 [Default:CallIridium:B] Stopped
2013-04-24T21:51:10.012Z,1366840270.012 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-24T21:51:10.012Z,1366840270.012 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-24T21:51:10.012Z,1366840270.012 [Default:CallIridium] Stopped
2013-04-24T21:51:10.012Z,1366840270.012 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-24T21:51:20.016Z,1366840280.016 [NAL9602](INFO): Powering down
2013-04-24T21:56:10.088Z,1366840570.088 [Default:CallIridium] Running Loop=1
2013-04-24T21:56:10.088Z,1366840570.088 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-24T21:56:10.088Z,1366840570.088 [Default:CallIridium:A] Running Loop=1
2013-04-24T21:56:10.089Z,1366840570.089 [Default:CallIridium:A] Stopped
2013-04-24T21:56:10.089Z,1366840570.089 [Default:CallIridium:B] Running Loop=1
2013-04-24T21:56:10.089Z,1366840570.089 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-24T21:56:15.049Z,1366840575.049 [Default:Iridium] Running Loop=1
2013-04-24T21:56:15.049Z,1366840575.049 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-24T21:56:15.049Z,1366840575.049 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-24T21:56:15.050Z,1366840575.050 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-24T21:56:15.050Z,1366840575.050 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-24T21:56:15.050Z,1366840575.050 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T21:56:15.051Z,1366840575.051 [Default:Iridium:B.GoToSurface] Stopped
2013-04-24T21:56:15.051Z,1366840575.051 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T21:56:15.051Z,1366840575.051 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-24T21:56:15.051Z,1366840575.051 [Default:GPS] Running Loop=1
2013-04-24T21:56:15.052Z,1366840575.052 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-24T21:56:15.052Z,1366840575.052 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-24T21:56:15.052Z,1366840575.052 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-24T21:56:15.052Z,1366840575.052 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-24T21:56:15.052Z,1366840575.052 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T21:56:15.053Z,1366840575.053 [Default:GPS:B.GoToSurface] Stopped
2013-04-24T21:56:15.053Z,1366840575.053 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T21:56:15.053Z,1366840575.053 [Default:GPS:Read_GPS] Running Loop=1
2013-04-24T21:56:15.665Z,1366840575.665 [NAL9602](INFO): Powering up
2013-04-24T21:56:26.554Z,1366840586.554 [NAL9602](INFO): NAL9602 initialized
2013-04-24T21:57:14.301Z,1366840634.301 [NAL9602](INFO): SBD MO Status=1, MOMSN=9851, MT Status=0, MTMSN=0
2013-04-24T21:57:14.350Z,1366840634.350 [NAL9602](INFO): Sent 157 bytes from file Logs/20130424T200753/shore0018.lzma
2013-04-24T21:57:14.350Z,1366840634.350 [NAL9602](INFO): Packets left to send: 0
2013-04-24T21:57:14.352Z,1366840634.352 [NAL9602](INFO): Stored copy of sent data in Logs/20130424T200753/shore0018.lzma.parts/0000.sbd
2013-04-24T21:57:25.499Z,1366840645.499 [NAL9602](INFO): SBD MO Status=0, MOMSN=9852, MT Status=0, MTMSN=0
2013-04-24T21:57:29.108Z,1366840649.109 [Default:Iridium:Read_Iridium] Stopped
2013-04-24T21:57:29.109Z,1366840649.109 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-24T21:57:29.109Z,1366840649.109 [Default:Iridium] Stopped
2013-04-24T21:57:29.109Z,1366840649.109 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-24T21:57:29.109Z,1366840649.109 [Default:Iridium:A.SetSpeed] Stopped
2013-04-24T21:57:29.109Z,1366840649.109 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T21:57:29.431Z,1366840649.431 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-24T21:57:29.431Z,1366840649.431 [Default:CallIridium:B] Stopped
2013-04-24T21:57:29.431Z,1366840649.431 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-24T21:57:29.432Z,1366840649.432 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-24T21:57:29.432Z,1366840649.432 [Default:CallIridium] Stopped
2013-04-24T21:57:29.432Z,1366840649.432 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-24T21:57:30.172Z,1366840650.172 [NAL9602](IMPORTANT): GPS fix at: 1366840658.00
2013-04-24T21:57:30.188Z,1366840650.188 [Default:GPS:Read_GPS] Stopped
2013-04-24T21:57:30.188Z,1366840650.188 [Default:GPS:D] Running Loop=1
2013-04-24T21:57:30.595Z,1366840650.595 [Default:GPS:D] Stopped
2013-04-24T21:57:30.595Z,1366840650.595 [Default:GPS](INFO): Completed Default:GPS
2013-04-24T21:57:30.596Z,1366840650.596 [Default:GPS] Stopped
2013-04-24T21:57:30.596Z,1366840650.596 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-24T21:57:30.596Z,1366840650.596 [Default:GPS:A.SetSpeed] Stopped
2013-04-24T21:57:30.596Z,1366840650.596 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T21:57:51.088Z,1366840671.088 [NAL9602](INFO): Powering down
2013-04-24T22:02:26.123Z,1366840946.123 [Default:CallIridium] Running Loop=1
2013-04-24T22:02:26.123Z,1366840946.123 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-24T22:02:26.123Z,1366840946.123 [Default:CallIridium:A] Running Loop=1
2013-04-24T22:02:26.124Z,1366840946.124 [Default:CallIridium:A] Stopped
2013-04-24T22:02:26.124Z,1366840946.124 [Default:CallIridium:B] Running Loop=1
2013-04-24T22:02:26.124Z,1366840946.124 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-24T22:02:31.098Z,1366840951.098 [Default:Iridium] Running Loop=1
2013-04-24T22:02:31.098Z,1366840951.098 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-24T22:02:31.098Z,1366840951.099 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-24T22:02:31.099Z,1366840951.099 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-24T22:02:31.099Z,1366840951.099 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-24T22:02:31.099Z,1366840951.099 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T22:02:31.100Z,1366840951.100 [Default:Iridium:B.GoToSurface] Stopped
2013-04-24T22:02:31.100Z,1366840951.100 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T22:02:31.100Z,1366840951.100 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-24T22:02:31.100Z,1366840951.100 [Default:GPS] Running Loop=1
2013-04-24T22:02:31.100Z,1366840951.100 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-24T22:02:31.100Z,1366840951.100 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-24T22:02:31.101Z,1366840951.101 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-24T22:02:31.101Z,1366840951.101 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-24T22:02:31.101Z,1366840951.101 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T22:02:31.102Z,1366840951.102 [Default:GPS:B.GoToSurface] Stopped
2013-04-24T22:02:31.102Z,1366840951.102 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T22:02:31.102Z,1366840951.102 [Default:GPS:Read_GPS] Running Loop=1
2013-04-24T22:02:31.732Z,1366840951.731 [NAL9602](INFO): Powering up
2013-04-24T22:02:42.722Z,1366840962.722 [NAL9602](INFO): NAL9602 initialized
2013-04-24T22:03:11.985Z,1366840991.985 [NAL9602](INFO): SBD MO Status=1, MOMSN=9853, MT Status=0, MTMSN=0
2013-04-24T22:03:12.034Z,1366840992.034 [NAL9602](INFO): Sent 162 bytes from file Logs/20130424T200753/shore0019.lzma
2013-04-24T22:03:12.034Z,1366840992.034 [NAL9602](INFO): Packets left to send: 0
2013-04-24T22:03:12.036Z,1366840992.036 [NAL9602](INFO): Stored copy of sent data in Logs/20130424T200753/shore0019.lzma.parts/0000.sbd
2013-04-24T22:03:37.169Z,1366841017.169 [NAL9602](INFO): SBD MO Status=2, MOMSN=9854, MT Status=2, MTMSN=0
2013-04-24T22:03:37.169Z,1366841017.169 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-24T22:03:38.373Z,1366841018.373 [NAL9602](IMPORTANT): GPS fix at: 1366841026.00
2013-04-24T22:03:38.389Z,1366841018.389 [Default:GPS:Read_GPS] Stopped
2013-04-24T22:03:38.389Z,1366841018.389 [Default:GPS:D] Running Loop=1
2013-04-24T22:03:38.797Z,1366841018.797 [Default:GPS:D] Stopped
2013-04-24T22:03:38.798Z,1366841018.798 [Default:GPS](INFO): Completed Default:GPS
2013-04-24T22:03:38.798Z,1366841018.798 [Default:GPS] Stopped
2013-04-24T22:03:38.798Z,1366841018.798 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-24T22:03:38.798Z,1366841018.798 [Default:GPS:A.SetSpeed] Stopped
2013-04-24T22:03:38.798Z,1366841018.798 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T22:03:46.679Z,1366841026.679 [NAL9602](INFO): SBD MO Status=0, MOMSN=9854, MT Status=0, MTMSN=0
2013-04-24T22:03:50.335Z,1366841030.335 [Default:Iridium:Read_Iridium] Stopped
2013-04-24T22:03:50.335Z,1366841030.335 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-24T22:03:50.335Z,1366841030.335 [Default:Iridium] Stopped
2013-04-24T22:03:50.335Z,1366841030.335 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-24T22:03:50.335Z,1366841030.335 [Default:Iridium:A.SetSpeed] Stopped
2013-04-24T22:03:50.336Z,1366841030.336 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T22:03:50.691Z,1366841030.691 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-24T22:03:50.691Z,1366841030.691 [Default:CallIridium:B] Stopped
2013-04-24T22:03:50.691Z,1366841030.691 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-24T22:03:50.691Z,1366841030.691 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-24T22:03:50.691Z,1366841030.691 [Default:CallIridium] Stopped
2013-04-24T22:03:50.691Z,1366841030.691 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-24T22:04:00.736Z,1366841040.736 [NAL9602](INFO): Powering down
2013-04-24T22:08:50.717Z,1366841330.717 [Default:CallIridium] Running Loop=1
2013-04-24T22:08:50.717Z,1366841330.717 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-24T22:08:50.717Z,1366841330.717 [Default:CallIridium:A] Running Loop=1
2013-04-24T22:08:50.718Z,1366841330.718 [Default:CallIridium:A] Stopped
2013-04-24T22:08:50.718Z,1366841330.718 [Default:CallIridium:B] Running Loop=1
2013-04-24T22:08:50.718Z,1366841330.718 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-24T22:08:55.683Z,1366841335.683 [Default:Iridium] Running Loop=1
2013-04-24T22:08:55.683Z,1366841335.683 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-24T22:08:55.683Z,1366841335.683 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-24T22:08:55.683Z,1366841335.683 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-24T22:08:55.684Z,1366841335.684 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-24T22:08:55.684Z,1366841335.684 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T22:08:55.684Z,1366841335.684 [Default:Iridium:B.GoToSurface] Stopped
2013-04-24T22:08:55.684Z,1366841335.684 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T22:08:55.685Z,1366841335.685 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-24T22:08:55.685Z,1366841335.685 [Default:GPS] Running Loop=1
2013-04-24T22:08:55.685Z,1366841335.685 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-24T22:08:55.685Z,1366841335.685 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-24T22:08:55.685Z,1366841335.685 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-24T22:08:55.686Z,1366841335.686 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-24T22:08:55.686Z,1366841335.686 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T22:08:55.687Z,1366841335.687 [Default:GPS:B.GoToSurface] Stopped
2013-04-24T22:08:55.687Z,1366841335.687 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T22:08:55.687Z,1366841335.687 [Default:GPS:Read_GPS] Running Loop=1
2013-04-24T22:08:56.331Z,1366841336.331 [NAL9602](INFO): Powering up
2013-04-24T22:09:07.266Z,1366841347.266 [NAL9602](INFO): NAL9602 initialized
2013-04-24T22:09:25.589Z,1366841365.589 [NAL9602](INFO): SBD MO Status=1, MOMSN=9855, MT Status=0, MTMSN=0
2013-04-24T22:09:25.638Z,1366841365.638 [NAL9602](INFO): Sent 163 bytes from file Logs/20130424T200753/shore0020.lzma
2013-04-24T22:09:25.638Z,1366841365.638 [NAL9602](INFO): Packets left to send: 0
2013-04-24T22:09:25.640Z,1366841365.640 [NAL9602](INFO): Stored copy of sent data in Logs/20130424T200753/shore0020.lzma.parts/0000.sbd
2013-04-24T22:09:32.004Z,1366841372.004 [NAL9602](INFO): SBD MO Status=0, MOMSN=9856, MT Status=0, MTMSN=0
2013-04-24T22:09:35.785Z,1366841375.785 [Default:Iridium:Read_Iridium] Stopped
2013-04-24T22:09:35.785Z,1366841375.785 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-24T22:09:35.785Z,1366841375.785 [Default:Iridium] Stopped
2013-04-24T22:09:35.785Z,1366841375.785 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-24T22:09:35.785Z,1366841375.785 [Default:Iridium:A.SetSpeed] Stopped
2013-04-24T22:09:35.785Z,1366841375.786 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T22:09:36.119Z,1366841376.119 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-24T22:09:36.119Z,1366841376.119 [Default:CallIridium:B] Stopped
2013-04-24T22:09:36.119Z,1366841376.119 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-24T22:09:36.119Z,1366841376.119 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-24T22:09:36.119Z,1366841376.119 [Default:CallIridium] Stopped
2013-04-24T22:09:36.119Z,1366841376.119 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-24T22:09:36.847Z,1366841376.847 [NAL9602](IMPORTANT): GPS fix at: 1366841386.00
2013-04-24T22:09:36.885Z,1366841376.885 [Default:GPS:Read_GPS] Stopped
2013-04-24T22:09:36.885Z,1366841376.885 [Default:GPS:D] Running Loop=1
2013-04-24T22:09:37.245Z,1366841377.245 [Default:GPS:D] Stopped
2013-04-24T22:09:37.246Z,1366841377.246 [Default:GPS](INFO): Completed Default:GPS
2013-04-24T22:09:37.246Z,1366841377.246 [Default:GPS] Stopped
2013-04-24T22:09:37.246Z,1366841377.246 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-24T22:09:37.246Z,1366841377.246 [Default:GPS:A.SetSpeed] Stopped
2013-04-24T22:09:37.246Z,1366841377.246 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T22:09:57.774Z,1366841397.774 [NAL9602](INFO): Powering down
2013-04-24T22:14:32.759Z,1366841672.759 [Default:CallIridium] Running Loop=1
2013-04-24T22:14:32.759Z,1366841672.759 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-24T22:14:32.759Z,1366841672.759 [Default:CallIridium:A] Running Loop=1
2013-04-24T22:14:32.760Z,1366841672.760 [Default:CallIridium:A] Stopped
2013-04-24T22:14:32.760Z,1366841672.760 [Default:CallIridium:B] Running Loop=1
2013-04-24T22:14:32.760Z,1366841672.760 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-24T22:14:37.789Z,1366841677.789 [Default:Iridium] Running Loop=1
2013-04-24T22:14:37.789Z,1366841677.789 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-24T22:14:37.789Z,1366841677.789 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-24T22:14:37.790Z,1366841677.790 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-24T22:14:37.790Z,1366841677.790 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-24T22:14:37.790Z,1366841677.790 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T22:14:37.791Z,1366841677.791 [Default:Iridium:B.GoToSurface] Stopped
2013-04-24T22:14:37.791Z,1366841677.791 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T22:14:37.791Z,1366841677.791 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-24T22:14:37.791Z,1366841677.791 [Default:GPS] Running Loop=1
2013-04-24T22:14:37.791Z,1366841677.792 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-24T22:14:37.792Z,1366841677.792 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-24T22:14:37.792Z,1366841677.792 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-24T22:14:37.792Z,1366841677.792 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-24T22:14:37.792Z,1366841677.792 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-24T22:14:37.793Z,1366841677.793 [Default:GPS:B.GoToSurface] Stopped
2013-04-24T22:14:37.793Z,1366841677.793 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T22:14:37.793Z,1366841677.793 [Default:GPS:Read_GPS] Running Loop=1
2013-04-24T22:14:38.387Z,1366841678.387 [NAL9602](INFO): Powering up
2013-04-24T22:14:49.378Z,1366841689.378 [NAL9602](INFO): NAL9602 initialized
2013-04-24T22:15:13.469Z,1366841713.469 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=9857, MT Status=1, MTMSN=742
2013-04-24T22:15:13.518Z,1366841713.518 [NAL9602](INFO): Sent 162 bytes from file Logs/20130424T200753/shore0021.lzma
2013-04-24T22:15:13.518Z,1366841713.518 [NAL9602](INFO): Packets left to send: 0
2013-04-24T22:15:13.520Z,1366841713.520 [NAL9602](INFO): Stored copy of sent data in Logs/20130424T200753/shore0021.lzma.parts/0000.sbd
2013-04-24T22:15:14.114Z,1366841714.114 [NAL9602](INFO): Received command:restart app
2013-04-24T22:15:14.142Z,1366841714.142 [CommandLine](IMPORTANT): got command restart application
2013-04-24T22:15:15.290Z,1366841715.290 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2013-04-24T22:15:15.290Z,1366841715.290 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2013-04-24T22:15:15.358Z,1366841715.358 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2013-04-24T22:15:15.654Z,1366841715.654 [WetLabsBB2FL](INFO): Powering down
2013-04-24T22:15:15.662Z,1366841715.662 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2013-04-24T22:15:15.718Z,1366841715.718 [CTD_NeilBrown](INFO): Powering down
2013-04-24T22:15:15.738Z,1366841715.738 [ComponentRegistry](INFO): Shutting down DVL_micro ThreadHandler
2013-04-24T22:15:15.886Z,1366841715.886 [DVL_micro](INFO): uninitialize:Powering down
2013-04-24T22:15:15.898Z,1366841715.898 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler
2013-04-24T22:15:16.038Z,1366841716.038 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator.
2013-04-24T22:15:16.059Z,1366841716.059 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2013-04-24T22:15:16.079Z,1366841716.079 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2013-04-24T22:15:16.080Z,1366841716.080 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2013-04-24T22:15:16.158Z,1366841716.158 [controlThread](DEBUG): Uninitializing ControlThread
2013-04-24T22:15:16.159Z,1366841716.159 [AHRS_sp3003D](INFO): Powering down
2013-04-24T22:15:16.247Z,1366841716.247 [NAL9602](INFO): Powering down
2013-04-24T22:15:16.248Z,1366841716.248 [DAT](INFO): Powering down
2013-04-24T22:15:16.249Z,1366841716.249 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2013-04-24T22:15:16.250Z,1366841716.250 [NavChart](DEBUG): Uninitialize NavChart Derivation.
2013-04-24T22:15:16.251Z,1366841716.251 [Default] Stopped
2013-04-24T22:15:16.251Z,1366841716.251 [Default](INFO): Aggregate::uninitialize Default
2013-04-24T22:15:16.251Z,1366841716.251 [Default:GPS] Stopped
2013-04-24T22:15:16.251Z,1366841716.251 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-24T22:15:16.251Z,1366841716.251 [Default:GPS:A.SetSpeed] Stopped
2013-04-24T22:15:16.252Z,1366841716.251 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T22:15:16.252Z,1366841716.252 [Default:GPS:Read_GPS] Stopped
2013-04-24T22:15:16.252Z,1366841716.252 [Default:Iridium] Stopped
2013-04-24T22:15:16.252Z,1366841716.252 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-24T22:15:16.252Z,1366841716.252 [Default:Iridium:A.SetSpeed] Stopped
2013-04-24T22:15:16.252Z,1366841716.252 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-24T22:15:16.252Z,1366841716.252 [Default:Iridium:Read_Iridium] Stopped
2013-04-24T22:15:16.252Z,1366841716.252 [Default:CallIridium] Stopped
2013-04-24T22:15:16.252Z,1366841716.252 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-24T22:15:16.252Z,1366841716.252 [Default:CallIridium:B] Stopped
2013-04-24T22:15:16.252Z,1366841716.252 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-24T22:15:16.252Z,1366841716.253 [Default:D.SetSpeed] Stopped
2013-04-24T22:15:16.252Z,1366841716.253 [Default:D.SetSpeed](DEBUG): Uninitialize.
2013-04-24T22:15:16.253Z,1366841716.253 [Default:E.GoToSurface] Stopped
2013-04-24T22:15:16.253Z,1366841716.253 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-24T22:15:16.253Z,1366841716.253 [Default:F.Wait] Stopped
2013-04-24T22:15:16.253Z,1366841716.253 [Default:F.Wait](DEBUG): Uninitialize Wait Component.
2013-04-24T22:15:16.257Z,1366841716.257 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2013-04-24T22:15:16.257Z,1366841716.257 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2013-04-24T22:15:16.258Z,1366841716.258 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2013-04-24T22:15:16.258Z,1366841716.258 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2013-04-24T22:15:16.259Z,1366841716.259 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-04-24T22:15:16.259Z,1366841716.259 [BuoyancyServo](INFO): Powering down
2013-04-24T22:15:16.270Z,1366841716.270 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2013-04-24T22:15:16.270Z,1366841716.270 [ElevatorServo](INFO): Powering down
2013-04-24T22:15:16.271Z,1366841716.271 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-04-24T22:15:16.271Z,1366841716.271 [MassServo](INFO): Powering down
2013-04-24T22:15:16.272Z,1366841716.272 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2013-04-24T22:15:16.272Z,1366841716.272 [RudderServo](INFO): Powering down
2013-04-24T22:15:16.273Z,1366841716.273 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-04-24T22:15:16.273Z,1366841716.273 [ThrusterServo](INFO): Powering down
2013-04-24T22:15:16.274Z,1366841716.274 [SBIT](DEBUG): Uninitialize SBIT Component.
2013-04-24T22:15:16.274Z,1366841716.274 [IBIT](DEBUG): Uninitialize IBIT Component.
2013-04-24T22:15:16.274Z,1366841716.274 [CBIT](DEBUG): Uninitialize CBIT Component.