2013-04-02T20:15:49.443Z,1364933749.443 [Supervisor](DEBUG): Initializing supervisor.
2013-04-02T20:15:49.446Z,1364933749.446 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2013-04-02T20:15:49.451Z,1364933749.451 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2013-04-02T20:15:49.454Z,1364933749.454 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0
2013-04-02T20:15:49.458Z,1364933749.458 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2013-04-02T20:15:49.468Z,1364933749.468 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2013-04-02T20:15:49.470Z,1364933749.470 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0
2013-04-02T20:15:49.471Z,1364933749.471 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2013-04-02T20:15:49.474Z,1364933749.474 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403CB4E0
2013-04-02T20:15:49.475Z,1364933749.475 [Supervisor](INFO): Looking for Config files in directory: Config/
2013-04-02T20:15:49.479Z,1364933749.479 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2013-04-02T20:15:49.790Z,1364933749.790 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2013-04-02T20:15:49.790Z,1364933749.790 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2013-04-02T20:15:49.998Z,1364933749.998 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2013-04-02T20:15:49.998Z,1364933749.999 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2013-04-02T20:15:50.094Z,1364933750.094 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2013-04-02T20:15:50.096Z,1364933750.096 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2013-04-02T20:15:50.294Z,1364933750.294 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2013-04-02T20:15:50.294Z,1364933750.294 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2013-04-02T20:15:50.450Z,1364933750.450 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2013-04-02T20:15:50.452Z,1364933750.452 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2013-04-02T20:15:50.706Z,1364933750.706 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2013-04-02T20:15:50.707Z,1364933750.707 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2013-04-02T20:15:50.895Z,1364933750.895 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2013-04-02T20:15:50.897Z,1364933750.897 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2013-04-02T20:15:51.180Z,1364933751.180 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2013-04-02T20:15:51.182Z,1364933751.182 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2013-04-02T20:15:51.295Z,1364933751.295 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2013-04-02T20:15:51.296Z,1364933751.296 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2013-04-02T20:15:51.736Z,1364933751.736 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2013-04-02T20:15:51.736Z,1364933751.736 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2013-04-02T20:15:51.863Z,1364933751.863 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2013-04-02T20:15:51.864Z,1364933751.864 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2013-04-02T20:15:51.960Z,1364933751.960 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2013-04-02T20:15:51.963Z,1364933751.963 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2013-04-02T20:15:52.076Z,1364933752.076 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2013-04-02T20:15:52.220Z,1364933752.220 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2013-04-02T20:15:52.314Z,1364933752.314 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2013-04-02T20:15:52.426Z,1364933752.426 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2013-04-02T20:15:52.536Z,1364933752.536 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2013-04-02T20:15:52.668Z,1364933752.668 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2013-04-02T20:15:52.773Z,1364933752.773 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2013-04-02T20:15:52.961Z,1364933752.961 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2013-04-02T20:15:52.000Z,1364933753.000 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2013-04-02T20:15:53.197Z,1364933753.197 [InternalSim] Loaded
2013-04-02T20:15:53.197Z,1364933753.197 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2013-04-02T20:15:53.198Z,1364933753.198 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2013-04-02T20:15:53.203Z,1364933753.203 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2013-04-02T20:15:53.285Z,1364933753.285 [SBIT](DEBUG): Construct Startup Built In Test.
2013-04-02T20:15:53.319Z,1364933753.319 [SBIT] Loaded
2013-04-02T20:15:53.319Z,1364933753.319 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2013-04-02T20:15:53.320Z,1364933753.320 [IBIT](DEBUG): Construct Initiated Built In Test.
2013-04-02T20:15:53.349Z,1364933753.349 [IBIT] Loaded
2013-04-02T20:15:53.349Z,1364933753.349 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2013-04-02T20:15:53.352Z,1364933753.352 [CBIT](DEBUG): Construct CBIT Built In Test.
2013-04-02T20:15:53.488Z,1364933753.488 [CBIT] Loaded
2013-04-02T20:15:53.488Z,1364933753.488 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2013-04-02T20:15:53.488Z,1364933753.489 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2013-04-02T20:15:53.489Z,1364933753.489 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2013-04-02T20:15:53.668Z,1364933753.668 [BuoyancyServo] Loaded
2013-04-02T20:15:53.669Z,1364933753.668 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2013-04-02T20:15:53.681Z,1364933753.681 [ElevatorServo] Loaded
2013-04-02T20:15:53.681Z,1364933753.681 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2013-04-02T20:15:53.697Z,1364933753.697 [MassServo] Loaded
2013-04-02T20:15:53.697Z,1364933753.697 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2013-04-02T20:15:53.710Z,1364933753.710 [RudderServo] Loaded
2013-04-02T20:15:53.710Z,1364933753.710 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2013-04-02T20:15:53.722Z,1364933753.722 [ThrusterServo] Loaded
2013-04-02T20:15:53.722Z,1364933753.722 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2013-04-02T20:15:53.723Z,1364933753.723 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2013-04-02T20:15:53.723Z,1364933753.723 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2013-04-02T20:15:53.755Z,1364933753.755 [DepthRateCalculator] Loaded
2013-04-02T20:15:53.756Z,1364933753.756 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2013-04-02T20:15:57.106Z,1364933757.106 [HFRadarModelCalc] Loaded
2013-04-02T20:15:57.106Z,1364933757.106 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2013-04-02T20:15:57.122Z,1364933757.122 [NavChart] Loaded
2013-04-02T20:15:57.123Z,1364933757.123 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2013-04-02T20:15:57.128Z,1364933757.128 [PitchRateCalculator] Loaded
2013-04-02T20:15:57.129Z,1364933757.129 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2013-04-02T20:15:57.135Z,1364933757.135 [SpeedCalculator] Loaded
2013-04-02T20:15:57.135Z,1364933757.135 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2013-04-02T20:15:57.154Z,1364933757.154 [TempGradientCalculator] Loaded
2013-04-02T20:15:57.154Z,1364933757.154 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2013-04-02T20:15:57.160Z,1364933757.160 [YawRateCalculator] Loaded
2013-04-02T20:15:57.161Z,1364933757.161 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2013-04-02T20:15:57.205Z,1364933757.205 [Navigation] Loaded
2013-04-02T20:15:57.206Z,1364933757.206 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread.
2013-04-02T20:15:57.210Z,1364933757.210 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2013-04-02T20:15:57.211Z,1364933757.211 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2013-04-02T20:15:57.487Z,1364933757.487 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2013-04-02T20:15:57.488Z,1364933757.488 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2013-04-02T20:15:57.529Z,1364933757.529 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2013-04-02T20:15:57.529Z,1364933757.529 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2013-04-02T20:15:57.595Z,1364933757.595 [VerticalControl](DEBUG): Construct VerticalControl.
2013-04-02T20:15:57.697Z,1364933757.697 [VerticalControl] Loaded
2013-04-02T20:15:57.698Z,1364933757.698 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2013-04-02T20:15:57.699Z,1364933757.699 [HorizontalControl](DEBUG): Construct HorizontalControl.
2013-04-02T20:15:57.760Z,1364933757.760 [HorizontalControl] Loaded
2013-04-02T20:15:57.760Z,1364933757.760 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2013-04-02T20:15:57.761Z,1364933757.761 [SpeedControl](DEBUG): Construct SpeedControl.
2013-04-02T20:15:57.767Z,1364933757.767 [SpeedControl] Loaded
2013-04-02T20:15:57.767Z,1364933757.767 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2013-04-02T20:15:57.768Z,1364933757.768 [LoopControl](DEBUG): Construct LoopControl.
2013-04-02T20:15:57.769Z,1364933757.769 [LoopControl] Loaded
2013-04-02T20:15:57.769Z,1364933757.769 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2013-04-02T20:15:57.770Z,1364933757.770 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2013-04-02T20:15:57.770Z,1364933757.770 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2013-04-02T20:15:57.778Z,1364933757.778 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2013-04-02T20:15:57.783Z,1364933757.783 [AsyncPiEstimator] Loaded
2013-04-02T20:15:57.783Z,1364933757.783 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2013-04-02T20:15:57.786Z,1364933757.786 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 4063B4E0
2013-04-02T20:15:57.787Z,1364933757.787 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2013-04-02T20:15:57.788Z,1364933757.788 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2013-04-02T20:15:57.972Z,1364933757.972 [AHRS_sp3003D] Loaded
2013-04-02T20:15:57.972Z,1364933757.972 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2013-04-02T20:15:58.260Z,1364933758.260 [Batt_Ocean_Server] Loaded
2013-04-02T20:15:58.260Z,1364933758.260 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread.
2013-04-02T20:15:58.273Z,1364933758.273 [Depth_Keller] Loaded
2013-04-02T20:15:58.274Z,1364933758.274 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2013-04-02T20:15:58.280Z,1364933758.280 [DropWeight] Loaded
2013-04-02T20:15:58.280Z,1364933758.280 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2013-04-02T20:15:58.475Z,1364933758.475 [DVL_micro] Loaded
2013-04-02T20:15:58.475Z,1364933758.475 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread.
2013-04-02T20:15:58.478Z,1364933758.478 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406BF4E0
2013-04-02T20:15:58.569Z,1364933758.569 [NAL9602] Loaded
2013-04-02T20:15:58.570Z,1364933758.569 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2013-04-02T20:15:58.631Z,1364933758.631 [Onboard] Loaded
2013-04-02T20:15:58.631Z,1364933758.631 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2013-04-02T20:15:58.638Z,1364933758.638 [Radio_Freewave] Loaded
2013-04-02T20:15:58.638Z,1364933758.638 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread.
2013-04-02T20:15:58.639Z,1364933758.639 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2013-04-02T20:15:58.640Z,1364933758.640 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2013-04-02T20:15:58.747Z,1364933758.747 [CTD_NeilBrown] Loaded
2013-04-02T20:15:58.747Z,1364933758.747 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2013-04-02T20:15:58.750Z,1364933758.750 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 407114E0
2013-04-02T20:15:58.767Z,1364933758.767 [PAR_Licor] Loaded
2013-04-02T20:15:58.767Z,1364933758.767 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2013-04-02T20:15:58.801Z,1364933758.801 [WetLabsBB2FL] Loaded
2013-04-02T20:15:58.801Z,1364933758.801 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2013-04-02T20:15:58.806Z,1364933758.806 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407414E0
2013-04-02T20:15:58.807Z,1364933758.807 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2013-04-02T20:15:58.809Z,1364933758.809 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2013-04-02T20:15:58.810Z,1364933758.810 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2013-04-02T20:15:58.820Z,1364933758.820 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2013-04-02T20:15:58.822Z,1364933758.822 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407714E0
2013-04-02T20:15:58.826Z,1364933758.826 [Supervisor](DEBUG): Running supervisor.
2013-04-02T20:15:58.828Z,1364933758.828 [CommandLine](INFO): Thread ID is 749
2013-04-02T20:15:58.831Z,1364933758.831 [controlThread](INFO): Thread ID is 748
2013-04-02T20:15:58.831Z,1364933758.831 [controlThread](DEBUG): Initializing ControlThread
2013-04-02T20:15:58.832Z,1364933758.832 [CycleStarter](INFO): Thread ID is 747
2013-04-02T20:15:58.832Z,1364933758.832 [InternalSim](DEBUG):  InternalSim initializing...
2013-04-02T20:15:58.938Z,1364933758.938 [logger](INFO): Thread ID is 750
2013-04-02T20:15:59.038Z,1364933759.038 [AsyncPiEstimator](INFO): Thread ID is 811
2013-04-02T20:15:59.038Z,1364933759.038 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2013-04-02T20:15:59.151Z,1364933759.151 [DVL_micro](INFO): Thread ID is 812
2013-04-02T20:15:59.170Z,1364933759.170 [DVL_micro](INFO): Initializing
2013-04-02T20:15:59.170Z,1364933759.170 [DVL_micro](INFO): start:Powering up
2013-04-02T20:15:59.171Z,1364933759.171 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-04-02T20:15:59.172Z,1364933759.172 [DVL_micro](INFO): Cycling power to configure device.
2013-04-02T20:15:59.212Z,1364933759.212 [CTD_NeilBrown](INFO): Thread ID is 813
2013-04-02T20:15:59.212Z,1364933759.212 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2013-04-02T20:15:59.219Z,1364933759.219 [WetLabsBB2FL](INFO): Thread ID is 814
2013-04-02T20:15:59.220Z,1364933759.220 [WetLabsBB2FL](INFO): Powering down
2013-04-02T20:15:59.238Z,1364933759.239 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2013-04-02T20:15:59.265Z,1364933759.265 [SBIT](INFO): Initialize SBIT Component.
2013-04-02T20:15:59.266Z,1364933759.266 [SBIT](IMPORTANT): Tethys CM Info:
$Rev:10279
2013-04-02T20:15:59.270Z,1364933759.270 [NavChartDb](INFO): Thread ID is 815
2013-04-02T20:15:59.279Z,1364933759.279 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2013-04-02T20:15:59.280Z,1364933759.280 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2013-04-02T20:15:59.280Z,1364933759.280 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2013-04-02T20:15:59.281Z,1364933759.281 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2013-04-02T20:15:59.281Z,1364933759.281 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2013-04-02T20:15:59.281Z,1364933759.281 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2013-04-02T20:15:59.282Z,1364933759.282 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2013-04-02T20:15:59.282Z,1364933759.282 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2013-04-02T20:15:59.282Z,1364933759.282 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2013-04-02T20:15:59.299Z,1364933759.299 [SBIT](IMPORTANT): Kernel Release:2.6.27.8
2013-04-02T20:15:59.299Z,1364933759.299 [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-02T20:15:59.300Z,1364933759.300 [IBIT](INFO): Initialize IBIT Component.
2013-04-02T20:15:59.301Z,1364933759.300 [CBIT](DEBUG): Initialize CBIT Component.
2013-04-02T20:15:59.301Z,1364933759.301 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2013-04-02T20:15:59.301Z,1364933759.301 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2013-04-02T20:15:59.328Z,1364933759.328 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2013-04-02T20:15:59.329Z,1364933759.329 [NavChart](DEBUG): Initialize NavChart Derivation.
2013-04-02T20:15:59.329Z,1364933759.329 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2013-04-02T20:15:59.329Z,1364933759.329 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2013-04-02T20:15:59.330Z,1364933759.330 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2013-04-02T20:15:59.331Z,1364933759.331 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2013-04-02T20:15:59.332Z,1364933759.332 [Navigation](DEBUG): Initializing Navigation.
2013-04-02T20:15:59.332Z,1364933759.332 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2013-04-02T20:15:59.334Z,1364933759.334 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2013-04-02T20:15:59.335Z,1364933759.335 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2013-04-02T20:15:59.335Z,1364933759.335 [LoopControl](DEBUG): Initialize LoopControlComponent.
2013-04-02T20:16:00.981Z,1364933760.981 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2013-04-02T20:16:01.072Z,1364933761.072 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2013-04-02T20:16:01.109Z,1364933761.109 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2013-04-02T20:16:01.136Z,1364933761.136 [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-02T20:16:01.137Z,1364933761.136 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2013-04-02T20:16:01.213Z,1364933761.213 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2013-04-02T20:16:01.215Z,1364933761.215 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2013-04-02T20:16:01.227Z,1364933761.227 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-04-02T20:16:01.235Z,1364933761.235 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2013-04-02T20:16:01.238Z,1364933761.238 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-04-02T20:16:01.276Z,1364933761.276 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2013-04-02T20:16:01.315Z,1364933761.315 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-04-02T20:16:01.315Z,1364933761.315 [DVL_micro](INFO): Querying output modes
2013-04-02T20:16:01.315Z,1364933761.316 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-04-02T20:16:01.327Z,1364933761.327 [DVL_micro](DEBUG): cmdResponse: 01
2013-04-02T20:16:01.327Z,1364933761.327 [DVL_micro](INFO): NQ1 output enabled
2013-04-02T20:16:01.327Z,1364933761.327 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-04-02T20:16:01.328Z,1364933761.328 [Default:D.SetSpeed](DEBUG): Construct.
2013-04-02T20:16:01.331Z,1364933761.331 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2013-04-02T20:16:01.336Z,1364933761.336 [Default:F.Wait](DEBUG): Construct Wait.
2013-04-02T20:16:01.339Z,1364933761.339 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2013-04-02T20:16:01.344Z,1364933761.344 [DVL_micro](INFO): pause:Powering down
2013-04-02T20:16:01.371Z,1364933761.371 [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-02T20:16:01.379Z,1364933761.379 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,NAL9602,Onboard,Radio_Freewave,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,HFRadarModelCalc,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,
2013-04-02T20:16:01.423Z,1364933761.423 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-04-02T20:16:01.642Z,1364933761.642 [Radio_Freewave](INFO): Powering up
2013-04-02T20:16:02.009Z,1364933762.009 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-04-02T20:16:02.023Z,1364933762.023 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-04-02T20:16:02.029Z,1364933762.029 [ElevatorServo](DEBUG): Initializing EZServoServo.
2013-04-02T20:16:02.035Z,1364933762.035 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2013-04-02T20:16:02.080Z,1364933762.080 [MassServo](DEBUG): Initializing EZServoServo.
2013-04-02T20:16:02.087Z,1364933762.087 [MassServo](DEBUG): Initializing MassServo.
2013-04-02T20:16:02.101Z,1364933762.101 [RudderServo](DEBUG): Initializing EZServoServo.
2013-04-02T20:16:02.107Z,1364933762.107 [RudderServo](DEBUG): Initializing RudderServo.
2013-04-02T20:16:02.114Z,1364933762.114 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-04-02T20:16:02.127Z,1364933762.127 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-04-02T20:16:03.818Z,1364933763.818 [NAL9602](INFO): Powering up NAL9602
2013-04-02T20:16:14.347Z,1364933774.347 [NAL9602](INFO): NAL9602 initialized
2013-04-02T20:16:14.804Z,1364933774.804 [SBIT](IMPORTANT): Beginning Startup BIT
2013-04-02T20:16:14.807Z,1364933774.807 [CBIT](IMPORTANT): Beginning GF scan
2013-04-02T20:16:41.924Z,1364933801.924 [CBIT](IMPORTANT): No ground fault detected
2013-04-02T20:17:08.328Z,1364933828.328 [SBIT](IMPORTANT): SBIT PASSED
2013-04-02T20:17:08.702Z,1364933828.702 [MissionManager](IMPORTANT): Started mission Startup
2013-04-02T20:17:08.702Z,1364933828.702 [Startup] Running Loop=1
2013-04-02T20:17:08.702Z,1364933828.702 [Startup](INFO): Aggregate::initialize Startup
2013-04-02T20:17:08.702Z,1364933828.703 [Startup:A.GoToSurface] Running Loop=1
2013-04-02T20:17:08.703Z,1364933828.703 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T20:17:08.711Z,1364933828.711 [Startup:StartupSatComms] Running Loop=1
2013-04-02T20:17:08.711Z,1364933828.711 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2013-04-02T20:17:08.712Z,1364933828.712 [Startup:StartupSatComms:A] Running Loop=1
2013-04-02T20:17:09.135Z,1364933829.135 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-04-02T20:17:24.983Z,1364933844.983 [CommandLine](IMPORTANT): got command failComponent
2013-04-02T20:17:24.983Z,1364933844.983 [CommandLine](IMPORTANT): Failed components:
2013-04-02T20:17:24.983Z,1364933844.983 [CommandLine](IMPORTANT): No failed Components.
2013-04-02T20:18:08.732Z,1364933888.732 [Startup:StartupSatComms:A](INFO): Timed out from 2013-04-02T20:17:08.7Z
2013-04-02T20:18:08.732Z,1364933888.732 [Startup:StartupSatComms:A:A_Timeout] Running Loop=1
2013-04-02T20:18:08.732Z,1364933888.732 [Startup:StartupSatComms:A:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:A:A_Timeout
2013-04-02T20:18:08.732Z,1364933888.732 [Startup:StartupSatComms:A:A_Timeout](INFO): Completed Startup:StartupSatComms:A:A_Timeout
2013-04-02T20:18:08.732Z,1364933888.732 [Startup:StartupSatComms:A] Stopped
2013-04-02T20:18:08.733Z,1364933888.733 [Startup:StartupSatComms:B] Running Loop=1
2013-04-02T20:18:09.091Z,1364933889.091 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2013-04-02T20:19:08.970Z,1364933948.970 [Startup:StartupSatComms:B](INFO): Timed out from 2013-04-02T20:18:08.7Z
2013-04-02T20:19:08.970Z,1364933948.970 [Startup:StartupSatComms:B:A_Timeout] Running Loop=1
2013-04-02T20:19:08.970Z,1364933948.970 [Startup:StartupSatComms:B:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:B:A_Timeout
2013-04-02T20:19:08.971Z,1364933948.971 [Startup:StartupSatComms:B:A_Timeout](INFO): Completed Startup:StartupSatComms:B:A_Timeout
2013-04-02T20:19:08.971Z,1364933948.971 [Startup:StartupSatComms:B] Stopped
2013-04-02T20:19:08.971Z,1364933948.971 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2013-04-02T20:19:08.971Z,1364933948.971 [Startup:StartupSatComms] Stopped
2013-04-02T20:19:08.971Z,1364933948.971 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2013-04-02T20:19:08.972Z,1364933948.972 [Startup](INFO): Completed Startup
2013-04-02T20:19:08.972Z,1364933948.972 [Startup] Stopped
2013-04-02T20:19:08.972Z,1364933948.972 [Startup](INFO): Aggregate::uninitialize Startup
2013-04-02T20:19:08.973Z,1364933948.973 [Startup:A.GoToSurface] Stopped
2013-04-02T20:19:08.973Z,1364933948.973 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T20:19:09.368Z,1364933949.368 [MissionManager](IMPORTANT): Started mission Default
2013-04-02T20:19:09.368Z,1364933949.368 [Default] Running Loop=1
2013-04-02T20:19:09.368Z,1364933949.368 [Default](INFO): Aggregate::initialize Default
2013-04-02T20:19:09.368Z,1364933949.368 [Default:D.SetSpeed] Running Loop=1
2013-04-02T20:19:09.368Z,1364933949.368 [Default:D.SetSpeed](DEBUG): Initialize.
2013-04-02T20:19:09.368Z,1364933949.368 [Default:E.GoToSurface] Running Loop=1
2013-04-02T20:19:09.368Z,1364933949.368 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T20:19:09.369Z,1364933949.369 [Default:Iridium] Running Loop=1
2013-04-02T20:19:09.369Z,1364933949.369 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-02T20:19:09.369Z,1364933949.369 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-02T20:19:09.369Z,1364933949.369 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-02T20:19:09.369Z,1364933949.369 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-02T20:19:09.369Z,1364933949.369 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T20:19:09.370Z,1364933949.370 [Default:E.GoToSurface] Running Loop=1
2013-04-02T20:19:09.378Z,1364933949.378 [Default:D.SetSpeed] Running Loop=1
2013-04-02T20:19:09.383Z,1364933949.383 [Default:CallIridium] Running Loop=1
2013-04-02T20:19:09.383Z,1364933949.383 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-02T20:19:09.384Z,1364933949.384 [Default:CallIridium:A] Running Loop=1
2013-04-02T20:19:09.385Z,1364933949.385 [Default:CallIridium:A] Stopped
2013-04-02T20:19:09.386Z,1364933949.386 [Default:CallIridium:B] Running Loop=1
2013-04-02T20:19:09.386Z,1364933949.386 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-02T20:19:09.391Z,1364933949.391 [Default:Iridium:B.GoToSurface] Stopped
2013-04-02T20:19:09.391Z,1364933949.391 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T20:19:09.391Z,1364933949.391 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-02T20:19:09.392Z,1364933949.392 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-02T20:19:09.397Z,1364933949.397 [Default:GPS] Running Loop=1
2013-04-02T20:19:09.397Z,1364933949.397 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-02T20:19:09.397Z,1364933949.397 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-02T20:19:09.397Z,1364933949.397 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-02T20:19:09.397Z,1364933949.397 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-02T20:19:09.397Z,1364933949.397 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T20:19:09.403Z,1364933949.403 [Default:GPS:B.GoToSurface] Stopped
2013-04-02T20:19:09.403Z,1364933949.403 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T20:19:09.403Z,1364933949.403 [Default:GPS:Read_GPS] Running Loop=1
2013-04-02T20:19:09.403Z,1364933949.403 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-02T20:19:09.810Z,1364933949.810 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2013-04-02T20:19:09.812Z,1364933949.812 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-04-02T20:22:27.318Z,1364934147.318 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#0 STATUS: 65535
2013-04-02T20:26:15.492Z,1364934375.492 [NAL9602](FAULT): GPS failed to acquire within timeout.
2013-04-02T20:26:15.492Z,1364934375.492 [NAL9602] Data Fault, FailCount= 1
2013-04-02T20:26:15.493Z,1364934375.493 [NAL9602](ERROR): Data Fault
2013-04-02T20:26:15.518Z,1364934375.518 [CBIT](ERROR): Data Fault in component: NAL9602
2013-04-02T20:26:15.854Z,1364934375.854 [NAL9602](INFO): Powering down
2013-04-02T20:26:16.675Z,1364934376.675 [CBIT](INFO): Clearing failed state for component NAL9602
2013-04-02T20:26:16.675Z,1364934376.675 [NAL9602] No Fault, FailCount= 1
2013-04-02T20:26:21.102Z,1364934381.102 [NAL9602](INFO): Powering up NAL9602
2013-04-02T20:26:31.931Z,1364934391.931 [NAL9602](INFO): NAL9602 initialized
2013-04-02T20:31:17.282Z,1364934677.283 [NAL9602](INFO): SBD MO Status=1, MOMSN=9458, MT Status=0, MTMSN=0
2013-04-02T20:31:17.331Z,1364934677.331 [NAL9602](INFO): Sent 332 bytes from file Logs/20130402T200253/shore0000.lzma
2013-04-02T20:31:17.331Z,1364934677.331 [NAL9602](INFO): Packets left to send: 2
2013-04-02T20:31:17.333Z,1364934677.333 [NAL9602](INFO): Stored copy of sent data in Logs/20130402T200253/shore0000.lzma.parts/0002.sbd
2013-04-02T20:31:29.910Z,1364934689.910 [NAL9602](INFO): SBD MO Status=1, MOMSN=9459, MT Status=0, MTMSN=0
2013-04-02T20:31:29.959Z,1364934689.959 [NAL9602](INFO): Sent 332 bytes from file Logs/20130402T200253/shore0000.lzma
2013-04-02T20:31:29.959Z,1364934689.959 [NAL9602](INFO): Packets left to send: 1
2013-04-02T20:31:29.960Z,1364934689.960 [NAL9602](INFO): Stored copy of sent data in Logs/20130402T200253/shore0000.lzma.parts/0001.sbd
2013-04-02T20:31:37.482Z,1364934697.482 [NAL9602](INFO): SBD MO Status=1, MOMSN=9460, MT Status=0, MTMSN=0
2013-04-02T20:31:37.531Z,1364934697.531 [NAL9602](INFO): Sent 21 bytes from file Logs/20130402T200253/shore0000.lzma
2013-04-02T20:31:37.531Z,1364934697.531 [NAL9602](INFO): Packets left to send: 0
2013-04-02T20:31:37.532Z,1364934697.532 [NAL9602](INFO): Stored copy of sent data in Logs/20130402T200253/shore0000.lzma.parts/0000.sbd
2013-04-02T20:31:48.478Z,1364934708.478 [NAL9602](INFO): SBD MO Status=1, MOMSN=9461, MT Status=0, MTMSN=0
2013-04-02T20:31:48.527Z,1364934708.527 [NAL9602](INFO): Sent 332 bytes from file Logs/20130402T200253/shore0001.lzma
2013-04-02T20:31:48.527Z,1364934708.527 [NAL9602](INFO): Packets left to send: 1
2013-04-02T20:31:48.529Z,1364934708.529 [NAL9602](INFO): Stored copy of sent data in Logs/20130402T200253/shore0001.lzma.parts/0001.sbd
2013-04-02T20:31:59.510Z,1364934719.510 [NAL9602](INFO): SBD MO Status=1, MOMSN=9462, MT Status=0, MTMSN=0
2013-04-02T20:31:59.559Z,1364934719.559 [NAL9602](INFO): Sent 30 bytes from file Logs/20130402T200253/shore0001.lzma
2013-04-02T20:31:59.559Z,1364934719.559 [NAL9602](INFO): Packets left to send: 0
2013-04-02T20:31:59.560Z,1364934719.560 [NAL9602](INFO): Stored copy of sent data in Logs/20130402T200253/shore0001.lzma.parts/0000.sbd
2013-04-02T20:32:12.186Z,1364934732.186 [NAL9602](INFO): SBD MO Status=1, MOMSN=9463, MT Status=0, MTMSN=0
2013-04-02T20:32:12.235Z,1364934732.235 [NAL9602](INFO): Sent 332 bytes from file Logs/20130402T201549/shore0000.lzma
2013-04-02T20:32:12.235Z,1364934732.235 [NAL9602](INFO): Packets left to send: 2
2013-04-02T20:32:12.237Z,1364934732.237 [NAL9602](INFO): Stored copy of sent data in Logs/20130402T201549/shore0000.lzma.parts/0002.sbd
2013-04-02T20:32:22.406Z,1364934742.406 [NAL9602](INFO): SBD MO Status=1, MOMSN=9464, MT Status=0, MTMSN=0
2013-04-02T20:32:22.457Z,1364934742.457 [NAL9602](INFO): Sent 332 bytes from file Logs/20130402T201549/shore0000.lzma
2013-04-02T20:32:22.457Z,1364934742.457 [NAL9602](INFO): Packets left to send: 1
2013-04-02T20:32:22.458Z,1364934742.458 [NAL9602](INFO): Stored copy of sent data in Logs/20130402T201549/shore0000.lzma.parts/0001.sbd
2013-04-02T20:32:30.630Z,1364934750.630 [NAL9602](INFO): SBD MO Status=1, MOMSN=9465, MT Status=0, MTMSN=0
2013-04-02T20:32:30.683Z,1364934750.683 [NAL9602](INFO): Sent 20 bytes from file Logs/20130402T201549/shore0000.lzma
2013-04-02T20:32:30.683Z,1364934750.683 [NAL9602](INFO): Packets left to send: 0
2013-04-02T20:32:30.684Z,1364934750.684 [NAL9602](INFO): Stored copy of sent data in Logs/20130402T201549/shore0000.lzma.parts/0000.sbd
2013-04-02T20:32:38.229Z,1364934758.229 [NAL9602](INFO): SBD MO Status=0, MOMSN=9466, MT Status=0, MTMSN=0
2013-04-02T20:32:41.877Z,1364934761.877 [Default:Iridium:Read_Iridium] Stopped
2013-04-02T20:32:41.877Z,1364934761.877 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-02T20:32:41.877Z,1364934761.878 [Default:Iridium] Stopped
2013-04-02T20:32:41.878Z,1364934761.878 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-02T20:32:41.878Z,1364934761.878 [Default:Iridium:A.SetSpeed] Stopped
2013-04-02T20:32:41.878Z,1364934761.878 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T20:32:41.878Z,1364934761.878 [Default:F.Wait] Running Loop=1
2013-04-02T20:32:41.878Z,1364934761.878 [Default:F.Wait](DEBUG): Initialize Wait Component.
2013-04-02T20:32:42.245Z,1364934762.245 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-02T20:32:42.245Z,1364934762.245 [Default:CallIridium:B] Stopped
2013-04-02T20:32:42.246Z,1364934762.246 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-02T20:32:42.246Z,1364934762.246 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-02T20:32:42.246Z,1364934762.246 [Default:CallIridium] Stopped
2013-04-02T20:32:42.246Z,1364934762.246 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-02T20:32:42.872Z,1364934762.872 [NAL9602](IMPORTANT): GPS fix at: 1364934763.00
2013-04-02T20:32:42.889Z,1364934762.889 [Default:GPS:Read_GPS] Stopped
2013-04-02T20:32:42.889Z,1364934762.889 [Default:GPS:D] Running Loop=1
2013-04-02T20:32:43.332Z,1364934763.332 [Default:GPS:D] Stopped
2013-04-02T20:32:43.333Z,1364934763.333 [Default:GPS](INFO): Completed Default:GPS
2013-04-02T20:32:43.333Z,1364934763.333 [Default:GPS] Stopped
2013-04-02T20:32:43.333Z,1364934763.333 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-02T20:32:43.333Z,1364934763.333 [Default:GPS:A.SetSpeed] Stopped
2013-04-02T20:32:43.333Z,1364934763.333 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T20:33:03.863Z,1364934783.863 [NAL9602](INFO): Powering down
2013-04-02T20:33:57.927Z,1364934837.927 [CommandLine](IMPORTANT): got command load
2013-04-02T20:33:57.927Z,1364934837.927 [CommandLine](FAULT): Incomplete syntax. Try: help load
2013-04-02T20:37:38.879Z,1364935058.879 [Default:CallIridium] Running Loop=1
2013-04-02T20:37:38.879Z,1364935058.879 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-02T20:37:38.879Z,1364935058.879 [Default:CallIridium:A] Running Loop=1
2013-04-02T20:37:38.879Z,1364935058.879 [Default:CallIridium:A] Stopped
2013-04-02T20:37:38.880Z,1364935058.880 [Default:CallIridium:B] Running Loop=1
2013-04-02T20:37:38.880Z,1364935058.880 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-02T20:37:43.846Z,1364935063.846 [Default:Iridium] Running Loop=1
2013-04-02T20:37:43.846Z,1364935063.846 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-02T20:37:43.846Z,1364935063.846 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-02T20:37:43.846Z,1364935063.846 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-02T20:37:43.846Z,1364935063.846 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-02T20:37:43.846Z,1364935063.846 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T20:37:43.847Z,1364935063.847 [Default:Iridium:B.GoToSurface] Stopped
2013-04-02T20:37:43.847Z,1364935063.847 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T20:37:43.848Z,1364935063.848 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-02T20:37:43.848Z,1364935063.848 [Default:GPS] Running Loop=1
2013-04-02T20:37:43.848Z,1364935063.848 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-02T20:37:43.848Z,1364935063.848 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-02T20:37:43.848Z,1364935063.848 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-02T20:37:43.848Z,1364935063.848 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-02T20:37:43.848Z,1364935063.848 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T20:37:43.849Z,1364935063.849 [Default:GPS:B.GoToSurface] Stopped
2013-04-02T20:37:43.849Z,1364935063.849 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T20:37:43.849Z,1364935063.849 [Default:GPS:Read_GPS] Running Loop=1
2013-04-02T20:37:44.472Z,1364935064.472 [NAL9602](INFO): Powering up
2013-04-02T20:37:55.307Z,1364935075.307 [NAL9602](INFO): NAL9602 initialized
2013-04-02T20:38:27.934Z,1364935107.934 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=9467, MT Status=1, MTMSN=687
2013-04-02T20:38:27.979Z,1364935107.979 [NAL9602](INFO): Sent 332 bytes from file Logs/20130402T201549/shore0001.lzma
2013-04-02T20:38:27.979Z,1364935107.979 [NAL9602](INFO): Packets left to send: 1
2013-04-02T20:38:27.984Z,1364935107.984 [NAL9602](INFO): Stored copy of sent data in Logs/20130402T201549/shore0001.lzma.parts/0001.sbd
2013-04-02T20:38:28.343Z,1364935108.343 [NAL9602](INFO): Received command:ibit
2013-04-02T20:38:28.431Z,1364935108.431 [CommandLine](IMPORTANT): got command ibit
2013-04-02T20:38:28.677Z,1364935108.677 [IBIT](IMPORTANT): Beginning Initiated BIT
2013-04-02T20:38:28.677Z,1364935108.677 [IBIT](IMPORTANT): Beginning control surface checks.

2013-04-02T20:38:28.679Z,1364935108.679 [CBIT](IMPORTANT): Beginning GF scan
2013-04-02T20:38:29.455Z,1364935109.455 [NAL9602](IMPORTANT): GPS fix at: 1364935110.00
2013-04-02T20:38:29.461Z,1364935109.461 [Radio_Freewave](INFO): Powering down
2013-04-02T20:38:30.286Z,1364935110.286 [Radio_Freewave](INFO): Powering up
2013-04-02T20:38:49.994Z,1364935129.994 [NAL9602](INFO): SBD MO Status=1, MOMSN=9468, MT Status=0, MTMSN=0
2013-04-02T20:38:50.043Z,1364935130.043 [NAL9602](INFO): Sent 194 bytes from file Logs/20130402T201549/shore0001.lzma
2013-04-02T20:38:50.043Z,1364935130.043 [NAL9602](INFO): Packets left to send: 0
2013-04-02T20:38:50.044Z,1364935130.044 [NAL9602](INFO): Stored copy of sent data in Logs/20130402T201549/shore0001.lzma.parts/0000.sbd
2013-04-02T20:38:56.867Z,1364935136.867 [CBIT](IMPORTANT): No ground fault detected
2013-04-02T20:39:01.237Z,1364935141.237 [NAL9602](INFO): SBD MO Status=0, MOMSN=9469, MT Status=0, MTMSN=0
2013-04-02T20:39:02.435Z,1364935142.435 [NAL9602](IMPORTANT): GPS fix at: 1364935143.00
2013-04-02T20:39:14.530Z,1364935154.530 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802605 Longitude: -121.787567
2013-04-02T20:39:14.914Z,1364935154.914 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 236.710007
Voltage: 16.320562
2013-04-02T20:39:14.914Z,1364935154.914 [IBIT](IMPORTANT): batteryCapacityThreshold: 5.000000 Ah
2013-04-02T20:39:14.915Z,1364935154.915 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.500000 V
2013-04-02T20:39:15.329Z,1364935155.329 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2013-04-02T20:39:15.329Z,1364935155.329 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2013-04-02T20:39:15.330Z,1364935155.330 [IBIT](IMPORTANT): Pressure:17.106230 PSI
2013-04-02T20:39:15.330Z,1364935155.330 [IBIT](IMPORTANT): Humidity:13.870228 %
2013-04-02T20:39:15.713Z,1364935155.713 [IBIT](IMPORTANT): surfaceThreshold: 0.500000 m
2013-04-02T20:39:15.714Z,1364935155.714 [IBIT](IMPORTANT): buoyancyNeutral: 410.000000 cc
2013-04-02T20:39:15.714Z,1364935155.714 [IBIT](IMPORTANT): massDefault: -0.405000 cm
2013-04-02T20:39:15.714Z,1364935155.714 [IBIT](IMPORTANT): stopDepth: 95.000000 m
2013-04-02T20:39:15.715Z,1364935155.715 [IBIT](IMPORTANT): abortDepth: 125.000000 m
2013-04-02T20:39:15.715Z,1364935155.715 [IBIT](IMPORTANT): IBIT PASSED
2013-04-02T20:39:16.047Z,1364935156.047 [Default:GPS:Read_GPS] Stopped
2013-04-02T20:39:16.047Z,1364935156.047 [Default:GPS:D] Running Loop=1
2013-04-02T20:39:16.449Z,1364935156.449 [Default:GPS:D] Stopped
2013-04-02T20:39:16.449Z,1364935156.450 [Default:GPS](INFO): Completed Default:GPS
2013-04-02T20:39:16.450Z,1364935156.450 [Default:GPS] Stopped
2013-04-02T20:39:16.450Z,1364935156.450 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-02T20:39:16.450Z,1364935156.450 [Default:GPS:A.SetSpeed] Stopped
2013-04-02T20:39:16.450Z,1364935156.450 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T20:39:19.229Z,1364935159.229 [NAL9602](INFO): SBD MO Status=1, MOMSN=9470, MT Status=0, MTMSN=0
2013-04-02T20:39:19.283Z,1364935159.283 [NAL9602](INFO): Sent 319 bytes from file Logs/20130402T201549/shore0002.lzma
2013-04-02T20:39:19.283Z,1364935159.283 [NAL9602](INFO): Packets left to send: 0
2013-04-02T20:39:19.285Z,1364935159.285 [NAL9602](INFO): Stored copy of sent data in Logs/20130402T201549/shore0002.lzma.parts/0000.sbd
2013-04-02T20:39:30.029Z,1364935170.029 [NAL9602](INFO): SBD MO Status=0, MOMSN=9471, MT Status=0, MTMSN=0
2013-04-02T20:39:33.601Z,1364935173.601 [Default:Iridium:Read_Iridium] Stopped
2013-04-02T20:39:33.602Z,1364935173.602 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-02T20:39:33.602Z,1364935173.602 [Default:Iridium] Stopped
2013-04-02T20:39:33.602Z,1364935173.602 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-02T20:39:33.602Z,1364935173.602 [Default:Iridium:A.SetSpeed] Stopped
2013-04-02T20:39:33.602Z,1364935173.602 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T20:39:34.033Z,1364935174.033 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-02T20:39:34.033Z,1364935174.033 [Default:CallIridium:B] Stopped
2013-04-02T20:39:34.033Z,1364935174.033 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-02T20:39:34.033Z,1364935174.033 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-02T20:39:34.033Z,1364935174.033 [Default:CallIridium] Stopped
2013-04-02T20:39:34.034Z,1364935174.033 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-02T20:39:44.049Z,1364935184.049 [NAL9602](INFO): Powering down
2013-04-02T20:44:34.043Z,1364935474.043 [Default:CallIridium] Running Loop=1
2013-04-02T20:44:34.044Z,1364935474.044 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-02T20:44:34.044Z,1364935474.044 [Default:CallIridium:A] Running Loop=1
2013-04-02T20:44:34.044Z,1364935474.044 [Default:CallIridium:A] Stopped
2013-04-02T20:44:34.044Z,1364935474.044 [Default:CallIridium:B] Running Loop=1
2013-04-02T20:44:34.044Z,1364935474.044 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-02T20:44:39.077Z,1364935479.077 [Default:Iridium] Running Loop=1
2013-04-02T20:44:39.077Z,1364935479.077 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-02T20:44:39.077Z,1364935479.077 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-02T20:44:39.077Z,1364935479.077 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-02T20:44:39.077Z,1364935479.077 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-02T20:44:39.078Z,1364935479.078 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T20:44:39.079Z,1364935479.079 [Default:Iridium:B.GoToSurface] Stopped
2013-04-02T20:44:39.079Z,1364935479.079 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T20:44:39.079Z,1364935479.079 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-02T20:44:39.079Z,1364935479.079 [Default:GPS] Running Loop=1
2013-04-02T20:44:39.079Z,1364935479.079 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-02T20:44:39.079Z,1364935479.079 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-02T20:44:39.080Z,1364935479.080 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-02T20:44:39.080Z,1364935479.080 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-02T20:44:39.080Z,1364935479.080 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T20:44:39.081Z,1364935479.081 [Default:GPS:B.GoToSurface] Stopped
2013-04-02T20:44:39.081Z,1364935479.081 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T20:44:39.081Z,1364935479.081 [Default:GPS:Read_GPS] Running Loop=1
2013-04-02T20:44:39.757Z,1364935479.757 [NAL9602](INFO): Powering up
2013-04-02T20:44:50.199Z,1364935490.199 [NAL9602](INFO): NAL9602 initialized
2013-04-02T20:45:12.065Z,1364935512.065 [NAL9602](INFO): SBD MO Status=2, MOMSN=9472, MT Status=2, MTMSN=0
2013-04-02T20:45:12.065Z,1364935512.066 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T20:45:27.189Z,1364935527.189 [NAL9602](INFO): SBD MO Status=2, MOMSN=9472, MT Status=2, MTMSN=0
2013-04-02T20:45:27.190Z,1364935527.190 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T20:45:42.293Z,1364935542.293 [NAL9602](INFO): SBD MO Status=2, MOMSN=9472, MT Status=2, MTMSN=0
2013-04-02T20:45:42.294Z,1364935542.293 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T20:46:04.776Z,1364935564.776 [NAL9602](INFO): SBD MO Status=2, MOMSN=9472, MT Status=2, MTMSN=0
2013-04-02T20:46:04.776Z,1364935564.776 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T20:46:27.558Z,1364935587.557 [NAL9602](INFO): SBD MO Status=2, MOMSN=9472, MT Status=2, MTMSN=0
2013-04-02T20:46:27.558Z,1364935587.558 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T20:46:50.655Z,1364935610.655 [NAL9602](INFO): SBD MO Status=2, MOMSN=9472, MT Status=2, MTMSN=0
2013-04-02T20:46:50.656Z,1364935610.656 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T20:46:58.685Z,1364935618.685 [NAL9602](INFO): SBD MO Status=2, MOMSN=9472, MT Status=2, MTMSN=0
2013-04-02T20:46:58.686Z,1364935618.685 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T20:47:14.256Z,1364935634.256 [NAL9602](INFO): SBD MO Status=2, MOMSN=9472, MT Status=2, MTMSN=0
2013-04-02T20:47:14.256Z,1364935634.256 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T20:47:30.580Z,1364935650.580 [NAL9602](INFO): SBD MO Status=2, MOMSN=9472, MT Status=2, MTMSN=0
2013-04-02T20:47:30.580Z,1364935650.580 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T20:47:46.156Z,1364935666.156 [NAL9602](INFO): SBD MO Status=2, MOMSN=9472, MT Status=2, MTMSN=0
2013-04-02T20:47:46.156Z,1364935666.156 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T20:48:00.965Z,1364935680.965 [NAL9602](INFO): SBD MO Status=2, MOMSN=9472, MT Status=2, MTMSN=0
2013-04-02T20:48:00.965Z,1364935680.966 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T20:48:02.203Z,1364935682.203 [NAL9602](IMPORTANT): GPS fix at: 1364935683.00
2013-04-02T20:48:02.221Z,1364935682.221 [Default:GPS:Read_GPS] Stopped
2013-04-02T20:48:02.221Z,1364935682.221 [Default:GPS:D] Running Loop=1
2013-04-02T20:48:02.584Z,1364935682.583 [Default:GPS:D] Stopped
2013-04-02T20:48:02.584Z,1364935682.584 [Default:GPS](INFO): Completed Default:GPS
2013-04-02T20:48:02.584Z,1364935682.584 [Default:GPS] Stopped
2013-04-02T20:48:02.584Z,1364935682.584 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-02T20:48:02.584Z,1364935682.584 [Default:GPS:A.SetSpeed] Stopped
2013-04-02T20:48:02.584Z,1364935682.584 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T20:48:16.485Z,1364935696.485 [NAL9602](INFO): SBD MO Status=2, MOMSN=9472, MT Status=2, MTMSN=0
2013-04-02T20:48:16.485Z,1364935696.485 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T20:48:33.993Z,1364935713.993 [NAL9602](INFO): SBD MO Status=2, MOMSN=9472, MT Status=2, MTMSN=0
2013-04-02T20:48:33.993Z,1364935713.993 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T20:48:50.745Z,1364935730.745 [NAL9602](INFO): SBD MO Status=2, MOMSN=9472, MT Status=2, MTMSN=0
2013-04-02T20:48:50.746Z,1364935730.746 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T20:48:59.397Z,1364935739.397 [NAL9602](INFO): SBD MO Status=2, MOMSN=9472, MT Status=2, MTMSN=0
2013-04-02T20:48:59.397Z,1364935739.397 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T20:49:07.914Z,1364935747.914 [NAL9602](INFO): SBD MO Status=2, MOMSN=9472, MT Status=2, MTMSN=0
2013-04-02T20:49:07.915Z,1364935747.915 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T20:49:16.993Z,1364935756.993 [NAL9602](INFO): SBD MO Status=2, MOMSN=9472, MT Status=2, MTMSN=0
2013-04-02T20:49:16.993Z,1364935756.993 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T20:49:41.665Z,1364935781.665 [NAL9602](INFO): SBD MO Status=1, MOMSN=9472, MT Status=0, MTMSN=0
2013-04-02T20:49:41.715Z,1364935781.715 [NAL9602](INFO): Sent 332 bytes from file Logs/20130402T201549/shore0003.lzma
2013-04-02T20:49:41.715Z,1364935781.715 [NAL9602](INFO): Packets left to send: 1
2013-04-02T20:49:41.720Z,1364935781.720 [NAL9602](INFO): Stored copy of sent data in Logs/20130402T201549/shore0003.lzma.parts/0001.sbd
2013-04-02T20:49:56.049Z,1364935796.049 [NAL9602](INFO): SBD MO Status=1, MOMSN=9473, MT Status=0, MTMSN=0
2013-04-02T20:49:56.099Z,1364935796.099 [NAL9602](INFO): Sent 157 bytes from file Logs/20130402T201549/shore0003.lzma
2013-04-02T20:49:56.099Z,1364935796.099 [NAL9602](INFO): Packets left to send: 0
2013-04-02T20:49:56.100Z,1364935796.100 [NAL9602](INFO): Stored copy of sent data in Logs/20130402T201549/shore0003.lzma.parts/0000.sbd
2013-04-02T20:51:01.684Z,1364935861.684 [NAL9602](ERROR): Verify xmit timeout failure.
2013-04-02T20:51:08.478Z,1364935868.478 [NAL9602](INFO): SBD MO Status=2, MOMSN=9474, MT Status=2, MTMSN=0
2013-04-02T20:51:08.478Z,1364935868.478 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T20:51:20.841Z,1364935880.841 [NAL9602](INFO): SBD MO Status=0, MOMSN=9474, MT Status=0, MTMSN=0
2013-04-02T20:51:24.416Z,1364935884.416 [Default:Iridium:Read_Iridium] Stopped
2013-04-02T20:51:24.416Z,1364935884.416 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-02T20:51:24.417Z,1364935884.417 [Default:Iridium] Stopped
2013-04-02T20:51:24.417Z,1364935884.417 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-02T20:51:24.417Z,1364935884.417 [Default:Iridium:A.SetSpeed] Stopped
2013-04-02T20:51:24.417Z,1364935884.417 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T20:51:24.828Z,1364935884.828 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-02T20:51:24.828Z,1364935884.828 [Default:CallIridium:B] Stopped
2013-04-02T20:51:24.828Z,1364935884.829 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-02T20:51:24.829Z,1364935884.829 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-02T20:51:24.829Z,1364935884.829 [Default:CallIridium] Stopped
2013-04-02T20:51:24.829Z,1364935884.829 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-02T20:51:34.864Z,1364935894.864 [NAL9602](INFO): Powering down
2013-04-02T20:54:00.243Z,1364936040.243 [CommandLine](IMPORTANT): got command ! ls /mnt/mmc/LRAUV/Data/HFRadarModel
2013-04-02T20:54:00.459Z,1364936040.459 [CommandLine](IMPORTANT): 2013032720
2013032721
2013032722
2013032723
2013032800
2013032801
2013032802
2013032803
2013032804
2013032805
2013032806
2013032807
2013032808
2013032809
2013032810
2013032811
2013032812
2013032813
2013032814
2013032815
2013032816
2013032817
2013032818
2013032819
2013032820
2013032821
2013032822
2013032823
2013032900
2013032901
2013032902
2013032903
2013032904
2013032905
2013032906
2013032907
2013032908
2013032909
2013032910
2013032911
2013032912
2013032913
2013032914
2013032915
2013032916
2013032917
2013032918
2013032919
2013032920
2013032921
2013032922
2013032923
2013033000
2013033001
2013033002
2013033003
2013033004
2013033005
2013033006
2013033007
2013033008
2013033009
2013033010
2013033011
2013033012
2013033013
2013033014
2013033015
2013033016
2013033017
2013033018
2013033019
2013033020
2013033021
2013033022
2013033023
2013033100
2013033101
2013033102
2013033103
2013033104
2013033105
2013033106
2013033107
2013033108
2013033109
2013033110
2013033111
2013033112
2013033113
2013033114
2013033115
2013033116
2013033117
2013033118
2013033119
2013033120
2013033121
2013033122
2013033123
2013040100
2013040101
2013040102
2013040103
2013040104
2013040105
2013040106
2013040107
2013040108
2013040109
2013040110
2013040111
2013040112
2013040113
2013040114
2013040115
2013040116
2013040117
2013040118
2013040119
2013040120
2013040121
2013040122
2013040123
2013040200
2013040201
2013040202
2013040203
2013040204
2013040205
2013040206
2013040207
2013040208
2013040209
2013040210
2013040211
2013040212
2013040213
2013040214
2013040215
2013040216
2013040217
2013040218

2013-04-02T20:56:24.878Z,1364936184.878 [Default:CallIridium] Running Loop=1
2013-04-02T20:56:24.879Z,1364936184.879 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-02T20:56:24.879Z,1364936184.879 [Default:CallIridium:A] Running Loop=1
2013-04-02T20:56:24.879Z,1364936184.879 [Default:CallIridium:A] Stopped
2013-04-02T20:56:24.879Z,1364936184.879 [Default:CallIridium:B] Running Loop=1
2013-04-02T20:56:24.879Z,1364936184.879 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-02T20:56:29.845Z,1364936189.845 [Default:Iridium] Running Loop=1
2013-04-02T20:56:29.845Z,1364936189.845 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-02T20:56:29.845Z,1364936189.845 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-02T20:56:29.845Z,1364936189.845 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-02T20:56:29.845Z,1364936189.845 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-02T20:56:29.845Z,1364936189.845 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T20:56:29.846Z,1364936189.846 [Default:Iridium:B.GoToSurface] Stopped
2013-04-02T20:56:29.847Z,1364936189.847 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T20:56:29.847Z,1364936189.847 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-02T20:56:29.847Z,1364936189.847 [Default:GPS] Running Loop=1
2013-04-02T20:56:29.847Z,1364936189.847 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-02T20:56:29.847Z,1364936189.847 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-02T20:56:29.847Z,1364936189.847 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-02T20:56:29.848Z,1364936189.848 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-02T20:56:29.848Z,1364936189.848 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T20:56:29.849Z,1364936189.849 [Default:GPS:B.GoToSurface] Stopped
2013-04-02T20:56:29.849Z,1364936189.849 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T20:56:29.849Z,1364936189.849 [Default:GPS:Read_GPS] Running Loop=1
2013-04-02T20:56:30.532Z,1364936190.532 [NAL9602](INFO): Powering up
2013-04-02T20:56:39.003Z,1364936199.003 [CommandLine](IMPORTANT): got command ! echo "MJS: That was a listing of the Compact Model data on the vehicle, initiated via freewave, to show what was on the vehicle before any SBDs are sent."
2013-04-02T20:56:39.102Z,1364936199.102 [CommandLine](IMPORTANT): MJS: That was a listing of the Compact Model data on the vehicle, initiated via freewave, to show what was on the vehicle before any SBDs are sent.

2013-04-02T20:56:40.967Z,1364936200.967 [NAL9602](INFO): NAL9602 initialized
2013-04-02T20:57:00.893Z,1364936220.893 [NAL9602](INFO): SBD MO Status=2, MOMSN=9475, MT Status=2, MTMSN=0
2013-04-02T20:57:00.893Z,1364936220.893 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T20:57:19.233Z,1364936239.233 [NAL9602](INFO): SBD MO Status=2, MOMSN=9475, MT Status=2, MTMSN=0
2013-04-02T20:57:19.234Z,1364936239.234 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T20:57:55.989Z,1364936275.989 [NAL9602](INFO): SBD MO Status=2, MOMSN=9475, MT Status=0, MTMSN=0
2013-04-02T20:57:55.990Z,1364936275.990 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T20:57:57.191Z,1364936277.191 [NAL9602](IMPORTANT): GPS fix at: 1364936279.00
2013-04-02T20:57:57.208Z,1364936277.208 [Default:GPS:Read_GPS] Stopped
2013-04-02T20:57:57.208Z,1364936277.208 [Default:GPS:D] Running Loop=1
2013-04-02T20:57:57.633Z,1364936277.633 [Default:GPS:D] Stopped
2013-04-02T20:57:57.634Z,1364936277.634 [Default:GPS](INFO): Completed Default:GPS
2013-04-02T20:57:57.634Z,1364936277.634 [Default:GPS] Stopped
2013-04-02T20:57:57.634Z,1364936277.634 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-02T20:57:57.634Z,1364936277.634 [Default:GPS:A.SetSpeed] Stopped
2013-04-02T20:57:57.634Z,1364936277.634 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T20:58:33.985Z,1364936313.985 [NAL9602](INFO): SBD MO Status=1, MOMSN=9475, MT Status=0, MTMSN=0
2013-04-02T20:58:34.035Z,1364936314.035 [NAL9602](INFO): Sent 332 bytes from file Logs/20130402T201549/shore0004.lzma
2013-04-02T20:58:34.035Z,1364936314.035 [NAL9602](INFO): Packets left to send: 1
2013-04-02T20:58:34.048Z,1364936314.048 [NAL9602](INFO): Stored copy of sent data in Logs/20130402T201549/shore0004.lzma.parts/0001.sbd
2013-04-02T20:58:52.229Z,1364936332.229 [NAL9602](INFO): SBD MO Status=1, MOMSN=9476, MT Status=0, MTMSN=0
2013-04-02T20:58:52.283Z,1364936332.283 [NAL9602](INFO): Sent 176 bytes from file Logs/20130402T201549/shore0004.lzma
2013-04-02T20:58:52.283Z,1364936332.283 [NAL9602](INFO): Packets left to send: 0
2013-04-02T20:58:52.284Z,1364936332.284 [NAL9602](INFO): Stored copy of sent data in Logs/20130402T201549/shore0004.lzma.parts/0000.sbd
2013-04-02T20:59:09.025Z,1364936349.025 [NAL9602](INFO): SBD MO Status=0, MOMSN=9477, MT Status=0, MTMSN=0
2013-04-02T20:59:12.592Z,1364936352.592 [Default:Iridium:Read_Iridium] Stopped
2013-04-02T20:59:12.593Z,1364936352.593 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-02T20:59:12.593Z,1364936352.593 [Default:Iridium] Stopped
2013-04-02T20:59:12.593Z,1364936352.593 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-02T20:59:12.593Z,1364936352.593 [Default:Iridium:A.SetSpeed] Stopped
2013-04-02T20:59:12.593Z,1364936352.593 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T20:59:12.917Z,1364936352.917 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-02T20:59:12.917Z,1364936352.917 [Default:CallIridium:B] Stopped
2013-04-02T20:59:12.917Z,1364936352.917 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-02T20:59:12.917Z,1364936352.917 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-02T20:59:12.917Z,1364936352.917 [Default:CallIridium] Stopped
2013-04-02T20:59:12.917Z,1364936352.917 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-02T20:59:22.928Z,1364936362.928 [NAL9602](INFO): Powering down
2013-04-02T21:04:12.997Z,1364936652.997 [Default:CallIridium] Running Loop=1
2013-04-02T21:04:12.997Z,1364936652.997 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-02T21:04:12.997Z,1364936652.997 [Default:CallIridium:A] Running Loop=1
2013-04-02T21:04:12.997Z,1364936652.997 [Default:CallIridium:A] Stopped
2013-04-02T21:04:12.998Z,1364936652.997 [Default:CallIridium:B] Running Loop=1
2013-04-02T21:04:12.998Z,1364936652.998 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-02T21:04:17.976Z,1364936657.976 [Default:Iridium] Running Loop=1
2013-04-02T21:04:17.976Z,1364936657.976 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-02T21:04:17.976Z,1364936657.976 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-02T21:04:17.976Z,1364936657.976 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-02T21:04:17.976Z,1364936657.976 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-02T21:04:17.976Z,1364936657.976 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T21:04:17.977Z,1364936657.977 [Default:Iridium:B.GoToSurface] Stopped
2013-04-02T21:04:17.977Z,1364936657.977 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T21:04:17.977Z,1364936657.977 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-02T21:04:17.978Z,1364936657.978 [Default:GPS] Running Loop=1
2013-04-02T21:04:17.978Z,1364936657.978 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-02T21:04:17.978Z,1364936657.978 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-02T21:04:17.978Z,1364936657.978 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-02T21:04:17.978Z,1364936657.978 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-02T21:04:17.979Z,1364936657.978 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T21:04:17.979Z,1364936657.979 [Default:GPS:B.GoToSurface] Stopped
2013-04-02T21:04:17.979Z,1364936657.979 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T21:04:17.979Z,1364936657.980 [Default:GPS:Read_GPS] Running Loop=1
2013-04-02T21:04:18.588Z,1364936658.588 [NAL9602](INFO): Powering up
2013-04-02T21:04:29.423Z,1364936669.423 [NAL9602](INFO): NAL9602 initialized
2013-04-02T21:04:47.973Z,1364936687.973 [NAL9602](INFO): SBD MO Status=1, MOMSN=9478, MT Status=0, MTMSN=0
2013-04-02T21:04:48.023Z,1364936688.023 [NAL9602](INFO): Sent 138 bytes from file Logs/20130402T201549/shore0005.lzma
2013-04-02T21:04:48.023Z,1364936688.023 [NAL9602](INFO): Packets left to send: 0
2013-04-02T21:04:48.025Z,1364936688.025 [NAL9602](INFO): Stored copy of sent data in Logs/20130402T201549/shore0005.lzma.parts/0000.sbd
2013-04-02T21:05:03.614Z,1364936703.614 [NAL9602](INFO): SBD MO Status=2, MOMSN=9479, MT Status=2, MTMSN=0
2013-04-02T21:05:03.614Z,1364936703.614 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T21:05:04.807Z,1364936704.807 [NAL9602](IMPORTANT): GPS fix at: 1364936708.00
2013-04-02T21:05:04.824Z,1364936704.824 [Default:GPS:Read_GPS] Stopped
2013-04-02T21:05:04.824Z,1364936704.824 [Default:GPS:D] Running Loop=1
2013-04-02T21:05:05.199Z,1364936705.199 [Default:GPS:D] Stopped
2013-04-02T21:05:05.200Z,1364936705.200 [Default:GPS](INFO): Completed Default:GPS
2013-04-02T21:05:05.200Z,1364936705.200 [Default:GPS] Stopped
2013-04-02T21:05:05.200Z,1364936705.200 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-02T21:05:05.200Z,1364936705.200 [Default:GPS:A.SetSpeed] Stopped
2013-04-02T21:05:05.200Z,1364936705.200 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T21:05:19.533Z,1364936719.533 [NAL9602](INFO): SBD MO Status=2, MOMSN=9479, MT Status=2, MTMSN=0
2013-04-02T21:05:19.534Z,1364936719.534 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T21:05:40.321Z,1364936740.321 [NAL9602](INFO): SBD MO Status=2, MOMSN=9479, MT Status=2, MTMSN=0
2013-04-02T21:05:40.322Z,1364936740.322 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T21:06:01.460Z,1364936761.460 [NAL9602](INFO): SBD MO Status=2, MOMSN=9479, MT Status=2, MTMSN=0
2013-04-02T21:06:01.460Z,1364936761.460 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T21:06:22.580Z,1364936782.580 [NAL9602](INFO): SBD MO Status=2, MOMSN=9479, MT Status=2, MTMSN=0
2013-04-02T21:06:22.580Z,1364936782.580 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T21:06:32.962Z,1364936792.962 [NAL9602](INFO): SBD MO Status=0, MOMSN=9479, MT Status=0, MTMSN=0
2013-04-02T21:06:36.543Z,1364936796.543 [Default:Iridium:Read_Iridium] Stopped
2013-04-02T21:06:36.544Z,1364936796.544 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-02T21:06:36.544Z,1364936796.544 [Default:Iridium] Stopped
2013-04-02T21:06:36.544Z,1364936796.544 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-02T21:06:36.544Z,1364936796.544 [Default:Iridium:A.SetSpeed] Stopped
2013-04-02T21:06:36.544Z,1364936796.544 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T21:06:36.937Z,1364936796.937 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-02T21:06:36.937Z,1364936796.937 [Default:CallIridium:B] Stopped
2013-04-02T21:06:36.937Z,1364936796.937 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-02T21:06:36.937Z,1364936796.937 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-02T21:06:36.937Z,1364936796.937 [Default:CallIridium] Stopped
2013-04-02T21:06:36.938Z,1364936796.938 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-02T21:06:46.948Z,1364936806.948 [NAL9602](INFO): Powering down
2013-04-02T21:11:36.941Z,1364937096.941 [Default:CallIridium] Running Loop=1
2013-04-02T21:11:36.941Z,1364937096.941 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-02T21:11:36.941Z,1364937096.941 [Default:CallIridium:A] Running Loop=1
2013-04-02T21:11:36.941Z,1364937096.941 [Default:CallIridium:A] Stopped
2013-04-02T21:11:36.942Z,1364937096.941 [Default:CallIridium:B] Running Loop=1
2013-04-02T21:11:36.942Z,1364937096.942 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-02T21:11:41.976Z,1364937101.976 [Default:Iridium] Running Loop=1
2013-04-02T21:11:41.976Z,1364937101.976 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-02T21:11:41.976Z,1364937101.976 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-02T21:11:41.976Z,1364937101.976 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-02T21:11:41.977Z,1364937101.977 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-02T21:11:41.977Z,1364937101.977 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T21:11:41.977Z,1364937101.977 [Default:Iridium:B.GoToSurface] Stopped
2013-04-02T21:11:41.978Z,1364937101.977 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T21:11:41.978Z,1364937101.978 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-02T21:11:41.978Z,1364937101.978 [Default:GPS] Running Loop=1
2013-04-02T21:11:41.978Z,1364937101.978 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-02T21:11:41.978Z,1364937101.978 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-02T21:11:41.979Z,1364937101.978 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-02T21:11:41.979Z,1364937101.979 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-02T21:11:41.979Z,1364937101.979 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T21:11:41.979Z,1364937101.980 [Default:GPS:B.GoToSurface] Stopped
2013-04-02T21:11:41.980Z,1364937101.980 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T21:11:41.980Z,1364937101.980 [Default:GPS:Read_GPS] Running Loop=1
2013-04-02T21:11:42.570Z,1364937102.570 [NAL9602](INFO): Powering up
2013-04-02T21:11:53.011Z,1364937113.011 [NAL9602](INFO): NAL9602 initialized
2013-04-02T21:12:13.925Z,1364937133.925 [NAL9602](INFO): SBD MO Status=1, MOMSN=9480, MT Status=0, MTMSN=0
2013-04-02T21:12:13.975Z,1364937133.975 [NAL9602](INFO): Sent 136 bytes from file Logs/20130402T201549/shore0006.lzma
2013-04-02T21:12:13.975Z,1364937133.975 [NAL9602](INFO): Packets left to send: 0
2013-04-02T21:12:13.977Z,1364937133.977 [NAL9602](INFO): Stored copy of sent data in Logs/20130402T201549/shore0006.lzma.parts/0000.sbd
2013-04-02T21:12:25.586Z,1364937145.586 [NAL9602](INFO): SBD MO Status=2, MOMSN=9481, MT Status=2, MTMSN=0
2013-04-02T21:12:25.586Z,1364937145.586 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T21:12:26.726Z,1364937146.726 [NAL9602](IMPORTANT): GPS fix at: 1364937150.00
2013-04-02T21:12:26.743Z,1364937146.743 [Default:GPS:Read_GPS] Stopped
2013-04-02T21:12:26.743Z,1364937146.743 [Default:GPS:D] Running Loop=1
2013-04-02T21:12:27.171Z,1364937147.171 [Default:GPS:D] Stopped
2013-04-02T21:12:27.171Z,1364937147.171 [Default:GPS](INFO): Completed Default:GPS
2013-04-02T21:12:27.171Z,1364937147.171 [Default:GPS] Stopped
2013-04-02T21:12:27.172Z,1364937147.172 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-02T21:12:27.172Z,1364937147.172 [Default:GPS:A.SetSpeed] Stopped
2013-04-02T21:12:27.172Z,1364937147.172 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T21:12:35.902Z,1364937155.902 [NAL9602](INFO): SBD MO Status=0, MOMSN=9481, MT Status=0, MTMSN=0
2013-04-02T21:12:39.463Z,1364937159.463 [Default:Iridium:Read_Iridium] Stopped
2013-04-02T21:12:39.464Z,1364937159.464 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-02T21:12:39.464Z,1364937159.464 [Default:Iridium] Stopped
2013-04-02T21:12:39.464Z,1364937159.464 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-02T21:12:39.464Z,1364937159.464 [Default:Iridium:A.SetSpeed] Stopped
2013-04-02T21:12:39.464Z,1364937159.464 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T21:12:39.775Z,1364937159.775 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-02T21:12:39.775Z,1364937159.775 [Default:CallIridium:B] Stopped
2013-04-02T21:12:39.775Z,1364937159.775 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-02T21:12:39.775Z,1364937159.775 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-02T21:12:39.775Z,1364937159.775 [Default:CallIridium] Stopped
2013-04-02T21:12:39.775Z,1364937159.775 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-02T21:12:49.817Z,1364937169.816 [NAL9602](INFO): Powering down
2013-04-02T21:17:39.848Z,1364937459.848 [Default:CallIridium] Running Loop=1
2013-04-02T21:17:39.849Z,1364937459.849 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-02T21:17:39.849Z,1364937459.849 [Default:CallIridium:A] Running Loop=1
2013-04-02T21:17:39.849Z,1364937459.849 [Default:CallIridium:A] Stopped
2013-04-02T21:17:39.849Z,1364937459.849 [Default:CallIridium:B] Running Loop=1
2013-04-02T21:17:39.849Z,1364937459.849 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-02T21:17:44.816Z,1364937464.816 [Default:Iridium] Running Loop=1
2013-04-02T21:17:44.816Z,1364937464.816 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-02T21:17:44.816Z,1364937464.816 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-02T21:17:44.816Z,1364937464.816 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-02T21:17:44.816Z,1364937464.816 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-02T21:17:44.817Z,1364937464.816 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T21:17:44.817Z,1364937464.817 [Default:Iridium:B.GoToSurface] Stopped
2013-04-02T21:17:44.817Z,1364937464.817 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T21:17:44.817Z,1364937464.818 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-02T21:17:44.818Z,1364937464.818 [Default:GPS] Running Loop=1
2013-04-02T21:17:44.818Z,1364937464.818 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-02T21:17:44.818Z,1364937464.818 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-02T21:17:44.818Z,1364937464.818 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-02T21:17:44.818Z,1364937464.819 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-02T21:17:44.819Z,1364937464.819 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T21:17:44.819Z,1364937464.819 [Default:GPS:B.GoToSurface] Stopped
2013-04-02T21:17:44.820Z,1364937464.819 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T21:17:44.820Z,1364937464.820 [Default:GPS:Read_GPS] Running Loop=1
2013-04-02T21:17:45.492Z,1364937465.492 [NAL9602](INFO): Powering up
2013-04-02T21:17:55.931Z,1364937475.931 [NAL9602](INFO): NAL9602 initialized
2013-04-02T21:18:20.453Z,1364937500.453 [NAL9602](INFO): SBD MO Status=2, MOMSN=9482, MT Status=2, MTMSN=0
2013-04-02T21:18:20.453Z,1364937500.453 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T21:18:21.650Z,1364937501.649 [NAL9602](IMPORTANT): GPS fix at: 1364937506.00
2013-04-02T21:18:21.667Z,1364937501.667 [Default:GPS:Read_GPS] Stopped
2013-04-02T21:18:21.667Z,1364937501.667 [Default:GPS:D] Running Loop=1
2013-04-02T21:18:22.080Z,1364937502.080 [Default:GPS:D] Stopped
2013-04-02T21:18:22.080Z,1364937502.080 [Default:GPS](INFO): Completed Default:GPS
2013-04-02T21:18:22.080Z,1364937502.080 [Default:GPS] Stopped
2013-04-02T21:18:22.080Z,1364937502.080 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-02T21:18:22.080Z,1364937502.080 [Default:GPS:A.SetSpeed] Stopped
2013-04-02T21:18:22.080Z,1364937502.080 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T21:19:40.153Z,1364937580.153 [NAL9602](INFO): SBD MO Status=2, MOMSN=9482, MT Status=2, MTMSN=0
2013-04-02T21:19:40.154Z,1364937580.154 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T21:20:28.521Z,1364937628.521 [NAL9602](INFO): SBD MO Status=1, MOMSN=9482, MT Status=0, MTMSN=0
2013-04-02T21:20:28.571Z,1364937628.571 [NAL9602](INFO): Sent 137 bytes from file Logs/20130402T201549/shore0007.lzma
2013-04-02T21:20:28.571Z,1364937628.571 [NAL9602](INFO): Packets left to send: 0
2013-04-02T21:20:28.573Z,1364937628.573 [NAL9602](INFO): Stored copy of sent data in Logs/20130402T201549/shore0007.lzma.parts/0000.sbd
2013-04-02T21:20:38.925Z,1364937638.925 [NAL9602](INFO): SBD MO Status=0, MOMSN=9483, MT Status=0, MTMSN=0
2013-04-02T21:20:42.492Z,1364937642.492 [Default:Iridium:Read_Iridium] Stopped
2013-04-02T21:20:42.492Z,1364937642.492 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-02T21:20:42.492Z,1364937642.492 [Default:Iridium] Stopped
2013-04-02T21:20:42.492Z,1364937642.492 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-02T21:20:42.493Z,1364937642.493 [Default:Iridium:A.SetSpeed] Stopped
2013-04-02T21:20:42.493Z,1364937642.493 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T21:20:42.821Z,1364937642.821 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-02T21:20:42.821Z,1364937642.821 [Default:CallIridium:B] Stopped
2013-04-02T21:20:42.822Z,1364937642.822 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-02T21:20:42.822Z,1364937642.822 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-02T21:20:42.822Z,1364937642.822 [Default:CallIridium] Stopped
2013-04-02T21:20:42.822Z,1364937642.822 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-02T21:20:52.880Z,1364937652.880 [NAL9602](INFO): Powering down
2013-04-02T21:25:42.853Z,1364937942.853 [Default:CallIridium] Running Loop=1
2013-04-02T21:25:42.853Z,1364937942.853 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-02T21:25:42.853Z,1364937942.853 [Default:CallIridium:A] Running Loop=1
2013-04-02T21:25:42.854Z,1364937942.854 [Default:CallIridium:A] Stopped
2013-04-02T21:25:42.854Z,1364937942.854 [Default:CallIridium:B] Running Loop=1
2013-04-02T21:25:42.854Z,1364937942.854 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-02T21:25:47.923Z,1364937947.923 [Default:Iridium] Running Loop=1
2013-04-02T21:25:47.923Z,1364937947.923 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-02T21:25:47.924Z,1364937947.924 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-02T21:25:47.924Z,1364937947.924 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-02T21:25:47.924Z,1364937947.924 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-02T21:25:47.924Z,1364937947.924 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T21:25:47.925Z,1364937947.925 [Default:Iridium:B.GoToSurface] Stopped
2013-04-02T21:25:47.925Z,1364937947.925 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T21:25:47.925Z,1364937947.925 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-02T21:25:47.925Z,1364937947.925 [Default:GPS] Running Loop=1
2013-04-02T21:25:47.925Z,1364937947.925 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-02T21:25:47.925Z,1364937947.925 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-02T21:25:47.925Z,1364937947.925 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-02T21:25:47.926Z,1364937947.926 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-02T21:25:47.926Z,1364937947.926 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T21:25:47.927Z,1364937947.927 [Default:GPS:B.GoToSurface] Stopped
2013-04-02T21:25:47.927Z,1364937947.927 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T21:25:47.927Z,1364937947.927 [Default:GPS:Read_GPS] Running Loop=1
2013-04-02T21:25:48.552Z,1364937948.552 [NAL9602](INFO): Powering up
2013-04-02T21:25:58.959Z,1364937958.959 [NAL9602](INFO): NAL9602 initialized
2013-04-02T21:26:24.305Z,1364937984.305 [NAL9602](INFO): SBD MO Status=1, MOMSN=9484, MT Status=0, MTMSN=0
2013-04-02T21:26:24.355Z,1364937984.355 [NAL9602](INFO): Sent 136 bytes from file Logs/20130402T201549/shore0008.lzma
2013-04-02T21:26:24.355Z,1364937984.355 [NAL9602](INFO): Packets left to send: 0
2013-04-02T21:26:24.357Z,1364937984.357 [NAL9602](INFO): Stored copy of sent data in Logs/20130402T201549/shore0008.lzma.parts/0000.sbd
2013-04-02T21:26:33.954Z,1364937993.954 [NAL9602](INFO): SBD MO Status=2, MOMSN=9485, MT Status=2, MTMSN=0
2013-04-02T21:26:33.955Z,1364937993.955 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T21:26:35.105Z,1364937995.105 [NAL9602](IMPORTANT): GPS fix at: 1364938000.00
2013-04-02T21:26:35.123Z,1364937995.123 [Default:GPS:Read_GPS] Stopped
2013-04-02T21:26:35.123Z,1364937995.123 [Default:GPS:D] Running Loop=1
2013-04-02T21:26:35.527Z,1364937995.527 [Default:GPS:D] Stopped
2013-04-02T21:26:35.528Z,1364937995.528 [Default:GPS](INFO): Completed Default:GPS
2013-04-02T21:26:35.528Z,1364937995.528 [Default:GPS] Stopped
2013-04-02T21:26:35.528Z,1364937995.528 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-02T21:26:35.528Z,1364937995.528 [Default:GPS:A.SetSpeed] Stopped
2013-04-02T21:26:35.528Z,1364937995.528 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T21:26:50.625Z,1364938010.625 [NAL9602](INFO): SBD MO Status=0, MOMSN=9485, MT Status=0, MTMSN=0
2013-04-02T21:26:54.381Z,1364938014.381 [Default:Iridium:Read_Iridium] Stopped
2013-04-02T21:26:54.382Z,1364938014.382 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-02T21:26:54.382Z,1364938014.382 [Default:Iridium] Stopped
2013-04-02T21:26:54.382Z,1364938014.382 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-02T21:26:54.382Z,1364938014.382 [Default:Iridium:A.SetSpeed] Stopped
2013-04-02T21:26:54.382Z,1364938014.382 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T21:26:54.793Z,1364938014.793 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-02T21:26:54.793Z,1364938014.793 [Default:CallIridium:B] Stopped
2013-04-02T21:26:54.794Z,1364938014.794 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-02T21:26:54.794Z,1364938014.794 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-02T21:26:54.794Z,1364938014.794 [Default:CallIridium] Stopped
2013-04-02T21:26:54.794Z,1364938014.794 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-02T21:27:04.773Z,1364938024.773 [NAL9602](INFO): Powering down
2013-04-02T21:31:54.763Z,1364938314.763 [Default:CallIridium] Running Loop=1
2013-04-02T21:31:54.763Z,1364938314.763 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-02T21:31:54.763Z,1364938314.763 [Default:CallIridium:A] Running Loop=1
2013-04-02T21:31:54.764Z,1364938314.764 [Default:CallIridium:A] Stopped
2013-04-02T21:31:54.764Z,1364938314.764 [Default:CallIridium:B] Running Loop=1
2013-04-02T21:31:54.764Z,1364938314.764 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-02T21:31:59.809Z,1364938319.809 [Default:Iridium] Running Loop=1
2013-04-02T21:31:59.809Z,1364938319.809 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-02T21:31:59.810Z,1364938319.810 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-02T21:31:59.810Z,1364938319.810 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-02T21:31:59.810Z,1364938319.810 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-02T21:31:59.810Z,1364938319.810 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T21:31:59.811Z,1364938319.811 [Default:Iridium:B.GoToSurface] Stopped
2013-04-02T21:31:59.811Z,1364938319.811 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T21:31:59.811Z,1364938319.811 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-02T21:31:59.812Z,1364938319.812 [Default:GPS] Running Loop=1
2013-04-02T21:31:59.812Z,1364938319.812 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-02T21:31:59.812Z,1364938319.812 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-02T21:31:59.812Z,1364938319.812 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-02T21:31:59.812Z,1364938319.812 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-02T21:31:59.812Z,1364938319.812 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T21:31:59.813Z,1364938319.813 [Default:GPS:B.GoToSurface] Stopped
2013-04-02T21:31:59.813Z,1364938319.813 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T21:31:59.813Z,1364938319.813 [Default:GPS:Read_GPS] Running Loop=1
2013-04-02T21:32:00.416Z,1364938320.416 [NAL9602](INFO): Powering up
2013-04-02T21:32:11.247Z,1364938331.247 [NAL9602](INFO): NAL9602 initialized
2013-04-02T21:32:36.166Z,1364938356.166 [NAL9602](INFO): SBD MO Status=1, MOMSN=9486, MT Status=0, MTMSN=0
2013-04-02T21:32:36.215Z,1364938356.215 [NAL9602](INFO): Sent 155 bytes from file Logs/20130402T201549/shore0009.lzma
2013-04-02T21:32:36.215Z,1364938356.215 [NAL9602](INFO): Packets left to send: 0
2013-04-02T21:32:36.217Z,1364938356.217 [NAL9602](INFO): Stored copy of sent data in Logs/20130402T201549/shore0009.lzma.parts/0000.sbd
2013-04-02T21:32:46.569Z,1364938366.569 [NAL9602](INFO): SBD MO Status=0, MOMSN=9487, MT Status=0, MTMSN=0
2013-04-02T21:32:50.250Z,1364938370.250 [Default:Iridium:Read_Iridium] Stopped
2013-04-02T21:32:50.250Z,1364938370.250 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-02T21:32:50.250Z,1364938370.250 [Default:Iridium] Stopped
2013-04-02T21:32:50.250Z,1364938370.250 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-02T21:32:50.251Z,1364938370.251 [Default:Iridium:A.SetSpeed] Stopped
2013-04-02T21:32:50.251Z,1364938370.251 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T21:32:50.625Z,1364938370.625 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-02T21:32:50.625Z,1364938370.625 [Default:CallIridium:B] Stopped
2013-04-02T21:32:50.625Z,1364938370.625 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-02T21:32:50.625Z,1364938370.625 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-02T21:32:50.625Z,1364938370.625 [Default:CallIridium] Stopped
2013-04-02T21:32:50.625Z,1364938370.625 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-02T21:32:51.247Z,1364938371.247 [NAL9602](IMPORTANT): GPS fix at: 1364938377.00
2013-04-02T21:32:51.263Z,1364938371.263 [Default:GPS:Read_GPS] Stopped
2013-04-02T21:32:51.264Z,1364938371.263 [Default:GPS:D] Running Loop=1
2013-04-02T21:32:51.677Z,1364938371.677 [Default:GPS:D] Stopped
2013-04-02T21:32:51.677Z,1364938371.677 [Default:GPS](INFO): Completed Default:GPS
2013-04-02T21:32:51.677Z,1364938371.677 [Default:GPS] Stopped
2013-04-02T21:32:51.678Z,1364938371.678 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-02T21:32:51.678Z,1364938371.678 [Default:GPS:A.SetSpeed] Stopped
2013-04-02T21:32:51.678Z,1364938371.678 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T21:33:12.261Z,1364938392.261 [NAL9602](INFO): Powering down
2013-04-02T21:37:47.252Z,1364938667.253 [Default:CallIridium] Running Loop=1
2013-04-02T21:37:47.253Z,1364938667.253 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-02T21:37:47.253Z,1364938667.253 [Default:CallIridium:A] Running Loop=1
2013-04-02T21:37:47.253Z,1364938667.253 [Default:CallIridium:A] Stopped
2013-04-02T21:37:47.253Z,1364938667.253 [Default:CallIridium:B] Running Loop=1
2013-04-02T21:37:47.253Z,1364938667.253 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-02T21:37:52.224Z,1364938672.224 [Default:Iridium] Running Loop=1
2013-04-02T21:37:52.224Z,1364938672.224 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-02T21:37:52.224Z,1364938672.224 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-02T21:37:52.224Z,1364938672.224 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-02T21:37:52.224Z,1364938672.224 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-02T21:37:52.225Z,1364938672.224 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T21:37:52.225Z,1364938672.225 [Default:Iridium:B.GoToSurface] Stopped
2013-04-02T21:37:52.225Z,1364938672.225 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T21:37:52.226Z,1364938672.226 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-02T21:37:52.226Z,1364938672.226 [Default:GPS] Running Loop=1
2013-04-02T21:37:52.226Z,1364938672.226 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-02T21:37:52.226Z,1364938672.226 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-02T21:37:52.226Z,1364938672.226 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-02T21:37:52.227Z,1364938672.227 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-02T21:37:52.227Z,1364938672.227 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T21:37:52.227Z,1364938672.227 [Default:GPS:B.GoToSurface] Stopped
2013-04-02T21:37:52.228Z,1364938672.227 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T21:37:52.228Z,1364938672.228 [Default:GPS:Read_GPS] Running Loop=1
2013-04-02T21:37:52.844Z,1364938672.844 [NAL9602](INFO): Powering up
2013-04-02T21:38:03.283Z,1364938683.283 [NAL9602](INFO): NAL9602 initialized
2013-04-02T21:38:15.461Z,1364938695.461 [NAL9602](INFO): SBD MO Status=1, MOMSN=9488, MT Status=0, MTMSN=0
2013-04-02T21:38:15.519Z,1364938695.519 [NAL9602](INFO): Sent 138 bytes from file Logs/20130402T201549/shore0010.lzma
2013-04-02T21:38:15.519Z,1364938695.519 [NAL9602](INFO): Packets left to send: 0
2013-04-02T21:38:15.521Z,1364938695.521 [NAL9602](INFO): Stored copy of sent data in Logs/20130402T201549/shore0010.lzma.parts/0000.sbd
2013-04-02T21:38:25.461Z,1364938705.461 [NAL9602](INFO): SBD MO Status=0, MOMSN=9489, MT Status=0, MTMSN=0
2013-04-02T21:38:29.201Z,1364938709.201 [Default:Iridium:Read_Iridium] Stopped
2013-04-02T21:38:29.201Z,1364938709.201 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-02T21:38:29.201Z,1364938709.201 [Default:Iridium] Stopped
2013-04-02T21:38:29.201Z,1364938709.201 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-02T21:38:29.201Z,1364938709.201 [Default:Iridium:A.SetSpeed] Stopped
2013-04-02T21:38:29.201Z,1364938709.201 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T21:38:29.540Z,1364938709.540 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-02T21:38:29.541Z,1364938709.541 [Default:CallIridium:B] Stopped
2013-04-02T21:38:29.541Z,1364938709.541 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-02T21:38:29.541Z,1364938709.541 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-02T21:38:29.541Z,1364938709.541 [Default:CallIridium] Stopped
2013-04-02T21:38:29.541Z,1364938709.541 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-02T21:38:30.201Z,1364938710.201 [NAL9602](IMPORTANT): GPS fix at: 1364938716.00
2013-04-02T21:38:30.219Z,1364938710.219 [Default:GPS:Read_GPS] Stopped
2013-04-02T21:38:30.219Z,1364938710.219 [Default:GPS:D] Running Loop=1
2013-04-02T21:38:30.679Z,1364938710.679 [Default:GPS:D] Stopped
2013-04-02T21:38:30.679Z,1364938710.680 [Default:GPS](INFO): Completed Default:GPS
2013-04-02T21:38:30.680Z,1364938710.680 [Default:GPS] Stopped
2013-04-02T21:38:30.680Z,1364938710.680 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-02T21:38:30.680Z,1364938710.680 [Default:GPS:A.SetSpeed] Stopped
2013-04-02T21:38:30.680Z,1364938710.680 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T21:38:51.158Z,1364938731.158 [NAL9602](INFO): Powering down
2013-04-02T21:43:26.172Z,1364939006.172 [Default:CallIridium] Running Loop=1
2013-04-02T21:43:26.173Z,1364939006.173 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-02T21:43:26.173Z,1364939006.173 [Default:CallIridium:A] Running Loop=1
2013-04-02T21:43:26.173Z,1364939006.173 [Default:CallIridium:A] Stopped
2013-04-02T21:43:26.173Z,1364939006.173 [Default:CallIridium:B] Running Loop=1
2013-04-02T21:43:26.174Z,1364939006.174 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-02T21:43:31.217Z,1364939011.217 [Default:Iridium] Running Loop=1
2013-04-02T21:43:31.217Z,1364939011.217 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-02T21:43:31.217Z,1364939011.217 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-02T21:43:31.217Z,1364939011.217 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-02T21:43:31.218Z,1364939011.217 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-02T21:43:31.218Z,1364939011.218 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T21:43:31.219Z,1364939011.219 [Default:Iridium:B.GoToSurface] Stopped
2013-04-02T21:43:31.219Z,1364939011.219 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T21:43:31.219Z,1364939011.219 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-02T21:43:31.219Z,1364939011.219 [Default:GPS] Running Loop=1
2013-04-02T21:43:31.220Z,1364939011.220 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-02T21:43:31.220Z,1364939011.220 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-02T21:43:31.220Z,1364939011.220 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-02T21:43:31.220Z,1364939011.220 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-02T21:43:31.220Z,1364939011.220 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T21:43:31.221Z,1364939011.221 [Default:GPS:B.GoToSurface] Stopped
2013-04-02T21:43:31.221Z,1364939011.221 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T21:43:31.221Z,1364939011.221 [Default:GPS:Read_GPS] Running Loop=1
2013-04-02T21:43:31.816Z,1364939011.816 [NAL9602](INFO): Powering up
2013-04-02T21:43:42.647Z,1364939022.647 [NAL9602](INFO): NAL9602 initialized
2013-04-02T21:44:05.200Z,1364939045.200 [NAL9602](INFO): SBD MO Status=2, MOMSN=9490, MT Status=2, MTMSN=0
2013-04-02T21:44:05.200Z,1364939045.200 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T21:44:34.477Z,1364939074.477 [NAL9602](INFO): SBD MO Status=2, MOMSN=9490, MT Status=2, MTMSN=0
2013-04-02T21:44:34.478Z,1364939074.478 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T21:44:35.675Z,1364939075.675 [NAL9602](IMPORTANT): GPS fix at: 1364939082.00
2013-04-02T21:44:35.692Z,1364939075.692 [Default:GPS:Read_GPS] Stopped
2013-04-02T21:44:35.692Z,1364939075.692 [Default:GPS:D] Running Loop=1
2013-04-02T21:44:36.111Z,1364939076.111 [Default:GPS:D] Stopped
2013-04-02T21:44:36.111Z,1364939076.111 [Default:GPS](INFO): Completed Default:GPS
2013-04-02T21:44:36.111Z,1364939076.111 [Default:GPS] Stopped
2013-04-02T21:44:36.112Z,1364939076.112 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-02T21:44:36.112Z,1364939076.112 [Default:GPS:A.SetSpeed] Stopped
2013-04-02T21:44:36.112Z,1364939076.112 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T21:44:51.040Z,1364939091.040 [NAL9602](INFO): SBD MO Status=2, MOMSN=9490, MT Status=2, MTMSN=0
2013-04-02T21:44:51.040Z,1364939091.040 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T21:45:25.657Z,1364939125.657 [NAL9602](INFO): SBD MO Status=2, MOMSN=9490, MT Status=2, MTMSN=0
2013-04-02T21:45:25.658Z,1364939125.658 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T21:45:44.228Z,1364939144.228 [NAL9602](INFO): SBD MO Status=1, MOMSN=9490, MT Status=0, MTMSN=0
2013-04-02T21:45:44.279Z,1364939144.279 [NAL9602](INFO): Sent 138 bytes from file Logs/20130402T201549/shore0011.lzma
2013-04-02T21:45:44.279Z,1364939144.279 [NAL9602](INFO): Packets left to send: 0
2013-04-02T21:45:44.281Z,1364939144.281 [NAL9602](INFO): Stored copy of sent data in Logs/20130402T201549/shore0011.lzma.parts/0000.sbd
2013-04-02T21:45:56.234Z,1364939156.234 [NAL9602](INFO): SBD MO Status=0, MOMSN=9491, MT Status=0, MTMSN=0
2013-04-02T21:46:00.000Z,1364939160.000 [Default:Iridium:Read_Iridium] Stopped
2013-04-02T21:46:00.001Z,1364939160.001 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-02T21:46:00.001Z,1364939160.001 [Default:Iridium] Stopped
2013-04-02T21:46:00.001Z,1364939160.001 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-02T21:46:00.001Z,1364939160.001 [Default:Iridium:A.SetSpeed] Stopped
2013-04-02T21:46:00.001Z,1364939160.001 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T21:46:00.374Z,1364939160.374 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-02T21:46:00.374Z,1364939160.374 [Default:CallIridium:B] Stopped
2013-04-02T21:46:00.374Z,1364939160.374 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-02T21:46:00.375Z,1364939160.375 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-02T21:46:00.375Z,1364939160.375 [Default:CallIridium] Stopped
2013-04-02T21:46:00.375Z,1364939160.375 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-02T21:46:10.364Z,1364939170.364 [NAL9602](INFO): Powering down
2013-04-02T21:51:00.379Z,1364939460.379 [Default:CallIridium] Running Loop=1
2013-04-02T21:51:00.379Z,1364939460.379 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-02T21:51:00.380Z,1364939460.380 [Default:CallIridium:A] Running Loop=1
2013-04-02T21:51:00.380Z,1364939460.380 [Default:CallIridium:A] Stopped
2013-04-02T21:51:00.380Z,1364939460.380 [Default:CallIridium:B] Running Loop=1
2013-04-02T21:51:00.380Z,1364939460.380 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-02T21:51:05.418Z,1364939465.418 [Default:Iridium] Running Loop=1
2013-04-02T21:51:05.418Z,1364939465.418 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-02T21:51:05.418Z,1364939465.418 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-02T21:51:05.418Z,1364939465.418 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-02T21:51:05.418Z,1364939465.418 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-02T21:51:05.418Z,1364939465.418 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T21:51:05.419Z,1364939465.419 [Default:Iridium:B.GoToSurface] Stopped
2013-04-02T21:51:05.419Z,1364939465.419 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T21:51:05.419Z,1364939465.420 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-02T21:51:05.420Z,1364939465.420 [Default:GPS] Running Loop=1
2013-04-02T21:51:05.420Z,1364939465.420 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-02T21:51:05.420Z,1364939465.420 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-02T21:51:05.420Z,1364939465.420 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-02T21:51:05.420Z,1364939465.420 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-02T21:51:05.420Z,1364939465.420 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T21:51:05.421Z,1364939465.421 [Default:GPS:B.GoToSurface] Stopped
2013-04-02T21:51:05.421Z,1364939465.421 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T21:51:05.421Z,1364939465.421 [Default:GPS:Read_GPS] Running Loop=1
2013-04-02T21:51:06.019Z,1364939466.019 [NAL9602](INFO): Powering up
2013-04-02T21:51:16.847Z,1364939476.847 [NAL9602](INFO): NAL9602 initialized
2013-04-02T21:51:40.993Z,1364939500.993 [NAL9602](INFO): SBD MO Status=1, MOMSN=9492, MT Status=0, MTMSN=0
2013-04-02T21:51:41.043Z,1364939501.043 [NAL9602](INFO): Sent 137 bytes from file Logs/20130402T201549/shore0012.lzma
2013-04-02T21:51:41.043Z,1364939501.043 [NAL9602](INFO): Packets left to send: 0
2013-04-02T21:51:41.045Z,1364939501.045 [NAL9602](INFO): Stored copy of sent data in Logs/20130402T201549/shore0012.lzma.parts/0000.sbd
2013-04-02T21:51:49.788Z,1364939509.788 [NAL9602](INFO): SBD MO Status=0, MOMSN=9493, MT Status=0, MTMSN=0
2013-04-02T21:51:53.414Z,1364939513.414 [Default:Iridium:Read_Iridium] Stopped
2013-04-02T21:51:53.414Z,1364939513.414 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-02T21:51:53.415Z,1364939513.415 [Default:Iridium] Stopped
2013-04-02T21:51:53.415Z,1364939513.415 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-02T21:51:53.415Z,1364939513.415 [Default:Iridium:A.SetSpeed] Stopped
2013-04-02T21:51:53.415Z,1364939513.415 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T21:51:53.824Z,1364939513.824 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-02T21:51:53.824Z,1364939513.824 [Default:CallIridium:B] Stopped
2013-04-02T21:51:53.825Z,1364939513.825 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-02T21:51:53.825Z,1364939513.825 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-02T21:51:53.825Z,1364939513.825 [Default:CallIridium] Stopped
2013-04-02T21:51:53.825Z,1364939513.825 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-02T21:51:54.435Z,1364939514.435 [NAL9602](IMPORTANT): GPS fix at: 1364939522.00
2013-04-02T21:51:54.451Z,1364939514.451 [Default:GPS:Read_GPS] Stopped
2013-04-02T21:51:54.451Z,1364939514.451 [Default:GPS:D] Running Loop=1
2013-04-02T21:51:54.847Z,1364939514.847 [Default:GPS:D] Stopped
2013-04-02T21:51:54.848Z,1364939514.848 [Default:GPS](INFO): Completed Default:GPS
2013-04-02T21:51:54.848Z,1364939514.848 [Default:GPS] Stopped
2013-04-02T21:51:54.848Z,1364939514.848 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-02T21:51:54.848Z,1364939514.848 [Default:GPS:A.SetSpeed] Stopped
2013-04-02T21:51:54.848Z,1364939514.848 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T21:52:15.389Z,1364939535.389 [NAL9602](INFO): Powering down
2013-04-02T21:56:50.418Z,1364939810.418 [Default:CallIridium] Running Loop=1
2013-04-02T21:56:50.419Z,1364939810.419 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-02T21:56:50.419Z,1364939810.419 [Default:CallIridium:A] Running Loop=1
2013-04-02T21:56:50.419Z,1364939810.419 [Default:CallIridium:A] Stopped
2013-04-02T21:56:50.419Z,1364939810.419 [Default:CallIridium:B] Running Loop=1
2013-04-02T21:56:50.419Z,1364939810.419 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-02T21:56:55.424Z,1364939815.424 [Default:Iridium] Running Loop=1
2013-04-02T21:56:55.424Z,1364939815.424 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-02T21:56:55.424Z,1364939815.424 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-02T21:56:55.424Z,1364939815.424 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-02T21:56:55.424Z,1364939815.424 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-02T21:56:55.424Z,1364939815.424 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T21:56:55.425Z,1364939815.425 [Default:Iridium:B.GoToSurface] Stopped
2013-04-02T21:56:55.425Z,1364939815.425 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T21:56:55.425Z,1364939815.425 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-02T21:56:55.425Z,1364939815.425 [Default:GPS] Running Loop=1
2013-04-02T21:56:55.426Z,1364939815.426 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-02T21:56:55.426Z,1364939815.426 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-02T21:56:55.426Z,1364939815.426 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-02T21:56:55.426Z,1364939815.426 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-02T21:56:55.426Z,1364939815.426 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T21:56:55.427Z,1364939815.427 [Default:GPS:B.GoToSurface] Stopped
2013-04-02T21:56:55.427Z,1364939815.427 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T21:56:55.427Z,1364939815.427 [Default:GPS:Read_GPS] Running Loop=1
2013-04-02T21:56:56.014Z,1364939816.014 [NAL9602](INFO): Powering up
2013-04-02T21:57:06.451Z,1364939826.451 [NAL9602](INFO): NAL9602 initialized
2013-04-02T21:57:29.054Z,1364939849.054 [NAL9602](INFO): SBD MO Status=1, MOMSN=9494, MT Status=0, MTMSN=0
2013-04-02T21:57:29.108Z,1364939849.108 [NAL9602](INFO): Sent 137 bytes from file Logs/20130402T201549/shore0013.lzma
2013-04-02T21:57:29.108Z,1364939849.108 [NAL9602](INFO): Packets left to send: 0
2013-04-02T21:57:29.116Z,1364939849.116 [NAL9602](INFO): Stored copy of sent data in Logs/20130402T201549/shore0013.lzma.parts/0000.sbd
2013-04-02T21:57:37.804Z,1364939857.804 [NAL9602](INFO): SBD MO Status=0, MOMSN=9495, MT Status=0, MTMSN=0
2013-04-02T21:57:41.390Z,1364939861.390 [Default:Iridium:Read_Iridium] Stopped
2013-04-02T21:57:41.390Z,1364939861.390 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-02T21:57:41.390Z,1364939861.390 [Default:Iridium] Stopped
2013-04-02T21:57:41.391Z,1364939861.391 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-02T21:57:41.391Z,1364939861.391 [Default:Iridium:A.SetSpeed] Stopped
2013-04-02T21:57:41.391Z,1364939861.391 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T21:57:41.817Z,1364939861.817 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-02T21:57:41.817Z,1364939861.817 [Default:CallIridium:B] Stopped
2013-04-02T21:57:41.817Z,1364939861.817 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-02T21:57:41.817Z,1364939861.817 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-02T21:57:41.817Z,1364939861.817 [Default:CallIridium] Stopped
2013-04-02T21:57:41.817Z,1364939861.817 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-02T21:57:42.419Z,1364939862.419 [NAL9602](IMPORTANT): GPS fix at: 1364939870.00
2013-04-02T21:57:42.436Z,1364939862.436 [Default:GPS:Read_GPS] Stopped
2013-04-02T21:57:42.436Z,1364939862.436 [Default:GPS:D] Running Loop=1
2013-04-02T21:57:42.839Z,1364939862.839 [Default:GPS:D] Stopped
2013-04-02T21:57:42.839Z,1364939862.839 [Default:GPS](INFO): Completed Default:GPS
2013-04-02T21:57:42.839Z,1364939862.839 [Default:GPS] Stopped
2013-04-02T21:57:42.840Z,1364939862.839 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-02T21:57:42.840Z,1364939862.840 [Default:GPS:A.SetSpeed] Stopped
2013-04-02T21:57:42.840Z,1364939862.840 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T21:58:03.389Z,1364939883.389 [NAL9602](INFO): Powering down
2013-04-02T22:02:38.379Z,1364940158.379 [Default:CallIridium] Running Loop=1
2013-04-02T22:02:38.380Z,1364940158.379 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-02T22:02:38.380Z,1364940158.380 [Default:CallIridium:A] Running Loop=1
2013-04-02T22:02:38.380Z,1364940158.380 [Default:CallIridium:A] Stopped
2013-04-02T22:02:38.380Z,1364940158.380 [Default:CallIridium:B] Running Loop=1
2013-04-02T22:02:38.380Z,1364940158.380 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-02T22:02:43.416Z,1364940163.416 [Default:Iridium] Running Loop=1
2013-04-02T22:02:43.417Z,1364940163.417 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-02T22:02:43.417Z,1364940163.417 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-02T22:02:43.417Z,1364940163.417 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-02T22:02:43.417Z,1364940163.417 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-02T22:02:43.417Z,1364940163.417 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T22:02:43.418Z,1364940163.418 [Default:Iridium:B.GoToSurface] Stopped
2013-04-02T22:02:43.418Z,1364940163.418 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T22:02:43.418Z,1364940163.418 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-02T22:02:43.419Z,1364940163.419 [Default:GPS] Running Loop=1
2013-04-02T22:02:43.419Z,1364940163.419 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-02T22:02:43.419Z,1364940163.419 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-02T22:02:43.419Z,1364940163.419 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-02T22:02:43.419Z,1364940163.419 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-02T22:02:43.419Z,1364940163.419 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T22:02:43.420Z,1364940163.420 [Default:GPS:B.GoToSurface] Stopped
2013-04-02T22:02:43.420Z,1364940163.420 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T22:02:43.420Z,1364940163.420 [Default:GPS:Read_GPS] Running Loop=1
2013-04-02T22:02:44.056Z,1364940164.056 [NAL9602](INFO): Powering up
2013-04-02T22:02:54.847Z,1364940174.847 [NAL9602](INFO): NAL9602 initialized
2013-04-02T22:03:16.609Z,1364940196.609 [NAL9602](INFO): SBD MO Status=2, MOMSN=9496, MT Status=2, MTMSN=0
2013-04-02T22:03:16.609Z,1364940196.609 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T22:03:17.811Z,1364940197.811 [NAL9602](IMPORTANT): GPS fix at: 1364940206.00
2013-04-02T22:03:17.830Z,1364940197.830 [Default:GPS:Read_GPS] Stopped
2013-04-02T22:03:17.830Z,1364940197.830 [Default:GPS:D] Running Loop=1
2013-04-02T22:03:18.235Z,1364940198.235 [Default:GPS:D] Stopped
2013-04-02T22:03:18.236Z,1364940198.236 [Default:GPS](INFO): Completed Default:GPS
2013-04-02T22:03:18.236Z,1364940198.236 [Default:GPS] Stopped
2013-04-02T22:03:18.236Z,1364940198.236 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-02T22:03:18.236Z,1364940198.236 [Default:GPS:A.SetSpeed] Stopped
2013-04-02T22:03:18.236Z,1364940198.236 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T22:03:39.621Z,1364940219.621 [NAL9602](INFO): SBD MO Status=2, MOMSN=9496, MT Status=2, MTMSN=0
2013-04-02T22:03:39.621Z,1364940219.621 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T22:03:54.600Z,1364940234.599 [NAL9602](INFO): SBD MO Status=2, MOMSN=9496, MT Status=2, MTMSN=0
2013-04-02T22:03:54.600Z,1364940234.600 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T22:04:10.846Z,1364940250.846 [NAL9602](INFO): SBD MO Status=2, MOMSN=9496, MT Status=2, MTMSN=0
2013-04-02T22:04:10.846Z,1364940250.846 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-04-02T22:04:29.813Z,1364940269.813 [NAL9602](INFO): SBD MO Status=1, MOMSN=9496, MT Status=0, MTMSN=0
2013-04-02T22:04:29.863Z,1364940269.863 [NAL9602](INFO): Sent 140 bytes from file Logs/20130402T201549/shore0014.lzma
2013-04-02T22:04:29.863Z,1364940269.863 [NAL9602](INFO): Packets left to send: 0
2013-04-02T22:04:29.865Z,1364940269.865 [NAL9602](INFO): Stored copy of sent data in Logs/20130402T201549/shore0014.lzma.parts/0000.sbd
2013-04-02T22:04:40.613Z,1364940280.613 [NAL9602](INFO): SBD MO Status=0, MOMSN=9497, MT Status=0, MTMSN=0
2013-04-02T22:04:44.250Z,1364940284.250 [Default:Iridium:Read_Iridium] Stopped
2013-04-02T22:04:44.250Z,1364940284.250 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-02T22:04:44.251Z,1364940284.251 [Default:Iridium] Stopped
2013-04-02T22:04:44.251Z,1364940284.251 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-02T22:04:44.251Z,1364940284.251 [Default:Iridium:A.SetSpeed] Stopped
2013-04-02T22:04:44.251Z,1364940284.251 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T22:04:44.625Z,1364940284.625 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-02T22:04:44.625Z,1364940284.625 [Default:CallIridium:B] Stopped
2013-04-02T22:04:44.625Z,1364940284.625 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-02T22:04:44.626Z,1364940284.626 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-02T22:04:44.626Z,1364940284.626 [Default:CallIridium] Stopped
2013-04-02T22:04:44.626Z,1364940284.626 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-02T22:04:54.628Z,1364940294.628 [NAL9602](INFO): Powering down
2013-04-02T22:09:44.681Z,1364940584.681 [Default:CallIridium] Running Loop=1
2013-04-02T22:09:44.681Z,1364940584.681 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-02T22:09:44.681Z,1364940584.681 [Default:CallIridium:A] Running Loop=1
2013-04-02T22:09:44.682Z,1364940584.682 [Default:CallIridium:A] Stopped
2013-04-02T22:09:44.682Z,1364940584.682 [Default:CallIridium:B] Running Loop=1
2013-04-02T22:09:44.682Z,1364940584.682 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-02T22:09:49.624Z,1364940589.625 [Default:Iridium] Running Loop=1
2013-04-02T22:09:49.625Z,1364940589.625 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-02T22:09:49.625Z,1364940589.625 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-02T22:09:49.625Z,1364940589.625 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-02T22:09:49.625Z,1364940589.625 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-02T22:09:49.625Z,1364940589.625 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T22:09:49.626Z,1364940589.626 [Default:Iridium:B.GoToSurface] Stopped
2013-04-02T22:09:49.626Z,1364940589.626 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T22:09:49.626Z,1364940589.626 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-02T22:09:49.627Z,1364940589.626 [Default:GPS] Running Loop=1
2013-04-02T22:09:49.627Z,1364940589.627 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-02T22:09:49.627Z,1364940589.627 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-02T22:09:49.627Z,1364940589.627 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-02T22:09:49.627Z,1364940589.627 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-02T22:09:49.627Z,1364940589.627 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T22:09:49.628Z,1364940589.628 [Default:GPS:B.GoToSurface] Stopped
2013-04-02T22:09:49.628Z,1364940589.628 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T22:09:49.628Z,1364940589.628 [Default:GPS:Read_GPS] Running Loop=1
2013-04-02T22:09:50.244Z,1364940590.244 [NAL9602](INFO): Powering up
2013-04-02T22:10:01.087Z,1364940601.087 [NAL9602](INFO): NAL9602 initialized
2013-04-02T22:10:17.612Z,1364940617.612 [NAL9602](INFO): SBD MO Status=1, MOMSN=9498, MT Status=0, MTMSN=0
2013-04-02T22:10:17.663Z,1364940617.663 [NAL9602](INFO): Sent 161 bytes from file Logs/20130402T201549/shore0015.lzma
2013-04-02T22:10:17.663Z,1364940617.663 [NAL9602](INFO): Packets left to send: 0
2013-04-02T22:10:17.665Z,1364940617.665 [NAL9602](INFO): Stored copy of sent data in Logs/20130402T201549/shore0015.lzma.parts/0000.sbd
2013-04-02T22:10:25.221Z,1364940625.221 [NAL9602](INFO): SBD MO Status=0, MOMSN=9499, MT Status=0, MTMSN=0
2013-04-02T22:10:28.804Z,1364940628.804 [Default:Iridium:Read_Iridium] Stopped
2013-04-02T22:10:28.804Z,1364940628.805 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-02T22:10:28.805Z,1364940628.805 [Default:Iridium] Stopped
2013-04-02T22:10:28.805Z,1364940628.805 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-02T22:10:28.805Z,1364940628.805 [Default:Iridium:A.SetSpeed] Stopped
2013-04-02T22:10:28.805Z,1364940628.805 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T22:10:29.169Z,1364940629.169 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-02T22:10:29.169Z,1364940629.169 [Default:CallIridium:B] Stopped
2013-04-02T22:10:29.169Z,1364940629.169 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-02T22:10:29.169Z,1364940629.169 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-02T22:10:29.169Z,1364940629.169 [Default:CallIridium] Stopped
2013-04-02T22:10:29.169Z,1364940629.169 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-02T22:10:29.839Z,1364940629.839 [NAL9602](IMPORTANT): GPS fix at: 1364940639.00
2013-04-02T22:10:29.855Z,1364940629.855 [Default:GPS:Read_GPS] Stopped
2013-04-02T22:10:29.855Z,1364940629.855 [Default:GPS:D] Running Loop=1
2013-04-02T22:10:30.227Z,1364940630.227 [Default:GPS:D] Stopped
2013-04-02T22:10:30.228Z,1364940630.227 [Default:GPS](INFO): Completed Default:GPS
2013-04-02T22:10:30.228Z,1364940630.227 [Default:GPS] Stopped
2013-04-02T22:10:30.228Z,1364940630.228 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-02T22:10:30.228Z,1364940630.228 [Default:GPS:A.SetSpeed] Stopped
2013-04-02T22:10:30.228Z,1364940630.228 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T22:10:50.841Z,1364940650.841 [NAL9602](INFO): Powering down
2013-04-02T22:15:25.820Z,1364940925.820 [Default:CallIridium] Running Loop=1
2013-04-02T22:15:25.820Z,1364940925.820 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-02T22:15:25.820Z,1364940925.820 [Default:CallIridium:A] Running Loop=1
2013-04-02T22:15:25.820Z,1364940925.820 [Default:CallIridium:A] Stopped
2013-04-02T22:15:25.821Z,1364940925.820 [Default:CallIridium:B] Running Loop=1
2013-04-02T22:15:25.821Z,1364940925.821 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-02T22:15:30.777Z,1364940930.777 [Default:Iridium] Running Loop=1
2013-04-02T22:15:30.777Z,1364940930.777 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-02T22:15:30.778Z,1364940930.778 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-02T22:15:30.778Z,1364940930.778 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-02T22:15:30.778Z,1364940930.778 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-02T22:15:30.778Z,1364940930.778 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T22:15:30.779Z,1364940930.779 [Default:Iridium:B.GoToSurface] Stopped
2013-04-02T22:15:30.779Z,1364940930.779 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T22:15:30.779Z,1364940930.779 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-02T22:15:30.780Z,1364940930.780 [Default:GPS] Running Loop=1
2013-04-02T22:15:30.780Z,1364940930.780 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-02T22:15:30.780Z,1364940930.780 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-02T22:15:30.780Z,1364940930.780 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-02T22:15:30.780Z,1364940930.780 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-02T22:15:30.780Z,1364940930.780 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T22:15:30.781Z,1364940930.781 [Default:GPS:B.GoToSurface] Stopped
2013-04-02T22:15:30.781Z,1364940930.781 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T22:15:30.781Z,1364940930.781 [Default:GPS:Read_GPS] Running Loop=1
2013-04-02T22:15:31.401Z,1364940931.401 [NAL9602](INFO): Powering up
2013-04-02T22:15:41.875Z,1364940941.875 [NAL9602](INFO): NAL9602 initialized
2013-04-02T22:15:58.040Z,1364940958.040 [NAL9602](INFO): SBD MO Status=1, MOMSN=9500, MT Status=0, MTMSN=0
2013-04-02T22:15:58.091Z,1364940958.091 [NAL9602](INFO): Sent 162 bytes from file Logs/20130402T201549/shore0016.lzma
2013-04-02T22:15:58.091Z,1364940958.091 [NAL9602](INFO): Packets left to send: 0
2013-04-02T22:15:58.093Z,1364940958.093 [NAL9602](INFO): Stored copy of sent data in Logs/20130402T201549/shore0016.lzma.parts/0000.sbd
2013-04-02T22:16:08.799Z,1364940968.799 [NAL9602](INFO): SBD MO Status=0, MOMSN=9501, MT Status=0, MTMSN=0
2013-04-02T22:16:12.420Z,1364940972.420 [Default:Iridium:Read_Iridium] Stopped
2013-04-02T22:16:12.420Z,1364940972.420 [Default:Iridium](INFO): Completed Default:Iridium
2013-04-02T22:16:12.420Z,1364940972.420 [Default:Iridium] Stopped
2013-04-02T22:16:12.421Z,1364940972.421 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-02T22:16:12.421Z,1364940972.421 [Default:Iridium:A.SetSpeed] Stopped
2013-04-02T22:16:12.421Z,1364940972.421 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T22:16:12.765Z,1364940972.765 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-04-02T22:16:12.766Z,1364940972.766 [Default:CallIridium:B] Stopped
2013-04-02T22:16:12.766Z,1364940972.766 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-02T22:16:12.766Z,1364940972.766 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-04-02T22:16:12.766Z,1364940972.766 [Default:CallIridium] Stopped
2013-04-02T22:16:12.766Z,1364940972.766 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-02T22:16:13.419Z,1364940973.419 [NAL9602](IMPORTANT): GPS fix at: 1364940983.00
2013-04-02T22:16:13.436Z,1364940973.436 [Default:GPS:Read_GPS] Stopped
2013-04-02T22:16:13.436Z,1364940973.436 [Default:GPS:D] Running Loop=1
2013-04-02T22:16:13.853Z,1364940973.853 [Default:GPS:D] Stopped
2013-04-02T22:16:13.853Z,1364940973.853 [Default:GPS](INFO): Completed Default:GPS
2013-04-02T22:16:13.853Z,1364940973.853 [Default:GPS] Stopped
2013-04-02T22:16:13.853Z,1364940973.853 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-02T22:16:13.854Z,1364940973.853 [Default:GPS:A.SetSpeed] Stopped
2013-04-02T22:16:13.854Z,1364940973.854 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T22:16:34.425Z,1364940994.425 [NAL9602](INFO): Powering down
2013-04-02T22:18:29.406Z,1364941109.406 [Radio_Freewave](INFO): Powering down
2013-04-02T22:18:39.410Z,1364941119.410 [Radio_Freewave](INFO): Powering up
2013-04-02T22:21:09.393Z,1364941269.393 [Default:CallIridium] Running Loop=1
2013-04-02T22:21:09.393Z,1364941269.393 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-04-02T22:21:09.393Z,1364941269.393 [Default:CallIridium:A] Running Loop=1
2013-04-02T22:21:09.394Z,1364941269.394 [Default:CallIridium:A] Stopped
2013-04-02T22:21:09.394Z,1364941269.394 [Default:CallIridium:B] Running Loop=1
2013-04-02T22:21:09.394Z,1364941269.394 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-04-02T22:21:14.424Z,1364941274.424 [Default:Iridium] Running Loop=1
2013-04-02T22:21:14.425Z,1364941274.425 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-04-02T22:21:14.425Z,1364941274.425 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-04-02T22:21:14.425Z,1364941274.425 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-04-02T22:21:14.425Z,1364941274.425 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-04-02T22:21:14.425Z,1364941274.425 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T22:21:14.426Z,1364941274.426 [Default:Iridium:B.GoToSurface] Stopped
2013-04-02T22:21:14.426Z,1364941274.426 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T22:21:14.426Z,1364941274.426 [Default:Iridium:Read_Iridium] Running Loop=1
2013-04-02T22:21:14.426Z,1364941274.427 [Default:GPS] Running Loop=1
2013-04-02T22:21:14.427Z,1364941274.427 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-04-02T22:21:14.427Z,1364941274.427 [Default:GPS:A.SetSpeed] Running Loop=1
2013-04-02T22:21:14.427Z,1364941274.427 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-04-02T22:21:14.427Z,1364941274.427 [Default:GPS:B.GoToSurface] Running Loop=1
2013-04-02T22:21:14.427Z,1364941274.427 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-04-02T22:21:14.428Z,1364941274.428 [Default:GPS:B.GoToSurface] Stopped
2013-04-02T22:21:14.428Z,1364941274.428 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T22:21:14.428Z,1364941274.428 [Default:GPS:Read_GPS] Running Loop=1
2013-04-02T22:21:15.020Z,1364941275.020 [NAL9602](INFO): Powering up
2013-04-02T22:21:25.459Z,1364941285.459 [NAL9602](INFO): NAL9602 initialized
2013-04-02T22:21:48.466Z,1364941308.466 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=9502, MT Status=1, MTMSN=688
2013-04-02T22:21:48.519Z,1364941308.519 [NAL9602](INFO): Sent 160 bytes from file Logs/20130402T201549/shore0017.lzma
2013-04-02T22:21:48.519Z,1364941308.519 [NAL9602](INFO): Packets left to send: 0
2013-04-02T22:21:48.521Z,1364941308.521 [NAL9602](INFO): Stored copy of sent data in Logs/20130402T201549/shore0017.lzma.parts/0000.sbd
2013-04-02T22:21:48.899Z,1364941308.899 [NAL9602](INFO): Received command:restart app
2013-04-02T22:21:48.927Z,1364941308.927 [CommandLine](IMPORTANT): got command restart application
2013-04-02T22:21:49.971Z,1364941309.971 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2013-04-02T22:21:49.971Z,1364941309.971 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2013-04-02T22:21:50.350Z,1364941310.351 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2013-04-02T22:21:50.375Z,1364941310.375 [WetLabsBB2FL](INFO): Powering down
2013-04-02T22:21:50.390Z,1364941310.390 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2013-04-02T22:21:50.663Z,1364941310.663 [CTD_NeilBrown](INFO): Powering down
2013-04-02T22:21:50.670Z,1364941310.671 [ComponentRegistry](INFO): Shutting down DVL_micro ThreadHandler
2013-04-02T22:21:50.915Z,1364941310.915 [DVL_micro](INFO): uninitialize:Powering down
2013-04-02T22:21:50.930Z,1364941310.930 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler
2013-04-02T22:21:51.035Z,1364941311.035 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator.
2013-04-02T22:21:51.056Z,1364941311.056 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2013-04-02T22:21:51.115Z,1364941311.115 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2013-04-02T22:21:51.116Z,1364941311.116 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2013-04-02T22:21:51.246Z,1364941311.246 [controlThread](DEBUG): Uninitializing ControlThread
2013-04-02T22:21:51.247Z,1364941311.247 [AHRS_sp3003D](INFO): Powering down
2013-04-02T22:21:51.336Z,1364941311.336 [NAL9602](INFO): Powering down
2013-04-02T22:21:51.338Z,1364941311.338 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2013-04-02T22:21:51.338Z,1364941311.338 [NavChart](DEBUG): Uninitialize NavChart Derivation.
2013-04-02T22:21:51.339Z,1364941311.339 [Default] Stopped
2013-04-02T22:21:51.340Z,1364941311.339 [Default](INFO): Aggregate::uninitialize Default
2013-04-02T22:21:51.340Z,1364941311.340 [Default:GPS] Stopped
2013-04-02T22:21:51.340Z,1364941311.340 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-04-02T22:21:51.340Z,1364941311.340 [Default:GPS:A.SetSpeed] Stopped
2013-04-02T22:21:51.340Z,1364941311.340 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T22:21:51.340Z,1364941311.340 [Default:GPS:Read_GPS] Stopped
2013-04-02T22:21:51.340Z,1364941311.340 [Default:Iridium] Stopped
2013-04-02T22:21:51.340Z,1364941311.340 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-04-02T22:21:51.340Z,1364941311.340 [Default:Iridium:A.SetSpeed] Stopped
2013-04-02T22:21:51.340Z,1364941311.340 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-04-02T22:21:51.340Z,1364941311.340 [Default:Iridium:Read_Iridium] Stopped
2013-04-02T22:21:51.340Z,1364941311.340 [Default:CallIridium] Stopped
2013-04-02T22:21:51.340Z,1364941311.341 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-04-02T22:21:51.341Z,1364941311.341 [Default:CallIridium:B] Stopped
2013-04-02T22:21:51.341Z,1364941311.341 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-04-02T22:21:51.341Z,1364941311.341 [Default:D.SetSpeed] Stopped
2013-04-02T22:21:51.341Z,1364941311.341 [Default:D.SetSpeed](DEBUG): Uninitialize.
2013-04-02T22:21:51.341Z,1364941311.341 [Default:E.GoToSurface] Stopped
2013-04-02T22:21:51.341Z,1364941311.341 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-04-02T22:21:51.341Z,1364941311.341 [Default:F.Wait] Stopped
2013-04-02T22:21:51.341Z,1364941311.341 [Default:F.Wait](DEBUG): Uninitialize Wait Component.
2013-04-02T22:21:51.345Z,1364941311.345 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2013-04-02T22:21:51.346Z,1364941311.346 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2013-04-02T22:21:51.346Z,1364941311.346 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2013-04-02T22:21:51.347Z,1364941311.346 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2013-04-02T22:21:51.347Z,1364941311.347 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-04-02T22:21:51.347Z,1364941311.347 [BuoyancyServo](INFO): Powering down
2013-04-02T22:21:51.359Z,1364941311.359 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2013-04-02T22:21:51.359Z,1364941311.359 [ElevatorServo](INFO): Powering down
2013-04-02T22:21:51.360Z,1364941311.360 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-04-02T22:21:51.360Z,1364941311.360 [MassServo](INFO): Powering down
2013-04-02T22:21:51.361Z,1364941311.361 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2013-04-02T22:21:51.361Z,1364941311.361 [RudderServo](INFO): Powering down
2013-04-02T22:21:51.361Z,1364941311.361 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-04-02T22:21:51.362Z,1364941311.362 [ThrusterServo](INFO): Powering down
2013-04-02T22:21:51.363Z,1364941311.362 [SBIT](DEBUG): Uninitialize SBIT Component.
2013-04-02T22:21:51.363Z,1364941311.363 [IBIT](DEBUG): Uninitialize IBIT Component.
2013-04-02T22:21:51.363Z,1364941311.363 [CBIT](DEBUG): Uninitialize CBIT Component.