2013-03-13T19:50:25.706Z,1363204225.706 [Supervisor](DEBUG): Initializing supervisor.
2013-03-13T19:50:25.708Z,1363204225.708 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2013-03-13T19:50:25.709Z,1363204225.709 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2013-03-13T19:50:25.710Z,1363204225.710 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0
2013-03-13T19:50:25.714Z,1363204225.714 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2013-03-13T19:50:25.725Z,1363204225.724 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2013-03-13T19:50:25.726Z,1363204225.726 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0
2013-03-13T19:50:25.726Z,1363204225.727 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2013-03-13T19:50:25.727Z,1363204225.727 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403CB4E0
2013-03-13T19:50:25.728Z,1363204225.728 [Supervisor](INFO): Looking for Config files in directory: Config/
2013-03-13T19:50:25.729Z,1363204225.729 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2013-03-13T19:50:26.011Z,1363204226.011 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2013-03-13T19:50:26.011Z,1363204226.011 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2013-03-13T19:50:26.203Z,1363204226.203 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2013-03-13T19:50:26.203Z,1363204226.203 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2013-03-13T19:50:26.287Z,1363204226.287 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2013-03-13T19:50:26.288Z,1363204226.288 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2013-03-13T19:50:26.471Z,1363204226.471 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2013-03-13T19:50:26.472Z,1363204226.471 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2013-03-13T19:50:26.604Z,1363204226.603 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2013-03-13T19:50:26.604Z,1363204226.604 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2013-03-13T19:50:26.833Z,1363204226.833 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2013-03-13T19:50:26.834Z,1363204226.834 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2013-03-13T19:50:27.002Z,1363204227.003 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2013-03-13T19:50:27.003Z,1363204227.003 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2013-03-13T19:50:27.253Z,1363204227.253 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2013-03-13T19:50:27.253Z,1363204227.253 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2013-03-13T19:50:27.351Z,1363204227.351 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2013-03-13T19:50:27.352Z,1363204227.352 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2013-03-13T19:50:27.747Z,1363204227.747 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2013-03-13T19:50:27.747Z,1363204227.747 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2013-03-13T19:50:27.861Z,1363204227.861 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2013-03-13T19:50:27.862Z,1363204227.862 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2013-03-13T19:50:27.947Z,1363204227.947 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2013-03-13T19:50:27.948Z,1363204227.948 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2013-03-13T19:50:28.049Z,1363204228.049 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2013-03-13T19:50:28.177Z,1363204228.178 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2013-03-13T19:50:28.263Z,1363204228.263 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2013-03-13T19:50:28.364Z,1363204228.364 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2013-03-13T19:50:28.463Z,1363204228.463 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2013-03-13T19:50:28.580Z,1363204228.580 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2013-03-13T19:50:28.674Z,1363204228.674 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2013-03-13T19:50:28.760Z,1363204228.760 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2013-03-13T19:50:28.784Z,1363204228.784 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2013-03-13T19:50:28.986Z,1363204228.986 [InternalSim] Loaded
2013-03-13T19:50:28.986Z,1363204228.986 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2013-03-13T19:50:28.987Z,1363204228.987 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2013-03-13T19:50:28.988Z,1363204228.988 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2013-03-13T19:50:29.045Z,1363204229.045 [SBIT](DEBUG): Construct Startup Built In Test.
2013-03-13T19:50:29.073Z,1363204229.073 [SBIT] Loaded
2013-03-13T19:50:29.073Z,1363204229.073 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2013-03-13T19:50:29.074Z,1363204229.074 [IBIT](DEBUG): Construct Initiated Built In Test.
2013-03-13T19:50:29.103Z,1363204229.103 [IBIT] Loaded
2013-03-13T19:50:29.103Z,1363204229.103 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2013-03-13T19:50:29.106Z,1363204229.106 [CBIT](DEBUG): Construct CBIT Built In Test.
2013-03-13T19:50:29.224Z,1363204229.224 [CBIT] Loaded
2013-03-13T19:50:29.225Z,1363204229.225 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2013-03-13T19:50:29.225Z,1363204229.225 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2013-03-13T19:50:29.226Z,1363204229.226 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2013-03-13T19:50:29.375Z,1363204229.375 [BuoyancyServo] Loaded
2013-03-13T19:50:29.375Z,1363204229.375 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2013-03-13T19:50:29.388Z,1363204229.388 [ElevatorServo] Loaded
2013-03-13T19:50:29.388Z,1363204229.388 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2013-03-13T19:50:29.400Z,1363204229.400 [MassServo] Loaded
2013-03-13T19:50:29.401Z,1363204229.401 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2013-03-13T19:50:29.413Z,1363204229.413 [RudderServo] Loaded
2013-03-13T19:50:29.413Z,1363204229.413 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2013-03-13T19:50:29.425Z,1363204229.425 [ThrusterServo] Loaded
2013-03-13T19:50:29.425Z,1363204229.425 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2013-03-13T19:50:29.425Z,1363204229.425 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2013-03-13T19:50:29.426Z,1363204229.426 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2013-03-13T19:50:29.443Z,1363204229.443 [DepthRateCalculator] Loaded
2013-03-13T19:50:29.443Z,1363204229.443 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2013-03-13T19:50:33.204Z,1363204233.204 [HFRadarModelCalc] Loaded
2013-03-13T19:50:33.204Z,1363204233.204 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2013-03-13T19:50:33.220Z,1363204233.220 [NavChart] Loaded
2013-03-13T19:50:33.220Z,1363204233.220 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2013-03-13T19:50:33.226Z,1363204233.226 [PitchRateCalculator] Loaded
2013-03-13T19:50:33.227Z,1363204233.227 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2013-03-13T19:50:33.233Z,1363204233.233 [SpeedCalculator] Loaded
2013-03-13T19:50:33.233Z,1363204233.233 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2013-03-13T19:50:33.249Z,1363204233.249 [TempGradientCalculator] Loaded
2013-03-13T19:50:33.249Z,1363204233.249 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2013-03-13T19:50:33.255Z,1363204233.255 [YawRateCalculator] Loaded
2013-03-13T19:50:33.255Z,1363204233.256 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2013-03-13T19:50:33.296Z,1363204233.296 [Navigation] Loaded
2013-03-13T19:50:33.297Z,1363204233.297 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread.
2013-03-13T19:50:33.297Z,1363204233.297 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2013-03-13T19:50:33.298Z,1363204233.298 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2013-03-13T19:50:33.522Z,1363204233.522 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2013-03-13T19:50:33.522Z,1363204233.522 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2013-03-13T19:50:33.544Z,1363204233.544 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2013-03-13T19:50:33.545Z,1363204233.545 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2013-03-13T19:50:33.586Z,1363204233.586 [VerticalControl](DEBUG): Construct VerticalControl.
2013-03-13T19:50:33.681Z,1363204233.681 [VerticalControl] Loaded
2013-03-13T19:50:33.681Z,1363204233.681 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2013-03-13T19:50:33.682Z,1363204233.682 [HorizontalControl](DEBUG): Construct HorizontalControl.
2013-03-13T19:50:33.739Z,1363204233.739 [HorizontalControl] Loaded
2013-03-13T19:50:33.740Z,1363204233.740 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2013-03-13T19:50:33.740Z,1363204233.740 [SpeedControl](DEBUG): Construct SpeedControl.
2013-03-13T19:50:33.742Z,1363204233.742 [SpeedControl] Loaded
2013-03-13T19:50:33.743Z,1363204233.743 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2013-03-13T19:50:33.743Z,1363204233.743 [LoopControl](DEBUG): Construct LoopControl.
2013-03-13T19:50:33.744Z,1363204233.744 [LoopControl] Loaded
2013-03-13T19:50:33.744Z,1363204233.744 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2013-03-13T19:50:33.745Z,1363204233.745 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2013-03-13T19:50:33.745Z,1363204233.745 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2013-03-13T19:50:33.751Z,1363204233.751 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2013-03-13T19:50:33.756Z,1363204233.756 [AsyncPiEstimator] Loaded
2013-03-13T19:50:33.756Z,1363204233.756 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2013-03-13T19:50:33.757Z,1363204233.757 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 406A44E0
2013-03-13T19:50:33.758Z,1363204233.758 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2013-03-13T19:50:33.758Z,1363204233.758 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2013-03-13T19:50:33.885Z,1363204233.885 [AHRS_sp3003D] Loaded
2013-03-13T19:50:33.886Z,1363204233.886 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2013-03-13T19:50:34.145Z,1363204234.145 [Batt_Ocean_Server] Loaded
2013-03-13T19:50:34.145Z,1363204234.145 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread.
2013-03-13T19:50:34.159Z,1363204234.159 [Depth_Keller] Loaded
2013-03-13T19:50:34.159Z,1363204234.159 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2013-03-13T19:50:34.165Z,1363204234.165 [DropWeight] Loaded
2013-03-13T19:50:34.165Z,1363204234.165 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2013-03-13T19:50:34.342Z,1363204234.342 [DVL_micro] Loaded
2013-03-13T19:50:34.342Z,1363204234.342 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread.
2013-03-13T19:50:34.343Z,1363204234.343 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 407284E0
2013-03-13T19:50:34.428Z,1363204234.428 [NAL9602] Loaded
2013-03-13T19:50:34.428Z,1363204234.428 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2013-03-13T19:50:34.480Z,1363204234.480 [Onboard] Loaded
2013-03-13T19:50:34.480Z,1363204234.480 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2013-03-13T19:50:34.487Z,1363204234.487 [Radio_Freewave] Loaded
2013-03-13T19:50:34.487Z,1363204234.487 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread.
2013-03-13T19:50:34.488Z,1363204234.488 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2013-03-13T19:50:34.488Z,1363204234.489 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2013-03-13T19:50:34.545Z,1363204234.545 [Aanderaa_O2] Loaded
2013-03-13T19:50:34.545Z,1363204234.545 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2013-03-13T19:50:34.579Z,1363204234.579 [CTD_NeilBrown] Loaded
2013-03-13T19:50:34.579Z,1363204234.579 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2013-03-13T19:50:34.580Z,1363204234.580 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4077A4E0
2013-03-13T19:50:34.597Z,1363204234.597 [PAR_Licor] Loaded
2013-03-13T19:50:34.597Z,1363204234.597 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2013-03-13T19:50:34.627Z,1363204234.627 [WetLabsBB2FL] Loaded
2013-03-13T19:50:34.628Z,1363204234.628 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2013-03-13T19:50:34.629Z,1363204234.629 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407AA4E0
2013-03-13T19:50:34.629Z,1363204234.629 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2013-03-13T19:50:34.632Z,1363204234.632 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2013-03-13T19:50:34.632Z,1363204234.632 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2013-03-13T19:50:34.639Z,1363204234.639 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2013-03-13T19:50:34.640Z,1363204234.640 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407DA4E0
2013-03-13T19:50:34.645Z,1363204234.645 [Supervisor](DEBUG): Running supervisor.
2013-03-13T19:50:34.646Z,1363204234.646 [CommandLine](INFO): Thread ID is 879
2013-03-13T19:50:34.648Z,1363204234.648 [controlThread](INFO): Thread ID is 878
2013-03-13T19:50:34.648Z,1363204234.648 [controlThread](DEBUG): Initializing ControlThread
2013-03-13T19:50:34.649Z,1363204234.649 [CycleStarter](INFO): Thread ID is 877
2013-03-13T19:50:34.649Z,1363204234.649 [InternalSim](DEBUG):  InternalSim initializing...
2013-03-13T19:50:34.682Z,1363204234.682 [logger](INFO): Thread ID is 880
2013-03-13T19:50:34.705Z,1363204234.705 [SBIT](INFO): Initialize SBIT Component.
2013-03-13T19:50:34.706Z,1363204234.706 [SBIT](IMPORTANT): Tethys CM Info:
$Rev: 10234
2013-03-13T19:50:34.714Z,1363204234.714 [IBIT](INFO): Initialize IBIT Component.
2013-03-13T19:50:34.715Z,1363204234.715 [CBIT](DEBUG): Initialize CBIT Component.
2013-03-13T19:50:34.715Z,1363204234.715 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2013-03-13T19:50:34.716Z,1363204234.716 [AsyncPiEstimator](INFO): Thread ID is 942
2013-03-13T19:50:34.716Z,1363204234.716 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2013-03-13T19:50:34.736Z,1363204234.736 [DVL_micro](INFO): Thread ID is 943
2013-03-13T19:50:34.748Z,1363204234.747 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2013-03-13T19:50:34.748Z,1363204234.748 [NavChart](DEBUG): Initialize NavChart Derivation.
2013-03-13T19:50:34.748Z,1363204234.749 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2013-03-13T19:50:34.749Z,1363204234.749 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2013-03-13T19:50:34.749Z,1363204234.749 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2013-03-13T19:50:34.750Z,1363204234.750 [DVL_micro](INFO): Initializing
2013-03-13T19:50:34.750Z,1363204234.750 [DVL_micro](INFO): start:Powering up
2013-03-13T19:50:34.751Z,1363204234.751 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-03-13T19:50:34.751Z,1363204234.751 [DVL_micro](INFO): Cycling power to configure device.
2013-03-13T19:50:34.755Z,1363204234.755 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2013-03-13T19:50:34.755Z,1363204234.755 [Navigation](DEBUG): Initializing Navigation.
2013-03-13T19:50:34.756Z,1363204234.756 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2013-03-13T19:50:34.758Z,1363204234.758 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2013-03-13T19:50:34.758Z,1363204234.758 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2013-03-13T19:50:34.759Z,1363204234.759 [LoopControl](DEBUG): Initialize LoopControlComponent.
2013-03-13T19:50:34.775Z,1363204234.774 [CTD_NeilBrown](INFO): Thread ID is 944
2013-03-13T19:50:34.775Z,1363204234.775 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2013-03-13T19:50:34.779Z,1363204234.779 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2013-03-13T19:50:34.779Z,1363204234.779 [WetLabsBB2FL](INFO): Thread ID is 945
2013-03-13T19:50:34.780Z,1363204234.780 [WetLabsBB2FL](INFO): Powering down
2013-03-13T19:50:34.826Z,1363204234.826 [NavChartDb](INFO): Thread ID is 946
2013-03-13T19:50:34.829Z,1363204234.829 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2013-03-13T19:50:34.830Z,1363204234.830 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2013-03-13T19:50:34.830Z,1363204234.830 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2013-03-13T19:50:34.831Z,1363204234.830 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2013-03-13T19:50:34.831Z,1363204234.831 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2013-03-13T19:50:34.831Z,1363204234.831 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2013-03-13T19:50:34.831Z,1363204234.831 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2013-03-13T19:50:34.831Z,1363204234.832 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2013-03-13T19:50:34.832Z,1363204234.832 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2013-03-13T19:50:36.506Z,1363204236.506 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2013-03-13T19:50:36.536Z,1363204236.536 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2013-03-13T19:50:36.555Z,1363204236.555 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2013-03-13T19:50:36.573Z,1363204236.573 [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-03-13T19:50:36.578Z,1363204236.578 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2013-03-13T19:50:36.663Z,1363204236.663 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2013-03-13T19:50:36.665Z,1363204236.665 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2013-03-13T19:50:36.668Z,1363204236.668 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-03-13T19:50:36.680Z,1363204236.681 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2013-03-13T19:50:36.684Z,1363204236.684 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-03-13T19:50:36.725Z,1363204236.725 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2013-03-13T19:50:36.745Z,1363204236.745 [Default:D.SetSpeed](DEBUG): Construct.
2013-03-13T19:50:36.760Z,1363204236.760 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2013-03-13T19:50:36.765Z,1363204236.765 [Default:F.Wait](DEBUG): Construct Wait.
2013-03-13T19:50:36.768Z,1363204236.768 [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-03-13T19:50:36.781Z,1363204236.782 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,NAL9602,Onboard,Radio_Freewave,Aanderaa_O2,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,HFRadarModelCalc,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,
2013-03-13T19:50:36.830Z,1363204236.830 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-03-13T19:50:36.830Z,1363204236.830 [DVL_micro](INFO): Querying output modes
2013-03-13T19:50:36.831Z,1363204236.831 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-03-13T19:50:36.842Z,1363204236.842 [DVL_micro](DEBUG): cmdResponse: 01
2013-03-13T19:50:36.842Z,1363204236.842 [DVL_micro](INFO): NQ1 output enabled
2013-03-13T19:50:36.842Z,1363204236.842 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-03-13T19:50:36.854Z,1363204236.854 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2013-03-13T19:50:36.859Z,1363204236.859 [DVL_micro](INFO): pause:Powering down
2013-03-13T19:50:36.861Z,1363204236.861 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-03-13T19:50:36.911Z,1363204236.911 [Depth_Keller](ERROR): Pressure reading out of range: 1831.078247 decibar
2013-03-13T19:50:37.030Z,1363204237.030 [Radio_Freewave](INFO): Powering up
2013-03-13T19:50:37.269Z,1363204237.269 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-03-13T19:50:37.282Z,1363204237.282 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-03-13T19:50:37.297Z,1363204237.297 [ElevatorServo](DEBUG): Initializing EZServoServo.
2013-03-13T19:50:37.306Z,1363204237.306 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2013-03-13T19:50:37.312Z,1363204237.312 [MassServo](DEBUG): Initializing EZServoServo.
2013-03-13T19:50:37.318Z,1363204237.318 [MassServo](DEBUG): Initializing MassServo.
2013-03-13T19:50:37.332Z,1363204237.332 [RudderServo](DEBUG): Initializing EZServoServo.
2013-03-13T19:50:37.337Z,1363204237.337 [RudderServo](DEBUG): Initializing RudderServo.
2013-03-13T19:50:37.350Z,1363204237.351 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-03-13T19:50:37.358Z,1363204237.358 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-03-13T19:50:39.446Z,1363204239.446 [Aanderaa_O2](INFO): Powering down
2013-03-13T19:50:39.804Z,1363204239.804 [NAL9602](INFO): Powering up NAL9602
2013-03-13T19:50:50.314Z,1363204250.314 [NAL9602](INFO): NAL9602 initialized
2013-03-13T19:50:50.384Z,1363204250.384 [SBIT](IMPORTANT): Beginning Startup BIT
2013-03-13T19:50:50.402Z,1363204250.402 [CBIT](IMPORTANT): Beginning GF scan
2013-03-13T19:51:16.890Z,1363204276.890 [CBIT](IMPORTANT): No ground fault detected
2013-03-13T19:51:36.631Z,1363204296.631 [NAL9602](IMPORTANT): GPS fix at: 1363204282.00
2013-03-13T19:51:43.839Z,1363204303.839 [NAL9602](INFO): Powering down
2013-03-13T19:51:44.324Z,1363204304.323 [SBIT](IMPORTANT): SBIT PASSED
2013-03-13T19:51:44.657Z,1363204304.657 [MissionManager](IMPORTANT): Started mission Startup
2013-03-13T19:51:44.658Z,1363204304.658 [Startup] Running Loop=1
2013-03-13T19:51:44.658Z,1363204304.658 [Startup](INFO): Aggregate::initialize Startup
2013-03-13T19:51:44.658Z,1363204304.658 [Startup:A.GoToSurface] Running Loop=1
2013-03-13T19:51:44.658Z,1363204304.658 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T19:51:44.664Z,1363204304.664 [Startup:StartupSatComms] Running Loop=1
2013-03-13T19:51:44.664Z,1363204304.664 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2013-03-13T19:51:44.664Z,1363204304.664 [Startup:StartupSatComms:A] Running Loop=1
2013-03-13T19:51:45.050Z,1363204305.050 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-03-13T19:51:45.463Z,1363204305.463 [NAL9602](INFO): Powering up
2013-03-13T19:51:57.922Z,1363204317.922 [NAL9602](ERROR): NAL9602 initialize uart error: serial timeout
2013-03-13T19:51:57.922Z,1363204317.922 [NAL9602] Communications Fault, FailCount= 1
2013-03-13T19:51:57.922Z,1363204317.922 [NAL9602](ERROR): Communications Fault
2013-03-13T19:51:58.065Z,1363204318.065 [CBIT](ERROR): Communications Fault in component: NAL9602
2013-03-13T19:51:58.372Z,1363204318.372 [NAL9602](INFO): Powering down
2013-03-13T19:51:59.241Z,1363204319.241 [CBIT](INFO): Clearing failed state for component NAL9602
2013-03-13T19:51:59.241Z,1363204319.241 [NAL9602] No Fault, FailCount= 1
2013-03-13T19:52:03.512Z,1363204323.512 [NAL9602](INFO): Powering up NAL9602
2013-03-13T19:52:13.942Z,1363204333.942 [NAL9602](INFO): NAL9602 initialized
2013-03-13T19:52:44.724Z,1363204364.724 [Startup:StartupSatComms:A](INFO): Timed out from 2013-03-13T19:51:44.7Z
2013-03-13T19:52:44.724Z,1363204364.724 [Startup:StartupSatComms:A:A_Timeout] Running Loop=1
2013-03-13T19:52:44.725Z,1363204364.724 [Startup:StartupSatComms:A:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:A:A_Timeout
2013-03-13T19:52:44.725Z,1363204364.725 [Startup:StartupSatComms:A:A_Timeout](INFO): Completed Startup:StartupSatComms:A:A_Timeout
2013-03-13T19:52:44.725Z,1363204364.725 [Startup:StartupSatComms:A] Stopped
2013-03-13T19:52:44.725Z,1363204364.725 [Startup:StartupSatComms:B] Running Loop=1
2013-03-13T19:52:45.124Z,1363204365.124 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2013-03-13T19:53:01.000Z,1363204381.000 [NAL9602](INFO): SBD MO Status=1, MOMSN=7182, MT Status=0, MTMSN=0
2013-03-13T19:53:01.050Z,1363204381.050 [NAL9602](INFO): Sent 312 bytes from file Logs/20130313T193334/shore0002.lzma
2013-03-13T19:53:01.050Z,1363204381.050 [NAL9602](INFO): Packets left to send: 0
2013-03-13T19:53:01.052Z,1363204381.052 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T193334/shore0002.lzma.parts/0000.sbd
2013-03-13T19:53:10.848Z,1363204390.848 [NAL9602](INFO): SBD MO Status=1, MOMSN=7183, MT Status=0, MTMSN=0
2013-03-13T19:53:10.898Z,1363204390.898 [NAL9602](INFO): Sent 332 bytes from file Logs/20130313T195025/shore0000.lzma
2013-03-13T19:53:10.898Z,1363204390.898 [NAL9602](INFO): Packets left to send: 2
2013-03-13T19:53:10.900Z,1363204390.900 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0000.lzma.parts/0002.sbd
2013-03-13T19:53:19.032Z,1363204399.032 [NAL9602](INFO): SBD MO Status=1, MOMSN=7184, MT Status=0, MTMSN=0
2013-03-13T19:53:19.082Z,1363204399.082 [NAL9602](INFO): Sent 332 bytes from file Logs/20130313T195025/shore0000.lzma
2013-03-13T19:53:19.082Z,1363204399.082 [NAL9602](INFO): Packets left to send: 1
2013-03-13T19:53:19.083Z,1363204399.083 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0000.lzma.parts/0001.sbd
2013-03-13T19:53:29.723Z,1363204409.723 [NAL9602](INFO): SBD MO Status=1, MOMSN=7185, MT Status=0, MTMSN=0
2013-03-13T19:53:29.774Z,1363204409.774 [NAL9602](INFO): Sent 47 bytes from file Logs/20130313T195025/shore0000.lzma
2013-03-13T19:53:29.774Z,1363204409.774 [NAL9602](INFO): Packets left to send: 0
2013-03-13T19:53:29.775Z,1363204409.775 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0000.lzma.parts/0000.sbd
2013-03-13T19:53:40.876Z,1363204420.876 [NAL9602](INFO): SBD MO Status=0, MOMSN=7186, MT Status=0, MTMSN=0
2013-03-13T19:53:40.965Z,1363204420.965 [Startup:StartupSatComms:B] Stopped
2013-03-13T19:53:40.965Z,1363204420.965 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2013-03-13T19:53:40.965Z,1363204420.965 [Startup:StartupSatComms] Stopped
2013-03-13T19:53:40.965Z,1363204420.965 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2013-03-13T19:53:40.966Z,1363204420.966 [Startup](INFO): Completed Startup
2013-03-13T19:53:40.966Z,1363204420.966 [Startup] Stopped
2013-03-13T19:53:40.966Z,1363204420.966 [Startup](INFO): Aggregate::uninitialize Startup
2013-03-13T19:53:40.966Z,1363204420.967 [Startup:A.GoToSurface] Stopped
2013-03-13T19:53:40.967Z,1363204420.967 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T19:53:41.285Z,1363204421.285 [MissionManager](IMPORTANT): Started mission Default
2013-03-13T19:53:41.285Z,1363204421.285 [Default] Running Loop=1
2013-03-13T19:53:41.300Z,1363204421.300 [Default](INFO): Aggregate::initialize Default
2013-03-13T19:53:41.300Z,1363204421.300 [Default:D.SetSpeed] Running Loop=1
2013-03-13T19:53:41.301Z,1363204421.300 [Default:D.SetSpeed](DEBUG): Initialize.
2013-03-13T19:53:41.301Z,1363204421.301 [Default:E.GoToSurface] Running Loop=1
2013-03-13T19:53:41.301Z,1363204421.301 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T19:53:41.301Z,1363204421.301 [Default:Iridium] Running Loop=1
2013-03-13T19:53:41.301Z,1363204421.301 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-13T19:53:41.301Z,1363204421.301 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-13T19:53:41.301Z,1363204421.301 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-13T19:53:41.302Z,1363204421.302 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-13T19:53:41.302Z,1363204421.302 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T19:53:41.302Z,1363204421.302 [Default:E.GoToSurface] Running Loop=1
2013-03-13T19:53:41.307Z,1363204421.307 [Default:D.SetSpeed] Running Loop=1
2013-03-13T19:53:41.317Z,1363204421.317 [Default:Iridium:B.GoToSurface] Stopped
2013-03-13T19:53:41.317Z,1363204421.317 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T19:53:41.317Z,1363204421.318 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-13T19:53:41.318Z,1363204421.318 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-13T19:53:41.322Z,1363204421.322 [Default:GPS] Running Loop=1
2013-03-13T19:53:41.322Z,1363204421.322 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-13T19:53:41.322Z,1363204421.322 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-13T19:53:41.323Z,1363204421.323 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-13T19:53:41.323Z,1363204421.323 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-13T19:53:41.323Z,1363204421.323 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T19:53:41.328Z,1363204421.328 [Default:GPS:B.GoToSurface] Stopped
2013-03-13T19:53:41.328Z,1363204421.328 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T19:53:41.328Z,1363204421.328 [Default:GPS:Read_GPS] Running Loop=1
2013-03-13T19:53:41.328Z,1363204421.328 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-13T19:53:41.684Z,1363204421.684 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2013-03-13T19:53:41.702Z,1363204421.702 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-03-13T19:53:43.306Z,1363204423.306 [NAL9602](IMPORTANT): GPS fix at: 1363204409.00
2013-03-13T19:53:43.323Z,1363204423.323 [Default:GPS:Read_GPS] Stopped
2013-03-13T19:53:43.323Z,1363204423.323 [Default:GPS:D] Running Loop=1
2013-03-13T19:53:43.705Z,1363204423.705 [Default:GPS:D] Stopped
2013-03-13T19:53:43.706Z,1363204423.706 [Default:GPS](INFO): Completed Default:GPS
2013-03-13T19:53:43.706Z,1363204423.706 [Default:GPS] Stopped
2013-03-13T19:53:43.706Z,1363204423.706 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-13T19:53:43.706Z,1363204423.706 [Default:GPS:A.SetSpeed] Stopped
2013-03-13T19:53:43.706Z,1363204423.706 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T19:54:00.737Z,1363204440.737 [NAL9602](INFO): SBD MO Status=1, MOMSN=7187, MT Status=0, MTMSN=0
2013-03-13T19:54:00.786Z,1363204440.786 [NAL9602](INFO): Sent 169 bytes from file Logs/20130313T195025/shore0001.lzma
2013-03-13T19:54:00.786Z,1363204440.786 [NAL9602](INFO): Packets left to send: 0
2013-03-13T19:54:00.788Z,1363204440.788 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0001.lzma.parts/0000.sbd
2013-03-13T19:54:08.736Z,1363204448.736 [NAL9602](INFO): SBD MO Status=0, MOMSN=7188, MT Status=0, MTMSN=0
2013-03-13T19:54:08.820Z,1363204448.820 [Default:Iridium:Read_Iridium] Stopped
2013-03-13T19:54:08.820Z,1363204448.820 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-13T19:54:08.820Z,1363204448.820 [Default:Iridium] Stopped
2013-03-13T19:54:08.821Z,1363204448.820 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-13T19:54:08.821Z,1363204448.820 [Default:Iridium:A.SetSpeed] Stopped
2013-03-13T19:54:08.821Z,1363204448.821 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T19:54:08.821Z,1363204448.821 [Default:F.Wait] Running Loop=1
2013-03-13T19:54:08.821Z,1363204448.821 [Default:F.Wait](DEBUG): Initialize Wait Component.
2013-03-13T19:54:19.324Z,1363204459.324 [NAL9602](INFO): Powering down
2013-03-13T19:59:09.313Z,1363204749.313 [Default:CallIridium] Running Loop=1
2013-03-13T19:59:09.314Z,1363204749.314 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-13T19:59:09.314Z,1363204749.314 [Default:CallIridium:A] Running Loop=1
2013-03-13T19:59:09.316Z,1363204749.316 [Default:CallIridium:A] Stopped
2013-03-13T19:59:09.316Z,1363204749.316 [Default:CallIridium:B] Running Loop=1
2013-03-13T19:59:09.316Z,1363204749.316 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-13T19:59:14.351Z,1363204754.351 [Default:Iridium] Running Loop=1
2013-03-13T19:59:14.351Z,1363204754.351 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-13T19:59:14.351Z,1363204754.351 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-13T19:59:14.351Z,1363204754.351 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-13T19:59:14.351Z,1363204754.351 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-13T19:59:14.351Z,1363204754.351 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T19:59:14.352Z,1363204754.352 [Default:Iridium:B.GoToSurface] Stopped
2013-03-13T19:59:14.352Z,1363204754.352 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T19:59:14.352Z,1363204754.352 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-13T19:59:14.353Z,1363204754.353 [Default:GPS] Running Loop=1
2013-03-13T19:59:14.353Z,1363204754.353 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-13T19:59:14.353Z,1363204754.353 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-13T19:59:14.353Z,1363204754.353 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-13T19:59:14.353Z,1363204754.353 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-13T19:59:14.353Z,1363204754.353 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T19:59:14.354Z,1363204754.354 [Default:GPS:B.GoToSurface] Stopped
2013-03-13T19:59:14.354Z,1363204754.354 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T19:59:14.354Z,1363204754.354 [Default:GPS:Read_GPS] Running Loop=1
2013-03-13T19:59:14.947Z,1363204754.947 [NAL9602](INFO): Powering up
2013-03-13T19:59:25.834Z,1363204765.834 [NAL9602](INFO): NAL9602 initialized
2013-03-13T19:59:43.928Z,1363204783.928 [NAL9602](INFO): SBD MO Status=1, MOMSN=7189, MT Status=0, MTMSN=0
2013-03-13T19:59:43.978Z,1363204783.978 [NAL9602](INFO): Sent 93 bytes from file Logs/20130313T195025/shore0002.lzma
2013-03-13T19:59:43.978Z,1363204783.978 [NAL9602](INFO): Packets left to send: 0
2013-03-13T19:59:43.979Z,1363204783.980 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0002.lzma.parts/0000.sbd
2013-03-13T19:59:57.565Z,1363204797.565 [NAL9602](INFO): SBD MO Status=0, MOMSN=7190, MT Status=0, MTMSN=0
2013-03-13T19:59:57.647Z,1363204797.647 [Default:Iridium:Read_Iridium] Stopped
2013-03-13T19:59:57.647Z,1363204797.647 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-13T19:59:57.647Z,1363204797.647 [Default:Iridium] Stopped
2013-03-13T19:59:57.647Z,1363204797.647 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-13T19:59:57.647Z,1363204797.647 [Default:Iridium:A.SetSpeed] Stopped
2013-03-13T19:59:57.647Z,1363204797.647 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T19:59:57.947Z,1363204797.947 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-13T19:59:57.947Z,1363204797.947 [Default:CallIridium:B] Stopped
2013-03-13T19:59:57.947Z,1363204797.947 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-13T19:59:57.948Z,1363204797.948 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-13T19:59:57.948Z,1363204797.948 [Default:CallIridium] Stopped
2013-03-13T19:59:57.948Z,1363204797.948 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-13T20:00:16.724Z,1363204816.724 [NAL9602](IMPORTANT): GPS fix at: 1363204803.00
2013-03-13T20:00:16.745Z,1363204816.745 [Default:GPS:Read_GPS] Stopped
2013-03-13T20:00:16.746Z,1363204816.746 [Default:GPS:D] Running Loop=1
2013-03-13T20:00:17.205Z,1363204817.205 [Default:GPS:D] Stopped
2013-03-13T20:00:17.205Z,1363204817.205 [Default:GPS](INFO): Completed Default:GPS
2013-03-13T20:00:17.206Z,1363204817.205 [Default:GPS] Stopped
2013-03-13T20:00:17.206Z,1363204817.206 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-13T20:00:17.206Z,1363204817.206 [Default:GPS:A.SetSpeed] Stopped
2013-03-13T20:00:17.206Z,1363204817.206 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T20:00:37.716Z,1363204837.716 [NAL9602](INFO): Powering down
2013-03-13T20:04:57.707Z,1363205097.707 [Default:CallIridium] Running Loop=1
2013-03-13T20:04:57.707Z,1363205097.707 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-13T20:04:57.707Z,1363205097.707 [Default:CallIridium:A] Running Loop=1
2013-03-13T20:04:57.708Z,1363205097.708 [Default:CallIridium:A] Stopped
2013-03-13T20:04:57.708Z,1363205097.708 [Default:CallIridium:B] Running Loop=1
2013-03-13T20:04:57.708Z,1363205097.708 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-13T20:05:02.748Z,1363205102.748 [Default:Iridium] Running Loop=1
2013-03-13T20:05:02.748Z,1363205102.748 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-13T20:05:02.748Z,1363205102.748 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-13T20:05:02.748Z,1363205102.748 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-13T20:05:02.748Z,1363205102.748 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-13T20:05:02.748Z,1363205102.748 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T20:05:02.749Z,1363205102.749 [Default:Iridium:B.GoToSurface] Stopped
2013-03-13T20:05:02.749Z,1363205102.749 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T20:05:02.749Z,1363205102.750 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-13T20:05:02.750Z,1363205102.750 [Default:GPS] Running Loop=1
2013-03-13T20:05:02.750Z,1363205102.750 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-13T20:05:02.750Z,1363205102.750 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-13T20:05:02.750Z,1363205102.750 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-13T20:05:02.750Z,1363205102.750 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-13T20:05:02.750Z,1363205102.750 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T20:05:02.751Z,1363205102.751 [Default:GPS:B.GoToSurface] Stopped
2013-03-13T20:05:02.751Z,1363205102.751 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T20:05:02.751Z,1363205102.751 [Default:GPS:Read_GPS] Running Loop=1
2013-03-13T20:05:03.347Z,1363205103.347 [NAL9602](INFO): Powering up
2013-03-13T20:05:14.182Z,1363205114.182 [NAL9602](INFO): NAL9602 initialized
2013-03-13T20:05:31.161Z,1363205131.161 [NAL9602](INFO): SBD MO Status=1, MOMSN=7191, MT Status=0, MTMSN=0
2013-03-13T20:05:31.218Z,1363205131.218 [NAL9602](INFO): Sent 249 bytes from file Logs/20130313T195025/shore0003.lzma
2013-03-13T20:05:31.218Z,1363205131.218 [NAL9602](INFO): Packets left to send: 0
2013-03-13T20:05:31.220Z,1363205131.220 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0003.lzma.parts/0000.sbd
2013-03-13T20:05:37.567Z,1363205137.567 [NAL9602](INFO): SBD MO Status=0, MOMSN=7192, MT Status=0, MTMSN=0
2013-03-13T20:05:37.652Z,1363205137.652 [Default:Iridium:Read_Iridium] Stopped
2013-03-13T20:05:37.652Z,1363205137.652 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-13T20:05:37.653Z,1363205137.653 [Default:Iridium] Stopped
2013-03-13T20:05:37.653Z,1363205137.653 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-13T20:05:37.653Z,1363205137.653 [Default:Iridium:A.SetSpeed] Stopped
2013-03-13T20:05:37.653Z,1363205137.653 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T20:05:37.975Z,1363205137.975 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-13T20:05:37.975Z,1363205137.975 [Default:CallIridium:B] Stopped
2013-03-13T20:05:37.975Z,1363205137.975 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-13T20:05:37.975Z,1363205137.976 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-13T20:05:37.976Z,1363205137.976 [Default:CallIridium] Stopped
2013-03-13T20:05:37.976Z,1363205137.976 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-13T20:05:38.796Z,1363205138.796 [NAL9602](IMPORTANT): GPS fix at: 1363205125.00
2013-03-13T20:05:38.812Z,1363205138.812 [Default:GPS:Read_GPS] Stopped
2013-03-13T20:05:38.813Z,1363205138.813 [Default:GPS:D] Running Loop=1
2013-03-13T20:05:39.207Z,1363205139.207 [Default:GPS:D] Stopped
2013-03-13T20:05:39.208Z,1363205139.208 [Default:GPS](INFO): Completed Default:GPS
2013-03-13T20:05:39.208Z,1363205139.208 [Default:GPS] Stopped
2013-03-13T20:05:39.208Z,1363205139.208 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-13T20:05:39.208Z,1363205139.208 [Default:GPS:A.SetSpeed] Stopped
2013-03-13T20:05:39.208Z,1363205139.208 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T20:05:59.752Z,1363205159.752 [NAL9602](INFO): Powering down
2013-03-13T20:10:39.739Z,1363205439.740 [Default:CallIridium] Running Loop=1
2013-03-13T20:10:39.740Z,1363205439.740 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-13T20:10:39.740Z,1363205439.740 [Default:CallIridium:A] Running Loop=1
2013-03-13T20:10:39.740Z,1363205439.740 [Default:CallIridium:A] Stopped
2013-03-13T20:10:39.740Z,1363205439.740 [Default:CallIridium:B] Running Loop=1
2013-03-13T20:10:39.740Z,1363205439.740 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-13T20:10:44.766Z,1363205444.766 [Default:Iridium] Running Loop=1
2013-03-13T20:10:44.766Z,1363205444.766 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-13T20:10:44.766Z,1363205444.766 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-13T20:10:44.766Z,1363205444.766 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-13T20:10:44.766Z,1363205444.766 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-13T20:10:44.766Z,1363205444.766 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T20:10:44.767Z,1363205444.767 [Default:Iridium:B.GoToSurface] Stopped
2013-03-13T20:10:44.767Z,1363205444.767 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T20:10:44.767Z,1363205444.767 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-13T20:10:44.768Z,1363205444.768 [Default:GPS] Running Loop=1
2013-03-13T20:10:44.768Z,1363205444.768 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-13T20:10:44.768Z,1363205444.768 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-13T20:10:44.768Z,1363205444.768 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-13T20:10:44.768Z,1363205444.768 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-13T20:10:44.768Z,1363205444.768 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T20:10:44.769Z,1363205444.769 [Default:GPS:B.GoToSurface] Stopped
2013-03-13T20:10:44.769Z,1363205444.769 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T20:10:44.769Z,1363205444.769 [Default:GPS:Read_GPS] Running Loop=1
2013-03-13T20:10:45.371Z,1363205445.371 [NAL9602](INFO): Powering up
2013-03-13T20:10:45.377Z,1363205445.376 [Radio_Freewave](INFO): Powering down
2013-03-13T20:10:49.278Z,1363205449.278 [ThrusterServo](FAULT): Overload Error
2013-03-13T20:10:49.278Z,1363205449.278 [ThrusterServo] Hardware Fault, FailCount= 1
2013-03-13T20:10:49.278Z,1363205449.278 [ThrusterServo](ERROR): Hardware Fault
2013-03-13T20:10:49.280Z,1363205449.280 [CBIT](ERROR): Hardware Fault in component: ThrusterServo
2013-03-13T20:10:49.623Z,1363205449.623 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-03-13T20:10:49.623Z,1363205449.623 [ThrusterServo](INFO): Powering down
2013-03-13T20:10:50.407Z,1363205450.408 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-03-13T20:10:50.527Z,1363205450.527 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-03-13T20:10:50.529Z,1363205450.529 [CBIT](INFO): Clearing failed state for component ThrusterServo
2013-03-13T20:10:50.529Z,1363205450.529 [ThrusterServo] No Fault, FailCount= 1
2013-03-13T20:10:50.717Z,1363205450.717 [Radio_Freewave](INFO): Powering up
2013-03-13T20:10:56.046Z,1363205456.046 [NAL9602](INFO): NAL9602 initialized
2013-03-13T20:10:58.017Z,1363205458.017 [Radio_Freewave](INFO): Powering down
2013-03-13T20:11:02.382Z,1363205462.382 [ThrusterServo](FAULT): Overload Error
2013-03-13T20:11:02.382Z,1363205462.382 [ThrusterServo] Hardware Fault, FailCount= 2
2013-03-13T20:11:02.382Z,1363205462.382 [ThrusterServo](ERROR): Hardware Fault
2013-03-13T20:11:02.384Z,1363205462.385 [CBIT](ERROR): Hardware Fault in component: ThrusterServo
2013-03-13T20:11:02.683Z,1363205462.683 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-03-13T20:11:02.683Z,1363205462.683 [ThrusterServo](INFO): Powering down
2013-03-13T20:11:03.519Z,1363205463.519 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-03-13T20:11:03.638Z,1363205463.638 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-03-13T20:11:03.641Z,1363205463.641 [CBIT](INFO): Clearing failed state for component ThrusterServo
2013-03-13T20:11:03.641Z,1363205463.641 [ThrusterServo] No Fault, FailCount= 2
2013-03-13T20:11:09.732Z,1363205469.732 [Radio_Freewave](INFO): Powering up
2013-03-13T20:11:09.970Z,1363205469.970 [ThrusterServo](FAULT): Overload Error
2013-03-13T20:11:09.970Z,1363205469.970 [ThrusterServo] Hardware Fault, FailCount= 3
2013-03-13T20:11:09.970Z,1363205469.970 [ThrusterServo](ERROR): Hardware Fault
2013-03-13T20:11:09.972Z,1363205469.972 [CBIT](ERROR): Hardware Fault in component: ThrusterServo
2013-03-13T20:11:10.275Z,1363205470.275 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-03-13T20:11:10.275Z,1363205470.275 [ThrusterServo](INFO): Powering down
2013-03-13T20:11:10.647Z,1363205470.647 [ThrusterServo](FAULT): LCB fault:  Software Overcurrent.
2013-03-13T20:11:11.196Z,1363205471.196 [CBIT](INFO): Clearing failed state for component ThrusterServo
2013-03-13T20:11:11.196Z,1363205471.196 [ThrusterServo] No Fault, FailCount= 3
2013-03-13T20:11:11.527Z,1363205471.527 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-03-13T20:11:11.528Z,1363205471.528 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-03-13T20:11:34.172Z,1363205494.172 [NAL9602](INFO): SBD MO Status=1, MOMSN=7193, MT Status=0, MTMSN=0
2013-03-13T20:11:34.230Z,1363205494.230 [NAL9602](INFO): Sent 196 bytes from file Logs/20130313T195025/shore0004.lzma
2013-03-13T20:11:34.230Z,1363205494.230 [NAL9602](INFO): Packets left to send: 0
2013-03-13T20:11:34.232Z,1363205494.232 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0004.lzma.parts/0000.sbd
2013-03-13T20:11:38.968Z,1363205498.968 [NAL9602](INFO): SBD MO Status=0, MOMSN=7194, MT Status=0, MTMSN=0
2013-03-13T20:11:39.054Z,1363205499.054 [Default:Iridium:Read_Iridium] Stopped
2013-03-13T20:11:39.055Z,1363205499.055 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-13T20:11:39.055Z,1363205499.055 [Default:Iridium] Stopped
2013-03-13T20:11:39.055Z,1363205499.055 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-13T20:11:39.055Z,1363205499.055 [Default:Iridium:A.SetSpeed] Stopped
2013-03-13T20:11:39.055Z,1363205499.055 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T20:11:39.382Z,1363205499.382 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-13T20:11:39.382Z,1363205499.382 [Default:CallIridium:B] Stopped
2013-03-13T20:11:39.382Z,1363205499.382 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-13T20:11:39.382Z,1363205499.382 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-13T20:11:39.382Z,1363205499.382 [Default:CallIridium] Stopped
2013-03-13T20:11:39.383Z,1363205499.383 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-13T20:11:45.770Z,1363205505.770 [NAL9602](IMPORTANT): GPS fix at: 1363205493.00
2013-03-13T20:11:45.803Z,1363205505.803 [Default:GPS:Read_GPS] Stopped
2013-03-13T20:11:45.803Z,1363205505.803 [Default:GPS:D] Running Loop=1
2013-03-13T20:11:46.204Z,1363205506.204 [Default:GPS:D] Stopped
2013-03-13T20:11:46.204Z,1363205506.204 [Default:GPS](INFO): Completed Default:GPS
2013-03-13T20:11:46.204Z,1363205506.204 [Default:GPS] Stopped
2013-03-13T20:11:46.205Z,1363205506.204 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-13T20:11:46.205Z,1363205506.204 [Default:GPS:A.SetSpeed] Stopped
2013-03-13T20:11:46.205Z,1363205506.205 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T20:12:06.764Z,1363205526.764 [NAL9602](INFO): Powering down
2013-03-13T20:15:06.723Z,1363205706.723 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2013-03-13T20:16:41.756Z,1363205801.756 [Default:CallIridium] Running Loop=1
2013-03-13T20:16:41.756Z,1363205801.756 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-13T20:16:41.756Z,1363205801.756 [Default:CallIridium:A] Running Loop=1
2013-03-13T20:16:41.756Z,1363205801.756 [Default:CallIridium:A] Stopped
2013-03-13T20:16:41.757Z,1363205801.757 [Default:CallIridium:B] Running Loop=1
2013-03-13T20:16:41.757Z,1363205801.757 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-13T20:16:46.780Z,1363205806.780 [Default:Iridium] Running Loop=1
2013-03-13T20:16:46.780Z,1363205806.780 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-13T20:16:46.780Z,1363205806.780 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-13T20:16:46.780Z,1363205806.780 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-13T20:16:46.780Z,1363205806.780 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-13T20:16:46.780Z,1363205806.780 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T20:16:46.781Z,1363205806.781 [Default:Iridium:B.GoToSurface] Stopped
2013-03-13T20:16:46.781Z,1363205806.781 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T20:16:46.781Z,1363205806.781 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-13T20:16:46.782Z,1363205806.782 [Default:GPS] Running Loop=1
2013-03-13T20:16:46.782Z,1363205806.782 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-13T20:16:46.782Z,1363205806.782 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-13T20:16:46.782Z,1363205806.782 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-13T20:16:46.782Z,1363205806.782 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-13T20:16:46.782Z,1363205806.782 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T20:16:46.783Z,1363205806.783 [Default:GPS:B.GoToSurface] Stopped
2013-03-13T20:16:46.783Z,1363205806.783 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T20:16:46.783Z,1363205806.783 [Default:GPS:Read_GPS] Running Loop=1
2013-03-13T20:16:47.403Z,1363205807.403 [NAL9602](INFO): Powering up
2013-03-13T20:16:57.842Z,1363205817.842 [NAL9602](INFO): NAL9602 initialized
2013-03-13T20:17:36.400Z,1363205856.400 [NAL9602](INFO): SBD MO Status=2, MOMSN=7195, MT Status=2, MTMSN=0
2013-03-13T20:17:36.401Z,1363205856.401 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-13T20:17:37.602Z,1363205857.602 [NAL9602](IMPORTANT): GPS fix at: 1363205845.00
2013-03-13T20:17:37.619Z,1363205857.619 [Default:GPS:Read_GPS] Stopped
2013-03-13T20:17:37.619Z,1363205857.619 [Default:GPS:D] Running Loop=1
2013-03-13T20:17:38.032Z,1363205858.032 [Default:GPS:D] Stopped
2013-03-13T20:17:38.032Z,1363205858.032 [Default:GPS](INFO): Completed Default:GPS
2013-03-13T20:17:38.032Z,1363205858.032 [Default:GPS] Stopped
2013-03-13T20:17:38.032Z,1363205858.033 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-13T20:17:38.033Z,1363205858.033 [Default:GPS:A.SetSpeed] Stopped
2013-03-13T20:17:38.033Z,1363205858.033 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T20:17:56.160Z,1363205876.160 [NAL9602](INFO): SBD MO Status=1, MOMSN=7195, MT Status=0, MTMSN=0
2013-03-13T20:17:56.210Z,1363205876.210 [NAL9602](INFO): Sent 219 bytes from file Logs/20130313T195025/shore0005.lzma
2013-03-13T20:17:56.210Z,1363205876.210 [NAL9602](INFO): Packets left to send: 0
2013-03-13T20:17:56.212Z,1363205876.212 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0005.lzma.parts/0000.sbd
2013-03-13T20:18:06.960Z,1363205886.960 [NAL9602](INFO): SBD MO Status=0, MOMSN=7196, MT Status=0, MTMSN=0
2013-03-13T20:18:07.044Z,1363205887.044 [Default:Iridium:Read_Iridium] Stopped
2013-03-13T20:18:07.045Z,1363205887.045 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-13T20:18:07.045Z,1363205887.045 [Default:Iridium] Stopped
2013-03-13T20:18:07.045Z,1363205887.045 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-13T20:18:07.045Z,1363205887.045 [Default:Iridium:A.SetSpeed] Stopped
2013-03-13T20:18:07.045Z,1363205887.045 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T20:18:07.375Z,1363205887.375 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-13T20:18:07.375Z,1363205887.375 [Default:CallIridium:B] Stopped
2013-03-13T20:18:07.375Z,1363205887.375 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-13T20:18:07.375Z,1363205887.375 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-13T20:18:07.375Z,1363205887.375 [Default:CallIridium] Stopped
2013-03-13T20:18:07.376Z,1363205887.376 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-13T20:18:17.516Z,1363205897.516 [NAL9602](INFO): Powering down
2013-03-13T20:20:47.559Z,1363206047.559 [Depth_Keller](ERROR): Pressure reading out of range: 1830.574585 decibar
2013-03-13T20:21:37.527Z,1363206097.527 [Depth_Keller](ERROR): Pressure reading out of range: 1830.612915 decibar
2013-03-13T20:21:47.527Z,1363206107.527 [Depth_Keller](ERROR): Pressure reading out of range: 1830.544922 decibar
2013-03-13T20:21:57.527Z,1363206117.527 [Depth_Keller](ERROR): Pressure reading out of range: 1830.547485 decibar
2013-03-13T20:22:07.523Z,1363206127.523 [Depth_Keller](ERROR): Pressure reading out of range: 1830.557983 decibar
2013-03-13T20:22:17.542Z,1363206137.542 [Depth_Keller](ERROR): Pressure reading out of range: 1830.583130 decibar
2013-03-13T20:23:07.568Z,1363206187.568 [Default:CallIridium] Running Loop=1
2013-03-13T20:23:07.568Z,1363206187.568 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-13T20:23:07.568Z,1363206187.568 [Default:CallIridium:A] Running Loop=1
2013-03-13T20:23:07.568Z,1363206187.568 [Default:CallIridium:A] Stopped
2013-03-13T20:23:07.568Z,1363206187.568 [Default:CallIridium:B] Running Loop=1
2013-03-13T20:23:07.568Z,1363206187.569 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-13T20:23:12.536Z,1363206192.536 [Default:Iridium] Running Loop=1
2013-03-13T20:23:12.536Z,1363206192.536 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-13T20:23:12.536Z,1363206192.536 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-13T20:23:12.536Z,1363206192.536 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-13T20:23:12.536Z,1363206192.536 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-13T20:23:12.536Z,1363206192.536 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T20:23:12.537Z,1363206192.537 [Default:Iridium:B.GoToSurface] Stopped
2013-03-13T20:23:12.537Z,1363206192.537 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T20:23:12.537Z,1363206192.537 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-13T20:23:12.538Z,1363206192.538 [Default:GPS] Running Loop=1
2013-03-13T20:23:12.538Z,1363206192.538 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-13T20:23:12.538Z,1363206192.538 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-13T20:23:12.538Z,1363206192.538 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-13T20:23:12.538Z,1363206192.538 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-13T20:23:12.538Z,1363206192.538 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T20:23:12.539Z,1363206192.539 [Default:GPS:B.GoToSurface] Stopped
2013-03-13T20:23:12.539Z,1363206192.539 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T20:23:12.539Z,1363206192.539 [Default:GPS:Read_GPS] Running Loop=1
2013-03-13T20:23:13.159Z,1363206193.159 [NAL9602](INFO): Powering up
2013-03-13T20:23:24.030Z,1363206204.030 [NAL9602](INFO): NAL9602 initialized
2013-03-13T20:23:44.920Z,1363206224.920 [NAL9602](INFO): SBD MO Status=1, MOMSN=7197, MT Status=0, MTMSN=0
2013-03-13T20:23:44.970Z,1363206224.970 [NAL9602](INFO): Sent 137 bytes from file Logs/20130313T195025/shore0006.lzma
2013-03-13T20:23:44.970Z,1363206224.970 [NAL9602](INFO): Packets left to send: 0
2013-03-13T20:23:44.975Z,1363206224.975 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0006.lzma.parts/0000.sbd
2013-03-13T20:23:53.753Z,1363206233.753 [NAL9602](INFO): SBD MO Status=0, MOMSN=7198, MT Status=0, MTMSN=0
2013-03-13T20:23:53.841Z,1363206233.841 [Default:Iridium:Read_Iridium] Stopped
2013-03-13T20:23:53.841Z,1363206233.841 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-13T20:23:53.841Z,1363206233.841 [Default:Iridium] Stopped
2013-03-13T20:23:53.842Z,1363206233.842 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-13T20:23:53.842Z,1363206233.842 [Default:Iridium:A.SetSpeed] Stopped
2013-03-13T20:23:53.842Z,1363206233.842 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T20:23:54.139Z,1363206234.139 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-13T20:23:54.139Z,1363206234.139 [Default:CallIridium:B] Stopped
2013-03-13T20:23:54.139Z,1363206234.139 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-13T20:23:54.140Z,1363206234.140 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-13T20:23:54.140Z,1363206234.140 [Default:CallIridium] Stopped
2013-03-13T20:23:54.140Z,1363206234.140 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-13T20:23:54.922Z,1363206234.922 [NAL9602](IMPORTANT): GPS fix at: 1363206223.00
2013-03-13T20:23:54.939Z,1363206234.939 [Default:GPS:Read_GPS] Stopped
2013-03-13T20:23:54.939Z,1363206234.939 [Default:GPS:D] Running Loop=1
2013-03-13T20:23:55.352Z,1363206235.352 [Default:GPS:D] Stopped
2013-03-13T20:23:55.352Z,1363206235.352 [Default:GPS](INFO): Completed Default:GPS
2013-03-13T20:23:55.352Z,1363206235.352 [Default:GPS] Stopped
2013-03-13T20:23:55.352Z,1363206235.352 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-13T20:23:55.352Z,1363206235.352 [Default:GPS:A.SetSpeed] Stopped
2013-03-13T20:23:55.352Z,1363206235.352 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T20:24:05.927Z,1363206245.927 [Depth_Keller](ERROR): Pressure reading out of range: 1830.575317 decibar
2013-03-13T20:24:15.926Z,1363206255.926 [Depth_Keller](ERROR): Pressure reading out of range: 1830.578857 decibar
2013-03-13T20:24:15.928Z,1363206255.928 [NAL9602](INFO): Powering down
2013-03-13T20:24:35.892Z,1363206275.892 [Depth_Keller](ERROR): Pressure reading out of range: 1830.580688 decibar
2013-03-13T20:24:45.900Z,1363206285.901 [Depth_Keller](ERROR): Pressure reading out of range: 1830.565063 decibar
2013-03-13T20:24:55.894Z,1363206295.894 [Depth_Keller](ERROR): Pressure reading out of range: 1830.572876 decibar
2013-03-13T20:25:05.915Z,1363206305.915 [Depth_Keller](ERROR): Pressure reading out of range: 1830.560547 decibar
2013-03-13T20:25:15.923Z,1363206315.923 [Depth_Keller](ERROR): Pressure reading out of range: 1830.563232 decibar
2013-03-13T20:25:25.923Z,1363206325.923 [Depth_Keller](ERROR): Pressure reading out of range: 1830.578125 decibar
2013-03-13T20:25:35.915Z,1363206335.915 [Depth_Keller](ERROR): Pressure reading out of range: 1830.560547 decibar
2013-03-13T20:25:45.911Z,1363206345.911 [Depth_Keller](ERROR): Pressure reading out of range: 1830.595459 decibar
2013-03-13T20:25:55.915Z,1363206355.915 [Depth_Keller](ERROR): Pressure reading out of range: 1830.563232 decibar
2013-03-13T20:26:05.903Z,1363206365.903 [Depth_Keller](ERROR): Pressure reading out of range: 1830.604248 decibar
2013-03-13T20:26:15.875Z,1363206375.875 [Depth_Keller](ERROR): Pressure reading out of range: 1830.603271 decibar
2013-03-13T20:26:25.875Z,1363206385.875 [Depth_Keller](ERROR): Pressure reading out of range: 1830.557007 decibar
2013-03-13T20:26:35.900Z,1363206395.900 [Depth_Keller](ERROR): Pressure reading out of range: 1830.576294 decibar
2013-03-13T20:26:45.875Z,1363206405.875 [Depth_Keller](ERROR): Pressure reading out of range: 1830.573608 decibar
2013-03-13T20:26:55.883Z,1363206415.883 [Depth_Keller](ERROR): Pressure reading out of range: 1830.599731 decibar
2013-03-13T20:27:05.875Z,1363206425.875 [Depth_Keller](ERROR): Pressure reading out of range: 1830.618164 decibar
2013-03-13T20:27:15.875Z,1363206435.875 [Depth_Keller](ERROR): Pressure reading out of range: 1830.590210 decibar
2013-03-13T20:27:25.879Z,1363206445.879 [Depth_Keller](ERROR): Pressure reading out of range: 1830.595459 decibar
2013-03-13T20:28:55.871Z,1363206535.871 [Default:CallIridium] Running Loop=1
2013-03-13T20:28:55.871Z,1363206535.871 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-13T20:28:55.872Z,1363206535.872 [Default:CallIridium:A] Running Loop=1
2013-03-13T20:28:55.872Z,1363206535.872 [Default:CallIridium:A] Stopped
2013-03-13T20:28:55.872Z,1363206535.872 [Default:CallIridium:B] Running Loop=1
2013-03-13T20:28:55.872Z,1363206535.872 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-13T20:29:00.932Z,1363206540.932 [Default:Iridium] Running Loop=1
2013-03-13T20:29:00.932Z,1363206540.932 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-13T20:29:00.932Z,1363206540.932 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-13T20:29:00.932Z,1363206540.932 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-13T20:29:00.933Z,1363206540.932 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-13T20:29:00.933Z,1363206540.932 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T20:29:00.933Z,1363206540.934 [Default:Iridium:B.GoToSurface] Stopped
2013-03-13T20:29:00.934Z,1363206540.934 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T20:29:00.934Z,1363206540.934 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-13T20:29:00.934Z,1363206540.934 [Default:GPS] Running Loop=1
2013-03-13T20:29:00.934Z,1363206540.934 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-13T20:29:00.934Z,1363206540.934 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-13T20:29:00.934Z,1363206540.934 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-13T20:29:00.934Z,1363206540.934 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-13T20:29:00.935Z,1363206540.935 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T20:29:00.935Z,1363206540.935 [Default:GPS:B.GoToSurface] Stopped
2013-03-13T20:29:00.935Z,1363206540.935 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T20:29:00.936Z,1363206540.936 [Default:GPS:Read_GPS] Running Loop=1
2013-03-13T20:29:01.543Z,1363206541.543 [NAL9602](INFO): Powering up
2013-03-13T20:29:11.970Z,1363206551.970 [NAL9602](INFO): NAL9602 initialized
2013-03-13T20:29:29.700Z,1363206569.700 [NAL9602](INFO): SBD MO Status=1, MOMSN=7199, MT Status=0, MTMSN=0
2013-03-13T20:29:29.750Z,1363206569.750 [NAL9602](INFO): Sent 135 bytes from file Logs/20130313T195025/shore0007.lzma
2013-03-13T20:29:29.750Z,1363206569.750 [NAL9602](INFO): Packets left to send: 0
2013-03-13T20:29:29.752Z,1363206569.752 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0007.lzma.parts/0000.sbd
2013-03-13T20:29:38.900Z,1363206578.900 [NAL9602](INFO): SBD MO Status=0, MOMSN=7200, MT Status=0, MTMSN=0
2013-03-13T20:29:38.984Z,1363206578.984 [Default:Iridium:Read_Iridium] Stopped
2013-03-13T20:29:38.984Z,1363206578.984 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-13T20:29:38.985Z,1363206578.985 [Default:Iridium] Stopped
2013-03-13T20:29:38.985Z,1363206578.985 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-13T20:29:38.985Z,1363206578.985 [Default:Iridium:A.SetSpeed] Stopped
2013-03-13T20:29:38.985Z,1363206578.985 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T20:29:39.315Z,1363206579.315 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-13T20:29:39.315Z,1363206579.316 [Default:CallIridium:B] Stopped
2013-03-13T20:29:39.316Z,1363206579.316 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-13T20:29:39.316Z,1363206579.316 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-13T20:29:39.316Z,1363206579.316 [Default:CallIridium] Stopped
2013-03-13T20:29:39.316Z,1363206579.316 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-13T20:29:40.102Z,1363206580.102 [NAL9602](IMPORTANT): GPS fix at: 1363206569.00
2013-03-13T20:29:40.119Z,1363206580.119 [Default:GPS:Read_GPS] Stopped
2013-03-13T20:29:40.119Z,1363206580.119 [Default:GPS:D] Running Loop=1
2013-03-13T20:29:40.535Z,1363206580.536 [Default:GPS:D] Stopped
2013-03-13T20:29:40.536Z,1363206580.536 [Default:GPS](INFO): Completed Default:GPS
2013-03-13T20:29:40.536Z,1363206580.536 [Default:GPS] Stopped
2013-03-13T20:29:40.536Z,1363206580.536 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-13T20:29:40.536Z,1363206580.536 [Default:GPS:A.SetSpeed] Stopped
2013-03-13T20:29:40.536Z,1363206580.536 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T20:30:01.100Z,1363206601.100 [NAL9602](INFO): Powering down
2013-03-13T20:34:41.110Z,1363206881.110 [Default:CallIridium] Running Loop=1
2013-03-13T20:34:41.110Z,1363206881.110 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-13T20:34:41.110Z,1363206881.110 [Default:CallIridium:A] Running Loop=1
2013-03-13T20:34:41.111Z,1363206881.111 [Default:CallIridium:A] Stopped
2013-03-13T20:34:41.111Z,1363206881.111 [Default:CallIridium:B] Running Loop=1
2013-03-13T20:34:41.111Z,1363206881.111 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-13T20:34:46.084Z,1363206886.084 [Default:Iridium] Running Loop=1
2013-03-13T20:34:46.084Z,1363206886.084 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-13T20:34:46.084Z,1363206886.084 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-13T20:34:46.084Z,1363206886.084 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-13T20:34:46.084Z,1363206886.084 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-13T20:34:46.084Z,1363206886.084 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T20:34:46.085Z,1363206886.085 [Default:Iridium:B.GoToSurface] Stopped
2013-03-13T20:34:46.085Z,1363206886.085 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T20:34:46.102Z,1363206886.102 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-13T20:34:46.102Z,1363206886.102 [Default:GPS] Running Loop=1
2013-03-13T20:34:46.102Z,1363206886.102 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-13T20:34:46.102Z,1363206886.102 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-13T20:34:46.102Z,1363206886.102 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-13T20:34:46.102Z,1363206886.102 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-13T20:34:46.102Z,1363206886.102 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T20:34:46.103Z,1363206886.103 [Default:GPS:B.GoToSurface] Stopped
2013-03-13T20:34:46.103Z,1363206886.103 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T20:34:46.104Z,1363206886.103 [Default:GPS:Read_GPS] Running Loop=1
2013-03-13T20:34:46.751Z,1363206886.751 [NAL9602](INFO): Powering up
2013-03-13T20:34:57.210Z,1363206897.210 [NAL9602](INFO): NAL9602 initialized
2013-03-13T20:35:15.360Z,1363206915.360 [NAL9602](INFO): SBD MO Status=2, MOMSN=7201, MT Status=2, MTMSN=0
2013-03-13T20:35:15.361Z,1363206915.361 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-13T20:35:16.595Z,1363206916.595 [NAL9602](IMPORTANT): GPS fix at: 1363206906.00
2013-03-13T20:35:16.613Z,1363206916.612 [Default:GPS:Read_GPS] Stopped
2013-03-13T20:35:16.613Z,1363206916.613 [Default:GPS:D] Running Loop=1
2013-03-13T20:35:17.002Z,1363206917.002 [Default:GPS:D] Stopped
2013-03-13T20:35:17.002Z,1363206917.002 [Default:GPS](INFO): Completed Default:GPS
2013-03-13T20:35:17.002Z,1363206917.002 [Default:GPS] Stopped
2013-03-13T20:35:17.002Z,1363206917.002 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-13T20:35:17.002Z,1363206917.002 [Default:GPS:A.SetSpeed] Stopped
2013-03-13T20:35:17.002Z,1363206917.003 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T20:36:04.032Z,1363206964.032 [NAL9602](INFO): SBD MO Status=2, MOMSN=7201, MT Status=2, MTMSN=0
2013-03-13T20:36:04.032Z,1363206964.032 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-13T20:36:27.064Z,1363206987.064 [NAL9602](INFO): SBD MO Status=1, MOMSN=7201, MT Status=0, MTMSN=0
2013-03-13T20:36:27.122Z,1363206987.122 [NAL9602](INFO): Sent 138 bytes from file Logs/20130313T195025/shore0008.lzma
2013-03-13T20:36:27.122Z,1363206987.122 [NAL9602](INFO): Packets left to send: 0
2013-03-13T20:36:27.124Z,1363206987.124 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0008.lzma.parts/0000.sbd
2013-03-13T20:36:48.717Z,1363207008.717 [NAL9602](INFO): SBD MO Status=2, MOMSN=7202, MT Status=2, MTMSN=0
2013-03-13T20:36:48.717Z,1363207008.717 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-13T20:37:00.191Z,1363207020.191 [NAL9602](INFO): SBD MO Status=0, MOMSN=7202, MT Status=0, MTMSN=0
2013-03-13T20:37:00.284Z,1363207020.284 [Default:Iridium:Read_Iridium] Stopped
2013-03-13T20:37:00.285Z,1363207020.285 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-13T20:37:00.285Z,1363207020.285 [Default:Iridium] Stopped
2013-03-13T20:37:00.285Z,1363207020.285 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-13T20:37:00.285Z,1363207020.285 [Default:Iridium:A.SetSpeed] Stopped
2013-03-13T20:37:00.285Z,1363207020.285 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T20:37:00.605Z,1363207020.605 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-13T20:37:00.605Z,1363207020.605 [Default:CallIridium:B] Stopped
2013-03-13T20:37:00.605Z,1363207020.605 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-13T20:37:00.605Z,1363207020.605 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-13T20:37:00.606Z,1363207020.606 [Default:CallIridium] Stopped
2013-03-13T20:37:00.606Z,1363207020.606 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-13T20:37:10.763Z,1363207030.763 [NAL9602](INFO): Powering down
2013-03-13T20:42:00.811Z,1363207320.811 [Default:CallIridium] Running Loop=1
2013-03-13T20:42:00.811Z,1363207320.811 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-13T20:42:00.811Z,1363207320.811 [Default:CallIridium:A] Running Loop=1
2013-03-13T20:42:00.812Z,1363207320.812 [Default:CallIridium:A] Stopped
2013-03-13T20:42:00.812Z,1363207320.812 [Default:CallIridium:B] Running Loop=1
2013-03-13T20:42:00.812Z,1363207320.812 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-13T20:42:05.760Z,1363207325.760 [Default:Iridium] Running Loop=1
2013-03-13T20:42:05.760Z,1363207325.760 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-13T20:42:05.760Z,1363207325.760 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-13T20:42:05.760Z,1363207325.760 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-13T20:42:05.760Z,1363207325.760 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-13T20:42:05.761Z,1363207325.760 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T20:42:05.761Z,1363207325.761 [Default:Iridium:B.GoToSurface] Stopped
2013-03-13T20:42:05.762Z,1363207325.761 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T20:42:05.762Z,1363207325.762 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-13T20:42:05.762Z,1363207325.762 [Default:GPS] Running Loop=1
2013-03-13T20:42:05.762Z,1363207325.762 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-13T20:42:05.762Z,1363207325.762 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-13T20:42:05.762Z,1363207325.762 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-13T20:42:05.762Z,1363207325.762 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-13T20:42:05.762Z,1363207325.763 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T20:42:05.763Z,1363207325.763 [Default:GPS:B.GoToSurface] Stopped
2013-03-13T20:42:05.763Z,1363207325.763 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T20:42:05.763Z,1363207325.763 [Default:GPS:Read_GPS] Running Loop=1
2013-03-13T20:42:06.389Z,1363207326.389 [NAL9602](INFO): Powering up
2013-03-13T20:42:17.230Z,1363207337.230 [NAL9602](INFO): NAL9602 initialized
2013-03-13T20:42:43.751Z,1363207363.751 [NAL9602](INFO): SBD MO Status=1, MOMSN=7203, MT Status=0, MTMSN=0
2013-03-13T20:42:43.798Z,1363207363.798 [NAL9602](INFO): Sent 135 bytes from file Logs/20130313T195025/shore0009.lzma
2013-03-13T20:42:43.798Z,1363207363.798 [NAL9602](INFO): Packets left to send: 0
2013-03-13T20:42:43.800Z,1363207363.800 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0009.lzma.parts/0000.sbd
2013-03-13T20:42:55.009Z,1363207375.009 [NAL9602](INFO): SBD MO Status=0, MOMSN=7204, MT Status=0, MTMSN=0
2013-03-13T20:42:55.106Z,1363207375.106 [Default:Iridium:Read_Iridium] Stopped
2013-03-13T20:42:55.107Z,1363207375.107 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-13T20:42:55.107Z,1363207375.107 [Default:Iridium] Stopped
2013-03-13T20:42:55.107Z,1363207375.107 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-13T20:42:55.107Z,1363207375.107 [Default:Iridium:A.SetSpeed] Stopped
2013-03-13T20:42:55.107Z,1363207375.107 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T20:42:55.378Z,1363207375.378 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-13T20:42:55.378Z,1363207375.378 [Default:CallIridium:B] Stopped
2013-03-13T20:42:55.379Z,1363207375.378 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-13T20:42:55.379Z,1363207375.379 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-13T20:42:55.379Z,1363207375.379 [Default:CallIridium] Stopped
2013-03-13T20:42:55.379Z,1363207375.379 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-13T20:42:56.202Z,1363207376.202 [NAL9602](IMPORTANT): GPS fix at: 1363207366.00
2013-03-13T20:42:56.219Z,1363207376.219 [Default:GPS:Read_GPS] Stopped
2013-03-13T20:42:56.219Z,1363207376.219 [Default:GPS:D] Running Loop=1
2013-03-13T20:42:56.597Z,1363207376.597 [Default:GPS:D] Stopped
2013-03-13T20:42:56.598Z,1363207376.598 [Default:GPS](INFO): Completed Default:GPS
2013-03-13T20:42:56.598Z,1363207376.598 [Default:GPS] Stopped
2013-03-13T20:42:56.598Z,1363207376.598 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-13T20:42:56.598Z,1363207376.598 [Default:GPS:A.SetSpeed] Stopped
2013-03-13T20:42:56.598Z,1363207376.598 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T20:43:17.127Z,1363207397.127 [NAL9602](INFO): Powering down
2013-03-13T20:47:57.140Z,1363207677.140 [Default:CallIridium] Running Loop=1
2013-03-13T20:47:57.140Z,1363207677.140 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-13T20:47:57.140Z,1363207677.140 [Default:CallIridium:A] Running Loop=1
2013-03-13T20:47:57.141Z,1363207677.141 [Default:CallIridium:A] Stopped
2013-03-13T20:47:57.141Z,1363207677.141 [Default:CallIridium:B] Running Loop=1
2013-03-13T20:47:57.141Z,1363207677.141 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-13T20:48:02.164Z,1363207682.164 [Default:Iridium] Running Loop=1
2013-03-13T20:48:02.164Z,1363207682.164 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-13T20:48:02.164Z,1363207682.164 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-13T20:48:02.164Z,1363207682.164 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-13T20:48:02.164Z,1363207682.164 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-13T20:48:02.164Z,1363207682.164 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T20:48:02.165Z,1363207682.165 [Default:Iridium:B.GoToSurface] Stopped
2013-03-13T20:48:02.165Z,1363207682.165 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T20:48:02.165Z,1363207682.165 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-13T20:48:02.166Z,1363207682.165 [Default:GPS] Running Loop=1
2013-03-13T20:48:02.166Z,1363207682.166 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-13T20:48:02.166Z,1363207682.166 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-13T20:48:02.166Z,1363207682.166 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-13T20:48:02.166Z,1363207682.166 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-13T20:48:02.166Z,1363207682.166 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T20:48:02.167Z,1363207682.167 [Default:GPS:B.GoToSurface] Stopped
2013-03-13T20:48:02.167Z,1363207682.167 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T20:48:02.167Z,1363207682.167 [Default:GPS:Read_GPS] Running Loop=1
2013-03-13T20:48:02.763Z,1363207682.763 [NAL9602](INFO): Powering up
2013-03-13T20:48:13.606Z,1363207693.606 [NAL9602](INFO): NAL9602 initialized
2013-03-13T20:48:37.753Z,1363207717.753 [NAL9602](INFO): SBD MO Status=1, MOMSN=7205, MT Status=0, MTMSN=0
2013-03-13T20:48:37.810Z,1363207717.810 [NAL9602](INFO): Sent 138 bytes from file Logs/20130313T195025/shore0010.lzma
2013-03-13T20:48:37.810Z,1363207717.810 [NAL9602](INFO): Packets left to send: 0
2013-03-13T20:48:37.812Z,1363207717.812 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0010.lzma.parts/0000.sbd
2013-03-13T20:48:47.349Z,1363207727.349 [NAL9602](INFO): SBD MO Status=0, MOMSN=7206, MT Status=0, MTMSN=0
2013-03-13T20:48:47.432Z,1363207727.432 [Default:Iridium:Read_Iridium] Stopped
2013-03-13T20:48:47.433Z,1363207727.433 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-13T20:48:47.433Z,1363207727.433 [Default:Iridium] Stopped
2013-03-13T20:48:47.433Z,1363207727.433 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-13T20:48:47.433Z,1363207727.433 [Default:Iridium:A.SetSpeed] Stopped
2013-03-13T20:48:47.433Z,1363207727.433 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T20:48:47.728Z,1363207727.728 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-13T20:48:47.728Z,1363207727.728 [Default:CallIridium:B] Stopped
2013-03-13T20:48:47.728Z,1363207727.728 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-13T20:48:47.729Z,1363207727.729 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-13T20:48:47.729Z,1363207727.729 [Default:CallIridium] Stopped
2013-03-13T20:48:47.729Z,1363207727.729 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-13T20:48:48.518Z,1363207728.518 [NAL9602](IMPORTANT): GPS fix at: 1363207719.00
2013-03-13T20:48:48.535Z,1363207728.535 [Default:GPS:Read_GPS] Stopped
2013-03-13T20:48:48.535Z,1363207728.535 [Default:GPS:D] Running Loop=1
2013-03-13T20:48:48.946Z,1363207728.946 [Default:GPS:D] Stopped
2013-03-13T20:48:48.947Z,1363207728.947 [Default:GPS](INFO): Completed Default:GPS
2013-03-13T20:48:48.947Z,1363207728.947 [Default:GPS] Stopped
2013-03-13T20:48:48.947Z,1363207728.947 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-13T20:48:48.947Z,1363207728.947 [Default:GPS:A.SetSpeed] Stopped
2013-03-13T20:48:48.947Z,1363207728.947 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T20:49:09.476Z,1363207749.476 [NAL9602](INFO): Powering down
2013-03-13T20:53:49.528Z,1363208029.528 [Default:CallIridium] Running Loop=1
2013-03-13T20:53:49.528Z,1363208029.528 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-13T20:53:49.528Z,1363208029.528 [Default:CallIridium:A] Running Loop=1
2013-03-13T20:53:49.529Z,1363208029.529 [Default:CallIridium:A] Stopped
2013-03-13T20:53:49.529Z,1363208029.529 [Default:CallIridium:B] Running Loop=1
2013-03-13T20:53:49.529Z,1363208029.529 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-13T20:53:54.515Z,1363208034.515 [Default:Iridium] Running Loop=1
2013-03-13T20:53:54.515Z,1363208034.515 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-13T20:53:54.515Z,1363208034.515 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-13T20:53:54.515Z,1363208034.515 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-13T20:53:54.515Z,1363208034.515 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-13T20:53:54.516Z,1363208034.516 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T20:53:54.516Z,1363208034.516 [Default:Iridium:B.GoToSurface] Stopped
2013-03-13T20:53:54.516Z,1363208034.516 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T20:53:54.516Z,1363208034.516 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-13T20:53:54.517Z,1363208034.517 [Default:GPS] Running Loop=1
2013-03-13T20:53:54.517Z,1363208034.517 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-13T20:53:54.517Z,1363208034.517 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-13T20:53:54.517Z,1363208034.517 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-13T20:53:54.517Z,1363208034.517 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-13T20:53:54.517Z,1363208034.517 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T20:53:54.518Z,1363208034.518 [Default:GPS:B.GoToSurface] Stopped
2013-03-13T20:53:54.518Z,1363208034.518 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T20:53:54.518Z,1363208034.518 [Default:GPS:Read_GPS] Running Loop=1
2013-03-13T20:53:55.115Z,1363208035.115 [NAL9602](INFO): Powering up
2013-03-13T20:54:05.950Z,1363208045.950 [NAL9602](INFO): NAL9602 initialized
2013-03-13T20:54:22.916Z,1363208062.916 [NAL9602](INFO): SBD MO Status=1, MOMSN=7207, MT Status=0, MTMSN=0
2013-03-13T20:54:22.966Z,1363208062.966 [NAL9602](INFO): Sent 137 bytes from file Logs/20130313T195025/shore0011.lzma
2013-03-13T20:54:22.966Z,1363208062.966 [NAL9602](INFO): Packets left to send: 0
2013-03-13T20:54:22.968Z,1363208062.968 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0011.lzma.parts/0000.sbd
2013-03-13T20:54:40.912Z,1363208080.912 [NAL9602](INFO): SBD MO Status=2, MOMSN=7208, MT Status=2, MTMSN=0
2013-03-13T20:54:40.913Z,1363208080.912 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-13T20:54:42.114Z,1363208082.114 [NAL9602](IMPORTANT): GPS fix at: 1363208073.00
2013-03-13T20:54:42.135Z,1363208082.135 [Default:GPS:Read_GPS] Stopped
2013-03-13T20:54:42.136Z,1363208082.136 [Default:GPS:D] Running Loop=1
2013-03-13T20:54:42.539Z,1363208082.539 [Default:GPS:D] Stopped
2013-03-13T20:54:42.539Z,1363208082.539 [Default:GPS](INFO): Completed Default:GPS
2013-03-13T20:54:42.539Z,1363208082.539 [Default:GPS] Stopped
2013-03-13T20:54:42.539Z,1363208082.539 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-13T20:54:42.539Z,1363208082.539 [Default:GPS:A.SetSpeed] Stopped
2013-03-13T20:54:42.539Z,1363208082.539 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T20:55:03.244Z,1363208103.244 [NAL9602](INFO): SBD MO Status=0, MOMSN=7208, MT Status=0, MTMSN=0
2013-03-13T20:55:03.328Z,1363208103.328 [Default:Iridium:Read_Iridium] Stopped
2013-03-13T20:55:03.329Z,1363208103.329 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-13T20:55:03.329Z,1363208103.329 [Default:Iridium] Stopped
2013-03-13T20:55:03.329Z,1363208103.329 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-13T20:55:03.329Z,1363208103.329 [Default:Iridium:A.SetSpeed] Stopped
2013-03-13T20:55:03.329Z,1363208103.329 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T20:55:03.659Z,1363208103.659 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-13T20:55:03.659Z,1363208103.659 [Default:CallIridium:B] Stopped
2013-03-13T20:55:03.659Z,1363208103.659 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-13T20:55:03.659Z,1363208103.659 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-13T20:55:03.659Z,1363208103.659 [Default:CallIridium] Stopped
2013-03-13T20:55:03.659Z,1363208103.659 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-13T20:55:13.816Z,1363208113.816 [NAL9602](INFO): Powering down
2013-03-13T21:00:03.848Z,1363208403.848 [Default:CallIridium] Running Loop=1
2013-03-13T21:00:03.848Z,1363208403.848 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-13T21:00:03.848Z,1363208403.848 [Default:CallIridium:A] Running Loop=1
2013-03-13T21:00:03.849Z,1363208403.849 [Default:CallIridium:A] Stopped
2013-03-13T21:00:03.849Z,1363208403.849 [Default:CallIridium:B] Running Loop=1
2013-03-13T21:00:03.849Z,1363208403.849 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-13T21:00:08.832Z,1363208408.832 [Default:Iridium] Running Loop=1
2013-03-13T21:00:08.832Z,1363208408.832 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-13T21:00:08.832Z,1363208408.832 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-13T21:00:08.832Z,1363208408.832 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-13T21:00:08.832Z,1363208408.832 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-13T21:00:08.832Z,1363208408.832 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T21:00:08.833Z,1363208408.833 [Default:Iridium:B.GoToSurface] Stopped
2013-03-13T21:00:08.833Z,1363208408.833 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T21:00:08.834Z,1363208408.834 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-13T21:00:08.834Z,1363208408.834 [Default:GPS] Running Loop=1
2013-03-13T21:00:08.834Z,1363208408.834 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-13T21:00:08.834Z,1363208408.834 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-13T21:00:08.834Z,1363208408.834 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-13T21:00:08.834Z,1363208408.834 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-13T21:00:08.835Z,1363208408.835 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T21:00:08.835Z,1363208408.835 [Default:GPS:B.GoToSurface] Stopped
2013-03-13T21:00:08.835Z,1363208408.835 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T21:00:08.836Z,1363208408.836 [Default:GPS:Read_GPS] Running Loop=1
2013-03-13T21:00:09.443Z,1363208409.443 [NAL9602](INFO): Powering up
2013-03-13T21:00:19.882Z,1363208419.882 [NAL9602](INFO): NAL9602 initialized
2013-03-13T21:00:37.749Z,1363208437.749 [NAL9602](INFO): SBD MO Status=1, MOMSN=7209, MT Status=0, MTMSN=0
2013-03-13T21:00:37.798Z,1363208437.798 [NAL9602](INFO): Sent 142 bytes from file Logs/20130313T195025/shore0012.lzma
2013-03-13T21:00:37.798Z,1363208437.798 [NAL9602](INFO): Packets left to send: 0
2013-03-13T21:00:37.800Z,1363208437.800 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0012.lzma.parts/0000.sbd
2013-03-13T21:00:48.493Z,1363208448.493 [NAL9602](INFO): SBD MO Status=0, MOMSN=7210, MT Status=0, MTMSN=0
2013-03-13T21:00:48.576Z,1363208448.576 [Default:Iridium:Read_Iridium] Stopped
2013-03-13T21:00:48.576Z,1363208448.576 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-13T21:00:48.576Z,1363208448.576 [Default:Iridium] Stopped
2013-03-13T21:00:48.576Z,1363208448.576 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-13T21:00:48.576Z,1363208448.576 [Default:Iridium:A.SetSpeed] Stopped
2013-03-13T21:00:48.577Z,1363208448.577 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T21:00:48.915Z,1363208448.915 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-13T21:00:48.915Z,1363208448.915 [Default:CallIridium:B] Stopped
2013-03-13T21:00:48.916Z,1363208448.916 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-13T21:00:48.916Z,1363208448.916 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-13T21:00:48.916Z,1363208448.916 [Default:CallIridium] Stopped
2013-03-13T21:00:48.916Z,1363208448.916 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-13T21:00:49.695Z,1363208449.695 [NAL9602](IMPORTANT): GPS fix at: 1363208441.00
2013-03-13T21:00:49.711Z,1363208449.711 [Default:GPS:Read_GPS] Stopped
2013-03-13T21:00:49.711Z,1363208449.711 [Default:GPS:D] Running Loop=1
2013-03-13T21:00:50.122Z,1363208450.122 [Default:GPS:D] Stopped
2013-03-13T21:00:50.123Z,1363208450.123 [Default:GPS](INFO): Completed Default:GPS
2013-03-13T21:00:50.123Z,1363208450.123 [Default:GPS] Stopped
2013-03-13T21:00:50.123Z,1363208450.123 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-13T21:00:50.123Z,1363208450.123 [Default:GPS:A.SetSpeed] Stopped
2013-03-13T21:00:50.123Z,1363208450.123 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T21:01:10.656Z,1363208470.656 [NAL9602](INFO): Powering down
2013-03-13T21:05:50.683Z,1363208750.683 [Default:CallIridium] Running Loop=1
2013-03-13T21:05:50.684Z,1363208750.684 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-13T21:05:50.684Z,1363208750.684 [Default:CallIridium:A] Running Loop=1
2013-03-13T21:05:50.684Z,1363208750.684 [Default:CallIridium:A] Stopped
2013-03-13T21:05:50.684Z,1363208750.684 [Default:CallIridium:B] Running Loop=1
2013-03-13T21:05:50.684Z,1363208750.684 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-13T21:05:55.703Z,1363208755.703 [Default:Iridium] Running Loop=1
2013-03-13T21:05:55.703Z,1363208755.703 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-13T21:05:55.703Z,1363208755.703 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-13T21:05:55.703Z,1363208755.703 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-13T21:05:55.703Z,1363208755.703 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-13T21:05:55.703Z,1363208755.704 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T21:05:55.704Z,1363208755.704 [Default:Iridium:B.GoToSurface] Stopped
2013-03-13T21:05:55.704Z,1363208755.704 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T21:05:55.704Z,1363208755.704 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-13T21:05:55.705Z,1363208755.705 [Default:GPS] Running Loop=1
2013-03-13T21:05:55.705Z,1363208755.705 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-13T21:05:55.705Z,1363208755.705 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-13T21:05:55.705Z,1363208755.705 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-13T21:05:55.705Z,1363208755.705 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-13T21:05:55.705Z,1363208755.705 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T21:05:55.706Z,1363208755.706 [Default:GPS:B.GoToSurface] Stopped
2013-03-13T21:05:55.706Z,1363208755.706 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T21:05:55.706Z,1363208755.706 [Default:GPS:Read_GPS] Running Loop=1
2013-03-13T21:05:56.307Z,1363208756.307 [NAL9602](INFO): Powering up
2013-03-13T21:06:07.146Z,1363208767.146 [NAL9602](INFO): NAL9602 initialized
2013-03-13T21:06:26.932Z,1363208786.932 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=7211, MT Status=1, MTMSN=574
2013-03-13T21:06:26.982Z,1363208786.982 [NAL9602](INFO): Sent 136 bytes from file Logs/20130313T195025/shore0013.lzma
2013-03-13T21:06:26.982Z,1363208786.982 [NAL9602](INFO): Packets left to send: 0
2013-03-13T21:06:26.984Z,1363208786.984 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0013.lzma.parts/0000.sbd
2013-03-13T21:06:27.710Z,1363208787.710 [NAL9602](IMPORTANT): Initialized file: Missions/Engineering/SendWaterCurrentToShore.xml
2013-03-13T21:06:27.711Z,1363208787.711 [NAL9602](IMPORTANT): More data left to go, at position 52
2013-03-13T21:06:39.060Z,1363208799.060 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=7212, MT Status=1, MTMSN=575
2013-03-13T21:06:39.713Z,1363208799.713 [NAL9602](IMPORTANT): Added data to file: Missions/Engineering/SendWaterCurrentToShore.xml
2013-03-13T21:06:41.828Z,1363208801.828 [NAL9602](IMPORTANT): Success executing cat Logs/latest/513FB015.part | gunzip -f -d | cat `cp Missions/Engineering/.svn/text-base/SendWaterCurrentToShore.xml.svn-base Missions/Engineering/SendWaterCurrentToShore.xml` | vim -e Missions/Engineering/SendWaterCurrentToShore.xml
2013-03-13T21:06:41.963Z,1363208801.963 [CommandLine](IMPORTANT): 52d30ced588376532e7843a91cdbe23d  Missions/Engineering/SendWaterCurrentToShore.xml

2013-03-13T21:06:43.002Z,1363208803.002 [NAL9602](IMPORTANT): GPS fix at: 1363208795.00
2013-03-13T21:06:43.019Z,1363208803.019 [Default:GPS:Read_GPS] Stopped
2013-03-13T21:06:43.020Z,1363208803.020 [Default:GPS:D] Running Loop=1
2013-03-13T21:06:43.441Z,1363208803.441 [Default:GPS:D] Stopped
2013-03-13T21:06:43.441Z,1363208803.441 [Default:GPS](INFO): Completed Default:GPS
2013-03-13T21:06:43.442Z,1363208803.441 [Default:GPS] Stopped
2013-03-13T21:06:43.442Z,1363208803.442 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-13T21:06:43.442Z,1363208803.442 [Default:GPS:A.SetSpeed] Stopped
2013-03-13T21:06:43.442Z,1363208803.442 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T21:06:59.719Z,1363208819.719 [NAL9602](INFO): SBD MO Status=0, MOMSN=7213, MT Status=0, MTMSN=0
2013-03-13T21:07:27.936Z,1363208847.936 [NAL9602](INFO): SBD MO Status=2, MOMSN=7214, MT Status=2, MTMSN=0
2013-03-13T21:07:27.937Z,1363208847.937 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-13T21:07:51.691Z,1363208871.691 [NAL9602](INFO): SBD MO Status=2, MOMSN=7214, MT Status=2, MTMSN=0
2013-03-13T21:07:51.691Z,1363208871.691 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-13T21:08:17.900Z,1363208897.900 [NAL9602](INFO): SBD MO Status=1, MOMSN=7214, MT Status=0, MTMSN=0
2013-03-13T21:08:17.950Z,1363208897.950 [NAL9602](INFO): Sent 332 bytes from file Logs/20130313T195025/shore0014.lzma
2013-03-13T21:08:17.950Z,1363208897.950 [NAL9602](INFO): Packets left to send: 1
2013-03-13T21:08:17.952Z,1363208897.952 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0014.lzma.parts/0001.sbd
2013-03-13T21:08:30.160Z,1363208910.160 [NAL9602](INFO): SBD MO Status=1, MOMSN=7215, MT Status=0, MTMSN=0
2013-03-13T21:08:30.210Z,1363208910.210 [NAL9602](INFO): Sent 154 bytes from file Logs/20130313T195025/shore0014.lzma
2013-03-13T21:08:30.210Z,1363208910.210 [NAL9602](INFO): Packets left to send: 0
2013-03-13T21:08:30.211Z,1363208910.211 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0014.lzma.parts/0000.sbd
2013-03-13T21:08:41.355Z,1363208921.355 [NAL9602](INFO): SBD MO Status=0, MOMSN=7216, MT Status=0, MTMSN=0
2013-03-13T21:08:41.440Z,1363208921.440 [Default:Iridium:Read_Iridium] Stopped
2013-03-13T21:08:41.441Z,1363208921.441 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-13T21:08:41.441Z,1363208921.441 [Default:Iridium] Stopped
2013-03-13T21:08:41.441Z,1363208921.441 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-13T21:08:41.441Z,1363208921.441 [Default:Iridium:A.SetSpeed] Stopped
2013-03-13T21:08:41.441Z,1363208921.441 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T21:08:41.781Z,1363208921.781 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-13T21:08:41.781Z,1363208921.781 [Default:CallIridium:B] Stopped
2013-03-13T21:08:41.781Z,1363208921.781 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-13T21:08:41.781Z,1363208921.781 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-13T21:08:41.781Z,1363208921.781 [Default:CallIridium] Stopped
2013-03-13T21:08:41.781Z,1363208921.782 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-13T21:08:52.008Z,1363208932.008 [NAL9602](INFO): Powering down
2013-03-13T21:13:41.952Z,1363209221.952 [Default:CallIridium] Running Loop=1
2013-03-13T21:13:41.952Z,1363209221.952 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-13T21:13:41.952Z,1363209221.952 [Default:CallIridium:A] Running Loop=1
2013-03-13T21:13:41.953Z,1363209221.953 [Default:CallIridium:A] Stopped
2013-03-13T21:13:41.953Z,1363209221.953 [Default:CallIridium:B] Running Loop=1
2013-03-13T21:13:41.953Z,1363209221.953 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-13T21:13:46.963Z,1363209226.963 [Default:Iridium] Running Loop=1
2013-03-13T21:13:46.963Z,1363209226.963 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-13T21:13:46.964Z,1363209226.964 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-13T21:13:46.964Z,1363209226.964 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-13T21:13:46.964Z,1363209226.964 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-13T21:13:46.964Z,1363209226.964 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T21:13:46.965Z,1363209226.965 [Default:Iridium:B.GoToSurface] Stopped
2013-03-13T21:13:46.965Z,1363209226.965 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T21:13:46.965Z,1363209226.965 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-13T21:13:46.965Z,1363209226.965 [Default:GPS] Running Loop=1
2013-03-13T21:13:46.965Z,1363209226.965 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-13T21:13:46.965Z,1363209226.965 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-13T21:13:46.965Z,1363209226.966 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-13T21:13:46.966Z,1363209226.966 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-13T21:13:46.966Z,1363209226.966 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T21:13:46.966Z,1363209226.966 [Default:GPS:B.GoToSurface] Stopped
2013-03-13T21:13:46.966Z,1363209226.967 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T21:13:46.967Z,1363209226.967 [Default:GPS:Read_GPS] Running Loop=1
2013-03-13T21:13:47.563Z,1363209227.563 [NAL9602](INFO): Powering up
2013-03-13T21:13:58.002Z,1363209238.002 [NAL9602](INFO): NAL9602 initialized
2013-03-13T21:14:19.761Z,1363209259.761 [NAL9602](INFO): SBD MO Status=1, MOMSN=7217, MT Status=0, MTMSN=0
2013-03-13T21:14:19.810Z,1363209259.810 [NAL9602](INFO): Sent 104 bytes from file Logs/20130313T195025/shore0015.lzma
2013-03-13T21:14:19.810Z,1363209259.810 [NAL9602](INFO): Packets left to send: 0
2013-03-13T21:14:19.812Z,1363209259.812 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0015.lzma.parts/0000.sbd
2013-03-13T21:14:30.932Z,1363209270.932 [NAL9602](INFO): SBD MO Status=0, MOMSN=7218, MT Status=0, MTMSN=0
2013-03-13T21:14:31.016Z,1363209271.016 [Default:Iridium:Read_Iridium] Stopped
2013-03-13T21:14:31.016Z,1363209271.016 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-13T21:14:31.017Z,1363209271.017 [Default:Iridium] Stopped
2013-03-13T21:14:31.017Z,1363209271.017 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-13T21:14:31.017Z,1363209271.017 [Default:Iridium:A.SetSpeed] Stopped
2013-03-13T21:14:31.017Z,1363209271.017 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T21:14:31.347Z,1363209271.347 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-13T21:14:31.347Z,1363209271.347 [Default:CallIridium:B] Stopped
2013-03-13T21:14:31.347Z,1363209271.347 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-13T21:14:31.348Z,1363209271.348 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-13T21:14:31.348Z,1363209271.348 [Default:CallIridium] Stopped
2013-03-13T21:14:31.348Z,1363209271.348 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-13T21:14:32.138Z,1363209272.138 [NAL9602](IMPORTANT): GPS fix at: 1363209265.00
2013-03-13T21:14:32.155Z,1363209272.155 [Default:GPS:Read_GPS] Stopped
2013-03-13T21:14:32.155Z,1363209272.155 [Default:GPS:D] Running Loop=1
2013-03-13T21:14:32.558Z,1363209272.558 [Default:GPS:D] Stopped
2013-03-13T21:14:32.559Z,1363209272.559 [Default:GPS](INFO): Completed Default:GPS
2013-03-13T21:14:32.559Z,1363209272.559 [Default:GPS] Stopped
2013-03-13T21:14:32.559Z,1363209272.559 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-13T21:14:32.559Z,1363209272.559 [Default:GPS:A.SetSpeed] Stopped
2013-03-13T21:14:32.559Z,1363209272.559 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T21:14:53.124Z,1363209293.124 [NAL9602](INFO): Powering down
2013-03-13T21:19:33.118Z,1363209573.118 [Default:CallIridium] Running Loop=1
2013-03-13T21:19:33.118Z,1363209573.118 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-13T21:19:33.118Z,1363209573.118 [Default:CallIridium:A] Running Loop=1
2013-03-13T21:19:33.119Z,1363209573.118 [Default:CallIridium:A] Stopped
2013-03-13T21:19:33.119Z,1363209573.119 [Default:CallIridium:B] Running Loop=1
2013-03-13T21:19:33.119Z,1363209573.119 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-13T21:19:38.152Z,1363209578.152 [Default:Iridium] Running Loop=1
2013-03-13T21:19:38.152Z,1363209578.152 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-13T21:19:38.152Z,1363209578.152 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-13T21:19:38.152Z,1363209578.152 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-13T21:19:38.152Z,1363209578.152 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-13T21:19:38.152Z,1363209578.152 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T21:19:38.153Z,1363209578.153 [Default:Iridium:B.GoToSurface] Stopped
2013-03-13T21:19:38.153Z,1363209578.153 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T21:19:38.153Z,1363209578.153 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-13T21:19:38.154Z,1363209578.154 [Default:GPS] Running Loop=1
2013-03-13T21:19:38.154Z,1363209578.154 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-13T21:19:38.154Z,1363209578.154 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-13T21:19:38.154Z,1363209578.154 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-13T21:19:38.154Z,1363209578.154 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-13T21:19:38.154Z,1363209578.154 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T21:19:38.155Z,1363209578.155 [Default:GPS:B.GoToSurface] Stopped
2013-03-13T21:19:38.155Z,1363209578.155 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T21:19:38.155Z,1363209578.155 [Default:GPS:Read_GPS] Running Loop=1
2013-03-13T21:19:38.751Z,1363209578.751 [NAL9602](INFO): Powering up
2013-03-13T21:19:49.586Z,1363209589.586 [NAL9602](INFO): NAL9602 initialized
2013-03-13T21:20:18.124Z,1363209618.124 [NAL9602](INFO): SBD MO Status=1, MOMSN=7219, MT Status=0, MTMSN=0
2013-03-13T21:20:18.174Z,1363209618.174 [NAL9602](INFO): Sent 164 bytes from file Logs/20130313T195025/shore0016.lzma
2013-03-13T21:20:18.174Z,1363209618.174 [NAL9602](INFO): Packets left to send: 0
2013-03-13T21:20:18.177Z,1363209618.177 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0016.lzma.parts/0000.sbd
2013-03-13T21:20:29.319Z,1363209629.319 [NAL9602](INFO): SBD MO Status=0, MOMSN=7220, MT Status=0, MTMSN=0
2013-03-13T21:20:29.412Z,1363209629.412 [Default:Iridium:Read_Iridium] Stopped
2013-03-13T21:20:29.412Z,1363209629.412 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-13T21:20:29.412Z,1363209629.412 [Default:Iridium] Stopped
2013-03-13T21:20:29.412Z,1363209629.412 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-13T21:20:29.413Z,1363209629.413 [Default:Iridium:A.SetSpeed] Stopped
2013-03-13T21:20:29.413Z,1363209629.413 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T21:20:29.743Z,1363209629.743 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-13T21:20:29.743Z,1363209629.743 [Default:CallIridium:B] Stopped
2013-03-13T21:20:29.743Z,1363209629.743 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-13T21:20:29.744Z,1363209629.744 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-13T21:20:29.744Z,1363209629.744 [Default:CallIridium] Stopped
2013-03-13T21:20:29.744Z,1363209629.744 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-13T21:23:08.926Z,1363209788.926 [NAL9602](IMPORTANT): GPS fix at: 1363209783.00
2013-03-13T21:23:08.942Z,1363209788.942 [Default:GPS:Read_GPS] Stopped
2013-03-13T21:23:08.943Z,1363209788.943 [Default:GPS:D] Running Loop=1
2013-03-13T21:23:09.360Z,1363209789.360 [Default:GPS:D] Stopped
2013-03-13T21:23:09.360Z,1363209789.360 [Default:GPS](INFO): Completed Default:GPS
2013-03-13T21:23:09.360Z,1363209789.360 [Default:GPS] Stopped
2013-03-13T21:23:09.360Z,1363209789.360 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-13T21:23:09.360Z,1363209789.360 [Default:GPS:A.SetSpeed] Stopped
2013-03-13T21:23:09.361Z,1363209789.361 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T21:23:29.884Z,1363209809.884 [NAL9602](INFO): Powering down
2013-03-13T21:25:29.936Z,1363209929.936 [Default:CallIridium] Running Loop=1
2013-03-13T21:25:29.936Z,1363209929.936 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-13T21:25:29.937Z,1363209929.937 [Default:CallIridium:A] Running Loop=1
2013-03-13T21:25:29.937Z,1363209929.937 [Default:CallIridium:A] Stopped
2013-03-13T21:25:29.937Z,1363209929.937 [Default:CallIridium:B] Running Loop=1
2013-03-13T21:25:29.937Z,1363209929.937 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-13T21:25:34.910Z,1363209934.910 [Default:Iridium] Running Loop=1
2013-03-13T21:25:34.910Z,1363209934.910 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-13T21:25:34.910Z,1363209934.910 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-13T21:25:34.910Z,1363209934.910 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-13T21:25:34.910Z,1363209934.911 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-13T21:25:34.911Z,1363209934.911 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T21:25:34.911Z,1363209934.911 [Default:Iridium:B.GoToSurface] Stopped
2013-03-13T21:25:34.911Z,1363209934.911 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T21:25:34.912Z,1363209934.912 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-13T21:25:34.912Z,1363209934.912 [Default:GPS] Running Loop=1
2013-03-13T21:25:34.912Z,1363209934.912 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-13T21:25:34.912Z,1363209934.912 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-13T21:25:34.912Z,1363209934.912 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-13T21:25:34.912Z,1363209934.912 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-13T21:25:34.912Z,1363209934.912 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T21:25:34.913Z,1363209934.913 [Default:GPS:B.GoToSurface] Stopped
2013-03-13T21:25:34.913Z,1363209934.913 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T21:25:34.914Z,1363209934.914 [Default:GPS:Read_GPS] Running Loop=1
2013-03-13T21:25:35.555Z,1363209935.555 [NAL9602](INFO): Powering up
2013-03-13T21:25:46.358Z,1363209946.358 [NAL9602](INFO): NAL9602 initialized
2013-03-13T21:26:05.707Z,1363209965.707 [NAL9602](INFO): SBD MO Status=1, MOMSN=7221, MT Status=0, MTMSN=0
2013-03-13T21:26:05.754Z,1363209965.754 [NAL9602](INFO): Sent 161 bytes from file Logs/20130313T195025/shore0017.lzma
2013-03-13T21:26:05.754Z,1363209965.754 [NAL9602](INFO): Packets left to send: 0
2013-03-13T21:26:05.756Z,1363209965.756 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0017.lzma.parts/0000.sbd
2013-03-13T21:26:17.308Z,1363209977.308 [NAL9602](INFO): SBD MO Status=0, MOMSN=7222, MT Status=0, MTMSN=0
2013-03-13T21:26:17.405Z,1363209977.405 [Default:Iridium:Read_Iridium] Stopped
2013-03-13T21:26:17.406Z,1363209977.406 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-13T21:26:17.406Z,1363209977.406 [Default:Iridium] Stopped
2013-03-13T21:26:17.406Z,1363209977.406 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-13T21:26:17.406Z,1363209977.406 [Default:Iridium:A.SetSpeed] Stopped
2013-03-13T21:26:17.406Z,1363209977.406 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T21:26:17.726Z,1363209977.726 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-13T21:26:17.726Z,1363209977.726 [Default:CallIridium:B] Stopped
2013-03-13T21:26:17.726Z,1363209977.726 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-13T21:26:17.726Z,1363209977.726 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-13T21:26:17.726Z,1363209977.726 [Default:CallIridium] Stopped
2013-03-13T21:26:17.726Z,1363209977.726 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-13T21:26:18.570Z,1363209978.570 [NAL9602](IMPORTANT): GPS fix at: 1363209973.00
2013-03-13T21:26:18.610Z,1363209978.610 [Default:GPS:Read_GPS] Stopped
2013-03-13T21:26:18.610Z,1363209978.610 [Default:GPS:D] Running Loop=1
2013-03-13T21:26:18.934Z,1363209978.934 [Default:GPS:D] Stopped
2013-03-13T21:26:18.934Z,1363209978.934 [Default:GPS](INFO): Completed Default:GPS
2013-03-13T21:26:18.934Z,1363209978.934 [Default:GPS] Stopped
2013-03-13T21:26:18.935Z,1363209978.935 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-13T21:26:18.935Z,1363209978.935 [Default:GPS:A.SetSpeed] Stopped
2013-03-13T21:26:18.935Z,1363209978.935 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T21:26:39.472Z,1363209999.472 [NAL9602](INFO): Powering down
2013-03-13T21:29:14.455Z,1363210154.455 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2013-03-13T21:31:19.484Z,1363210279.484 [Default:CallIridium] Running Loop=1
2013-03-13T21:31:19.484Z,1363210279.484 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-13T21:31:19.485Z,1363210279.485 [Default:CallIridium:A] Running Loop=1
2013-03-13T21:31:19.485Z,1363210279.485 [Default:CallIridium:A] Stopped
2013-03-13T21:31:19.485Z,1363210279.485 [Default:CallIridium:B] Running Loop=1
2013-03-13T21:31:19.485Z,1363210279.485 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-13T21:31:24.514Z,1363210284.514 [Default:Iridium] Running Loop=1
2013-03-13T21:31:24.514Z,1363210284.514 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-13T21:31:24.514Z,1363210284.514 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-13T21:31:24.514Z,1363210284.514 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-13T21:31:24.515Z,1363210284.514 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-13T21:31:24.515Z,1363210284.514 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T21:31:24.515Z,1363210284.515 [Default:Iridium:B.GoToSurface] Stopped
2013-03-13T21:31:24.515Z,1363210284.515 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T21:31:24.516Z,1363210284.516 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-13T21:31:24.516Z,1363210284.516 [Default:GPS] Running Loop=1
2013-03-13T21:31:24.516Z,1363210284.516 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-13T21:31:24.516Z,1363210284.516 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-13T21:31:24.516Z,1363210284.516 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-13T21:31:24.516Z,1363210284.516 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-13T21:31:24.516Z,1363210284.516 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T21:31:24.517Z,1363210284.517 [Default:GPS:B.GoToSurface] Stopped
2013-03-13T21:31:24.517Z,1363210284.517 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T21:31:24.517Z,1363210284.517 [Default:GPS:Read_GPS] Running Loop=1
2013-03-13T21:31:25.115Z,1363210285.115 [NAL9602](INFO): Powering up
2013-03-13T21:31:35.946Z,1363210295.946 [NAL9602](INFO): NAL9602 initialized
2013-03-13T21:31:52.460Z,1363210312.460 [NAL9602](INFO): SBD MO Status=1, MOMSN=7223, MT Status=0, MTMSN=0
2013-03-13T21:31:52.514Z,1363210312.514 [NAL9602](INFO): Sent 161 bytes from file Logs/20130313T195025/shore0018.lzma
2013-03-13T21:31:52.514Z,1363210312.514 [NAL9602](INFO): Packets left to send: 0
2013-03-13T21:31:52.516Z,1363210312.516 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0018.lzma.parts/0000.sbd
2013-03-13T21:32:03.264Z,1363210323.264 [NAL9602](INFO): SBD MO Status=0, MOMSN=7224, MT Status=0, MTMSN=0
2013-03-13T21:32:03.352Z,1363210323.352 [Default:Iridium:Read_Iridium] Stopped
2013-03-13T21:32:03.353Z,1363210323.353 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-13T21:32:03.353Z,1363210323.353 [Default:Iridium] Stopped
2013-03-13T21:32:03.353Z,1363210323.353 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-13T21:32:03.353Z,1363210323.353 [Default:Iridium:A.SetSpeed] Stopped
2013-03-13T21:32:03.353Z,1363210323.353 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T21:32:03.679Z,1363210323.679 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-13T21:32:03.679Z,1363210323.679 [Default:CallIridium:B] Stopped
2013-03-13T21:32:03.679Z,1363210323.679 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-13T21:32:03.679Z,1363210323.679 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-13T21:32:03.679Z,1363210323.679 [Default:CallIridium] Stopped
2013-03-13T21:32:03.679Z,1363210323.680 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-13T21:32:34.066Z,1363210354.066 [NAL9602](IMPORTANT): GPS fix at: 1363210349.00
2013-03-13T21:32:34.083Z,1363210354.083 [Default:GPS:Read_GPS] Stopped
2013-03-13T21:32:34.083Z,1363210354.083 [Default:GPS:D] Running Loop=1
2013-03-13T21:32:34.501Z,1363210354.501 [Default:GPS:D] Stopped
2013-03-13T21:32:34.502Z,1363210354.502 [Default:GPS](INFO): Completed Default:GPS
2013-03-13T21:32:34.502Z,1363210354.502 [Default:GPS] Stopped
2013-03-13T21:32:34.502Z,1363210354.502 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-13T21:32:34.502Z,1363210354.502 [Default:GPS:A.SetSpeed] Stopped
2013-03-13T21:32:34.502Z,1363210354.502 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T21:32:55.035Z,1363210375.035 [NAL9602](INFO): Powering down
2013-03-13T21:37:05.051Z,1363210625.051 [Default:CallIridium] Running Loop=1
2013-03-13T21:37:05.051Z,1363210625.051 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-13T21:37:05.052Z,1363210625.052 [Default:CallIridium:A] Running Loop=1
2013-03-13T21:37:05.052Z,1363210625.052 [Default:CallIridium:A] Stopped
2013-03-13T21:37:05.052Z,1363210625.052 [Default:CallIridium:B] Running Loop=1
2013-03-13T21:37:05.052Z,1363210625.052 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-13T21:37:10.062Z,1363210630.062 [Default:Iridium] Running Loop=1
2013-03-13T21:37:10.062Z,1363210630.062 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-13T21:37:10.062Z,1363210630.062 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-13T21:37:10.062Z,1363210630.062 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-13T21:37:10.062Z,1363210630.062 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-13T21:37:10.062Z,1363210630.062 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T21:37:10.063Z,1363210630.063 [Default:Iridium:B.GoToSurface] Stopped
2013-03-13T21:37:10.063Z,1363210630.063 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T21:37:10.064Z,1363210630.063 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-13T21:37:10.064Z,1363210630.064 [Default:GPS] Running Loop=1
2013-03-13T21:37:10.064Z,1363210630.064 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-13T21:37:10.064Z,1363210630.064 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-13T21:37:10.064Z,1363210630.064 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-13T21:37:10.064Z,1363210630.064 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-13T21:37:10.064Z,1363210630.064 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T21:37:10.065Z,1363210630.065 [Default:GPS:B.GoToSurface] Stopped
2013-03-13T21:37:10.065Z,1363210630.065 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T21:37:10.065Z,1363210630.066 [Default:GPS:Read_GPS] Running Loop=1
2013-03-13T21:37:10.663Z,1363210630.663 [NAL9602](INFO): Powering up
2013-03-13T21:37:21.502Z,1363210641.502 [NAL9602](INFO): NAL9602 initialized
2013-03-13T21:37:39.236Z,1363210659.236 [NAL9602](INFO): SBD MO Status=1, MOMSN=7225, MT Status=0, MTMSN=0
2013-03-13T21:37:39.286Z,1363210659.286 [NAL9602](INFO): Sent 161 bytes from file Logs/20130313T195025/shore0019.lzma
2013-03-13T21:37:39.286Z,1363210659.286 [NAL9602](INFO): Packets left to send: 0
2013-03-13T21:37:39.288Z,1363210659.288 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0019.lzma.parts/0000.sbd
2013-03-13T21:37:48.480Z,1363210668.480 [NAL9602](INFO): SBD MO Status=0, MOMSN=7226, MT Status=0, MTMSN=0
2013-03-13T21:37:48.564Z,1363210668.564 [Default:Iridium:Read_Iridium] Stopped
2013-03-13T21:37:48.565Z,1363210668.565 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-13T21:37:48.565Z,1363210668.565 [Default:Iridium] Stopped
2013-03-13T21:37:48.565Z,1363210668.565 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-13T21:37:48.565Z,1363210668.565 [Default:Iridium:A.SetSpeed] Stopped
2013-03-13T21:37:48.565Z,1363210668.565 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T21:37:48.851Z,1363210668.851 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-13T21:37:48.851Z,1363210668.851 [Default:CallIridium:B] Stopped
2013-03-13T21:37:48.851Z,1363210668.851 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-13T21:37:48.852Z,1363210668.852 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-13T21:37:48.852Z,1363210668.852 [Default:CallIridium] Stopped
2013-03-13T21:37:48.852Z,1363210668.852 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-13T21:38:01.634Z,1363210681.634 [NAL9602](IMPORTANT): GPS fix at: 1363210677.00
2013-03-13T21:38:01.651Z,1363210681.651 [Default:GPS:Read_GPS] Stopped
2013-03-13T21:38:01.651Z,1363210681.651 [Default:GPS:D] Running Loop=1
2013-03-13T21:38:02.061Z,1363210682.061 [Default:GPS:D] Stopped
2013-03-13T21:38:02.062Z,1363210682.062 [Default:GPS](INFO): Completed Default:GPS
2013-03-13T21:38:02.062Z,1363210682.062 [Default:GPS] Stopped
2013-03-13T21:38:02.062Z,1363210682.062 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-13T21:38:02.062Z,1363210682.062 [Default:GPS:A.SetSpeed] Stopped
2013-03-13T21:38:02.062Z,1363210682.062 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T21:38:22.584Z,1363210702.584 [NAL9602](INFO): Powering down
2013-03-13T21:42:52.614Z,1363210972.614 [Default:CallIridium] Running Loop=1
2013-03-13T21:42:52.614Z,1363210972.614 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-13T21:42:52.614Z,1363210972.614 [Default:CallIridium:A] Running Loop=1
2013-03-13T21:42:52.614Z,1363210972.614 [Default:CallIridium:A] Stopped
2013-03-13T21:42:52.614Z,1363210972.614 [Default:CallIridium:B] Running Loop=1
2013-03-13T21:42:52.614Z,1363210972.615 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-13T21:42:57.640Z,1363210977.639 [Default:Iridium] Running Loop=1
2013-03-13T21:42:57.640Z,1363210977.640 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-13T21:42:57.640Z,1363210977.640 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-13T21:42:57.640Z,1363210977.640 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-13T21:42:57.640Z,1363210977.640 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-13T21:42:57.640Z,1363210977.640 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T21:42:57.641Z,1363210977.641 [Default:Iridium:B.GoToSurface] Stopped
2013-03-13T21:42:57.641Z,1363210977.641 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T21:42:57.641Z,1363210977.641 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-13T21:42:57.641Z,1363210977.641 [Default:GPS] Running Loop=1
2013-03-13T21:42:57.642Z,1363210977.642 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-13T21:42:57.642Z,1363210977.642 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-13T21:42:57.642Z,1363210977.642 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-13T21:42:57.642Z,1363210977.642 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-13T21:42:57.642Z,1363210977.642 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T21:42:57.643Z,1363210977.643 [Default:GPS:B.GoToSurface] Stopped
2013-03-13T21:42:57.643Z,1363210977.643 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T21:42:57.643Z,1363210977.643 [Default:GPS:Read_GPS] Running Loop=1
2013-03-13T21:42:58.235Z,1363210978.235 [NAL9602](INFO): Powering up
2013-03-13T21:43:09.070Z,1363210989.070 [NAL9602](INFO): NAL9602 initialized
2013-03-13T21:43:32.040Z,1363211012.040 [NAL9602](INFO): SBD MO Status=1, MOMSN=7227, MT Status=0, MTMSN=0
2013-03-13T21:43:32.092Z,1363211012.092 [NAL9602](INFO): Sent 162 bytes from file Logs/20130313T195025/shore0020.lzma
2013-03-13T21:43:32.092Z,1363211012.092 [NAL9602](INFO): Packets left to send: 0
2013-03-13T21:43:32.094Z,1363211012.094 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0020.lzma.parts/0000.sbd
2013-03-13T21:43:40.469Z,1363211020.469 [NAL9602](INFO): SBD MO Status=0, MOMSN=7228, MT Status=0, MTMSN=0
2013-03-13T21:43:40.554Z,1363211020.554 [Default:Iridium:Read_Iridium] Stopped
2013-03-13T21:43:40.554Z,1363211020.554 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-13T21:43:40.554Z,1363211020.554 [Default:Iridium] Stopped
2013-03-13T21:43:40.554Z,1363211020.555 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-13T21:43:40.555Z,1363211020.555 [Default:Iridium:A.SetSpeed] Stopped
2013-03-13T21:43:40.555Z,1363211020.555 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T21:43:40.835Z,1363211020.835 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-13T21:43:40.835Z,1363211020.835 [Default:CallIridium:B] Stopped
2013-03-13T21:43:40.835Z,1363211020.835 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-13T21:43:40.835Z,1363211020.835 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-13T21:43:40.836Z,1363211020.836 [Default:CallIridium] Stopped
2013-03-13T21:43:40.836Z,1363211020.836 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-13T21:43:41.622Z,1363211021.622 [NAL9602](IMPORTANT): GPS fix at: 1363211017.00
2013-03-13T21:43:41.639Z,1363211021.639 [Default:GPS:Read_GPS] Stopped
2013-03-13T21:43:41.639Z,1363211021.639 [Default:GPS:D] Running Loop=1
2013-03-13T21:43:42.053Z,1363211022.053 [Default:GPS:D] Stopped
2013-03-13T21:43:42.053Z,1363211022.053 [Default:GPS](INFO): Completed Default:GPS
2013-03-13T21:43:42.053Z,1363211022.053 [Default:GPS] Stopped
2013-03-13T21:43:42.054Z,1363211022.054 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-13T21:43:42.054Z,1363211022.054 [Default:GPS:A.SetSpeed] Stopped
2013-03-13T21:43:42.054Z,1363211022.054 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T21:44:02.616Z,1363211042.616 [NAL9602](INFO): Powering down
2013-03-13T21:47:17.578Z,1363211237.578 [Depth_Keller](ERROR): Pressure reading out of range: 1830.968384 decibar
2013-03-13T21:47:27.599Z,1363211247.599 [Depth_Keller](ERROR): Pressure reading out of range: 1830.954224 decibar
2013-03-13T21:47:37.575Z,1363211257.575 [Depth_Keller](ERROR): Pressure reading out of range: 1830.915771 decibar
2013-03-13T21:47:47.575Z,1363211267.575 [Depth_Keller](ERROR): Pressure reading out of range: 1830.952515 decibar
2013-03-13T21:47:57.579Z,1363211277.579 [Depth_Keller](ERROR): Pressure reading out of range: 1830.916748 decibar
2013-03-13T21:48:07.575Z,1363211287.575 [Depth_Keller](ERROR): Pressure reading out of range: 1831.015381 decibar
2013-03-13T21:48:17.575Z,1363211297.575 [Depth_Keller](ERROR): Pressure reading out of range: 1830.974365 decibar
2013-03-13T21:48:27.575Z,1363211307.575 [Depth_Keller](ERROR): Pressure reading out of range: 1830.974365 decibar
2013-03-13T21:48:37.575Z,1363211317.575 [Depth_Keller](ERROR): Pressure reading out of range: 1830.997070 decibar
2013-03-13T21:48:42.628Z,1363211322.628 [Default:CallIridium] Running Loop=1
2013-03-13T21:48:42.628Z,1363211322.628 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-13T21:48:42.629Z,1363211322.629 [Default:CallIridium:A] Running Loop=1
2013-03-13T21:48:42.629Z,1363211322.629 [Default:CallIridium:A] Stopped
2013-03-13T21:48:42.629Z,1363211322.629 [Default:CallIridium:B] Running Loop=1
2013-03-13T21:48:42.629Z,1363211322.629 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-13T21:48:47.579Z,1363211327.579 [Depth_Keller](ERROR): Pressure reading out of range: 1830.944702 decibar
2013-03-13T21:48:47.614Z,1363211327.614 [Default:Iridium] Running Loop=1
2013-03-13T21:48:47.614Z,1363211327.614 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-13T21:48:47.614Z,1363211327.614 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-13T21:48:47.614Z,1363211327.614 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-13T21:48:47.614Z,1363211327.614 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-13T21:48:47.614Z,1363211327.614 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T21:48:47.615Z,1363211327.615 [Default:Iridium:B.GoToSurface] Stopped
2013-03-13T21:48:47.615Z,1363211327.615 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T21:48:47.615Z,1363211327.615 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-13T21:48:47.616Z,1363211327.616 [Default:GPS] Running Loop=1
2013-03-13T21:48:47.616Z,1363211327.616 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-13T21:48:47.616Z,1363211327.616 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-13T21:48:47.616Z,1363211327.616 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-13T21:48:47.616Z,1363211327.616 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-13T21:48:47.616Z,1363211327.616 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T21:48:47.617Z,1363211327.617 [Default:GPS:B.GoToSurface] Stopped
2013-03-13T21:48:47.617Z,1363211327.617 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T21:48:47.617Z,1363211327.617 [Default:GPS:Read_GPS] Running Loop=1
2013-03-13T21:48:48.219Z,1363211328.219 [NAL9602](INFO): Powering up
2013-03-13T21:48:59.050Z,1363211339.050 [NAL9602](INFO): NAL9602 initialized
2013-03-13T21:49:17.184Z,1363211357.184 [NAL9602](INFO): SBD MO Status=1, MOMSN=7229, MT Status=0, MTMSN=0
2013-03-13T21:49:17.234Z,1363211357.234 [NAL9602](INFO): Sent 163 bytes from file Logs/20130313T195025/shore0021.lzma
2013-03-13T21:49:17.234Z,1363211357.234 [NAL9602](INFO): Packets left to send: 0
2013-03-13T21:49:17.236Z,1363211357.236 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0021.lzma.parts/0000.sbd
2013-03-13T21:49:24.817Z,1363211364.817 [NAL9602](INFO): SBD MO Status=0, MOMSN=7230, MT Status=0, MTMSN=0
2013-03-13T21:49:24.911Z,1363211364.911 [Default:Iridium:Read_Iridium] Stopped
2013-03-13T21:49:24.911Z,1363211364.911 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-13T21:49:24.912Z,1363211364.912 [Default:Iridium] Stopped
2013-03-13T21:49:24.912Z,1363211364.912 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-13T21:49:24.912Z,1363211364.912 [Default:Iridium:A.SetSpeed] Stopped
2013-03-13T21:49:24.912Z,1363211364.912 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T21:49:25.211Z,1363211365.211 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-13T21:49:25.211Z,1363211365.211 [Default:CallIridium:B] Stopped
2013-03-13T21:49:25.212Z,1363211365.211 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-13T21:49:25.212Z,1363211365.212 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-13T21:49:25.212Z,1363211365.212 [Default:CallIridium] Stopped
2013-03-13T21:49:25.212Z,1363211365.212 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-13T21:49:25.990Z,1363211365.990 [NAL9602](IMPORTANT): GPS fix at: 1363211362.00
2013-03-13T21:49:26.007Z,1363211366.007 [Default:GPS:Read_GPS] Stopped
2013-03-13T21:49:26.007Z,1363211366.007 [Default:GPS:D] Running Loop=1
2013-03-13T21:49:26.476Z,1363211366.476 [Default:GPS:D] Stopped
2013-03-13T21:49:26.476Z,1363211366.476 [Default:GPS](INFO): Completed Default:GPS
2013-03-13T21:49:26.476Z,1363211366.476 [Default:GPS] Stopped
2013-03-13T21:49:26.476Z,1363211366.476 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-13T21:49:26.476Z,1363211366.476 [Default:GPS:A.SetSpeed] Stopped
2013-03-13T21:49:26.476Z,1363211366.476 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T21:49:36.951Z,1363211376.951 [Depth_Keller](ERROR): Pressure reading out of range: 1830.947266 decibar
2013-03-13T21:49:46.951Z,1363211386.951 [NAL9602](INFO): Powering down
2013-03-13T21:49:56.999Z,1363211396.999 [Depth_Keller](ERROR): Pressure reading out of range: 1830.913208 decibar
2013-03-13T21:50:06.931Z,1363211406.931 [Depth_Keller](ERROR): Pressure reading out of range: 1830.944702 decibar
2013-03-13T21:50:16.950Z,1363211416.950 [Depth_Keller](ERROR): Pressure reading out of range: 1830.992798 decibar
2013-03-13T21:50:26.970Z,1363211426.970 [Depth_Keller](ERROR): Pressure reading out of range: 1830.928833 decibar
2013-03-13T21:50:36.958Z,1363211436.958 [Depth_Keller](ERROR): Pressure reading out of range: 1831.056396 decibar
2013-03-13T21:50:46.975Z,1363211446.975 [Depth_Keller](ERROR): Pressure reading out of range: 1830.960327 decibar
2013-03-13T21:50:56.967Z,1363211456.967 [Depth_Keller](ERROR): Pressure reading out of range: 1830.975220 decibar
2013-03-13T21:51:06.967Z,1363211466.967 [Depth_Keller](ERROR): Pressure reading out of range: 1830.994507 decibar
2013-03-13T21:51:17.011Z,1363211477.011 [Depth_Keller](ERROR): Pressure reading out of range: 1830.950806 decibar
2013-03-13T21:51:26.967Z,1363211486.967 [Depth_Keller](ERROR): Pressure reading out of range: 1830.967407 decibar
2013-03-13T21:51:36.975Z,1363211496.975 [Depth_Keller](ERROR): Pressure reading out of range: 1830.921021 decibar
2013-03-13T21:51:46.951Z,1363211506.951 [Depth_Keller](ERROR): Pressure reading out of range: 1830.982178 decibar
2013-03-13T21:51:56.939Z,1363211516.939 [Depth_Keller](ERROR): Pressure reading out of range: 1830.934204 decibar
2013-03-13T21:52:06.935Z,1363211526.935 [Depth_Keller](ERROR): Pressure reading out of range: 1830.942993 decibar
2013-03-13T21:52:16.943Z,1363211536.943 [Depth_Keller](ERROR): Pressure reading out of range: 1373.256104 decibar
2013-03-13T21:52:26.943Z,1363211546.943 [Depth_Keller](ERROR): Pressure reading out of range: 1011.870789 decibar
2013-03-13T21:52:36.951Z,1363211556.951 [Depth_Keller](ERROR): Pressure reading out of range: 1373.232544 decibar
2013-03-13T21:53:06.975Z,1363211586.975 [Depth_Keller](ERROR): Pressure reading out of range: 1831.011108 decibar
2013-03-13T21:53:56.975Z,1363211636.975 [Depth_Keller](ERROR): Pressure reading out of range: 1831.058228 decibar
2013-03-13T21:54:06.975Z,1363211646.975 [Depth_Keller](ERROR): Pressure reading out of range: 1355.303711 decibar
2013-03-13T21:54:27.008Z,1363211667.008 [Default:CallIridium] Running Loop=1
2013-03-13T21:54:27.008Z,1363211667.008 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-13T21:54:27.008Z,1363211667.008 [Default:CallIridium:A] Running Loop=1
2013-03-13T21:54:27.008Z,1363211667.008 [Default:CallIridium:A] Stopped
2013-03-13T21:54:27.008Z,1363211667.008 [Default:CallIridium:B] Running Loop=1
2013-03-13T21:54:27.009Z,1363211667.009 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-13T21:54:31.964Z,1363211671.964 [Default:Iridium] Running Loop=1
2013-03-13T21:54:31.964Z,1363211671.964 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-13T21:54:31.964Z,1363211671.964 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-13T21:54:31.964Z,1363211671.964 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-13T21:54:31.964Z,1363211671.964 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-13T21:54:31.964Z,1363211671.964 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T21:54:31.965Z,1363211671.965 [Default:Iridium:B.GoToSurface] Stopped
2013-03-13T21:54:31.965Z,1363211671.965 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T21:54:31.965Z,1363211671.966 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-13T21:54:31.966Z,1363211671.966 [Default:GPS] Running Loop=1
2013-03-13T21:54:31.966Z,1363211671.966 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-13T21:54:31.966Z,1363211671.966 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-13T21:54:31.966Z,1363211671.966 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-13T21:54:31.966Z,1363211671.966 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-13T21:54:31.966Z,1363211671.966 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T21:54:31.967Z,1363211671.967 [Default:GPS:B.GoToSurface] Stopped
2013-03-13T21:54:31.967Z,1363211671.967 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T21:54:31.967Z,1363211671.967 [Default:GPS:Read_GPS] Running Loop=1
2013-03-13T21:54:32.583Z,1363211672.583 [NAL9602](INFO): Powering up
2013-03-13T21:54:43.458Z,1363211683.458 [NAL9602](INFO): NAL9602 initialized
2013-03-13T21:55:05.991Z,1363211705.991 [NAL9602](INFO): SBD MO Status=1, MOMSN=7231, MT Status=0, MTMSN=0
2013-03-13T21:55:06.038Z,1363211706.038 [NAL9602](INFO): Sent 166 bytes from file Logs/20130313T195025/shore0022.lzma
2013-03-13T21:55:06.038Z,1363211706.038 [NAL9602](INFO): Packets left to send: 0
2013-03-13T21:55:06.040Z,1363211706.040 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0022.lzma.parts/0000.sbd
2013-03-13T21:55:26.791Z,1363211726.791 [NAL9602](INFO): SBD MO Status=0, MOMSN=7232, MT Status=0, MTMSN=0
2013-03-13T21:55:26.872Z,1363211726.872 [Default:Iridium:Read_Iridium] Stopped
2013-03-13T21:55:26.873Z,1363211726.873 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-13T21:55:26.873Z,1363211726.873 [Default:Iridium] Stopped
2013-03-13T21:55:26.873Z,1363211726.873 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-13T21:55:26.873Z,1363211726.873 [Default:Iridium:A.SetSpeed] Stopped
2013-03-13T21:55:26.873Z,1363211726.873 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T21:55:27.215Z,1363211727.215 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-13T21:55:27.215Z,1363211727.215 [Default:CallIridium:B] Stopped
2013-03-13T21:55:27.215Z,1363211727.216 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-13T21:55:27.216Z,1363211727.216 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-13T21:55:27.216Z,1363211727.216 [Default:CallIridium] Stopped
2013-03-13T21:55:27.216Z,1363211727.216 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-13T21:55:28.054Z,1363211728.055 [NAL9602](IMPORTANT): GPS fix at: 1363211725.00
2013-03-13T21:55:28.072Z,1363211728.072 [Default:GPS:Read_GPS] Stopped
2013-03-13T21:55:28.072Z,1363211728.072 [Default:GPS:D] Running Loop=1
2013-03-13T21:55:28.428Z,1363211728.428 [Default:GPS:D] Stopped
2013-03-13T21:55:28.428Z,1363211728.428 [Default:GPS](INFO): Completed Default:GPS
2013-03-13T21:55:28.428Z,1363211728.428 [Default:GPS] Stopped
2013-03-13T21:55:28.428Z,1363211728.428 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-13T21:55:28.428Z,1363211728.428 [Default:GPS:A.SetSpeed] Stopped
2013-03-13T21:55:28.428Z,1363211728.428 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T21:55:38.955Z,1363211738.955 [Depth_Keller](ERROR): Pressure reading out of range: 1830.954224 decibar
2013-03-13T21:55:48.975Z,1363211748.975 [NAL9602](INFO): Powering down
2013-03-13T21:55:53.935Z,1363211753.935 [Depth_Keller](ERROR): Pressure reading out of range: 1830.925537 decibar
2013-03-13T21:56:08.975Z,1363211768.975 [Depth_Keller](ERROR): Pressure reading out of range: 1831.009399 decibar
2013-03-13T21:56:18.975Z,1363211778.975 [Depth_Keller](ERROR): Pressure reading out of range: 1355.329956 decibar
2013-03-13T21:56:28.975Z,1363211788.975 [Depth_Keller](ERROR): Pressure reading out of range: 1830.963867 decibar
2013-03-13T21:56:38.975Z,1363211798.975 [Depth_Keller](ERROR): Pressure reading out of range: 1831.026001 decibar
2013-03-13T21:56:48.958Z,1363211808.958 [Depth_Keller](ERROR): Pressure reading out of range: 1830.973389 decibar
2013-03-13T21:56:58.958Z,1363211818.958 [Depth_Keller](ERROR): Pressure reading out of range: 1355.322876 decibar
2013-03-13T21:57:08.962Z,1363211828.962 [Depth_Keller](ERROR): Pressure reading out of range: 1355.364868 decibar
2013-03-13T21:57:18.962Z,1363211838.962 [Depth_Keller](ERROR): Pressure reading out of range: 1830.860962 decibar
2013-03-13T21:57:28.962Z,1363211848.962 [Depth_Keller](ERROR): Pressure reading out of range: 1830.895874 decibar
2013-03-13T21:57:38.975Z,1363211858.975 [Depth_Keller](ERROR): Pressure reading out of range: 1830.918579 decibar
2013-03-13T21:57:48.963Z,1363211868.963 [Depth_Keller](ERROR): Pressure reading out of range: 1830.911499 decibar
2013-03-13T21:58:08.995Z,1363211888.995 [Depth_Keller](ERROR): Pressure reading out of range: 1830.933350 decibar
2013-03-13T22:00:29.008Z,1363212029.008 [Default:CallIridium] Running Loop=1
2013-03-13T22:00:29.008Z,1363212029.008 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-13T22:00:29.008Z,1363212029.008 [Default:CallIridium:A] Running Loop=1
2013-03-13T22:00:29.009Z,1363212029.009 [Default:CallIridium:A] Stopped
2013-03-13T22:00:29.009Z,1363212029.009 [Default:CallIridium:B] Running Loop=1
2013-03-13T22:00:29.009Z,1363212029.009 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-13T22:00:34.004Z,1363212034.004 [Default:Iridium] Running Loop=1
2013-03-13T22:00:34.004Z,1363212034.004 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-13T22:00:34.004Z,1363212034.004 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-13T22:00:34.004Z,1363212034.004 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-13T22:00:34.004Z,1363212034.004 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-13T22:00:34.004Z,1363212034.004 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T22:00:34.005Z,1363212034.005 [Default:Iridium:B.GoToSurface] Stopped
2013-03-13T22:00:34.005Z,1363212034.005 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T22:00:34.005Z,1363212034.005 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-13T22:00:34.010Z,1363212034.010 [Default:GPS] Running Loop=1
2013-03-13T22:00:34.010Z,1363212034.010 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-13T22:00:34.010Z,1363212034.010 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-13T22:00:34.010Z,1363212034.010 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-13T22:00:34.010Z,1363212034.010 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-13T22:00:34.010Z,1363212034.010 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T22:00:34.011Z,1363212034.011 [Default:GPS:B.GoToSurface] Stopped
2013-03-13T22:00:34.011Z,1363212034.011 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T22:00:34.011Z,1363212034.011 [Default:GPS:Read_GPS] Running Loop=1
2013-03-13T22:00:34.619Z,1363212034.619 [NAL9602](INFO): Powering up
2013-03-13T22:00:47.038Z,1363212047.037 [NAL9602](ERROR): NAL9602 initialize uart error: serial timeout
2013-03-13T22:00:47.038Z,1363212047.038 [NAL9602] Communications Fault, FailCount= 1
2013-03-13T22:00:47.038Z,1363212047.038 [NAL9602](ERROR): Communications Fault
2013-03-13T22:00:47.071Z,1363212047.071 [CBIT](ERROR): Communications Fault in component: NAL9602
2013-03-13T22:00:47.306Z,1363212047.306 [NAL9602](INFO): Powering down
2013-03-13T22:00:48.153Z,1363212048.153 [CBIT](INFO): Clearing failed state for component NAL9602
2013-03-13T22:00:48.153Z,1363212048.153 [NAL9602] No Fault, FailCount= 1
2013-03-13T22:00:52.464Z,1363212052.464 [NAL9602](INFO): Powering up NAL9602
2013-03-13T22:01:03.298Z,1363212063.298 [NAL9602](INFO): NAL9602 initialized
2013-03-13T22:01:22.624Z,1363212082.624 [NAL9602](INFO): SBD MO Status=1, MOMSN=7233, MT Status=0, MTMSN=0
2013-03-13T22:01:22.674Z,1363212082.674 [NAL9602](INFO): Sent 163 bytes from file Logs/20130313T195025/shore0023.lzma
2013-03-13T22:01:22.674Z,1363212082.674 [NAL9602](INFO): Packets left to send: 0
2013-03-13T22:01:22.676Z,1363212082.676 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0023.lzma.parts/0000.sbd
2013-03-13T22:01:31.024Z,1363212091.024 [NAL9602](INFO): SBD MO Status=0, MOMSN=7234, MT Status=0, MTMSN=0
2013-03-13T22:01:31.120Z,1363212091.120 [Default:Iridium:Read_Iridium] Stopped
2013-03-13T22:01:31.120Z,1363212091.120 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-13T22:01:31.120Z,1363212091.120 [Default:Iridium] Stopped
2013-03-13T22:01:31.120Z,1363212091.120 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-13T22:01:31.120Z,1363212091.120 [Default:Iridium:A.SetSpeed] Stopped
2013-03-13T22:01:31.120Z,1363212091.120 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T22:01:31.435Z,1363212091.435 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-13T22:01:31.435Z,1363212091.435 [Default:CallIridium:B] Stopped
2013-03-13T22:01:31.435Z,1363212091.435 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-13T22:01:31.435Z,1363212091.435 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-13T22:01:31.435Z,1363212091.435 [Default:CallIridium] Stopped
2013-03-13T22:01:31.435Z,1363212091.435 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-13T22:01:34.626Z,1363212094.626 [NAL9602](IMPORTANT): GPS fix at: 1363212092.00
2013-03-13T22:01:34.642Z,1363212094.642 [Default:GPS:Read_GPS] Stopped
2013-03-13T22:01:34.642Z,1363212094.642 [Default:GPS:D] Running Loop=1
2013-03-13T22:01:35.049Z,1363212095.049 [Default:GPS:D] Stopped
2013-03-13T22:01:35.049Z,1363212095.049 [Default:GPS](INFO): Completed Default:GPS
2013-03-13T22:01:35.049Z,1363212095.049 [Default:GPS] Stopped
2013-03-13T22:01:35.049Z,1363212095.049 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-13T22:01:35.050Z,1363212095.050 [Default:GPS:A.SetSpeed] Stopped
2013-03-13T22:01:35.050Z,1363212095.050 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T22:01:40.615Z,1363212100.615 [Depth_Keller](ERROR): Pressure reading out of range: 1831.018921 decibar
2013-03-13T22:01:55.584Z,1363212115.584 [NAL9602](INFO): Powering down
2013-03-13T22:06:35.613Z,1363212395.612 [Default:CallIridium] Running Loop=1
2013-03-13T22:06:35.613Z,1363212395.613 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-13T22:06:35.613Z,1363212395.613 [Default:CallIridium:A] Running Loop=1
2013-03-13T22:06:35.613Z,1363212395.613 [Default:CallIridium:A] Stopped
2013-03-13T22:06:35.613Z,1363212395.614 [Default:CallIridium:B] Running Loop=1
2013-03-13T22:06:35.614Z,1363212395.614 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-13T22:06:40.631Z,1363212400.631 [Default:Iridium] Running Loop=1
2013-03-13T22:06:40.631Z,1363212400.631 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-13T22:06:40.632Z,1363212400.632 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-13T22:06:40.632Z,1363212400.632 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-13T22:06:40.632Z,1363212400.632 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-13T22:06:40.632Z,1363212400.632 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T22:06:40.632Z,1363212400.632 [Default:Iridium:B.GoToSurface] Stopped
2013-03-13T22:06:40.633Z,1363212400.633 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T22:06:40.633Z,1363212400.633 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-13T22:06:40.633Z,1363212400.633 [Default:GPS] Running Loop=1
2013-03-13T22:06:40.633Z,1363212400.633 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-13T22:06:40.633Z,1363212400.633 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-13T22:06:40.633Z,1363212400.633 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-13T22:06:40.634Z,1363212400.634 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-13T22:06:40.634Z,1363212400.634 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T22:06:40.634Z,1363212400.634 [Default:GPS:B.GoToSurface] Stopped
2013-03-13T22:06:40.635Z,1363212400.635 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T22:06:40.635Z,1363212400.635 [Default:GPS:Read_GPS] Running Loop=1
2013-03-13T22:06:41.225Z,1363212401.225 [NAL9602](INFO): Powering up
2013-03-13T22:06:51.666Z,1363212411.666 [NAL9602](INFO): NAL9602 initialized
2013-03-13T22:07:30.636Z,1363212450.636 [NAL9602](INFO): SBD MO Status=1, MOMSN=7235, MT Status=0, MTMSN=0
2013-03-13T22:07:30.688Z,1363212450.688 [NAL9602](INFO): Sent 164 bytes from file Logs/20130313T195025/shore0024.lzma
2013-03-13T22:07:30.688Z,1363212450.688 [NAL9602](INFO): Packets left to send: 0
2013-03-13T22:07:30.690Z,1363212450.690 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0024.lzma.parts/0000.sbd
2013-03-13T22:07:49.836Z,1363212469.836 [NAL9602](INFO): SBD MO Status=2, MOMSN=7236, MT Status=2, MTMSN=0
2013-03-13T22:07:49.836Z,1363212469.836 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-13T22:07:51.038Z,1363212471.038 [NAL9602](IMPORTANT): GPS fix at: 1363212469.00
2013-03-13T22:07:51.058Z,1363212471.058 [Default:GPS:Read_GPS] Stopped
2013-03-13T22:07:51.058Z,1363212471.058 [Default:GPS:D] Running Loop=1
2013-03-13T22:07:51.542Z,1363212471.542 [Default:GPS:D] Stopped
2013-03-13T22:07:51.542Z,1363212471.542 [Default:GPS](INFO): Completed Default:GPS
2013-03-13T22:07:51.543Z,1363212471.543 [Default:GPS] Stopped
2013-03-13T22:07:51.543Z,1363212471.543 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-13T22:07:51.543Z,1363212471.543 [Default:GPS:A.SetSpeed] Stopped
2013-03-13T22:07:51.543Z,1363212471.543 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T22:08:02.955Z,1363212482.955 [NAL9602](INFO): SBD MO Status=0, MOMSN=7236, MT Status=0, MTMSN=0
2013-03-13T22:08:03.040Z,1363212483.040 [Default:Iridium:Read_Iridium] Stopped
2013-03-13T22:08:03.041Z,1363212483.041 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-13T22:08:03.041Z,1363212483.041 [Default:Iridium] Stopped
2013-03-13T22:08:03.041Z,1363212483.041 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-13T22:08:03.041Z,1363212483.041 [Default:Iridium:A.SetSpeed] Stopped
2013-03-13T22:08:03.041Z,1363212483.041 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T22:08:03.366Z,1363212483.366 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-13T22:08:03.366Z,1363212483.366 [Default:CallIridium:B] Stopped
2013-03-13T22:08:03.366Z,1363212483.366 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-13T22:08:03.366Z,1363212483.366 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-13T22:08:03.366Z,1363212483.366 [Default:CallIridium] Stopped
2013-03-13T22:08:03.366Z,1363212483.366 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-13T22:08:13.516Z,1363212493.516 [NAL9602](INFO): Powering down
2013-03-13T22:13:03.556Z,1363212783.556 [Default:CallIridium] Running Loop=1
2013-03-13T22:13:03.556Z,1363212783.556 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-13T22:13:03.556Z,1363212783.556 [Default:CallIridium:A] Running Loop=1
2013-03-13T22:13:03.557Z,1363212783.557 [Default:CallIridium:A] Stopped
2013-03-13T22:13:03.557Z,1363212783.557 [Default:CallIridium:B] Running Loop=1
2013-03-13T22:13:03.557Z,1363212783.557 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-13T22:13:08.531Z,1363212788.531 [Default:Iridium] Running Loop=1
2013-03-13T22:13:08.532Z,1363212788.532 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-13T22:13:08.532Z,1363212788.532 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-13T22:13:08.532Z,1363212788.532 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-13T22:13:08.532Z,1363212788.532 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-13T22:13:08.532Z,1363212788.532 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T22:13:08.533Z,1363212788.533 [Default:Iridium:B.GoToSurface] Stopped
2013-03-13T22:13:08.533Z,1363212788.533 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T22:13:08.533Z,1363212788.533 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-13T22:13:08.533Z,1363212788.533 [Default:GPS] Running Loop=1
2013-03-13T22:13:08.534Z,1363212788.533 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-13T22:13:08.534Z,1363212788.534 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-13T22:13:08.534Z,1363212788.534 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-13T22:13:08.534Z,1363212788.534 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-13T22:13:08.534Z,1363212788.534 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T22:13:08.535Z,1363212788.535 [Default:GPS:B.GoToSurface] Stopped
2013-03-13T22:13:08.535Z,1363212788.535 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T22:13:08.535Z,1363212788.535 [Default:GPS:Read_GPS] Running Loop=1
2013-03-13T22:13:09.159Z,1363212789.159 [NAL9602](INFO): Powering up
2013-03-13T22:13:20.030Z,1363212800.030 [NAL9602](INFO): NAL9602 initialized
2013-03-13T22:13:36.233Z,1363212816.233 [NAL9602](INFO): SBD MO Status=1, MOMSN=7237, MT Status=0, MTMSN=0
2013-03-13T22:13:36.282Z,1363212816.282 [NAL9602](INFO): Sent 162 bytes from file Logs/20130313T195025/shore0025.lzma
2013-03-13T22:13:36.282Z,1363212816.282 [NAL9602](INFO): Packets left to send: 0
2013-03-13T22:13:36.284Z,1363212816.284 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0025.lzma.parts/0000.sbd
2013-03-13T22:13:59.735Z,1363212839.735 [NAL9602](INFO): SBD MO Status=2, MOMSN=7238, MT Status=2, MTMSN=0
2013-03-13T22:13:59.735Z,1363212839.735 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-13T22:14:00.946Z,1363212840.946 [NAL9602](IMPORTANT): GPS fix at: 1363212839.00
2013-03-13T22:14:00.963Z,1363212840.963 [Default:GPS:Read_GPS] Stopped
2013-03-13T22:14:00.963Z,1363212840.963 [Default:GPS:D] Running Loop=1
2013-03-13T22:14:01.376Z,1363212841.376 [Default:GPS:D] Stopped
2013-03-13T22:14:01.377Z,1363212841.376 [Default:GPS](INFO): Completed Default:GPS
2013-03-13T22:14:01.377Z,1363212841.377 [Default:GPS] Stopped
2013-03-13T22:14:01.377Z,1363212841.377 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-13T22:14:01.377Z,1363212841.377 [Default:GPS:A.SetSpeed] Stopped
2013-03-13T22:14:01.377Z,1363212841.377 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T22:14:16.065Z,1363212856.065 [NAL9602](INFO): SBD MO Status=0, MOMSN=7238, MT Status=0, MTMSN=0
2013-03-13T22:14:16.153Z,1363212856.152 [Default:Iridium:Read_Iridium] Stopped
2013-03-13T22:14:16.153Z,1363212856.153 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-13T22:14:16.153Z,1363212856.153 [Default:Iridium] Stopped
2013-03-13T22:14:16.153Z,1363212856.153 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-13T22:14:16.153Z,1363212856.153 [Default:Iridium:A.SetSpeed] Stopped
2013-03-13T22:14:16.154Z,1363212856.154 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T22:14:16.484Z,1363212856.484 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-13T22:14:16.484Z,1363212856.484 [Default:CallIridium:B] Stopped
2013-03-13T22:14:16.484Z,1363212856.484 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-13T22:14:16.484Z,1363212856.484 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-13T22:14:16.484Z,1363212856.484 [Default:CallIridium] Stopped
2013-03-13T22:14:16.484Z,1363212856.484 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-13T22:14:26.591Z,1363212866.591 [NAL9602](INFO): Powering down
2013-03-13T22:19:16.631Z,1363213156.631 [Default:CallIridium] Running Loop=1
2013-03-13T22:19:16.631Z,1363213156.631 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-13T22:19:16.631Z,1363213156.631 [Default:CallIridium:A] Running Loop=1
2013-03-13T22:19:16.632Z,1363213156.632 [Default:CallIridium:A] Stopped
2013-03-13T22:19:16.632Z,1363213156.632 [Default:CallIridium:B] Running Loop=1
2013-03-13T22:19:16.632Z,1363213156.632 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-13T22:19:21.640Z,1363213161.640 [Default:Iridium] Running Loop=1
2013-03-13T22:19:21.640Z,1363213161.640 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-13T22:19:21.640Z,1363213161.640 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-13T22:19:21.640Z,1363213161.640 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-13T22:19:21.640Z,1363213161.640 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-13T22:19:21.640Z,1363213161.640 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T22:19:21.641Z,1363213161.641 [Default:Iridium:B.GoToSurface] Stopped
2013-03-13T22:19:21.641Z,1363213161.641 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T22:19:21.641Z,1363213161.641 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-13T22:19:21.642Z,1363213161.642 [Default:GPS] Running Loop=1
2013-03-13T22:19:21.642Z,1363213161.642 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-13T22:19:21.642Z,1363213161.642 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-13T22:19:21.642Z,1363213161.642 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-13T22:19:21.642Z,1363213161.642 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-13T22:19:21.642Z,1363213161.642 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T22:19:21.643Z,1363213161.643 [Default:GPS:B.GoToSurface] Stopped
2013-03-13T22:19:21.643Z,1363213161.643 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T22:19:21.643Z,1363213161.643 [Default:GPS:Read_GPS] Running Loop=1
2013-03-13T22:19:22.292Z,1363213162.292 [NAL9602](INFO): Powering up
2013-03-13T22:19:32.706Z,1363213172.706 [NAL9602](INFO): NAL9602 initialized
2013-03-13T22:19:55.691Z,1363213195.691 [NAL9602](INFO): SBD MO Status=2, MOMSN=7239, MT Status=2, MTMSN=0
2013-03-13T22:19:55.691Z,1363213195.691 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2013-03-13T22:19:56.896Z,1363213196.896 [NAL9602](IMPORTANT): GPS fix at: 1363213196.00
2013-03-13T22:19:56.913Z,1363213196.913 [Default:GPS:Read_GPS] Stopped
2013-03-13T22:19:56.914Z,1363213196.914 [Default:GPS:D] Running Loop=1
2013-03-13T22:19:57.324Z,1363213197.324 [Default:GPS:D] Stopped
2013-03-13T22:19:57.324Z,1363213197.324 [Default:GPS](INFO): Completed Default:GPS
2013-03-13T22:19:57.324Z,1363213197.324 [Default:GPS] Stopped
2013-03-13T22:19:57.324Z,1363213197.324 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-03-13T22:19:57.324Z,1363213197.324 [Default:GPS:A.SetSpeed] Stopped
2013-03-13T22:19:57.324Z,1363213197.325 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T22:20:24.648Z,1363213224.648 [NAL9602](INFO): SBD MO Status=1, MOMSN=7239, MT Status=0, MTMSN=0
2013-03-13T22:20:24.702Z,1363213224.702 [NAL9602](INFO): Sent 162 bytes from file Logs/20130313T195025/shore0026.lzma
2013-03-13T22:20:24.702Z,1363213224.702 [NAL9602](INFO): Packets left to send: 0
2013-03-13T22:20:24.704Z,1363213224.704 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0026.lzma.parts/0000.sbd
2013-03-13T22:20:38.648Z,1363213238.648 [NAL9602](INFO): SBD MO Status=0, MOMSN=7240, MT Status=0, MTMSN=0
2013-03-13T22:20:38.736Z,1363213238.736 [Default:Iridium:Read_Iridium] Stopped
2013-03-13T22:20:38.737Z,1363213238.737 [Default:Iridium](INFO): Completed Default:Iridium
2013-03-13T22:20:38.737Z,1363213238.737 [Default:Iridium] Stopped
2013-03-13T22:20:38.737Z,1363213238.737 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-03-13T22:20:38.737Z,1363213238.737 [Default:Iridium:A.SetSpeed] Stopped
2013-03-13T22:20:38.737Z,1363213238.737 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-03-13T22:20:39.108Z,1363213239.108 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2013-03-13T22:20:39.108Z,1363213239.108 [Default:CallIridium:B] Stopped
2013-03-13T22:20:39.108Z,1363213239.108 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-03-13T22:20:39.108Z,1363213239.108 [Default:CallIridium](INFO): Completed Default:CallIridium
2013-03-13T22:20:39.108Z,1363213239.108 [Default:CallIridium] Stopped
2013-03-13T22:20:39.108Z,1363213239.108 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-03-13T22:20:49.240Z,1363213249.240 [NAL9602](INFO): Powering down
2013-03-13T22:23:39.264Z,1363213419.264 [Radio_Freewave](INFO): Powering down
2013-03-13T22:24:24.229Z,1363213464.229 [Radio_Freewave](INFO): Powering up
2013-03-13T22:25:39.216Z,1363213539.216 [Default:CallIridium] Running Loop=1
2013-03-13T22:25:39.216Z,1363213539.216 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-03-13T22:25:39.216Z,1363213539.216 [Default:CallIridium:A] Running Loop=1
2013-03-13T22:25:39.216Z,1363213539.216 [Default:CallIridium:A] Stopped
2013-03-13T22:25:39.216Z,1363213539.216 [Default:CallIridium:B] Running Loop=1
2013-03-13T22:25:39.216Z,1363213539.217 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-03-13T22:25:44.259Z,1363213544.259 [Default:Iridium] Running Loop=1
2013-03-13T22:25:44.259Z,1363213544.259 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-03-13T22:25:44.259Z,1363213544.259 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-03-13T22:25:44.259Z,1363213544.260 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-03-13T22:25:44.260Z,1363213544.260 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-03-13T22:25:44.260Z,1363213544.260 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T22:25:44.261Z,1363213544.260 [Default:Iridium:B.GoToSurface] Stopped
2013-03-13T22:25:44.261Z,1363213544.261 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T22:25:44.261Z,1363213544.261 [Default:Iridium:Read_Iridium] Running Loop=1
2013-03-13T22:25:44.261Z,1363213544.261 [Default:GPS] Running Loop=1
2013-03-13T22:25:44.261Z,1363213544.261 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-03-13T22:25:44.261Z,1363213544.261 [Default:GPS:A.SetSpeed] Running Loop=1
2013-03-13T22:25:44.262Z,1363213544.261 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-03-13T22:25:44.262Z,1363213544.262 [Default:GPS:B.GoToSurface] Running Loop=1
2013-03-13T22:25:44.262Z,1363213544.262 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-03-13T22:25:44.263Z,1363213544.263 [Default:GPS:B.GoToSurface] Stopped
2013-03-13T22:25:44.263Z,1363213544.263 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-03-13T22:25:44.263Z,1363213544.263 [Default:GPS:Read_GPS] Running Loop=1
2013-03-13T22:25:44.867Z,1363213544.867 [NAL9602](INFO): Powering up
2013-03-13T22:25:55.694Z,1363213555.694 [NAL9602](INFO): NAL9602 initialized
2013-03-13T22:26:15.863Z,1363213575.863 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=7241, MT Status=1, MTMSN=576
2013-03-13T22:26:15.922Z,1363213575.922 [NAL9602](INFO): Sent 191 bytes from file Logs/20130313T195025/shore0027.lzma
2013-03-13T22:26:15.922Z,1363213575.922 [NAL9602](INFO): Packets left to send: 0
2013-03-13T22:26:15.924Z,1363213575.924 [NAL9602](INFO): Stored copy of sent data in Logs/20130313T195025/shore0027.lzma.parts/0000.sbd
2013-03-13T22:26:16.362Z,1363213576.362 [NAL9602](INFO): Received command:Restart logs