2013-05-15T00:02:38.706Z,1368576158.706 [Supervisor](DEBUG): Initializing supervisor.
2013-05-15T00:02:38.710Z,1368576158.710 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2013-05-15T00:02:38.710Z,1368576158.710 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2013-05-15T00:02:38.714Z,1368576158.714 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0
2013-05-15T00:02:38.717Z,1368576158.717 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2013-05-15T00:02:38.728Z,1368576158.728 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2013-05-15T00:02:38.730Z,1368576158.730 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0
2013-05-15T00:02:38.731Z,1368576158.731 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2013-05-15T00:02:38.734Z,1368576158.734 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403CB4E0
2013-05-15T00:02:38.734Z,1368576158.734 [Supervisor](INFO): Looking for Config files in directory: Config/
2013-05-15T00:02:38.739Z,1368576158.739 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2013-05-15T00:02:39.083Z,1368576159.083 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2013-05-15T00:02:39.083Z,1368576159.083 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2013-05-15T00:02:39.305Z,1368576159.305 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2013-05-15T00:02:39.306Z,1368576159.306 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2013-05-15T00:02:39.401Z,1368576159.401 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2013-05-15T00:02:39.403Z,1368576159.403 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2013-05-15T00:02:39.567Z,1368576159.567 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2013-05-15T00:02:39.567Z,1368576159.567 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2013-05-15T00:02:39.722Z,1368576159.722 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2013-05-15T00:02:39.725Z,1368576159.724 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2013-05-15T00:02:40.003Z,1368576160.003 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2013-05-15T00:02:40.004Z,1368576160.003 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2013-05-15T00:02:40.198Z,1368576160.198 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2013-05-15T00:02:40.201Z,1368576160.201 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2013-05-15T00:02:40.501Z,1368576160.501 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2013-05-15T00:02:40.504Z,1368576160.504 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2013-05-15T00:02:40.614Z,1368576160.615 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2013-05-15T00:02:40.615Z,1368576160.615 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2013-05-15T00:02:41.087Z,1368576161.087 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2013-05-15T00:02:41.088Z,1368576161.088 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2013-05-15T00:02:41.221Z,1368576161.220 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2013-05-15T00:02:41.221Z,1368576161.221 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2013-05-15T00:02:41.319Z,1368576161.319 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2013-05-15T00:02:41.324Z,1368576161.324 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2013-05-15T00:02:41.459Z,1368576161.459 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2013-05-15T00:02:41.633Z,1368576161.633 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2013-05-15T00:02:41.730Z,1368576161.730 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2013-05-15T00:02:41.847Z,1368576161.847 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2013-05-15T00:02:41.962Z,1368576161.962 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2013-05-15T00:02:42.099Z,1368576162.099 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2013-05-15T00:02:42.203Z,1368576162.203 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2013-05-15T00:02:42.301Z,1368576162.301 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2013-05-15T00:02:42.308Z,1368576162.308 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2013-05-15T00:02:42.511Z,1368576162.511 [InternalSim] Loaded
2013-05-15T00:02:42.512Z,1368576162.512 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2013-05-15T00:02:42.512Z,1368576162.512 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2013-05-15T00:02:42.513Z,1368576162.513 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2013-05-15T00:02:42.600Z,1368576162.600 [SBIT](DEBUG): Construct Startup Built In Test.
2013-05-15T00:02:42.633Z,1368576162.633 [SBIT] Loaded
2013-05-15T00:02:42.634Z,1368576162.634 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2013-05-15T00:02:42.635Z,1368576162.635 [IBIT](DEBUG): Construct Initiated Built In Test.
2013-05-15T00:02:42.667Z,1368576162.667 [IBIT] Loaded
2013-05-15T00:02:42.668Z,1368576162.668 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2013-05-15T00:02:42.671Z,1368576162.671 [CBIT](DEBUG): Construct CBIT Built In Test.
2013-05-15T00:02:42.803Z,1368576162.803 [CBIT] Loaded
2013-05-15T00:02:42.803Z,1368576162.803 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2013-05-15T00:02:42.804Z,1368576162.804 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2013-05-15T00:02:42.805Z,1368576162.805 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2013-05-15T00:02:42.983Z,1368576162.983 [BuoyancyServo] Loaded
2013-05-15T00:02:42.984Z,1368576162.984 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2013-05-15T00:02:43.000Z,1368576163.000 [ElevatorServo] Loaded
2013-05-15T00:02:43.000Z,1368576163.000 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2013-05-15T00:02:43.012Z,1368576163.012 [MassServo] Loaded
2013-05-15T00:02:43.013Z,1368576163.013 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2013-05-15T00:02:43.025Z,1368576163.025 [RudderServo] Loaded
2013-05-15T00:02:43.025Z,1368576163.025 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2013-05-15T00:02:43.037Z,1368576163.037 [ThrusterServo] Loaded
2013-05-15T00:02:43.037Z,1368576163.037 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2013-05-15T00:02:43.042Z,1368576163.042 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2013-05-15T00:02:43.042Z,1368576163.042 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2013-05-15T00:02:43.079Z,1368576163.079 [DepthRateCalculator] Loaded
2013-05-15T00:02:43.079Z,1368576163.079 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2013-05-15T00:02:46.467Z,1368576166.467 [HFRadarModelCalc] Loaded
2013-05-15T00:02:46.468Z,1368576166.468 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2013-05-15T00:02:46.495Z,1368576166.494 [NavChart] Loaded
2013-05-15T00:02:46.495Z,1368576166.495 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2013-05-15T00:02:46.501Z,1368576166.501 [PitchRateCalculator] Loaded
2013-05-15T00:02:46.501Z,1368576166.501 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2013-05-15T00:02:46.512Z,1368576166.512 [SpeedCalculator] Loaded
2013-05-15T00:02:46.512Z,1368576166.512 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2013-05-15T00:02:46.528Z,1368576166.528 [TempGradientCalculator] Loaded
2013-05-15T00:02:46.529Z,1368576166.529 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2013-05-15T00:02:46.539Z,1368576166.539 [YawRateCalculator] Loaded
2013-05-15T00:02:46.539Z,1368576166.539 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2013-05-15T00:02:46.594Z,1368576166.594 [Navigation] Loaded
2013-05-15T00:02:46.594Z,1368576166.594 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread.
2013-05-15T00:02:46.595Z,1368576166.595 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2013-05-15T00:02:46.595Z,1368576166.595 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2013-05-15T00:02:46.880Z,1368576166.880 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2013-05-15T00:02:46.881Z,1368576166.881 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2013-05-15T00:02:46.923Z,1368576166.923 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2013-05-15T00:02:46.924Z,1368576166.924 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2013-05-15T00:02:46.993Z,1368576166.993 [VerticalControl](DEBUG): Construct VerticalControl.
2013-05-15T00:02:47.095Z,1368576167.095 [VerticalControl] Loaded
2013-05-15T00:02:47.095Z,1368576167.095 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2013-05-15T00:02:47.096Z,1368576167.096 [HorizontalControl](DEBUG): Construct HorizontalControl.
2013-05-15T00:02:47.155Z,1368576167.155 [HorizontalControl] Loaded
2013-05-15T00:02:47.156Z,1368576167.156 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2013-05-15T00:02:47.157Z,1368576167.157 [SpeedControl](DEBUG): Construct SpeedControl.
2013-05-15T00:02:47.163Z,1368576167.163 [SpeedControl] Loaded
2013-05-15T00:02:47.163Z,1368576167.163 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2013-05-15T00:02:47.164Z,1368576167.164 [LoopControl](DEBUG): Construct LoopControl.
2013-05-15T00:02:47.164Z,1368576167.164 [LoopControl] Loaded
2013-05-15T00:02:47.165Z,1368576167.165 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2013-05-15T00:02:47.165Z,1368576167.165 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2013-05-15T00:02:47.166Z,1368576167.166 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2013-05-15T00:02:47.174Z,1368576167.174 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2013-05-15T00:02:47.179Z,1368576167.179 [AsyncPiEstimator] Loaded
2013-05-15T00:02:47.179Z,1368576167.179 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2013-05-15T00:02:47.182Z,1368576167.182 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 4063B4E0
2013-05-15T00:02:47.183Z,1368576167.182 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2013-05-15T00:02:47.183Z,1368576167.183 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2013-05-15T00:02:47.369Z,1368576167.369 [AHRS_sp3003D] Loaded
2013-05-15T00:02:47.370Z,1368576167.370 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2013-05-15T00:02:47.678Z,1368576167.678 [Batt_Ocean_Server] Loaded
2013-05-15T00:02:47.678Z,1368576167.678 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread.
2013-05-15T00:02:47.692Z,1368576167.692 [Depth_Keller] Loaded
2013-05-15T00:02:47.692Z,1368576167.692 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2013-05-15T00:02:47.702Z,1368576167.702 [DropWeight] Loaded
2013-05-15T00:02:47.702Z,1368576167.702 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2013-05-15T00:02:47.902Z,1368576167.902 [DVL_micro] Loaded
2013-05-15T00:02:47.902Z,1368576167.902 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread.
2013-05-15T00:02:47.906Z,1368576167.906 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406C04E0
2013-05-15T00:02:48.003Z,1368576168.003 [NAL9602] Loaded
2013-05-15T00:02:48.004Z,1368576168.003 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2013-05-15T00:02:48.064Z,1368576168.065 [Onboard] Loaded
2013-05-15T00:02:48.065Z,1368576168.065 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2013-05-15T00:02:48.072Z,1368576168.072 [Radio_Freewave] Loaded
2013-05-15T00:02:48.072Z,1368576168.072 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread.
2013-05-15T00:02:48.073Z,1368576168.073 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2013-05-15T00:02:48.074Z,1368576168.074 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2013-05-15T00:02:48.177Z,1368576168.177 [CTD_NeilBrown] Loaded
2013-05-15T00:02:48.177Z,1368576168.177 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2013-05-15T00:02:48.182Z,1368576168.182 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 407124E0
2013-05-15T00:02:48.219Z,1368576168.219 [WetLabsBB2FL] Loaded
2013-05-15T00:02:48.219Z,1368576168.219 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2013-05-15T00:02:48.222Z,1368576168.222 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407424E0
2013-05-15T00:02:48.222Z,1368576168.223 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2013-05-15T00:02:48.225Z,1368576168.225 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2013-05-15T00:02:48.226Z,1368576168.226 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2013-05-15T00:02:48.232Z,1368576168.232 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2013-05-15T00:02:48.234Z,1368576168.234 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407724E0
2013-05-15T00:02:48.238Z,1368576168.238 [Supervisor](DEBUG): Running supervisor.
2013-05-15T00:02:48.239Z,1368576168.239 [CommandLine](INFO): Thread ID is 749
2013-05-15T00:02:48.243Z,1368576168.243 [controlThread](INFO): Thread ID is 748
2013-05-15T00:02:48.243Z,1368576168.243 [controlThread](DEBUG): Initializing ControlThread
2013-05-15T00:02:48.243Z,1368576168.243 [CycleStarter](INFO): Thread ID is 747
2013-05-15T00:02:48.244Z,1368576168.244 [InternalSim](DEBUG):  InternalSim initializing...
2013-05-15T00:02:48.354Z,1368576168.354 [logger](INFO): Thread ID is 750
2013-05-15T00:02:48.470Z,1368576168.470 [AsyncPiEstimator](INFO): Thread ID is 811
2013-05-15T00:02:48.471Z,1368576168.471 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2013-05-15T00:02:48.561Z,1368576168.561 [DVL_micro](INFO): Thread ID is 812
2013-05-15T00:02:48.583Z,1368576168.583 [CTD_NeilBrown](INFO): Thread ID is 813
2013-05-15T00:02:48.583Z,1368576168.583 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2013-05-15T00:02:48.586Z,1368576168.586 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2013-05-15T00:02:48.639Z,1368576168.639 [WetLabsBB2FL](INFO): Thread ID is 814
2013-05-15T00:02:48.639Z,1368576168.639 [WetLabsBB2FL](INFO): Powering down
2013-05-15T00:02:48.654Z,1368576168.654 [SBIT](INFO): Initialize SBIT Component.
2013-05-15T00:02:48.655Z,1368576168.655 [SBIT](IMPORTANT): Tethys CM Info:
$Rev:10336
2013-05-15T00:02:48.655Z,1368576168.655 [SBIT](IMPORTANT): Kernel Release:2.6.27.8
2013-05-15T00:02:48.655Z,1368576168.655 [SBIT](IMPORTANT): Kernel Version:#634 PREEMPT Wed Feb 13 10:21:48 PST 2013
2013-05-15T00:02:48.656Z,1368576168.656 [IBIT](INFO): Initialize IBIT Component.
2013-05-15T00:02:48.657Z,1368576168.657 [CBIT](DEBUG): Initialize CBIT Component.
2013-05-15T00:02:48.657Z,1368576168.657 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2013-05-15T00:02:48.663Z,1368576168.663 [NavChartDb](INFO): Thread ID is 815
2013-05-15T00:02:48.674Z,1368576168.674 [DVL_micro](INFO): Initializing
2013-05-15T00:02:48.674Z,1368576168.674 [DVL_micro](INFO): start:Powering up
2013-05-15T00:02:48.675Z,1368576168.675 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-05-15T00:02:48.676Z,1368576168.676 [DVL_micro](INFO): Cycling power to configure device.
2013-05-15T00:02:48.683Z,1368576168.683 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2013-05-15T00:02:48.684Z,1368576168.684 [NavChart](DEBUG): Initialize NavChart Derivation.
2013-05-15T00:02:48.684Z,1368576168.684 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2013-05-15T00:02:48.685Z,1368576168.685 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2013-05-15T00:02:48.685Z,1368576168.685 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2013-05-15T00:02:48.687Z,1368576168.687 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2013-05-15T00:02:48.687Z,1368576168.687 [Navigation](DEBUG): Initializing Navigation.
2013-05-15T00:02:48.687Z,1368576168.687 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2013-05-15T00:02:48.689Z,1368576168.689 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2013-05-15T00:02:48.690Z,1368576168.690 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2013-05-15T00:02:48.690Z,1368576168.690 [LoopControl](DEBUG): Initialize LoopControlComponent.
2013-05-15T00:02:48.699Z,1368576168.699 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2013-05-15T00:02:48.700Z,1368576168.700 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2013-05-15T00:02:48.701Z,1368576168.701 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2013-05-15T00:02:48.701Z,1368576168.701 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2013-05-15T00:02:48.701Z,1368576168.701 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2013-05-15T00:02:48.701Z,1368576168.701 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2013-05-15T00:02:48.701Z,1368576168.701 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2013-05-15T00:02:48.702Z,1368576168.702 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2013-05-15T00:02:48.702Z,1368576168.702 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2013-05-15T00:02:50.410Z,1368576170.410 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2013-05-15T00:02:50.440Z,1368576170.440 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2013-05-15T00:02:50.498Z,1368576170.498 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2013-05-15T00:02:50.521Z,1368576170.521 [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-05-15T00:02:50.534Z,1368576170.534 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2013-05-15T00:02:50.632Z,1368576170.632 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2013-05-15T00:02:50.634Z,1368576170.634 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2013-05-15T00:02:50.637Z,1368576170.637 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-05-15T00:02:50.657Z,1368576170.657 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2013-05-15T00:02:50.660Z,1368576170.660 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-05-15T00:02:50.699Z,1368576170.699 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2013-05-15T00:02:50.724Z,1368576170.724 [Default:D.SetSpeed](DEBUG): Construct.
2013-05-15T00:02:50.727Z,1368576170.727 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2013-05-15T00:02:50.744Z,1368576170.744 [Default:F.Wait](DEBUG): Construct Wait.
2013-05-15T00:02:50.748Z,1368576170.748 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
xmlns:Control="Tethys/Control"
xmlns:Guidance="Tethys/Guidance" 
xmlns:Units="Tethys/Units"
xmlns:Universal="Tethys/Universal"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
Id="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-05-15T00:02:50.753Z,1368576170.753 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,NAL9602,Onboard,Radio_Freewave,Depth_Keller,DepthRateCalculator,HFRadarModelCalc,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,
2013-05-15T00:02:50.774Z,1368576170.774 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-05-15T00:02:50.775Z,1368576170.775 [DVL_micro](INFO): Querying output modes
2013-05-15T00:02:50.775Z,1368576170.775 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-05-15T00:02:50.786Z,1368576170.786 [DVL_micro](DEBUG): cmdResponse: 01
2013-05-15T00:02:50.786Z,1368576170.786 [DVL_micro](INFO): NQ1 output enabled
2013-05-15T00:02:50.787Z,1368576170.787 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-05-15T00:02:50.798Z,1368576170.798 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2013-05-15T00:02:50.803Z,1368576170.803 [DVL_micro](INFO): pause:Powering down
2013-05-15T00:02:50.832Z,1368576170.832 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-05-15T00:02:50.899Z,1368576170.899 [Depth_Keller](ERROR): Pressure reading out of range: 1831.078247 decibar
2013-05-15T00:02:50.951Z,1368576170.951 [Radio_Freewave](INFO): Powering up
2013-05-15T00:02:51.158Z,1368576171.158 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-05-15T00:02:51.186Z,1368576171.186 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-05-15T00:02:51.193Z,1368576171.193 [ElevatorServo](DEBUG): Initializing EZServoServo.
2013-05-15T00:02:51.202Z,1368576171.202 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2013-05-15T00:02:51.209Z,1368576171.209 [MassServo](DEBUG): Initializing EZServoServo.
2013-05-15T00:02:51.214Z,1368576171.214 [MassServo](DEBUG): Initializing MassServo.
2013-05-15T00:02:51.232Z,1368576171.232 [RudderServo](DEBUG): Initializing EZServoServo.
2013-05-15T00:02:51.238Z,1368576171.238 [RudderServo](DEBUG): Initializing RudderServo.
2013-05-15T00:02:51.244Z,1368576171.244 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-05-15T00:02:51.250Z,1368576171.250 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-05-15T00:02:53.310Z,1368576173.310 [NAL9602](INFO): Powering up NAL9602
2013-05-15T00:03:03.766Z,1368576183.766 [NAL9602](INFO): NAL9602 initialized
2013-05-15T00:03:04.166Z,1368576184.165 [SBIT](IMPORTANT): Beginning Startup BIT
2013-05-15T00:03:04.188Z,1368576184.188 [CBIT](IMPORTANT): Beginning GF scan
2013-05-15T00:03:28.482Z,1368576208.483 [NAL9602](IMPORTANT): GPS fix at: 1368576152.00
2013-05-15T00:03:30.927Z,1368576210.927 [CBIT](IMPORTANT): No ground fault detected
2013-05-15T00:03:35.659Z,1368576215.659 [NAL9602](INFO): Powering down
2013-05-15T00:03:58.288Z,1368576238.288 [SBIT](IMPORTANT): SBIT PASSED
2013-05-15T00:03:58.679Z,1368576238.679 [MissionManager](IMPORTANT): Started mission Startup
2013-05-15T00:03:58.679Z,1368576238.679 [Startup] Running Loop=1
2013-05-15T00:03:58.679Z,1368576238.679 [Startup](INFO): Aggregate::initialize Startup
2013-05-15T00:03:58.679Z,1368576238.679 [Startup:A.GoToSurface] Running Loop=1
2013-05-15T00:03:58.679Z,1368576238.679 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-05-15T00:03:58.689Z,1368576238.689 [Startup:StartupSatComms] Running Loop=1
2013-05-15T00:03:58.689Z,1368576238.689 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2013-05-15T00:03:58.689Z,1368576238.689 [Startup:StartupSatComms:A] Running Loop=1
2013-05-15T00:03:59.063Z,1368576239.063 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-05-15T00:03:59.455Z,1368576239.455 [NAL9602](INFO): Powering up
2013-05-15T00:04:10.450Z,1368576250.450 [NAL9602](INFO): NAL9602 initialized
2013-05-15T00:04:45.882Z,1368576285.882 [NAL9602](IMPORTANT): GPS fix at: 1368576229.50
2013-05-15T00:04:45.895Z,1368576285.895 [Startup:StartupSatComms:A] Stopped
2013-05-15T00:04:45.895Z,1368576285.895 [Startup:StartupSatComms:B] Running Loop=1
2013-05-15T00:04:46.345Z,1368576286.345 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2013-05-15T00:05:45.917Z,1368576345.917 [Startup:StartupSatComms:B](INFO): Timed out from 2013-05-15T00:04:45.9Z
2013-05-15T00:05:45.917Z,1368576345.917 [Startup:StartupSatComms:B:A_Timeout] Running Loop=1
2013-05-15T00:05:45.917Z,1368576345.917 [Startup:StartupSatComms:B:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:B:A_Timeout
2013-05-15T00:05:45.918Z,1368576345.918 [Startup:StartupSatComms:B:A_Timeout](INFO): Completed Startup:StartupSatComms:B:A_Timeout
2013-05-15T00:05:45.918Z,1368576345.918 [Startup:StartupSatComms:B] Stopped
2013-05-15T00:05:45.918Z,1368576345.918 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2013-05-15T00:05:45.918Z,1368576345.918 [Startup:StartupSatComms] Stopped
2013-05-15T00:05:45.918Z,1368576345.918 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2013-05-15T00:05:45.919Z,1368576345.919 [Startup](INFO): Completed Startup
2013-05-15T00:05:45.919Z,1368576345.919 [Startup] Stopped
2013-05-15T00:05:45.919Z,1368576345.919 [Startup](INFO): Aggregate::uninitialize Startup
2013-05-15T00:05:45.919Z,1368576345.919 [Startup:A.GoToSurface] Stopped
2013-05-15T00:05:45.919Z,1368576345.919 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-05-15T00:05:46.316Z,1368576346.316 [MissionManager](IMPORTANT): Started mission Default
2013-05-15T00:05:46.316Z,1368576346.316 [Default] Running Loop=1
2013-05-15T00:05:46.316Z,1368576346.316 [Default](INFO): Aggregate::initialize Default
2013-05-15T00:05:46.317Z,1368576346.316 [Default:D.SetSpeed] Running Loop=1
2013-05-15T00:05:46.317Z,1368576346.317 [Default:D.SetSpeed](DEBUG): Initialize.
2013-05-15T00:05:46.317Z,1368576346.317 [Default:E.GoToSurface] Running Loop=1
2013-05-15T00:05:46.317Z,1368576346.317 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-05-15T00:05:46.317Z,1368576346.317 [Default:Iridium] Running Loop=1
2013-05-15T00:05:46.317Z,1368576346.317 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-05-15T00:05:46.317Z,1368576346.317 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-05-15T00:05:46.317Z,1368576346.317 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-05-15T00:05:46.318Z,1368576346.318 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-05-15T00:05:46.318Z,1368576346.318 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-05-15T00:05:46.318Z,1368576346.318 [Default:E.GoToSurface] Running Loop=1
2013-05-15T00:05:46.324Z,1368576346.323 [Default:D.SetSpeed] Running Loop=1
2013-05-15T00:05:46.329Z,1368576346.329 [Default:CallIridium] Running Loop=1
2013-05-15T00:05:46.329Z,1368576346.329 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-05-15T00:05:46.329Z,1368576346.329 [Default:CallIridium:A] Running Loop=1
2013-05-15T00:05:46.331Z,1368576346.331 [Default:CallIridium:A] Stopped
2013-05-15T00:05:46.331Z,1368576346.331 [Default:CallIridium:B] Running Loop=1
2013-05-15T00:05:46.331Z,1368576346.331 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-05-15T00:05:46.336Z,1368576346.336 [Default:Iridium:B.GoToSurface] Stopped
2013-05-15T00:05:46.336Z,1368576346.336 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-05-15T00:05:46.336Z,1368576346.336 [Default:Iridium:Read_Iridium] Running Loop=1
2013-05-15T00:05:46.336Z,1368576346.336 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-05-15T00:05:46.341Z,1368576346.341 [Default:GPS] Running Loop=1
2013-05-15T00:05:46.342Z,1368576346.342 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-05-15T00:05:46.342Z,1368576346.342 [Default:GPS:A.SetSpeed] Running Loop=1
2013-05-15T00:05:46.342Z,1368576346.342 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-05-15T00:05:46.342Z,1368576346.342 [Default:GPS:B.GoToSurface] Running Loop=1
2013-05-15T00:05:46.342Z,1368576346.342 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-05-15T00:05:46.348Z,1368576346.348 [Default:GPS:B.GoToSurface] Stopped
2013-05-15T00:05:46.348Z,1368576346.348 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-05-15T00:05:46.348Z,1368576346.348 [Default:GPS:Read_GPS] Running Loop=1
2013-05-15T00:05:46.348Z,1368576346.348 [Default:GPS:A.SetSpeed] Running Loop=1
2013-05-15T00:05:46.717Z,1368576346.717 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2013-05-15T00:05:46.720Z,1368576346.720 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-05-15T00:05:50.620Z,1368576350.620 [NAL9602](IMPORTANT): GPS fix at: 1368576294.00
2013-05-15T00:05:50.636Z,1368576350.635 [Default:GPS:Read_GPS] Stopped
2013-05-15T00:05:50.636Z,1368576350.636 [Default:GPS:D] Running Loop=1
2013-05-15T00:05:51.096Z,1368576351.096 [Default:GPS:D] Stopped
2013-05-15T00:05:51.097Z,1368576351.097 [Default:GPS](INFO): Completed Default:GPS
2013-05-15T00:05:51.097Z,1368576351.097 [Default:GPS] Stopped
2013-05-15T00:05:51.097Z,1368576351.097 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-05-15T00:05:51.097Z,1368576351.097 [Default:GPS:A.SetSpeed] Stopped
2013-05-15T00:05:51.097Z,1368576351.097 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-05-15T00:07:06.049Z,1368576426.049 [NAL9602](INFO): SBD MO Status=1, MOMSN=12014, MT Status=0, MTMSN=0
2013-05-15T00:07:06.098Z,1368576426.098 [NAL9602](INFO): Sent 68 bytes from file Logs/20130514T183205/Courier0020.lzma
2013-05-15T00:07:06.098Z,1368576426.098 [NAL9602](INFO): Packets left to send: 0
2013-05-15T00:07:06.100Z,1368576426.100 [NAL9602](INFO): Stored copy of sent data in Logs/20130514T183205/Courier0020.lzma.parts/0000.sbd
2013-05-15T00:07:17.217Z,1368576437.217 [NAL9602](INFO): SBD MO Status=1, MOMSN=12015, MT Status=0, MTMSN=0
2013-05-15T00:07:17.266Z,1368576437.266 [NAL9602](INFO): Sent 261 bytes from file Logs/20130515T000238/Courier0000.lzma
2013-05-15T00:07:17.266Z,1368576437.266 [NAL9602](INFO): Packets left to send: 0
2013-05-15T00:07:17.268Z,1368576437.268 [NAL9602](INFO): Stored copy of sent data in Logs/20130515T000238/Courier0000.lzma.parts/0000.sbd
2013-05-15T00:07:28.640Z,1368576448.640 [NAL9602](INFO): SBD MO Status=1, MOMSN=12016, MT Status=0, MTMSN=0
2013-05-15T00:07:28.690Z,1368576448.690 [NAL9602](INFO): Sent 140 bytes from file Logs/20130514T183205/Express0021.lzma
2013-05-15T00:07:28.690Z,1368576448.690 [NAL9602](INFO): Packets left to send: 0
2013-05-15T00:07:28.692Z,1368576448.692 [NAL9602](INFO): Stored copy of sent data in Logs/20130514T183205/Express0021.lzma.parts/0000.sbd
2013-05-15T00:07:42.625Z,1368576462.625 [NAL9602](INFO): SBD MO Status=1, MOMSN=12017, MT Status=0, MTMSN=0
2013-05-15T00:07:42.674Z,1368576462.674 [NAL9602](INFO): Sent 332 bytes from file Logs/20130515T000238/Express0001.lzma
2013-05-15T00:07:42.674Z,1368576462.674 [NAL9602](INFO): Packets left to send: 1
2013-05-15T00:07:42.676Z,1368576462.676 [NAL9602](INFO): Stored copy of sent data in Logs/20130515T000238/Express0001.lzma.parts/0001.sbd
2013-05-15T00:07:53.761Z,1368576473.761 [NAL9602](INFO): SBD MO Status=1, MOMSN=12018, MT Status=0, MTMSN=0
2013-05-15T00:07:53.816Z,1368576473.816 [NAL9602](INFO): Sent 330 bytes from file Logs/20130515T000238/Express0001.lzma
2013-05-15T00:07:53.817Z,1368576473.816 [NAL9602](INFO): Packets left to send: 0
2013-05-15T00:07:53.818Z,1368576473.818 [NAL9602](INFO): Stored copy of sent data in Logs/20130515T000238/Express0001.lzma.parts/0000.sbd
2013-05-15T00:08:05.725Z,1368576485.725 [NAL9602](INFO): SBD MO Status=0, MOMSN=12019, MT Status=0, MTMSN=0
2013-05-15T00:08:09.454Z,1368576489.454 [Default:Iridium:Read_Iridium] Stopped
2013-05-15T00:08:09.455Z,1368576489.455 [Default:Iridium](INFO): Completed Default:Iridium
2013-05-15T00:08:09.455Z,1368576489.455 [Default:Iridium] Stopped
2013-05-15T00:08:09.455Z,1368576489.455 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-05-15T00:08:09.455Z,1368576489.455 [Default:Iridium:A.SetSpeed] Stopped
2013-05-15T00:08:09.455Z,1368576489.455 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-05-15T00:08:09.456Z,1368576489.455 [Default:F.Wait] Running Loop=1
2013-05-15T00:08:09.456Z,1368576489.456 [Default:F.Wait](DEBUG): Initialize Wait Component.
2013-05-15T00:08:09.898Z,1368576489.898 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-05-15T00:08:09.898Z,1368576489.898 [Default:CallIridium:B] Stopped
2013-05-15T00:08:09.898Z,1368576489.898 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-05-15T00:08:09.898Z,1368576489.898 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-05-15T00:08:09.898Z,1368576489.898 [Default:CallIridium] Stopped
2013-05-15T00:08:09.898Z,1368576489.898 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-05-15T00:08:19.878Z,1368576499.878 [NAL9602](INFO): Powering down
2013-05-15T00:13:09.831Z,1368576789.831 [Default:CallIridium] Running Loop=1
2013-05-15T00:13:09.831Z,1368576789.831 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-05-15T00:13:09.831Z,1368576789.831 [Default:CallIridium:A] Running Loop=1
2013-05-15T00:13:09.831Z,1368576789.831 [Default:CallIridium:A] Stopped
2013-05-15T00:13:09.831Z,1368576789.831 [Default:CallIridium:B] Running Loop=1
2013-05-15T00:13:09.831Z,1368576789.832 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-05-15T00:13:14.850Z,1368576794.850 [Default:Iridium] Running Loop=1
2013-05-15T00:13:14.851Z,1368576794.851 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-05-15T00:13:14.851Z,1368576794.851 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-05-15T00:13:14.851Z,1368576794.851 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-05-15T00:13:14.851Z,1368576794.851 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-05-15T00:13:14.851Z,1368576794.851 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-05-15T00:13:14.852Z,1368576794.852 [Default:Iridium:B.GoToSurface] Stopped
2013-05-15T00:13:14.852Z,1368576794.852 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-05-15T00:13:14.852Z,1368576794.852 [Default:Iridium:Read_Iridium] Running Loop=1
2013-05-15T00:13:14.852Z,1368576794.852 [Default:GPS] Running Loop=1
2013-05-15T00:13:14.852Z,1368576794.852 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-05-15T00:13:14.853Z,1368576794.852 [Default:GPS:A.SetSpeed] Running Loop=1
2013-05-15T00:13:14.853Z,1368576794.853 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-05-15T00:13:14.853Z,1368576794.853 [Default:GPS:B.GoToSurface] Running Loop=1
2013-05-15T00:13:14.853Z,1368576794.853 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-05-15T00:13:14.854Z,1368576794.854 [Default:GPS:B.GoToSurface] Stopped
2013-05-15T00:13:14.854Z,1368576794.854 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-05-15T00:13:14.855Z,1368576794.855 [Default:GPS:Read_GPS] Running Loop=1
2013-05-15T00:13:15.446Z,1368576795.446 [NAL9602](INFO): Powering up
2013-05-15T00:13:25.942Z,1368576805.942 [NAL9602](INFO): NAL9602 initialized
2013-05-15T00:13:58.025Z,1368576838.025 [NAL9602](IMPORTANT): GPS fix at: 1368576782.00
2013-05-15T00:13:58.041Z,1368576838.041 [Default:GPS:Read_GPS] Stopped
2013-05-15T00:13:58.041Z,1368576838.041 [Default:GPS:D] Running Loop=1
2013-05-15T00:13:58.463Z,1368576838.463 [Default:GPS:D] Stopped
2013-05-15T00:13:58.463Z,1368576838.463 [Default:GPS](INFO): Completed Default:GPS
2013-05-15T00:13:58.463Z,1368576838.463 [Default:GPS] Stopped
2013-05-15T00:13:58.463Z,1368576838.463 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-05-15T00:13:58.463Z,1368576838.464 [Default:GPS:A.SetSpeed] Stopped
2013-05-15T00:13:58.464Z,1368576838.464 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-05-15T00:15:58.480Z,1368576958.480 [NAL9602](INFO): SBD MO Status=2, MOMSN=12020, MT Status=2, MTMSN=0
2013-05-15T00:15:58.480Z,1368576958.480 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T00:16:14.905Z,1368576974.905 [NAL9602](INFO): SBD MO Status=2, MOMSN=12020, MT Status=2, MTMSN=0
2013-05-15T00:16:14.905Z,1368576974.905 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T00:16:24.005Z,1368576984.005 [NAL9602](INFO): SBD MO Status=2, MOMSN=12020, MT Status=2, MTMSN=0
2013-05-15T00:16:24.005Z,1368576984.005 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T00:16:31.829Z,1368576991.829 [NAL9602](INFO): SBD MO Status=2, MOMSN=12020, MT Status=2, MTMSN=0
2013-05-15T00:16:31.829Z,1368576991.829 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T00:16:40.488Z,1368577000.489 [NAL9602](INFO): SBD MO Status=2, MOMSN=12020, MT Status=2, MTMSN=0
2013-05-15T00:16:40.489Z,1368577000.489 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T00:19:18.644Z,1368577158.644 [NAL9602](INFO): SBD MO Status=2, MOMSN=12020, MT Status=2, MTMSN=0
2013-05-15T00:19:18.645Z,1368577158.645 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T00:19:35.253Z,1368577175.253 [NAL9602](INFO): SBD MO Status=2, MOMSN=12020, MT Status=2, MTMSN=0
2013-05-15T00:19:35.253Z,1368577175.253 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T00:19:43.884Z,1368577183.884 [NAL9602](INFO): SBD MO Status=2, MOMSN=12020, MT Status=2, MTMSN=0
2013-05-15T00:19:43.885Z,1368577183.885 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T00:19:52.528Z,1368577192.528 [NAL9602](INFO): SBD MO Status=2, MOMSN=12020, MT Status=2, MTMSN=0
2013-05-15T00:19:52.529Z,1368577192.529 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T00:20:01.161Z,1368577201.161 [NAL9602](INFO): SBD MO Status=2, MOMSN=12020, MT Status=2, MTMSN=0
2013-05-15T00:20:01.161Z,1368577201.161 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T00:21:57.657Z,1368577317.657 [NAL9602](INFO): SBD MO Status=2, MOMSN=12020, MT Status=2, MTMSN=0
2013-05-15T00:21:57.657Z,1368577317.657 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T00:28:39.521Z,1368577719.521 [NAL9602](INFO): SBD MO Status=2, MOMSN=12020, MT Status=2, MTMSN=0
2013-05-15T00:28:39.521Z,1368577719.521 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T00:28:56.109Z,1368577736.109 [NAL9602](INFO): SBD MO Status=2, MOMSN=12020, MT Status=2, MTMSN=0
2013-05-15T00:28:56.110Z,1368577736.110 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T00:29:09.977Z,1368577749.977 [NAL9602](INFO): SBD MO Status=2, MOMSN=12020, MT Status=2, MTMSN=0
2013-05-15T00:29:09.977Z,1368577749.977 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T00:30:25.225Z,1368577825.225 [NAL9602](INFO): SBD MO Status=2, MOMSN=12020, MT Status=2, MTMSN=0
2013-05-15T00:30:25.225Z,1368577825.225 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T00:30:47.925Z,1368577847.925 [NAL9602](INFO): SBD MO Status=2, MOMSN=12020, MT Status=2, MTMSN=0
2013-05-15T00:30:47.925Z,1368577847.925 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T00:32:40.297Z,1368577960.297 [NAL9602](INFO): SBD MO Status=2, MOMSN=12020, MT Status=2, MTMSN=0
2013-05-15T00:32:40.297Z,1368577960.297 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T00:38:22.641Z,1368578302.641 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=12020, MT Status=1, MTMSN=865
2013-05-15T00:38:22.690Z,1368578302.690 [NAL9602](INFO): Sent 68 bytes from file Logs/20130515T000238/Courier0004.lzma
2013-05-15T00:38:22.690Z,1368578302.690 [NAL9602](INFO): Packets left to send: 0
2013-05-15T00:38:22.692Z,1368578302.692 [NAL9602](INFO): Stored copy of sent data in Logs/20130515T000238/Courier0004.lzma.parts/0000.sbd
2013-05-15T00:38:23.414Z,1368578303.414 [NAL9602](IMPORTANT): Initialized file: Missions/Demo/Circle.xml
2013-05-15T00:38:23.415Z,1368578303.415 [NAL9602](IMPORTANT): More data left to go, at position 83
2013-05-15T00:39:49.521Z,1368578389.521 [NAL9602](INFO): SBD MO Status=2, MOMSN=12021, MT Status=2, MTMSN=0
2013-05-15T00:39:49.521Z,1368578389.521 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T00:41:41.976Z,1368578501.977 [NAL9602](INFO): SBD MO Status=2, MOMSN=12021, MT Status=2, MTMSN=0
2013-05-15T00:41:41.977Z,1368578501.977 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T00:47:47.051Z,1368578867.051 [NAL9602](INFO): SBD MO Status=2, MOMSN=12021, MT Status=2, MTMSN=0
2013-05-15T00:47:47.052Z,1368578867.052 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T00:48:17.877Z,1368578897.877 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=12021, MT Status=1, MTMSN=866
2013-05-15T00:48:17.926Z,1368578897.926 [NAL9602](INFO): Sent 259 bytes from file Logs/20130515T000238/Express0005.lzma
2013-05-15T00:48:17.926Z,1368578897.926 [NAL9602](INFO): Packets left to send: 0
2013-05-15T00:48:17.928Z,1368578897.928 [NAL9602](INFO): Stored copy of sent data in Logs/20130515T000238/Express0005.lzma.parts/0000.sbd
2013-05-15T00:48:18.457Z,1368578898.457 [NAL9602](IMPORTANT): Added data to file: Missions/Demo/Circle.xml
2013-05-15T00:48:19.344Z,1368578899.344 [NAL9602](IMPORTANT): Success executing cat Logs/latest/5192D696.part | gunzip -f -d | cat `cp Missions/Demo/.svn/text-base/Circle.xml.svn-base Missions/Demo/Circle.xml` | patch Missions/Demo/Circle.xml
2013-05-15T00:48:19.494Z,1368578899.494 [CommandLine](IMPORTANT): 2a657d1ac2b8b17ed166b488cafe620e  Missions/Demo/Circle.xml

2013-05-15T00:48:34.846Z,1368578914.846 [NAL9602](INFO): SBD MO Status=0, MOMSN=12022, MT Status=0, MTMSN=0
2013-05-15T00:48:59.481Z,1368578939.481 [NAL9602](INFO): SBD MO Status=2, MOMSN=12023, MT Status=2, MTMSN=0
2013-05-15T00:48:59.481Z,1368578939.481 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T00:49:25.609Z,1368578965.609 [NAL9602](INFO): SBD MO Status=2, MOMSN=12023, MT Status=2, MTMSN=0
2013-05-15T00:49:25.609Z,1368578965.609 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T00:49:47.089Z,1368578987.089 [NAL9602](INFO): SBD MO Status=2, MOMSN=12023, MT Status=2, MTMSN=0
2013-05-15T00:49:47.089Z,1368578987.089 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T00:50:36.143Z,1368579036.143 [NAL9602](INFO): SBD MO Status=2, MOMSN=12023, MT Status=2, MTMSN=0
2013-05-15T00:50:36.143Z,1368579036.143 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T00:51:29.238Z,1368579089.238 [NAL9602](INFO): SBD MO Status=2, MOMSN=12023, MT Status=2, MTMSN=0
2013-05-15T00:51:29.239Z,1368579089.239 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T00:55:57.002Z,1368579357.002 [NAL9602](INFO): SBD MO Status=1, MOMSN=12023, MT Status=0, MTMSN=0
2013-05-15T00:55:57.050Z,1368579357.050 [NAL9602](INFO): Sent 68 bytes from file Logs/20130515T000238/Courier0008.lzma
2013-05-15T00:55:57.051Z,1368579357.051 [NAL9602](INFO): Packets left to send: 0
2013-05-15T00:55:57.052Z,1368579357.052 [NAL9602](INFO): Stored copy of sent data in Logs/20130515T000238/Courier0008.lzma.parts/0000.sbd
2013-05-15T00:56:11.413Z,1368579371.413 [NAL9602](INFO): SBD MO Status=1, MOMSN=12024, MT Status=0, MTMSN=0
2013-05-15T00:56:11.462Z,1368579371.462 [NAL9602](INFO): Sent 332 bytes from file Logs/20130515T000238/Express0009.lzma
2013-05-15T00:56:11.463Z,1368579371.463 [NAL9602](INFO): Packets left to send: 1
2013-05-15T00:56:11.464Z,1368579371.464 [NAL9602](INFO): Stored copy of sent data in Logs/20130515T000238/Express0009.lzma.parts/0001.sbd
2013-05-15T00:56:28.412Z,1368579388.412 [NAL9602](INFO): SBD MO Status=2, MOMSN=12025, MT Status=2, MTMSN=0
2013-05-15T00:56:28.413Z,1368579388.413 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T00:56:49.029Z,1368579409.029 [NAL9602](INFO): SBD MO Status=1, MOMSN=12025, MT Status=0, MTMSN=0
2013-05-15T00:56:49.082Z,1368579409.082 [NAL9602](INFO): Sent 97 bytes from file Logs/20130515T000238/Express0009.lzma
2013-05-15T00:56:49.082Z,1368579409.082 [NAL9602](INFO): Packets left to send: 0
2013-05-15T00:56:49.083Z,1368579409.083 [NAL9602](INFO): Stored copy of sent data in Logs/20130515T000238/Express0009.lzma.parts/0000.sbd
2013-05-15T00:57:05.437Z,1368579425.437 [NAL9602](INFO): SBD MO Status=2, MOMSN=12026, MT Status=2, MTMSN=0
2013-05-15T00:57:05.437Z,1368579425.437 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T00:57:10.635Z,1368579430.635 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T00:57:20.184Z,1368579440.184 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T00:57:24.510Z,1368579444.510 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T00:57:32.654Z,1368579452.654 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T00:57:41.395Z,1368579461.395 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T00:57:49.713Z,1368579469.713 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T00:57:58.453Z,1368579478.453 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T00:58:29.568Z,1368579509.568 [NAL9602](INFO): SBD MO Status=2, MOMSN=12026, MT Status=0, MTMSN=0
2013-05-15T00:58:29.568Z,1368579509.568 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T00:58:40.285Z,1368579520.285 [NAL9602](INFO): SBD MO Status=0, MOMSN=12026, MT Status=0, MTMSN=0
2013-05-15T00:58:43.986Z,1368579523.986 [Default:Iridium:Read_Iridium] Stopped
2013-05-15T00:58:43.986Z,1368579523.986 [Default:Iridium](INFO): Completed Default:Iridium
2013-05-15T00:58:43.986Z,1368579523.986 [Default:Iridium] Stopped
2013-05-15T00:58:43.986Z,1368579523.986 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-05-15T00:58:43.987Z,1368579523.987 [Default:Iridium:A.SetSpeed] Stopped
2013-05-15T00:58:43.987Z,1368579523.987 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-05-15T00:58:44.394Z,1368579524.395 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-05-15T00:58:44.395Z,1368579524.395 [Default:CallIridium:B] Stopped
2013-05-15T00:58:44.395Z,1368579524.395 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-05-15T00:58:44.395Z,1368579524.395 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-05-15T00:58:44.395Z,1368579524.395 [Default:CallIridium] Stopped
2013-05-15T00:58:44.395Z,1368579524.395 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-05-15T00:58:54.360Z,1368579534.360 [NAL9602](INFO): Powering down
2013-05-15T01:03:44.392Z,1368579824.392 [Default:CallIridium] Running Loop=1
2013-05-15T01:03:44.392Z,1368579824.392 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-05-15T01:03:44.392Z,1368579824.392 [Default:CallIridium:A] Running Loop=1
2013-05-15T01:03:44.392Z,1368579824.392 [Default:CallIridium:A] Stopped
2013-05-15T01:03:44.393Z,1368579824.392 [Default:CallIridium:B] Running Loop=1
2013-05-15T01:03:44.393Z,1368579824.393 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-05-15T01:03:49.339Z,1368579829.339 [Default:Iridium] Running Loop=1
2013-05-15T01:03:49.339Z,1368579829.339 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-05-15T01:03:49.339Z,1368579829.339 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-05-15T01:03:49.339Z,1368579829.339 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-05-15T01:03:49.339Z,1368579829.339 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-05-15T01:03:49.339Z,1368579829.339 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-05-15T01:03:49.340Z,1368579829.340 [Default:Iridium:B.GoToSurface] Stopped
2013-05-15T01:03:49.340Z,1368579829.340 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-05-15T01:03:49.340Z,1368579829.340 [Default:Iridium:Read_Iridium] Running Loop=1
2013-05-15T01:03:49.340Z,1368579829.341 [Default:GPS] Running Loop=1
2013-05-15T01:03:49.341Z,1368579829.341 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-05-15T01:03:49.341Z,1368579829.341 [Default:GPS:A.SetSpeed] Running Loop=1
2013-05-15T01:03:49.341Z,1368579829.341 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-05-15T01:03:49.341Z,1368579829.341 [Default:GPS:B.GoToSurface] Running Loop=1
2013-05-15T01:03:49.341Z,1368579829.341 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-05-15T01:03:49.342Z,1368579829.342 [Default:GPS:B.GoToSurface] Stopped
2013-05-15T01:03:49.342Z,1368579829.342 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-05-15T01:03:49.342Z,1368579829.342 [Default:GPS:Read_GPS] Running Loop=1
2013-05-15T01:03:49.983Z,1368579829.983 [NAL9602](INFO): Powering up
2013-05-15T01:04:00.854Z,1368579840.854 [NAL9602](INFO): NAL9602 initialized
2013-05-15T01:04:44.846Z,1368579884.846 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#4 STATUS: 65535
2013-05-15T01:04:53.220Z,1368579893.220 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:04:53.221Z,1368579893.221 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:04:54.422Z,1368579894.422 [NAL9602](IMPORTANT): GPS fix at: 1368579845.00
2013-05-15T01:04:54.437Z,1368579894.437 [Default:GPS:Read_GPS] Stopped
2013-05-15T01:04:54.438Z,1368579894.438 [Default:GPS:D] Running Loop=1
2013-05-15T01:04:54.851Z,1368579894.851 [Default:GPS:D] Stopped
2013-05-15T01:04:54.851Z,1368579894.851 [Default:GPS](INFO): Completed Default:GPS
2013-05-15T01:04:54.851Z,1368579894.851 [Default:GPS] Stopped
2013-05-15T01:04:54.851Z,1368579894.851 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-05-15T01:04:54.851Z,1368579894.851 [Default:GPS:A.SetSpeed] Stopped
2013-05-15T01:04:54.851Z,1368579894.851 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-05-15T01:05:44.720Z,1368579944.720 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:05:44.721Z,1368579944.721 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:06:17.236Z,1368579977.237 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:06:17.237Z,1368579977.237 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:06:53.401Z,1368580013.401 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:06:53.401Z,1368580013.401 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:07:10.424Z,1368580030.424 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:07:10.425Z,1368580030.425 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:07:19.527Z,1368580039.527 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:07:19.527Z,1368580039.527 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:07:35.934Z,1368580055.934 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:07:35.934Z,1368580055.934 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:07:44.468Z,1368580064.468 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:07:44.469Z,1368580064.469 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:07:53.241Z,1368580073.241 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:07:53.241Z,1368580073.241 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:08:01.852Z,1368580081.852 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:08:01.853Z,1368580081.853 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:08:10.424Z,1368580090.424 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:08:10.425Z,1368580090.425 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:08:19.009Z,1368580099.009 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:08:19.009Z,1368580099.009 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:08:27.700Z,1368580107.700 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:08:27.701Z,1368580107.701 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:08:51.071Z,1368580131.071 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:08:51.071Z,1368580131.071 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:09:00.107Z,1368580140.107 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:09:00.107Z,1368580140.107 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:09:16.725Z,1368580156.725 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:09:16.725Z,1368580156.725 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:09:25.449Z,1368580165.449 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:09:25.449Z,1368580165.449 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:09:34.084Z,1368580174.084 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:09:34.085Z,1368580174.085 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:09:42.652Z,1368580182.652 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:09:42.653Z,1368580182.653 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:10:02.553Z,1368580202.553 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:10:02.553Z,1368580202.553 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:14:30.609Z,1368580470.609 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:14:30.609Z,1368580470.609 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:14:48.021Z,1368580488.021 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:14:48.021Z,1368580488.021 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:15:04.140Z,1368580504.140 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:15:04.140Z,1368580504.140 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:15:12.667Z,1368580512.667 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:15:12.668Z,1368580512.668 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:15:20.873Z,1368580520.873 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:15:20.873Z,1368580520.873 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:15:29.433Z,1368580529.432 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:15:29.433Z,1368580529.433 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:15:38.000Z,1368580538.000 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:15:38.001Z,1368580538.001 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:15:46.717Z,1368580546.717 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:15:46.717Z,1368580546.717 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:15:55.449Z,1368580555.449 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:15:55.449Z,1368580555.449 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:16:03.960Z,1368580563.960 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:16:03.960Z,1368580563.960 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:16:12.879Z,1368580572.879 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:16:12.879Z,1368580572.879 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:16:21.329Z,1368580581.329 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:16:21.329Z,1368580581.329 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:16:29.609Z,1368580589.609 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:16:29.609Z,1368580589.609 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:16:38.225Z,1368580598.225 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:16:38.225Z,1368580598.225 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:16:46.953Z,1368580606.953 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:16:46.953Z,1368580606.953 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:16:55.109Z,1368580615.109 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:16:55.109Z,1368580615.109 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:17:03.723Z,1368580623.723 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:17:03.723Z,1368580623.723 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:17:12.397Z,1368580632.397 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:17:12.397Z,1368580632.397 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:17:28.568Z,1368580648.568 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:17:28.569Z,1368580648.569 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:17:37.179Z,1368580657.179 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:17:37.179Z,1368580657.180 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:17:45.824Z,1368580665.824 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:17:45.825Z,1368580665.825 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:17:54.921Z,1368580674.921 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:17:54.922Z,1368580674.921 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:18:03.009Z,1368580683.009 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:18:03.009Z,1368580683.009 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:18:11.572Z,1368580691.572 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:18:11.572Z,1368580691.572 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:18:20.200Z,1368580700.201 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:18:20.201Z,1368580700.201 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:18:28.708Z,1368580708.708 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:18:28.709Z,1368580708.709 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:18:37.668Z,1368580717.668 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:18:37.668Z,1368580717.668 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:18:46.225Z,1368580726.225 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:18:46.225Z,1368580726.225 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:18:54.832Z,1368580734.832 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:18:54.833Z,1368580734.833 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:19:09.817Z,1368580749.817 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:19:09.817Z,1368580749.817 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:23:07.709Z,1368580987.708 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:23:07.709Z,1368580987.709 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:23:25.505Z,1368581005.505 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:23:25.505Z,1368581005.505 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:23:46.622Z,1368581026.622 [NAL9602](INFO): SBD MO Status=2, MOMSN=12027, MT Status=2, MTMSN=0
2013-05-15T01:23:46.622Z,1368581026.622 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:24:05.424Z,1368581045.424 [NAL9602](INFO): SBD MO Status=1, MOMSN=12027, MT Status=0, MTMSN=0
2013-05-15T01:24:05.474Z,1368581045.474 [NAL9602](INFO): Sent 47 bytes from file Logs/20130515T000238/Courier0012.lzma
2013-05-15T01:24:05.474Z,1368581045.474 [NAL9602](INFO): Packets left to send: 0
2013-05-15T01:24:05.476Z,1368581045.476 [NAL9602](INFO): Stored copy of sent data in Logs/20130515T000238/Courier0012.lzma.parts/0000.sbd
2013-05-15T01:24:13.773Z,1368581053.773 [NAL9602](INFO): SBD MO Status=1, MOMSN=12028, MT Status=0, MTMSN=0
2013-05-15T01:24:13.822Z,1368581053.822 [NAL9602](INFO): Sent 72 bytes from file Logs/20130515T000238/Express0013.lzma
2013-05-15T01:24:13.823Z,1368581053.823 [NAL9602](INFO): Packets left to send: 0
2013-05-15T01:24:13.824Z,1368581053.824 [NAL9602](INFO): Stored copy of sent data in Logs/20130515T000238/Express0013.lzma.parts/0000.sbd
2013-05-15T01:24:21.785Z,1368581061.785 [NAL9602](INFO): SBD MO Status=0, MOMSN=12029, MT Status=0, MTMSN=0
2013-05-15T01:24:25.544Z,1368581065.544 [Default:Iridium:Read_Iridium] Stopped
2013-05-15T01:24:25.545Z,1368581065.545 [Default:Iridium](INFO): Completed Default:Iridium
2013-05-15T01:24:25.545Z,1368581065.545 [Default:Iridium] Stopped
2013-05-15T01:24:25.545Z,1368581065.545 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-05-15T01:24:25.545Z,1368581065.545 [Default:Iridium:A.SetSpeed] Stopped
2013-05-15T01:24:25.545Z,1368581065.545 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-05-15T01:24:25.943Z,1368581065.943 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-05-15T01:24:25.943Z,1368581065.943 [Default:CallIridium:B] Stopped
2013-05-15T01:24:25.943Z,1368581065.943 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-05-15T01:24:25.943Z,1368581065.943 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-05-15T01:24:25.943Z,1368581065.943 [Default:CallIridium] Stopped
2013-05-15T01:24:25.943Z,1368581065.944 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-05-15T01:24:35.928Z,1368581075.928 [NAL9602](INFO): Powering down
2013-05-15T01:29:25.939Z,1368581365.939 [Default:CallIridium] Running Loop=1
2013-05-15T01:29:25.939Z,1368581365.939 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-05-15T01:29:25.939Z,1368581365.939 [Default:CallIridium:A] Running Loop=1
2013-05-15T01:29:25.939Z,1368581365.939 [Default:CallIridium:A] Stopped
2013-05-15T01:29:25.939Z,1368581365.940 [Default:CallIridium:B] Running Loop=1
2013-05-15T01:29:25.940Z,1368581365.940 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-05-15T01:29:30.914Z,1368581370.914 [Default:Iridium] Running Loop=1
2013-05-15T01:29:30.914Z,1368581370.914 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-05-15T01:29:30.914Z,1368581370.914 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-05-15T01:29:30.915Z,1368581370.915 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-05-15T01:29:30.915Z,1368581370.915 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-05-15T01:29:30.915Z,1368581370.915 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-05-15T01:29:30.916Z,1368581370.916 [Default:Iridium:B.GoToSurface] Stopped
2013-05-15T01:29:30.916Z,1368581370.916 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-05-15T01:29:30.916Z,1368581370.916 [Default:Iridium:Read_Iridium] Running Loop=1
2013-05-15T01:29:30.916Z,1368581370.916 [Default:GPS] Running Loop=1
2013-05-15T01:29:30.916Z,1368581370.916 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-05-15T01:29:30.916Z,1368581370.917 [Default:GPS:A.SetSpeed] Running Loop=1
2013-05-15T01:29:30.916Z,1368581370.917 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-05-15T01:29:30.917Z,1368581370.917 [Default:GPS:B.GoToSurface] Running Loop=1
2013-05-15T01:29:30.917Z,1368581370.917 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-05-15T01:29:30.918Z,1368581370.918 [Default:GPS:B.GoToSurface] Stopped
2013-05-15T01:29:30.918Z,1368581370.918 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-05-15T01:29:30.918Z,1368581370.918 [Default:GPS:Read_GPS] Running Loop=1
2013-05-15T01:29:31.543Z,1368581371.543 [NAL9602](INFO): Powering up
2013-05-15T01:29:42.070Z,1368581382.070 [NAL9602](INFO): NAL9602 initialized
2013-05-15T01:30:21.493Z,1368581421.493 [NAL9602](IMPORTANT): GPS fix at: 1368581373.00
2013-05-15T01:30:21.509Z,1368581421.509 [Default:GPS:Read_GPS] Stopped
2013-05-15T01:30:21.509Z,1368581421.509 [Default:GPS:D] Running Loop=1
2013-05-15T01:30:21.927Z,1368581421.927 [Default:GPS:D] Stopped
2013-05-15T01:30:21.928Z,1368581421.928 [Default:GPS](INFO): Completed Default:GPS
2013-05-15T01:30:21.928Z,1368581421.928 [Default:GPS] Stopped
2013-05-15T01:30:21.928Z,1368581421.928 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-05-15T01:30:21.928Z,1368581421.928 [Default:GPS:A.SetSpeed] Stopped
2013-05-15T01:30:21.928Z,1368581421.928 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-05-15T01:32:31.332Z,1368581551.332 [NAL9602](INFO): SBD MO Status=1, MOMSN=12030, MT Status=0, MTMSN=0
2013-05-15T01:32:31.382Z,1368581551.382 [NAL9602](INFO): Sent 70 bytes from file Logs/20130515T000238/Courier0016.lzma
2013-05-15T01:32:31.382Z,1368581551.382 [NAL9602](INFO): Packets left to send: 0
2013-05-15T01:32:31.384Z,1368581551.384 [NAL9602](INFO): Stored copy of sent data in Logs/20130515T000238/Courier0016.lzma.parts/0000.sbd
2013-05-15T01:32:45.307Z,1368581565.307 [NAL9602](INFO): SBD MO Status=2, MOMSN=12031, MT Status=2, MTMSN=0
2013-05-15T01:32:45.308Z,1368581565.308 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:33:13.909Z,1368581593.909 [NAL9602](INFO): SBD MO Status=1, MOMSN=12031, MT Status=0, MTMSN=0
2013-05-15T01:33:13.958Z,1368581593.958 [NAL9602](INFO): Sent 115 bytes from file Logs/20130515T000238/Express0017.lzma
2013-05-15T01:33:13.958Z,1368581593.958 [NAL9602](INFO): Packets left to send: 0
2013-05-15T01:33:13.960Z,1368581593.960 [NAL9602](INFO): Stored copy of sent data in Logs/20130515T000238/Express0017.lzma.parts/0000.sbd
2013-05-15T01:33:21.105Z,1368581601.105 [NAL9602](INFO): SBD MO Status=0, MOMSN=12032, MT Status=0, MTMSN=0
2013-05-15T01:33:24.836Z,1368581604.836 [Default:Iridium:Read_Iridium] Stopped
2013-05-15T01:33:24.836Z,1368581604.836 [Default:Iridium](INFO): Completed Default:Iridium
2013-05-15T01:33:24.836Z,1368581604.836 [Default:Iridium] Stopped
2013-05-15T01:33:24.836Z,1368581604.836 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-05-15T01:33:24.837Z,1368581604.837 [Default:Iridium:A.SetSpeed] Stopped
2013-05-15T01:33:24.837Z,1368581604.837 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-05-15T01:33:25.243Z,1368581605.243 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-05-15T01:33:25.243Z,1368581605.243 [Default:CallIridium:B] Stopped
2013-05-15T01:33:25.243Z,1368581605.243 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-05-15T01:33:25.243Z,1368581605.243 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-05-15T01:33:25.243Z,1368581605.243 [Default:CallIridium] Stopped
2013-05-15T01:33:25.243Z,1368581605.243 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-05-15T01:33:35.220Z,1368581615.220 [NAL9602](INFO): Powering down
2013-05-15T01:38:25.321Z,1368581905.321 [Default:CallIridium] Running Loop=1
2013-05-15T01:38:25.321Z,1368581905.321 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-05-15T01:38:25.321Z,1368581905.321 [Default:CallIridium:A] Running Loop=1
2013-05-15T01:38:25.322Z,1368581905.322 [Default:CallIridium:A] Stopped
2013-05-15T01:38:25.322Z,1368581905.322 [Default:CallIridium:B] Running Loop=1
2013-05-15T01:38:25.322Z,1368581905.322 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-05-15T01:38:30.219Z,1368581910.219 [Default:Iridium] Running Loop=1
2013-05-15T01:38:30.219Z,1368581910.219 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-05-15T01:38:30.219Z,1368581910.219 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-05-15T01:38:30.219Z,1368581910.219 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-05-15T01:38:30.219Z,1368581910.219 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-05-15T01:38:30.219Z,1368581910.219 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-05-15T01:38:30.220Z,1368581910.220 [Default:Iridium:B.GoToSurface] Stopped
2013-05-15T01:38:30.220Z,1368581910.220 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-05-15T01:38:30.220Z,1368581910.220 [Default:Iridium:Read_Iridium] Running Loop=1
2013-05-15T01:38:30.221Z,1368581910.220 [Default:GPS] Running Loop=1
2013-05-15T01:38:30.221Z,1368581910.221 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-05-15T01:38:30.221Z,1368581910.221 [Default:GPS:A.SetSpeed] Running Loop=1
2013-05-15T01:38:30.221Z,1368581910.221 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-05-15T01:38:30.221Z,1368581910.221 [Default:GPS:B.GoToSurface] Running Loop=1
2013-05-15T01:38:30.221Z,1368581910.221 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-05-15T01:38:30.222Z,1368581910.222 [Default:GPS:B.GoToSurface] Stopped
2013-05-15T01:38:30.222Z,1368581910.222 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-05-15T01:38:30.222Z,1368581910.222 [Default:GPS:Read_GPS] Running Loop=1
2013-05-15T01:38:30.830Z,1368581910.830 [NAL9602](INFO): Powering up
2013-05-15T01:38:41.438Z,1368581921.438 [NAL9602](INFO): NAL9602 initialized
2013-05-15T01:39:21.746Z,1368581961.746 [NAL9602](IMPORTANT): GPS fix at: 1368581914.00
2013-05-15T01:39:21.782Z,1368581961.782 [Default:GPS:Read_GPS] Stopped
2013-05-15T01:39:21.782Z,1368581961.782 [Default:GPS:D] Running Loop=1
2013-05-15T01:39:22.157Z,1368581962.157 [Default:GPS:D] Stopped
2013-05-15T01:39:22.157Z,1368581962.157 [Default:GPS](INFO): Completed Default:GPS
2013-05-15T01:39:22.157Z,1368581962.157 [Default:GPS] Stopped
2013-05-15T01:39:22.157Z,1368581962.157 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-05-15T01:39:22.157Z,1368581962.158 [Default:GPS:A.SetSpeed] Stopped
2013-05-15T01:39:22.157Z,1368581962.158 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-05-15T01:40:23.073Z,1368582023.073 [NAL9602](INFO): SBD MO Status=2, MOMSN=12033, MT Status=2, MTMSN=0
2013-05-15T01:40:23.073Z,1368582023.073 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:41:25.781Z,1368582085.781 [NAL9602](INFO): SBD MO Status=1, MOMSN=12033, MT Status=0, MTMSN=0
2013-05-15T01:41:25.830Z,1368582085.830 [NAL9602](INFO): Sent 68 bytes from file Logs/20130515T000238/Courier0020.lzma
2013-05-15T01:41:25.830Z,1368582085.830 [NAL9602](INFO): Packets left to send: 0
2013-05-15T01:41:25.832Z,1368582085.832 [NAL9602](INFO): Stored copy of sent data in Logs/20130515T000238/Courier0020.lzma.parts/0000.sbd
2013-05-15T01:41:34.821Z,1368582094.821 [NAL9602](INFO): SBD MO Status=1, MOMSN=12034, MT Status=0, MTMSN=0
2013-05-15T01:41:34.882Z,1368582094.882 [NAL9602](INFO): Sent 116 bytes from file Logs/20130515T000238/Express0021.lzma
2013-05-15T01:41:34.882Z,1368582094.882 [NAL9602](INFO): Packets left to send: 0
2013-05-15T01:41:34.884Z,1368582094.884 [NAL9602](INFO): Stored copy of sent data in Logs/20130515T000238/Express0021.lzma.parts/0000.sbd
2013-05-15T01:41:42.817Z,1368582102.816 [NAL9602](INFO): SBD MO Status=2, MOMSN=12035, MT Status=2, MTMSN=0
2013-05-15T01:41:42.817Z,1368582102.817 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:41:58.777Z,1368582118.777 [NAL9602](INFO): SBD MO Status=2, MOMSN=12035, MT Status=2, MTMSN=0
2013-05-15T01:41:58.777Z,1368582118.777 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:42:04.029Z,1368582124.029 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T01:42:07.146Z,1368582127.146 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T01:42:26.637Z,1368582146.637 [NAL9602](INFO): SBD MO Status=0, MOMSN=12035, MT Status=0, MTMSN=0
2013-05-15T01:42:30.283Z,1368582150.283 [Default:Iridium:Read_Iridium] Stopped
2013-05-15T01:42:30.284Z,1368582150.284 [Default:Iridium](INFO): Completed Default:Iridium
2013-05-15T01:42:30.284Z,1368582150.284 [Default:Iridium] Stopped
2013-05-15T01:42:30.284Z,1368582150.284 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-05-15T01:42:30.284Z,1368582150.284 [Default:Iridium:A.SetSpeed] Stopped
2013-05-15T01:42:30.284Z,1368582150.284 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-05-15T01:42:30.690Z,1368582150.690 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-05-15T01:42:30.690Z,1368582150.690 [Default:CallIridium:B] Stopped
2013-05-15T01:42:30.690Z,1368582150.690 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-05-15T01:42:30.690Z,1368582150.690 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-05-15T01:42:30.691Z,1368582150.691 [Default:CallIridium] Stopped
2013-05-15T01:42:30.691Z,1368582150.691 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-05-15T01:42:40.700Z,1368582160.700 [NAL9602](INFO): Powering down
2013-05-15T01:47:30.714Z,1368582450.714 [Default:CallIridium] Running Loop=1
2013-05-15T01:47:30.715Z,1368582450.714 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-05-15T01:47:30.715Z,1368582450.715 [Default:CallIridium:A] Running Loop=1
2013-05-15T01:47:30.715Z,1368582450.715 [Default:CallIridium:A] Stopped
2013-05-15T01:47:30.715Z,1368582450.715 [Default:CallIridium:B] Running Loop=1
2013-05-15T01:47:30.715Z,1368582450.715 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-05-15T01:47:35.651Z,1368582455.651 [Default:Iridium] Running Loop=1
2013-05-15T01:47:35.651Z,1368582455.651 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-05-15T01:47:35.651Z,1368582455.651 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-05-15T01:47:35.651Z,1368582455.651 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-05-15T01:47:35.651Z,1368582455.651 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-05-15T01:47:35.651Z,1368582455.651 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-05-15T01:47:35.652Z,1368582455.652 [Default:Iridium:B.GoToSurface] Stopped
2013-05-15T01:47:35.652Z,1368582455.652 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-05-15T01:47:35.652Z,1368582455.652 [Default:Iridium:Read_Iridium] Running Loop=1
2013-05-15T01:47:35.653Z,1368582455.653 [Default:GPS] Running Loop=1
2013-05-15T01:47:35.653Z,1368582455.653 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-05-15T01:47:35.653Z,1368582455.653 [Default:GPS:A.SetSpeed] Running Loop=1
2013-05-15T01:47:35.653Z,1368582455.653 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-05-15T01:47:35.653Z,1368582455.653 [Default:GPS:B.GoToSurface] Running Loop=1
2013-05-15T01:47:35.653Z,1368582455.653 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-05-15T01:47:35.654Z,1368582455.654 [Default:GPS:B.GoToSurface] Stopped
2013-05-15T01:47:35.654Z,1368582455.654 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-05-15T01:47:35.655Z,1368582455.655 [Default:GPS:Read_GPS] Running Loop=1
2013-05-15T01:47:36.274Z,1368582456.274 [NAL9602](INFO): Powering up
2013-05-15T01:47:46.942Z,1368582466.942 [NAL9602](INFO): NAL9602 initialized
2013-05-15T01:48:21.334Z,1368582501.334 [NAL9602](IMPORTANT): GPS fix at: 1368582455.00
2013-05-15T01:48:21.350Z,1368582501.350 [Default:GPS:Read_GPS] Stopped
2013-05-15T01:48:21.350Z,1368582501.350 [Default:GPS:D] Running Loop=1
2013-05-15T01:48:21.779Z,1368582501.779 [Default:GPS:D] Stopped
2013-05-15T01:48:21.779Z,1368582501.779 [Default:GPS](INFO): Completed Default:GPS
2013-05-15T01:48:21.779Z,1368582501.779 [Default:GPS] Stopped
2013-05-15T01:48:21.779Z,1368582501.779 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-05-15T01:48:21.779Z,1368582501.779 [Default:GPS:A.SetSpeed] Stopped
2013-05-15T01:48:21.780Z,1368582501.780 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-05-15T01:50:41.319Z,1368582641.319 [NAL9602](INFO): SBD MO Status=1, MOMSN=12036, MT Status=0, MTMSN=0
2013-05-15T01:50:41.369Z,1368582641.369 [NAL9602](INFO): Sent 68 bytes from file Logs/20130515T000238/Courier0024.lzma
2013-05-15T01:50:41.370Z,1368582641.369 [NAL9602](INFO): Packets left to send: 0
2013-05-15T01:50:41.371Z,1368582641.371 [NAL9602](INFO): Stored copy of sent data in Logs/20130515T000238/Courier0024.lzma.parts/0000.sbd
2013-05-15T01:51:07.813Z,1368582667.813 [NAL9602](INFO): SBD MO Status=2, MOMSN=12037, MT Status=2, MTMSN=0
2013-05-15T01:51:07.814Z,1368582667.814 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:51:36.825Z,1368582696.825 [NAL9602](INFO): SBD MO Status=2, MOMSN=12037, MT Status=2, MTMSN=0
2013-05-15T01:51:36.825Z,1368582696.825 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T01:51:56.179Z,1368582716.179 [NAL9602](INFO): SBD MO Status=1, MOMSN=12037, MT Status=0, MTMSN=0
2013-05-15T01:51:56.230Z,1368582716.230 [NAL9602](INFO): Sent 104 bytes from file Logs/20130515T000238/Express0025.lzma
2013-05-15T01:51:56.230Z,1368582716.230 [NAL9602](INFO): Packets left to send: 0
2013-05-15T01:51:56.232Z,1368582716.232 [NAL9602](INFO): Stored copy of sent data in Logs/20130515T000238/Express0025.lzma.parts/0000.sbd
2013-05-15T01:52:04.184Z,1368582724.184 [NAL9602](INFO): SBD MO Status=0, MOMSN=12038, MT Status=0, MTMSN=0
2013-05-15T01:52:07.825Z,1368582727.825 [Default:Iridium:Read_Iridium] Stopped
2013-05-15T01:52:07.825Z,1368582727.825 [Default:Iridium](INFO): Completed Default:Iridium
2013-05-15T01:52:07.825Z,1368582727.825 [Default:Iridium] Stopped
2013-05-15T01:52:07.825Z,1368582727.825 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-05-15T01:52:07.825Z,1368582727.825 [Default:Iridium:A.SetSpeed] Stopped
2013-05-15T01:52:07.826Z,1368582727.826 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-05-15T01:52:08.223Z,1368582728.223 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-05-15T01:52:08.223Z,1368582728.223 [Default:CallIridium:B] Stopped
2013-05-15T01:52:08.223Z,1368582728.223 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-05-15T01:52:08.223Z,1368582728.223 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-05-15T01:52:08.223Z,1368582728.224 [Default:CallIridium] Stopped
2013-05-15T01:52:08.224Z,1368582728.224 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-05-15T01:52:18.220Z,1368582738.220 [NAL9602](INFO): Powering down
2013-05-15T01:57:08.197Z,1368583028.197 [Default:CallIridium] Running Loop=1
2013-05-15T01:57:08.198Z,1368583028.198 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-05-15T01:57:08.198Z,1368583028.198 [Default:CallIridium:A] Running Loop=1
2013-05-15T01:57:08.198Z,1368583028.198 [Default:CallIridium:A] Stopped
2013-05-15T01:57:08.198Z,1368583028.198 [Default:CallIridium:B] Running Loop=1
2013-05-15T01:57:08.199Z,1368583028.199 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-05-15T01:57:13.391Z,1368583033.391 [Default:Iridium] Running Loop=1
2013-05-15T01:57:13.391Z,1368583033.391 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-05-15T01:57:13.391Z,1368583033.391 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-05-15T01:57:13.391Z,1368583033.391 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-05-15T01:57:13.391Z,1368583033.391 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-05-15T01:57:13.391Z,1368583033.391 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-05-15T01:57:13.392Z,1368583033.392 [Default:Iridium:B.GoToSurface] Stopped
2013-05-15T01:57:13.392Z,1368583033.392 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-05-15T01:57:13.392Z,1368583033.392 [Default:Iridium:Read_Iridium] Running Loop=1
2013-05-15T01:57:13.393Z,1368583033.393 [Default:GPS] Running Loop=1
2013-05-15T01:57:13.393Z,1368583033.393 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-05-15T01:57:13.393Z,1368583033.393 [Default:GPS:A.SetSpeed] Running Loop=1
2013-05-15T01:57:13.393Z,1368583033.393 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-05-15T01:57:13.393Z,1368583033.393 [Default:GPS:B.GoToSurface] Running Loop=1
2013-05-15T01:57:13.393Z,1368583033.393 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-05-15T01:57:13.394Z,1368583033.394 [Default:GPS:B.GoToSurface] Stopped
2013-05-15T01:57:13.394Z,1368583033.394 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-05-15T01:57:13.394Z,1368583033.395 [Default:GPS:Read_GPS] Running Loop=1
2013-05-15T01:57:13.983Z,1368583033.983 [NAL9602](INFO): Powering up
2013-05-15T01:57:24.469Z,1368583044.469 [NAL9602](INFO): NAL9602 initialized
2013-05-15T01:57:46.498Z,1368583066.498 [NAL9602](IMPORTANT): GPS fix at: 1368583021.99
2013-05-15T01:57:46.514Z,1368583066.514 [Default:GPS:Read_GPS] Stopped
2013-05-15T01:57:46.514Z,1368583066.514 [Default:GPS:D] Running Loop=1
2013-05-15T01:57:46.921Z,1368583066.921 [Default:GPS:D] Stopped
2013-05-15T01:57:46.921Z,1368583066.921 [Default:GPS](INFO): Completed Default:GPS
2013-05-15T01:57:46.921Z,1368583066.921 [Default:GPS] Stopped
2013-05-15T01:57:46.921Z,1368583066.921 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-05-15T01:57:46.922Z,1368583066.921 [Default:GPS:A.SetSpeed] Stopped
2013-05-15T01:57:46.922Z,1368583066.922 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-05-15T01:59:20.613Z,1368583160.612 [NAL9602](INFO): SBD MO Status=1, MOMSN=12039, MT Status=0, MTMSN=0
2013-05-15T01:59:20.662Z,1368583160.662 [NAL9602](INFO): Sent 70 bytes from file Logs/20130515T000238/Courier0028.lzma
2013-05-15T01:59:20.663Z,1368583160.662 [NAL9602](INFO): Packets left to send: 0
2013-05-15T01:59:20.664Z,1368583160.664 [NAL9602](INFO): Stored copy of sent data in Logs/20130515T000238/Courier0028.lzma.parts/0000.sbd
2013-05-15T01:59:40.477Z,1368583180.477 [NAL9602](INFO): SBD MO Status=2, MOMSN=12040, MT Status=2, MTMSN=0
2013-05-15T01:59:40.477Z,1368583180.477 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T02:01:06.637Z,1368583266.638 [NAL9602](INFO): SBD MO Status=1, MOMSN=12040, MT Status=0, MTMSN=0
2013-05-15T02:01:06.683Z,1368583266.682 [NAL9602](INFO): Sent 117 bytes from file Logs/20130515T000238/Express0029.lzma
2013-05-15T02:01:06.683Z,1368583266.683 [NAL9602](INFO): Packets left to send: 0
2013-05-15T02:01:06.684Z,1368583266.684 [NAL9602](INFO): Stored copy of sent data in Logs/20130515T000238/Express0029.lzma.parts/0000.sbd
2013-05-15T02:01:44.101Z,1368583304.101 [NAL9602](INFO): SBD MO Status=2, MOMSN=12041, MT Status=2, MTMSN=0
2013-05-15T02:01:44.101Z,1368583304.101 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T02:01:51.984Z,1368583311.984 [NAL9602](INFO): SBD MO Status=0, MOMSN=12041, MT Status=0, MTMSN=0
2013-05-15T02:01:55.707Z,1368583315.707 [Default:Iridium:Read_Iridium] Stopped
2013-05-15T02:01:55.708Z,1368583315.708 [Default:Iridium](INFO): Completed Default:Iridium
2013-05-15T02:01:55.708Z,1368583315.708 [Default:Iridium] Stopped
2013-05-15T02:01:55.708Z,1368583315.708 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-05-15T02:01:55.708Z,1368583315.708 [Default:Iridium:A.SetSpeed] Stopped
2013-05-15T02:01:55.708Z,1368583315.708 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-05-15T02:01:56.086Z,1368583316.086 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-05-15T02:01:56.086Z,1368583316.086 [Default:CallIridium:B] Stopped
2013-05-15T02:01:56.086Z,1368583316.086 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-05-15T02:01:56.087Z,1368583316.087 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-05-15T02:01:56.087Z,1368583316.087 [Default:CallIridium] Stopped
2013-05-15T02:01:56.087Z,1368583316.087 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-05-15T02:02:06.035Z,1368583326.035 [NAL9602](INFO): Powering down
2013-05-15T02:02:11.117Z,1368583331.117 [Radio_Freewave](FAULT): LCB fault:  Invalid Command. Hardware Overcurrent Shutdown. Current Limiter Activated.
2013-05-15T02:02:11.117Z,1368583331.118 [Radio_Freewave] Hardware Fault, FailCount= 1
2013-05-15T02:02:11.117Z,1368583331.118 [Radio_Freewave](ERROR): Hardware Fault
2013-05-15T02:02:11.138Z,1368583331.138 [CBIT](ERROR): Hardware Fault in component: Radio_Freewave
2013-05-15T02:02:16.080Z,1368583336.080 [Radio_Freewave](INFO): Powering down
2013-05-15T02:02:21.053Z,1368583341.053 [Radio_Freewave](FAULT): LCB fault:  Software Overcurrent.
2013-05-15T02:02:26.084Z,1368583346.084 [Radio_Freewave](INFO): Powering up
2013-05-15T02:02:26.102Z,1368583346.102 [CBIT](INFO): Clearing failed state for component Radio_Freewave
2013-05-15T02:02:26.102Z,1368583346.102 [Radio_Freewave] No Fault, FailCount= 1
2013-05-15T02:06:56.243Z,1368583616.243 [Default:CallIridium] Running Loop=1
2013-05-15T02:06:56.243Z,1368583616.243 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-05-15T02:06:56.243Z,1368583616.243 [Default:CallIridium:A] Running Loop=1
2013-05-15T02:06:56.244Z,1368583616.244 [Default:CallIridium:A] Stopped
2013-05-15T02:06:56.244Z,1368583616.244 [Default:CallIridium:B] Running Loop=1
2013-05-15T02:06:56.244Z,1368583616.244 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-05-15T02:07:01.286Z,1368583621.286 [Default:Iridium] Running Loop=1
2013-05-15T02:07:01.286Z,1368583621.286 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-05-15T02:07:01.286Z,1368583621.286 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-05-15T02:07:01.286Z,1368583621.286 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-05-15T02:07:01.287Z,1368583621.287 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-05-15T02:07:01.287Z,1368583621.287 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-05-15T02:07:01.287Z,1368583621.287 [Default:Iridium:B.GoToSurface] Stopped
2013-05-15T02:07:01.287Z,1368583621.287 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-05-15T02:07:01.288Z,1368583621.288 [Default:Iridium:Read_Iridium] Running Loop=1
2013-05-15T02:07:01.288Z,1368583621.288 [Default:GPS] Running Loop=1
2013-05-15T02:07:01.288Z,1368583621.288 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-05-15T02:07:01.288Z,1368583621.288 [Default:GPS:A.SetSpeed] Running Loop=1
2013-05-15T02:07:01.288Z,1368583621.288 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-05-15T02:07:01.289Z,1368583621.289 [Default:GPS:B.GoToSurface] Running Loop=1
2013-05-15T02:07:01.289Z,1368583621.289 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-05-15T02:07:01.289Z,1368583621.289 [Default:GPS:B.GoToSurface] Stopped
2013-05-15T02:07:01.289Z,1368583621.289 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-05-15T02:07:01.289Z,1368583621.289 [Default:GPS:Read_GPS] Running Loop=1
2013-05-15T02:07:01.939Z,1368583621.939 [NAL9602](INFO): Powering up
2013-05-15T02:07:12.800Z,1368583632.800 [NAL9602](INFO): NAL9602 initialized
2013-05-15T02:07:58.626Z,1368583678.626 [NAL9602](IMPORTANT): GPS fix at: 1368583634.00
2013-05-15T02:07:58.641Z,1368583678.641 [Default:GPS:Read_GPS] Stopped
2013-05-15T02:07:58.642Z,1368583678.642 [Default:GPS:D] Running Loop=1
2013-05-15T02:07:59.045Z,1368583679.045 [Default:GPS:D] Stopped
2013-05-15T02:07:59.045Z,1368583679.045 [Default:GPS](INFO): Completed Default:GPS
2013-05-15T02:07:59.045Z,1368583679.045 [Default:GPS] Stopped
2013-05-15T02:07:59.045Z,1368583679.046 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-05-15T02:07:59.046Z,1368583679.046 [Default:GPS:A.SetSpeed] Stopped
2013-05-15T02:07:59.046Z,1368583679.046 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-05-15T02:08:26.411Z,1368583706.411 [NAL9602](INFO): SBD MO Status=2, MOMSN=12042, MT Status=2, MTMSN=0
2013-05-15T02:08:26.411Z,1368583706.411 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T02:10:26.489Z,1368583826.489 [NAL9602](INFO): SBD MO Status=1, MOMSN=12042, MT Status=0, MTMSN=0
2013-05-15T02:10:26.538Z,1368583826.538 [NAL9602](INFO): Sent 69 bytes from file Logs/20130515T000238/Courier0032.lzma
2013-05-15T02:10:26.538Z,1368583826.538 [NAL9602](INFO): Packets left to send: 0
2013-05-15T02:10:26.540Z,1368583826.540 [NAL9602](INFO): Stored copy of sent data in Logs/20130515T000238/Courier0032.lzma.parts/0000.sbd
2013-05-15T02:10:52.731Z,1368583852.731 [NAL9602](INFO): SBD MO Status=2, MOMSN=12043, MT Status=2, MTMSN=0
2013-05-15T02:10:52.731Z,1368583852.731 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T02:11:11.601Z,1368583871.601 [NAL9602](INFO): SBD MO Status=1, MOMSN=12043, MT Status=0, MTMSN=0
2013-05-15T02:11:11.650Z,1368583871.650 [NAL9602](INFO): Sent 243 bytes from file Logs/20130515T000238/Express0033.lzma
2013-05-15T02:11:11.650Z,1368583871.650 [NAL9602](INFO): Packets left to send: 0
2013-05-15T02:11:11.652Z,1368583871.652 [NAL9602](INFO): Stored copy of sent data in Logs/20130515T000238/Express0033.lzma.parts/0000.sbd
2013-05-15T02:11:21.193Z,1368583881.193 [NAL9602](INFO): SBD MO Status=0, MOMSN=12044, MT Status=0, MTMSN=0
2013-05-15T02:11:24.914Z,1368583884.914 [Default:Iridium:Read_Iridium] Stopped
2013-05-15T02:11:24.915Z,1368583884.915 [Default:Iridium](INFO): Completed Default:Iridium
2013-05-15T02:11:24.915Z,1368583884.915 [Default:Iridium] Stopped
2013-05-15T02:11:24.915Z,1368583884.915 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-05-15T02:11:24.915Z,1368583884.915 [Default:Iridium:A.SetSpeed] Stopped
2013-05-15T02:11:24.915Z,1368583884.915 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-05-15T02:11:25.319Z,1368583885.319 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-05-15T02:11:25.319Z,1368583885.319 [Default:CallIridium:B] Stopped
2013-05-15T02:11:25.319Z,1368583885.319 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-05-15T02:11:25.319Z,1368583885.319 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-05-15T02:11:25.320Z,1368583885.320 [Default:CallIridium] Stopped
2013-05-15T02:11:25.320Z,1368583885.320 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-05-15T02:11:35.300Z,1368583895.300 [NAL9602](INFO): Powering down
2013-05-15T02:16:25.540Z,1368584185.540 [Default:CallIridium] Running Loop=1
2013-05-15T02:16:25.540Z,1368584185.540 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-05-15T02:16:25.540Z,1368584185.540 [Default:CallIridium:A] Running Loop=1
2013-05-15T02:16:25.541Z,1368584185.541 [Default:CallIridium:A] Stopped
2013-05-15T02:16:25.541Z,1368584185.541 [Default:CallIridium:B] Running Loop=1
2013-05-15T02:16:25.541Z,1368584185.541 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-05-15T02:16:30.295Z,1368584190.295 [Default:Iridium] Running Loop=1
2013-05-15T02:16:30.295Z,1368584190.295 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-05-15T02:16:30.295Z,1368584190.295 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-05-15T02:16:30.296Z,1368584190.296 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-05-15T02:16:30.296Z,1368584190.296 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-05-15T02:16:30.296Z,1368584190.296 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-05-15T02:16:30.296Z,1368584190.296 [Default:Iridium:B.GoToSurface] Stopped
2013-05-15T02:16:30.297Z,1368584190.297 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-05-15T02:16:30.297Z,1368584190.297 [Default:Iridium:Read_Iridium] Running Loop=1
2013-05-15T02:16:30.297Z,1368584190.297 [Default:GPS] Running Loop=1
2013-05-15T02:16:30.297Z,1368584190.297 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-05-15T02:16:30.297Z,1368584190.297 [Default:GPS:A.SetSpeed] Running Loop=1
2013-05-15T02:16:30.297Z,1368584190.297 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-05-15T02:16:30.298Z,1368584190.297 [Default:GPS:B.GoToSurface] Running Loop=1
2013-05-15T02:16:30.298Z,1368584190.298 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-05-15T02:16:30.299Z,1368584190.299 [Default:GPS:B.GoToSurface] Stopped
2013-05-15T02:16:30.299Z,1368584190.299 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-05-15T02:16:30.299Z,1368584190.299 [Default:GPS:Read_GPS] Running Loop=1
2013-05-15T02:16:30.908Z,1368584190.908 [NAL9602](INFO): Powering up
2013-05-15T02:16:41.958Z,1368584201.958 [NAL9602](INFO): NAL9602 initialized
2013-05-15T02:17:18.178Z,1368584238.178 [NAL9602](IMPORTANT): GPS fix at: 1368584194.00
2013-05-15T02:17:18.193Z,1368584238.193 [Default:GPS:Read_GPS] Stopped
2013-05-15T02:17:18.193Z,1368584238.193 [Default:GPS:D] Running Loop=1
2013-05-15T02:17:18.607Z,1368584238.607 [Default:GPS:D] Stopped
2013-05-15T02:17:18.607Z,1368584238.607 [Default:GPS](INFO): Completed Default:GPS
2013-05-15T02:17:18.607Z,1368584238.607 [Default:GPS] Stopped
2013-05-15T02:17:18.607Z,1368584238.607 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-05-15T02:17:18.608Z,1368584238.608 [Default:GPS:A.SetSpeed] Stopped
2013-05-15T02:17:18.608Z,1368584238.608 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-05-15T02:19:46.113Z,1368584386.113 [NAL9602](INFO): SBD MO Status=2, MOMSN=12045, MT Status=2, MTMSN=0
2013-05-15T02:19:46.113Z,1368584386.113 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T02:20:32.509Z,1368584432.509 [NAL9602](INFO): SBD MO Status=1, MOMSN=12045, MT Status=0, MTMSN=0
2013-05-15T02:20:32.558Z,1368584432.558 [NAL9602](INFO): Sent 68 bytes from file Logs/20130515T000238/Courier0036.lzma
2013-05-15T02:20:32.558Z,1368584432.558 [NAL9602](INFO): Packets left to send: 0
2013-05-15T02:20:32.560Z,1368584432.560 [NAL9602](INFO): Stored copy of sent data in Logs/20130515T000238/Courier0036.lzma.parts/0000.sbd
2013-05-15T02:20:49.241Z,1368584449.240 [NAL9602](INFO): SBD MO Status=2, MOMSN=12046, MT Status=2, MTMSN=0
2013-05-15T02:20:49.241Z,1368584449.241 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T02:22:48.705Z,1368584568.705 [NAL9602](INFO): SBD MO Status=1, MOMSN=12046, MT Status=0, MTMSN=0
2013-05-15T02:22:48.754Z,1368584568.754 [NAL9602](INFO): Sent 115 bytes from file Logs/20130515T000238/Express0037.lzma
2013-05-15T02:22:48.754Z,1368584568.754 [NAL9602](INFO): Packets left to send: 0
2013-05-15T02:22:48.756Z,1368584568.756 [NAL9602](INFO): Stored copy of sent data in Logs/20130515T000238/Express0037.lzma.parts/0000.sbd
2013-05-15T02:22:59.505Z,1368584579.505 [NAL9602](INFO): SBD MO Status=0, MOMSN=12047, MT Status=0, MTMSN=0
2013-05-15T02:23:03.349Z,1368584583.349 [Default:Iridium:Read_Iridium] Stopped
2013-05-15T02:23:03.350Z,1368584583.350 [Default:Iridium](INFO): Completed Default:Iridium
2013-05-15T02:23:03.350Z,1368584583.350 [Default:Iridium] Stopped
2013-05-15T02:23:03.350Z,1368584583.351 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-05-15T02:23:03.351Z,1368584583.351 [Default:Iridium:A.SetSpeed] Stopped
2013-05-15T02:23:03.351Z,1368584583.351 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-05-15T02:23:03.760Z,1368584583.760 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-05-15T02:23:03.760Z,1368584583.760 [Default:CallIridium:B] Stopped
2013-05-15T02:23:03.760Z,1368584583.760 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-05-15T02:23:03.760Z,1368584583.760 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-05-15T02:23:03.760Z,1368584583.760 [Default:CallIridium] Stopped
2013-05-15T02:23:03.760Z,1368584583.760 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-05-15T02:23:13.736Z,1368584593.736 [NAL9602](INFO): Powering down
2013-05-15T02:28:03.726Z,1368584883.726 [Default:CallIridium] Running Loop=1
2013-05-15T02:28:03.726Z,1368584883.726 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-05-15T02:28:03.727Z,1368584883.727 [Default:CallIridium:A] Running Loop=1
2013-05-15T02:28:03.727Z,1368584883.727 [Default:CallIridium:A] Stopped
2013-05-15T02:28:03.727Z,1368584883.727 [Default:CallIridium:B] Running Loop=1
2013-05-15T02:28:03.727Z,1368584883.727 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-05-15T02:28:08.747Z,1368584888.747 [Default:Iridium] Running Loop=1
2013-05-15T02:28:08.747Z,1368584888.747 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-05-15T02:28:08.747Z,1368584888.747 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-05-15T02:28:08.747Z,1368584888.747 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-05-15T02:28:08.748Z,1368584888.747 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-05-15T02:28:08.748Z,1368584888.748 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-05-15T02:28:08.748Z,1368584888.748 [Default:Iridium:B.GoToSurface] Stopped
2013-05-15T02:28:08.748Z,1368584888.748 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-05-15T02:28:08.748Z,1368584888.749 [Default:Iridium:Read_Iridium] Running Loop=1
2013-05-15T02:28:08.749Z,1368584888.749 [Default:GPS] Running Loop=1
2013-05-15T02:28:08.749Z,1368584888.749 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-05-15T02:28:08.749Z,1368584888.749 [Default:GPS:A.SetSpeed] Running Loop=1
2013-05-15T02:28:08.749Z,1368584888.749 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-05-15T02:28:08.749Z,1368584888.749 [Default:GPS:B.GoToSurface] Running Loop=1
2013-05-15T02:28:08.749Z,1368584888.749 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-05-15T02:28:08.750Z,1368584888.750 [Default:GPS:B.GoToSurface] Stopped
2013-05-15T02:28:08.750Z,1368584888.750 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-05-15T02:28:08.751Z,1368584888.751 [Default:GPS:Read_GPS] Running Loop=1
2013-05-15T02:28:09.347Z,1368584889.347 [NAL9602](INFO): Powering up
2013-05-15T02:28:20.250Z,1368584900.250 [NAL9602](INFO): NAL9602 initialized
2013-05-15T02:29:15.519Z,1368584955.519 [NAL9602](IMPORTANT): GPS fix at: 1368584913.00
2013-05-15T02:29:15.534Z,1368584955.534 [Default:GPS:Read_GPS] Stopped
2013-05-15T02:29:15.535Z,1368584955.534 [Default:GPS:D] Running Loop=1
2013-05-15T02:29:15.989Z,1368584955.989 [Default:GPS:D] Stopped
2013-05-15T02:29:15.990Z,1368584955.990 [Default:GPS](INFO): Completed Default:GPS
2013-05-15T02:29:15.990Z,1368584955.990 [Default:GPS] Stopped
2013-05-15T02:29:15.990Z,1368584955.990 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-05-15T02:29:15.990Z,1368584955.990 [Default:GPS:A.SetSpeed] Stopped
2013-05-15T02:29:15.990Z,1368584955.990 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-05-15T02:31:51.708Z,1368585111.708 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#3 STATUS: 65535
2013-05-15T02:32:08.713Z,1368585128.713 [NAL9602](INFO): SBD MO Status=1, MOMSN=12048, MT Status=0, MTMSN=0
2013-05-15T02:32:08.762Z,1368585128.762 [NAL9602](INFO): Sent 70 bytes from file Logs/20130515T000238/Courier0040.lzma
2013-05-15T02:32:08.762Z,1368585128.762 [NAL9602](INFO): Packets left to send: 0
2013-05-15T02:32:08.764Z,1368585128.764 [NAL9602](INFO): Stored copy of sent data in Logs/20130515T000238/Courier0040.lzma.parts/0000.sbd
2013-05-15T02:32:21.812Z,1368585141.812 [NAL9602](INFO): SBD MO Status=1, MOMSN=12049, MT Status=0, MTMSN=0
2013-05-15T02:32:21.862Z,1368585141.862 [NAL9602](INFO): Sent 115 bytes from file Logs/20130515T000238/Express0041.lzma
2013-05-15T02:32:21.862Z,1368585141.862 [NAL9602](INFO): Packets left to send: 0
2013-05-15T02:32:21.864Z,1368585141.864 [NAL9602](INFO): Stored copy of sent data in Logs/20130515T000238/Express0041.lzma.parts/0000.sbd
2013-05-15T02:32:53.413Z,1368585173.413 [NAL9602](INFO): SBD MO Status=2, MOMSN=12050, MT Status=2, MTMSN=0
2013-05-15T02:32:53.413Z,1368585173.413 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T02:32:58.632Z,1368585178.632 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:33:00.957Z,1368585180.957 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:33:05.286Z,1368585185.286 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:33:07.639Z,1368585187.639 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:33:11.933Z,1368585191.933 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:33:14.245Z,1368585194.246 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:33:18.633Z,1368585198.633 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:33:22.138Z,1368585202.138 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:33:30.428Z,1368585210.428 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:33:39.292Z,1368585219.293 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:33:43.639Z,1368585223.639 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:33:58.637Z,1368585238.637 [NAL9602](INFO): SBD MO Status=0, MOMSN=12050, MT Status=0, MTMSN=0
2013-05-15T02:34:02.283Z,1368585242.283 [Default:Iridium:Read_Iridium] Stopped
2013-05-15T02:34:02.284Z,1368585242.284 [Default:Iridium](INFO): Completed Default:Iridium
2013-05-15T02:34:02.284Z,1368585242.284 [Default:Iridium] Stopped
2013-05-15T02:34:02.284Z,1368585242.284 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-05-15T02:34:02.284Z,1368585242.284 [Default:Iridium:A.SetSpeed] Stopped
2013-05-15T02:34:02.284Z,1368585242.284 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-05-15T02:34:02.623Z,1368585242.623 [Depth_Keller](ERROR): Pressure reading out of range: 1830.564087 decibar
2013-05-15T02:34:02.639Z,1368585242.639 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-05-15T02:34:02.639Z,1368585242.639 [Default:CallIridium:B] Stopped
2013-05-15T02:34:02.640Z,1368585242.639 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-05-15T02:34:02.640Z,1368585242.640 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-05-15T02:34:02.640Z,1368585242.640 [Default:CallIridium] Stopped
2013-05-15T02:34:02.640Z,1368585242.640 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-05-15T02:34:12.648Z,1368585252.648 [NAL9602](INFO): Powering down
2013-05-15T02:39:02.662Z,1368585542.662 [Default:CallIridium] Running Loop=1
2013-05-15T02:39:02.662Z,1368585542.662 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-05-15T02:39:02.663Z,1368585542.663 [Default:CallIridium:A] Running Loop=1
2013-05-15T02:39:02.663Z,1368585542.663 [Default:CallIridium:A] Stopped
2013-05-15T02:39:02.663Z,1368585542.663 [Default:CallIridium:B] Running Loop=1
2013-05-15T02:39:02.663Z,1368585542.663 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-05-15T02:39:07.662Z,1368585547.662 [Default:Iridium] Running Loop=1
2013-05-15T02:39:07.663Z,1368585547.662 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-05-15T02:39:07.663Z,1368585547.663 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-05-15T02:39:07.663Z,1368585547.663 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-05-15T02:39:07.663Z,1368585547.663 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-05-15T02:39:07.663Z,1368585547.663 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-05-15T02:39:07.664Z,1368585547.664 [Default:Iridium:B.GoToSurface] Stopped
2013-05-15T02:39:07.664Z,1368585547.664 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-05-15T02:39:07.664Z,1368585547.664 [Default:Iridium:Read_Iridium] Running Loop=1
2013-05-15T02:39:07.664Z,1368585547.664 [Default:GPS] Running Loop=1
2013-05-15T02:39:07.664Z,1368585547.664 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-05-15T02:39:07.664Z,1368585547.664 [Default:GPS:A.SetSpeed] Running Loop=1
2013-05-15T02:39:07.664Z,1368585547.664 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-05-15T02:39:07.665Z,1368585547.665 [Default:GPS:B.GoToSurface] Running Loop=1
2013-05-15T02:39:07.665Z,1368585547.665 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-05-15T02:39:07.665Z,1368585547.665 [Default:GPS:B.GoToSurface] Stopped
2013-05-15T02:39:07.665Z,1368585547.665 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-05-15T02:39:07.682Z,1368585547.682 [Default:GPS:Read_GPS] Running Loop=1
2013-05-15T02:39:08.279Z,1368585548.279 [NAL9602](INFO): Powering up
2013-05-15T02:39:19.110Z,1368585559.110 [NAL9602](INFO): NAL9602 initialized
2013-05-15T02:39:50.002Z,1368585590.002 [NAL9602](IMPORTANT): GPS fix at: 1368585548.00
2013-05-15T02:39:50.027Z,1368585590.027 [Default:GPS:Read_GPS] Stopped
2013-05-15T02:39:50.027Z,1368585590.027 [Default:GPS:D] Running Loop=1
2013-05-15T02:39:50.442Z,1368585590.442 [Default:GPS:D] Stopped
2013-05-15T02:39:50.443Z,1368585590.443 [Default:GPS](INFO): Completed Default:GPS
2013-05-15T02:39:50.443Z,1368585590.443 [Default:GPS] Stopped
2013-05-15T02:39:50.443Z,1368585590.443 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-05-15T02:39:50.443Z,1368585590.443 [Default:GPS:A.SetSpeed] Stopped
2013-05-15T02:39:50.443Z,1368585590.443 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-05-15T02:42:12.772Z,1368585732.772 [NAL9602](INFO): SBD MO Status=2, MOMSN=12051, MT Status=2, MTMSN=0
2013-05-15T02:42:12.772Z,1368585732.772 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T02:43:09.452Z,1368585789.452 [NAL9602](INFO): SBD MO Status=2, MOMSN=12051, MT Status=2, MTMSN=0
2013-05-15T02:43:09.453Z,1368585789.453 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T02:44:30.052Z,1368585870.052 [NAL9602](INFO): SBD MO Status=2, MOMSN=12051, MT Status=2, MTMSN=0
2013-05-15T02:44:30.053Z,1368585870.053 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T02:45:11.547Z,1368585911.547 [NAL9602](INFO): SBD MO Status=2, MOMSN=12051, MT Status=2, MTMSN=0
2013-05-15T02:45:11.548Z,1368585911.548 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T02:46:56.113Z,1368586016.113 [NAL9602](INFO): SBD MO Status=2, MOMSN=12051, MT Status=2, MTMSN=0
2013-05-15T02:46:56.113Z,1368586016.113 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T02:51:08.313Z,1368586268.313 [NAL9602](INFO): SBD MO Status=2, MOMSN=12051, MT Status=2, MTMSN=0
2013-05-15T02:51:08.313Z,1368586268.313 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T02:51:51.525Z,1368586311.525 [NAL9602](INFO): SBD MO Status=2, MOMSN=12051, MT Status=2, MTMSN=0
2013-05-15T02:51:51.525Z,1368586311.525 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T02:52:13.320Z,1368586333.320 [NAL9602](INFO): SBD MO Status=1, MOMSN=12051, MT Status=0, MTMSN=0
2013-05-15T02:52:13.374Z,1368586333.374 [NAL9602](INFO): Sent 68 bytes from file Logs/20130515T000238/Courier0044.lzma
2013-05-15T02:52:13.374Z,1368586333.374 [NAL9602](INFO): Packets left to send: 0
2013-05-15T02:52:13.376Z,1368586333.376 [NAL9602](INFO): Stored copy of sent data in Logs/20130515T000238/Courier0044.lzma.parts/0000.sbd
2013-05-15T02:52:23.257Z,1368586343.257 [NAL9602](INFO): SBD MO Status=1, MOMSN=12052, MT Status=0, MTMSN=0
2013-05-15T02:52:23.310Z,1368586343.310 [NAL9602](INFO): Sent 115 bytes from file Logs/20130515T000238/Express0045.lzma
2013-05-15T02:52:23.310Z,1368586343.310 [NAL9602](INFO): Packets left to send: 0
2013-05-15T02:52:23.312Z,1368586343.312 [NAL9602](INFO): Stored copy of sent data in Logs/20130515T000238/Express0045.lzma.parts/0000.sbd
2013-05-15T02:52:54.057Z,1368586374.057 [NAL9602](INFO): SBD MO Status=2, MOMSN=12053, MT Status=2, MTMSN=0
2013-05-15T02:52:54.057Z,1368586374.057 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T02:52:58.832Z,1368586378.832 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:53:28.393Z,1368586408.393 [NAL9602](INFO): SBD MO Status=2, MOMSN=12053, MT Status=2, MTMSN=0
2013-05-15T02:53:28.393Z,1368586408.393 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T02:53:33.698Z,1368586413.698 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:53:35.929Z,1368586415.929 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:53:40.308Z,1368586420.308 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:53:42.602Z,1368586422.602 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:53:46.941Z,1368586426.941 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:53:57.260Z,1368586437.260 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:54:06.085Z,1368586446.085 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:54:14.425Z,1368586454.425 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:54:24.779Z,1368586464.779 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:54:46.553Z,1368586486.553 [NAL9602](INFO): SBD MO Status=2, MOMSN=12053, MT Status=2, MTMSN=0
2013-05-15T02:54:46.553Z,1368586486.553 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T02:54:51.782Z,1368586491.782 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:54:54.178Z,1368586494.178 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:54:58.441Z,1368586498.441 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:55:00.860Z,1368586500.860 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:55:05.223Z,1368586505.223 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:55:07.517Z,1368586507.517 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:55:11.786Z,1368586511.786 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:55:14.140Z,1368586514.141 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:55:18.437Z,1368586518.437 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:55:20.861Z,1368586520.861 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:55:25.137Z,1368586525.137 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:55:29.058Z,1368586529.059 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:55:37.783Z,1368586537.783 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:55:42.120Z,1368586542.120 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:55:50.838Z,1368586550.838 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:55:59.534Z,1368586559.534 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:56:03.891Z,1368586563.891 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:56:08.207Z,1368586568.207 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:56:16.586Z,1368586576.586 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:56:25.260Z,1368586585.260 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:56:34.047Z,1368586594.047 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:56:42.781Z,1368586602.781 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:56:51.103Z,1368586611.103 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:56:59.862Z,1368586619.862 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:57:04.178Z,1368586624.178 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:57:13.029Z,1368586633.029 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:57:17.241Z,1368586637.240 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:57:21.545Z,1368586641.545 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:57:23.921Z,1368586643.921 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:57:28.301Z,1368586648.301 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:57:30.535Z,1368586650.535 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:57:34.946Z,1368586654.946 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:57:37.181Z,1368586657.181 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:57:41.527Z,1368586661.527 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:57:51.851Z,1368586671.851 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:57:56.136Z,1368586676.136 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:58:00.449Z,1368586680.449 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:58:02.781Z,1368586682.781 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:58:07.174Z,1368586687.174 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:58:09.550Z,1368586689.550 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:58:13.985Z,1368586693.986 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:58:16.212Z,1368586696.212 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:58:20.590Z,1368586700.590 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:58:29.339Z,1368586709.339 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:58:33.631Z,1368586713.631 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:58:37.933Z,1368586717.933 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:58:42.254Z,1368586722.253 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:58:44.648Z,1368586724.648 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:58:48.891Z,1368586728.891 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2013-05-15T02:59:25.957Z,1368586765.957 [NAL9602](INFO): SBD MO Status=2, MOMSN=12053, MT Status=2, MTMSN=0
2013-05-15T02:59:25.957Z,1368586765.957 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T02:59:35.484Z,1368586775.484 [NAL9602](INFO): SBD MO Status=0, MOMSN=12053, MT Status=0, MTMSN=0
2013-05-15T02:59:39.326Z,1368586779.326 [Default:Iridium:Read_Iridium] Stopped
2013-05-15T02:59:39.326Z,1368586779.326 [Default:Iridium](INFO): Completed Default:Iridium
2013-05-15T02:59:39.326Z,1368586779.326 [Default:Iridium] Stopped
2013-05-15T02:59:39.326Z,1368586779.326 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-05-15T02:59:39.326Z,1368586779.326 [Default:Iridium:A.SetSpeed] Stopped
2013-05-15T02:59:39.326Z,1368586779.326 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-05-15T02:59:39.654Z,1368586779.654 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-05-15T02:59:39.654Z,1368586779.654 [Default:CallIridium:B] Stopped
2013-05-15T02:59:39.654Z,1368586779.654 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-05-15T02:59:39.655Z,1368586779.655 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-05-15T02:59:39.655Z,1368586779.655 [Default:CallIridium] Stopped
2013-05-15T02:59:39.655Z,1368586779.655 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-05-15T02:59:49.681Z,1368586789.681 [NAL9602](INFO): Powering down
2013-05-15T03:04:39.705Z,1368587079.704 [Default:CallIridium] Running Loop=1
2013-05-15T03:04:39.705Z,1368587079.705 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-05-15T03:04:39.705Z,1368587079.705 [Default:CallIridium:A] Running Loop=1
2013-05-15T03:04:39.705Z,1368587079.705 [Default:CallIridium:A] Stopped
2013-05-15T03:04:39.705Z,1368587079.705 [Default:CallIridium:B] Running Loop=1
2013-05-15T03:04:39.706Z,1368587079.705 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-05-15T03:04:44.722Z,1368587084.722 [Default:Iridium] Running Loop=1
2013-05-15T03:04:44.722Z,1368587084.722 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-05-15T03:04:44.722Z,1368587084.722 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-05-15T03:04:44.723Z,1368587084.723 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-05-15T03:04:44.723Z,1368587084.723 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-05-15T03:04:44.723Z,1368587084.723 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-05-15T03:04:44.724Z,1368587084.724 [Default:Iridium:B.GoToSurface] Stopped
2013-05-15T03:04:44.724Z,1368587084.724 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-05-15T03:04:44.724Z,1368587084.724 [Default:Iridium:Read_Iridium] Running Loop=1
2013-05-15T03:04:44.724Z,1368587084.724 [Default:GPS] Running Loop=1
2013-05-15T03:04:44.724Z,1368587084.724 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-05-15T03:04:44.724Z,1368587084.724 [Default:GPS:A.SetSpeed] Running Loop=1
2013-05-15T03:04:44.724Z,1368587084.724 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-05-15T03:04:44.725Z,1368587084.724 [Default:GPS:B.GoToSurface] Running Loop=1
2013-05-15T03:04:44.725Z,1368587084.725 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-05-15T03:04:44.725Z,1368587084.725 [Default:GPS:B.GoToSurface] Stopped
2013-05-15T03:04:44.725Z,1368587084.725 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-05-15T03:04:44.725Z,1368587084.726 [Default:GPS:Read_GPS] Running Loop=1
2013-05-15T03:04:45.319Z,1368587085.319 [NAL9602](INFO): Powering up
2013-05-15T03:04:55.750Z,1368587095.750 [NAL9602](INFO): NAL9602 initialized
2013-05-15T03:05:14.705Z,1368587114.705 [NAL9602](IMPORTANT): GPS fix at: 1368587075.00
2013-05-15T03:05:14.720Z,1368587114.720 [Default:GPS:Read_GPS] Stopped
2013-05-15T03:05:14.720Z,1368587114.720 [Default:GPS:D] Running Loop=1
2013-05-15T03:05:15.141Z,1368587115.141 [Default:GPS:D] Stopped
2013-05-15T03:05:15.141Z,1368587115.141 [Default:GPS](INFO): Completed Default:GPS
2013-05-15T03:05:15.141Z,1368587115.141 [Default:GPS] Stopped
2013-05-15T03:05:15.142Z,1368587115.142 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-05-15T03:05:15.142Z,1368587115.142 [Default:GPS:A.SetSpeed] Stopped
2013-05-15T03:05:15.142Z,1368587115.142 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-05-15T03:09:24.088Z,1368587364.088 [NAL9602](INFO): SBD MO Status=2, MOMSN=12054, MT Status=2, MTMSN=0
2013-05-15T03:09:24.088Z,1368587364.088 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-05-15T03:10:09.557Z,1368587409.557 [NAL9602](INFO): SBD MO Status=1, MOMSN=12054, MT Status=0, MTMSN=0
2013-05-15T03:10:09.630Z,1368587409.630 [NAL9602](INFO): Sent 70 bytes from file Logs/20130515T000238/Courier0048.lzma
2013-05-15T03:10:09.630Z,1368587409.630 [NAL9602](INFO): Packets left to send: 0
2013-05-15T03:10:09.632Z,1368587409.632 [NAL9602](INFO): Stored copy of sent data in Logs/20130515T000238/Courier0048.lzma.parts/0000.sbd
2013-05-15T03:10:21.347Z,1368587421.347 [NAL9602](INFO): SBD MO Status=1, MOMSN=12055, MT Status=0, MTMSN=0
2013-05-15T03:10:21.402Z,1368587421.402 [NAL9602](INFO): Sent 115 bytes from file Logs/20130515T000238/Express0049.lzma
2013-05-15T03:10:21.402Z,1368587421.402 [NAL9602](INFO): Packets left to send: 0
2013-05-15T03:10:21.404Z,1368587421.404 [NAL9602](INFO): Stored copy of sent data in Logs/20130515T000238/Express0049.lzma.parts/0000.sbd
2013-05-15T03:10:29.751Z,1368587429.751 [NAL9602](INFO): SBD MO Status=0, MOMSN=12056, MT Status=0, MTMSN=0
2013-05-15T03:10:33.484Z,1368587433.484 [Default:Iridium:Read_Iridium] Stopped
2013-05-15T03:10:33.485Z,1368587433.485 [Default:Iridium](INFO): Completed Default:Iridium
2013-05-15T03:10:33.485Z,1368587433.485 [Default:Iridium] Stopped
2013-05-15T03:10:33.485Z,1368587433.485 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-05-15T03:10:33.485Z,1368587433.485 [Default:Iridium:A.SetSpeed] Stopped
2013-05-15T03:10:33.485Z,1368587433.485 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-05-15T03:10:33.843Z,1368587433.843 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-05-15T03:10:33.843Z,1368587433.843 [Default:CallIridium:B] Stopped
2013-05-15T03:10:33.843Z,1368587433.843 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-05-15T03:10:33.843Z,1368587433.843 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-05-15T03:10:33.844Z,1368587433.843 [Default:CallIridium] Stopped
2013-05-15T03:10:33.844Z,1368587433.844 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-05-15T03:10:43.840Z,1368587443.840 [NAL9602](INFO): Powering down
2013-05-15T03:11:13.058Z,1368587473.058 [CommandLine](IMPORTANT): got command quit
2013-05-15T03:11:14.182Z,1368587474.182 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2013-05-15T03:11:14.182Z,1368587474.182 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2013-05-15T03:11:14.422Z,1368587474.422 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2013-05-15T03:11:14.694Z,1368587474.694 [WetLabsBB2FL](INFO): Powering down
2013-05-15T03:11:14.702Z,1368587474.702 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2013-05-15T03:11:14.742Z,1368587474.742 [CTD_NeilBrown](INFO): Powering down
2013-05-15T03:11:14.762Z,1368587474.762 [ComponentRegistry](INFO): Shutting down DVL_micro ThreadHandler
2013-05-15T03:11:14.798Z,1368587474.798 [DVL_micro](INFO): uninitialize:Powering down
2013-05-15T03:11:14.802Z,1368587474.802 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler
2013-05-15T03:11:15.130Z,1368587475.130 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator.
2013-05-15T03:11:15.143Z,1368587475.143 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2013-05-15T03:11:15.183Z,1368587475.183 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2013-05-15T03:11:15.184Z,1368587475.184 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2013-05-15T03:11:18.498Z,1368587478.498 [controlThread](DEBUG): Uninitializing ControlThread
2013-05-15T03:11:18.499Z,1368587478.499 [AHRS_sp3003D](INFO): Powering down
2013-05-15T03:11:18.587Z,1368587478.587 [NAL9602](INFO): Powering down
2013-05-15T03:11:18.589Z,1368587478.589 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2013-05-15T03:11:18.589Z,1368587478.589 [NavChart](DEBUG): Uninitialize NavChart Derivation.
2013-05-15T03:11:18.590Z,1368587478.590 [Default] Stopped
2013-05-15T03:11:18.590Z,1368587478.590 [Default](INFO): Aggregate::uninitialize Default
2013-05-15T03:11:18.590Z,1368587478.591 [Default:D.SetSpeed] Stopped
2013-05-15T03:11:18.591Z,1368587478.591 [Default:D.SetSpeed](DEBUG): Uninitialize.
2013-05-15T03:11:18.591Z,1368587478.591 [Default:E.GoToSurface] Stopped
2013-05-15T03:11:18.591Z,1368587478.591 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-05-15T03:11:18.591Z,1368587478.591 [Default:F.Wait] Stopped
2013-05-15T03:11:18.591Z,1368587478.591 [Default:F.Wait](DEBUG): Uninitialize Wait Component.
2013-05-15T03:11:18.619Z,1368587478.619 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2013-05-15T03:11:18.620Z,1368587478.620 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2013-05-15T03:11:18.620Z,1368587478.620 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2013-05-15T03:11:18.621Z,1368587478.621 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2013-05-15T03:11:18.621Z,1368587478.621 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-05-15T03:11:18.622Z,1368587478.622 [BuoyancyServo](INFO): Powering down
2013-05-15T03:11:18.634Z,1368587478.634 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2013-05-15T03:11:18.635Z,1368587478.635 [ElevatorServo](INFO): Powering down
2013-05-15T03:11:18.635Z,1368587478.635 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-05-15T03:11:18.636Z,1368587478.635 [MassServo](INFO): Powering down
2013-05-15T03:11:18.636Z,1368587478.636 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2013-05-15T03:11:18.636Z,1368587478.636 [RudderServo](INFO): Powering down
2013-05-15T03:11:18.637Z,1368587478.637 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-05-15T03:11:18.637Z,1368587478.637 [ThrusterServo](INFO): Powering down
2013-05-15T03:11:18.638Z,1368587478.638 [SBIT](DEBUG): Uninitialize SBIT Component.
2013-05-15T03:11:18.639Z,1368587478.639 [IBIT](DEBUG): Uninitialize IBIT Component.
2013-05-15T03:11:18.639Z,1368587478.639 [CBIT](DEBUG): Uninitialize CBIT Component.