2013-03-19T18:45:15.329Z,1363718715.329 [Supervisor](DEBUG): Initializing supervisor. 2013-03-19T18:45:15.331Z,1363718715.331 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0 2013-03-19T18:45:15.332Z,1363718715.332 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2013-03-19T18:45:15.333Z,1363718715.333 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0 2013-03-19T18:45:15.337Z,1363718715.337 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2013-03-19T18:45:15.347Z,1363718715.348 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2013-03-19T18:45:15.349Z,1363718715.349 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0 2013-03-19T18:45:15.349Z,1363718715.349 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2013-03-19T18:45:15.350Z,1363718715.350 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403CB4E0 2013-03-19T18:45:15.351Z,1363718715.351 [Supervisor](INFO): Looking for Config files in directory: Config/ 2013-03-19T18:45:15.352Z,1363718715.352 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2013-03-19T18:45:15.632Z,1363718715.632 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2013-03-19T18:45:15.632Z,1363718715.632 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2013-03-19T18:45:15.824Z,1363718715.824 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2013-03-19T18:45:15.825Z,1363718715.825 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2013-03-19T18:45:15.909Z,1363718715.909 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2013-03-19T18:45:15.910Z,1363718715.910 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2013-03-19T18:45:16.093Z,1363718716.093 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2013-03-19T18:45:16.094Z,1363718716.094 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2013-03-19T18:45:16.227Z,1363718716.227 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2013-03-19T18:45:16.227Z,1363718716.227 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2013-03-19T18:45:16.457Z,1363718716.457 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2013-03-19T18:45:16.457Z,1363718716.457 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2013-03-19T18:45:16.626Z,1363718716.626 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2013-03-19T18:45:16.627Z,1363718716.627 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2013-03-19T18:45:16.876Z,1363718716.876 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2013-03-19T18:45:16.876Z,1363718716.876 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2013-03-19T18:45:16.974Z,1363718716.974 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2013-03-19T18:45:16.975Z,1363718716.975 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2013-03-19T18:45:17.371Z,1363718717.371 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2013-03-19T18:45:17.372Z,1363718717.372 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2013-03-19T18:45:17.486Z,1363718717.486 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2013-03-19T18:45:17.487Z,1363718717.487 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2013-03-19T18:45:17.572Z,1363718717.572 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2013-03-19T18:45:17.573Z,1363718717.573 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2013-03-19T18:45:17.674Z,1363718717.674 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2013-03-19T18:45:17.803Z,1363718717.803 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2013-03-19T18:45:17.951Z,1363718717.951 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2013-03-19T18:45:18.052Z,1363718718.052 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2013-03-19T18:45:18.161Z,1363718718.161 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2013-03-19T18:45:18.306Z,1363718718.306 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2013-03-19T18:45:18.400Z,1363718718.400 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2013-03-19T18:45:18.487Z,1363718718.487 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2013-03-19T18:45:18.511Z,1363718718.511 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2013-03-19T18:45:18.666Z,1363718718.666 [InternalSim] Loaded 2013-03-19T18:45:18.666Z,1363718718.666 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread. 2013-03-19T18:45:18.667Z,1363718718.667 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2013-03-19T18:45:18.668Z,1363718718.668 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2013-03-19T18:45:18.728Z,1363718718.727 [SBIT](DEBUG): Construct Startup Built In Test. 2013-03-19T18:45:18.756Z,1363718718.756 [SBIT] Loaded 2013-03-19T18:45:18.756Z,1363718718.756 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2013-03-19T18:45:18.757Z,1363718718.757 [IBIT](DEBUG): Construct Initiated Built In Test. 2013-03-19T18:45:18.786Z,1363718718.786 [IBIT] Loaded 2013-03-19T18:45:18.786Z,1363718718.786 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2013-03-19T18:45:18.789Z,1363718718.789 [CBIT](DEBUG): Construct CBIT Built In Test. 2013-03-19T18:45:18.910Z,1363718718.910 [CBIT] Loaded 2013-03-19T18:45:18.910Z,1363718718.910 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2013-03-19T18:45:18.910Z,1363718718.910 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2013-03-19T18:45:18.911Z,1363718718.911 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2013-03-19T18:45:19.064Z,1363718719.064 [BuoyancyServo] Loaded 2013-03-19T18:45:19.064Z,1363718719.065 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2013-03-19T18:45:19.077Z,1363718719.077 [ElevatorServo] Loaded 2013-03-19T18:45:19.078Z,1363718719.078 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2013-03-19T18:45:19.089Z,1363718719.089 [MassServo] Loaded 2013-03-19T18:45:19.090Z,1363718719.090 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2013-03-19T18:45:19.102Z,1363718719.102 [RudderServo] Loaded 2013-03-19T18:45:19.102Z,1363718719.102 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2013-03-19T18:45:19.114Z,1363718719.114 [ThrusterServo] Loaded 2013-03-19T18:45:19.114Z,1363718719.114 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2013-03-19T18:45:19.115Z,1363718719.115 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2013-03-19T18:45:19.115Z,1363718719.115 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2013-03-19T18:45:19.132Z,1363718719.132 [DepthRateCalculator] Loaded 2013-03-19T18:45:19.133Z,1363718719.133 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2013-03-19T18:45:22.229Z,1363718722.229 [HFRadarModelCalc] Loaded 2013-03-19T18:45:22.229Z,1363718722.229 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread. 2013-03-19T18:45:22.245Z,1363718722.245 [NavChart] Loaded 2013-03-19T18:45:22.245Z,1363718722.245 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2013-03-19T18:45:22.251Z,1363718722.251 [PitchRateCalculator] Loaded 2013-03-19T18:45:22.252Z,1363718722.252 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2013-03-19T18:45:22.258Z,1363718722.258 [SpeedCalculator] Loaded 2013-03-19T18:45:22.259Z,1363718722.259 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2013-03-19T18:45:22.274Z,1363718722.274 [TempGradientCalculator] Loaded 2013-03-19T18:45:22.274Z,1363718722.274 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2013-03-19T18:45:22.280Z,1363718722.280 [YawRateCalculator] Loaded 2013-03-19T18:45:22.280Z,1363718722.280 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2013-03-19T18:45:22.322Z,1363718722.322 [Navigation] Loaded 2013-03-19T18:45:22.322Z,1363718722.322 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread. 2013-03-19T18:45:22.322Z,1363718722.322 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2013-03-19T18:45:22.323Z,1363718722.323 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2013-03-19T18:45:22.546Z,1363718722.546 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2013-03-19T18:45:22.546Z,1363718722.546 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2013-03-19T18:45:22.569Z,1363718722.569 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2013-03-19T18:45:22.569Z,1363718722.569 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2013-03-19T18:45:22.610Z,1363718722.610 [VerticalControl](DEBUG): Construct VerticalControl. 2013-03-19T18:45:22.705Z,1363718722.705 [VerticalControl] Loaded 2013-03-19T18:45:22.705Z,1363718722.705 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2013-03-19T18:45:22.706Z,1363718722.706 [HorizontalControl](DEBUG): Construct HorizontalControl. 2013-03-19T18:45:22.764Z,1363718722.764 [HorizontalControl] Loaded 2013-03-19T18:45:22.764Z,1363718722.764 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2013-03-19T18:45:22.765Z,1363718722.765 [SpeedControl](DEBUG): Construct SpeedControl. 2013-03-19T18:45:22.767Z,1363718722.767 [SpeedControl] Loaded 2013-03-19T18:45:22.767Z,1363718722.767 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2013-03-19T18:45:22.768Z,1363718722.768 [LoopControl](DEBUG): Construct LoopControl. 2013-03-19T18:45:22.769Z,1363718722.769 [LoopControl] Loaded 2013-03-19T18:45:22.769Z,1363718722.769 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2013-03-19T18:45:22.769Z,1363718722.769 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2013-03-19T18:45:22.770Z,1363718722.770 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2013-03-19T18:45:22.775Z,1363718722.775 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator. 2013-03-19T18:45:22.780Z,1363718722.780 [AsyncPiEstimator] Loaded 2013-03-19T18:45:22.780Z,1363718722.780 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread. 2013-03-19T18:45:22.782Z,1363718722.782 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 406A44E0 2013-03-19T18:45:22.782Z,1363718722.782 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2013-03-19T18:45:22.783Z,1363718722.783 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2013-03-19T18:45:22.911Z,1363718722.911 [AHRS_sp3003D] Loaded 2013-03-19T18:45:22.911Z,1363718722.911 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread. 2013-03-19T18:45:23.172Z,1363718723.172 [Batt_Ocean_Server] Loaded 2013-03-19T18:45:23.173Z,1363718723.173 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread. 2013-03-19T18:45:23.186Z,1363718723.186 [Depth_Keller] Loaded 2013-03-19T18:45:23.187Z,1363718723.187 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2013-03-19T18:45:23.192Z,1363718723.192 [DropWeight] Loaded 2013-03-19T18:45:23.193Z,1363718723.193 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2013-03-19T18:45:23.370Z,1363718723.369 [DVL_micro] Loaded 2013-03-19T18:45:23.370Z,1363718723.370 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread. 2013-03-19T18:45:23.371Z,1363718723.371 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 407284E0 2013-03-19T18:45:23.455Z,1363718723.455 [NAL9602] Loaded 2013-03-19T18:45:23.456Z,1363718723.456 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2013-03-19T18:45:23.508Z,1363718723.508 [Onboard] Loaded 2013-03-19T18:45:23.508Z,1363718723.508 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2013-03-19T18:45:23.515Z,1363718723.515 [Radio_Freewave] Loaded 2013-03-19T18:45:23.515Z,1363718723.515 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread. 2013-03-19T18:45:23.516Z,1363718723.516 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2013-03-19T18:45:23.516Z,1363718723.516 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2013-03-19T18:45:23.578Z,1363718723.578 [Aanderaa_O2] Loaded 2013-03-19T18:45:23.578Z,1363718723.578 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2013-03-19T18:45:23.617Z,1363718723.617 [CTD_NeilBrown] Loaded 2013-03-19T18:45:23.618Z,1363718723.618 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2013-03-19T18:45:23.619Z,1363718723.619 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4077A4E0 2013-03-19T18:45:23.635Z,1363718723.635 [PAR_Licor] Loaded 2013-03-19T18:45:23.636Z,1363718723.636 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2013-03-19T18:45:23.666Z,1363718723.666 [WetLabsBB2FL] Loaded 2013-03-19T18:45:23.666Z,1363718723.666 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2013-03-19T18:45:23.667Z,1363718723.667 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407AA4E0 2013-03-19T18:45:23.668Z,1363718723.668 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2013-03-19T18:45:23.670Z,1363718723.670 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2013-03-19T18:45:23.671Z,1363718723.671 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2013-03-19T18:45:23.678Z,1363718723.678 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2013-03-19T18:45:23.679Z,1363718723.679 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407DA4E0 2013-03-19T18:45:23.683Z,1363718723.683 [Supervisor](DEBUG): Running supervisor. 2013-03-19T18:45:23.684Z,1363718723.684 [CommandLine](INFO): Thread ID is 850 2013-03-19T18:45:23.687Z,1363718723.687 [controlThread](INFO): Thread ID is 849 2013-03-19T18:45:23.687Z,1363718723.687 [controlThread](DEBUG): Initializing ControlThread 2013-03-19T18:45:23.688Z,1363718723.688 [CycleStarter](INFO): Thread ID is 848 2013-03-19T18:45:23.688Z,1363718723.688 [InternalSim](DEBUG): InternalSim initializing... 2013-03-19T18:45:23.723Z,1363718723.723 [logger](INFO): Thread ID is 851 2013-03-19T18:45:23.748Z,1363718723.748 [SBIT](INFO): Initialize SBIT Component. 2013-03-19T18:45:23.749Z,1363718723.749 [SBIT](IMPORTANT): Tethys CM Info: $Rev: 10234 2013-03-19T18:45:23.749Z,1363718723.749 [IBIT](INFO): Initialize IBIT Component. 2013-03-19T18:45:23.750Z,1363718723.750 [CBIT](DEBUG): Initialize CBIT Component. 2013-03-19T18:45:23.751Z,1363718723.751 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2013-03-19T18:45:23.763Z,1363718723.763 [AsyncPiEstimator](INFO): Thread ID is 912 2013-03-19T18:45:23.763Z,1363718723.763 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator. 2013-03-19T18:45:23.776Z,1363718723.776 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2013-03-19T18:45:23.776Z,1363718723.776 [NavChart](DEBUG): Initialize NavChart Derivation. 2013-03-19T18:45:23.777Z,1363718723.777 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2013-03-19T18:45:23.777Z,1363718723.777 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2013-03-19T18:45:23.777Z,1363718723.777 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2013-03-19T18:45:23.779Z,1363718723.779 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2013-03-19T18:45:23.779Z,1363718723.779 [Navigation](DEBUG): Initializing Navigation. 2013-03-19T18:45:23.780Z,1363718723.780 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2013-03-19T18:45:23.781Z,1363718723.781 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2013-03-19T18:45:23.782Z,1363718723.782 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2013-03-19T18:45:23.783Z,1363718723.783 [LoopControl](DEBUG): Initialize LoopControlComponent. 2013-03-19T18:45:23.788Z,1363718723.788 [DVL_micro](INFO): Thread ID is 913 2013-03-19T18:45:23.798Z,1363718723.797 [DVL_micro](INFO): Initializing 2013-03-19T18:45:23.798Z,1363718723.798 [DVL_micro](INFO): start:Powering up 2013-03-19T18:45:23.799Z,1363718723.799 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2013-03-19T18:45:23.799Z,1363718723.799 [DVL_micro](INFO): Cycling power to configure device. 2013-03-19T18:45:23.812Z,1363718723.812 [CTD_NeilBrown](INFO): Thread ID is 914 2013-03-19T18:45:23.813Z,1363718723.813 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown. 2013-03-19T18:45:23.819Z,1363718723.819 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4 2013-03-19T18:45:23.836Z,1363718723.836 [WetLabsBB2FL](INFO): Thread ID is 915 2013-03-19T18:45:23.836Z,1363718723.836 [WetLabsBB2FL](INFO): Powering down 2013-03-19T18:45:23.869Z,1363718723.869 [NavChartDb](INFO): Thread ID is 916 2013-03-19T18:45:23.872Z,1363718723.872 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources 2013-03-19T18:45:23.873Z,1363718723.873 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2013-03-19T18:45:23.873Z,1363718723.873 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2013-03-19T18:45:23.873Z,1363718723.873 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2013-03-19T18:45:23.873Z,1363718723.874 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2013-03-19T18:45:23.874Z,1363718723.874 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2013-03-19T18:45:23.874Z,1363718723.874 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2013-03-19T18:45:23.874Z,1363718723.874 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2013-03-19T18:45:23.875Z,1363718723.875 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2013-03-19T18:45:25.591Z,1363718725.592 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized 2013-03-19T18:45:25.623Z,1363718725.624 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2013-03-19T18:45:25.663Z,1363718725.663 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2013-03-19T18:45:25.689Z,1363718725.689 [MissionManager](DEBUG): 2013-03-19T18:45:25.694Z,1363718725.694 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2013-03-19T18:45:25.779Z,1363718725.779 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool 2013-03-19T18:45:25.781Z,1363718725.782 [Default:GPS:A.SetSpeed](DEBUG): Construct. 2013-03-19T18:45:25.797Z,1363718725.797 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface. 2013-03-19T18:45:25.805Z,1363718725.805 [Default:Iridium:A.SetSpeed](DEBUG): Construct. 2013-03-19T18:45:25.820Z,1363718725.820 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface. 2013-03-19T18:45:25.851Z,1363718725.851 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2013-03-19T18:45:25.875Z,1363718725.875 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2013-03-19T18:45:25.875Z,1363718725.875 [DVL_micro](INFO): Querying output modes 2013-03-19T18:45:25.876Z,1363718725.875 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606 2013-03-19T18:45:25.877Z,1363718725.878 [Default:D.SetSpeed](DEBUG): Construct. 2013-03-19T18:45:25.885Z,1363718725.885 [Default:E.GoToSurface](DEBUG): Construct GoToSurface. 2013-03-19T18:45:25.887Z,1363718725.887 [DVL_micro](DEBUG): cmdResponse: 01 2013-03-19T18:45:25.887Z,1363718725.887 [DVL_micro](INFO): NQ1 output enabled 2013-03-19T18:45:25.887Z,1363718725.887 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525 2013-03-19T18:45:25.894Z,1363718725.894 [Default:F.Wait](DEBUG): Construct Wait. 2013-03-19T18:45:25.898Z,1363718725.898 [MissionManager](DEBUG): 400 400 Burn 300 Dropped drop weight due to communications timeout 5.0 1.0 5 2013-03-19T18:45:25.899Z,1363718725.899 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 2013-03-19T18:45:25.904Z,1363718725.904 [DVL_micro](INFO): pause:Powering down 2013-03-19T18:45:25.910Z,1363718725.909 [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-19T18:45:25.979Z,1363718725.979 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D. 2013-03-19T18:45:26.187Z,1363718726.187 [Radio_Freewave](INFO): Powering up 2013-03-19T18:45:26.498Z,1363718726.498 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2013-03-19T18:45:26.511Z,1363718726.511 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2013-03-19T18:45:26.517Z,1363718726.517 [ElevatorServo](DEBUG): Initializing EZServoServo. 2013-03-19T18:45:26.523Z,1363718726.523 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2013-03-19T18:45:26.541Z,1363718726.541 [MassServo](DEBUG): Initializing EZServoServo. 2013-03-19T18:45:26.547Z,1363718726.547 [MassServo](DEBUG): Initializing MassServo. 2013-03-19T18:45:26.575Z,1363718726.575 [RudderServo](DEBUG): Initializing EZServoServo. 2013-03-19T18:45:26.583Z,1363718726.583 [RudderServo](DEBUG): Initializing RudderServo. 2013-03-19T18:45:26.589Z,1363718726.589 [ThrusterServo](DEBUG): Initializing EZServoServo. 2013-03-19T18:45:26.595Z,1363718726.595 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2013-03-19T18:45:28.559Z,1363718728.559 [NAL9602](INFO): Powering up NAL9602 2013-03-19T18:45:28.715Z,1363718728.715 [Aanderaa_O2](INFO): Powering down 2013-03-19T18:45:39.255Z,1363718739.255 [NAL9602](INFO): NAL9602 initialized 2013-03-19T18:45:39.324Z,1363718739.324 [SBIT](IMPORTANT): Beginning Startup BIT 2013-03-19T18:45:39.344Z,1363718739.344 [CBIT](IMPORTANT): Beginning GF scan 2013-03-19T18:46:03.953Z,1363718763.953 [NAL9602](IMPORTANT): GPS fix at: 1363718762.00 2013-03-19T18:46:06.017Z,1363718766.017 [CBIT](FAULT): Chan 4 High side GF detected mA: CHAN 5 (24V): 0.055765 CHAN 4 (Batt): 0.004974 CHAN 2 (12V): 0.000670 CHAN 1 (5V): 0.000574 CHAN 0 (3.3V): 0.000574 OPEN: 0.008896 Full Scale Calc: 0.392 2013-03-19T18:46:11.148Z,1363718771.148 [NAL9602](INFO): Powering down 2013-03-19T18:46:33.351Z,1363718793.351 [SBIT](IMPORTANT): SBIT PASSED 2013-03-19T18:46:33.741Z,1363718793.741 [MissionManager](IMPORTANT): Started mission Startup 2013-03-19T18:46:33.741Z,1363718793.741 [Startup] Running Loop=1 2013-03-19T18:46:33.741Z,1363718793.741 [Startup](INFO): Aggregate::initialize Startup 2013-03-19T18:46:33.741Z,1363718793.741 [Startup:A.GoToSurface] Running Loop=1 2013-03-19T18:46:33.742Z,1363718793.741 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2013-03-19T18:46:33.747Z,1363718793.747 [Startup:StartupSatComms] Running Loop=1 2013-03-19T18:46:33.748Z,1363718793.747 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms 2013-03-19T18:46:33.748Z,1363718793.748 [Startup:StartupSatComms:A] Running Loop=1 2013-03-19T18:46:34.142Z,1363718794.142 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2013-03-19T18:46:34.530Z,1363718794.530 [NAL9602](INFO): Powering up 2013-03-19T18:46:44.959Z,1363718804.959 [NAL9602](INFO): NAL9602 initialized 2013-03-19T18:47:33.850Z,1363718853.850 [Startup:StartupSatComms:A](INFO): Timed out from 2013-03-19T18:46:33.7Z 2013-03-19T18:47:33.850Z,1363718853.850 [Startup:StartupSatComms:A:A_Timeout] Running Loop=1 2013-03-19T18:47:33.850Z,1363718853.850 [Startup:StartupSatComms:A:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:A:A_Timeout 2013-03-19T18:47:33.850Z,1363718853.851 [Startup:StartupSatComms:A:A_Timeout](INFO): Completed Startup:StartupSatComms:A:A_Timeout 2013-03-19T18:47:33.851Z,1363718853.851 [Startup:StartupSatComms:A] Stopped 2013-03-19T18:47:33.851Z,1363718853.851 [Startup:StartupSatComms:B] Running Loop=1 2013-03-19T18:47:34.161Z,1363718854.161 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2013-03-19T18:47:55.089Z,1363718875.089 [NAL9602](INFO): SBD MO Status=1, MOMSN=8290, MT Status=0, MTMSN=0 2013-03-19T18:47:55.139Z,1363718875.139 [NAL9602](INFO): Sent 332 bytes from file Logs/20130319T183750/shore0000.lzma 2013-03-19T18:47:55.139Z,1363718875.139 [NAL9602](INFO): Packets left to send: 1 2013-03-19T18:47:55.145Z,1363718875.145 [NAL9602](INFO): Stored copy of sent data in Logs/20130319T183750/shore0000.lzma.parts/0001.sbd 2013-03-19T18:48:08.141Z,1363718888.141 [NAL9602](INFO): SBD MO Status=1, MOMSN=8291, MT Status=0, MTMSN=0 2013-03-19T18:48:08.191Z,1363718888.191 [NAL9602](INFO): Sent 253 bytes from file Logs/20130319T183750/shore0000.lzma 2013-03-19T18:48:08.191Z,1363718888.191 [NAL9602](INFO): Packets left to send: 0 2013-03-19T18:48:08.192Z,1363718888.192 [NAL9602](INFO): Stored copy of sent data in Logs/20130319T183750/shore0000.lzma.parts/0000.sbd 2013-03-19T18:48:16.875Z,1363718896.875 [CommandLine](IMPORTANT): got command burn 5.000000 2013-03-19T18:48:18.413Z,1363718898.413 [NAL9602](INFO): SBD MO Status=1, MOMSN=8292, MT Status=0, MTMSN=0 2013-03-19T18:48:18.463Z,1363718898.463 [NAL9602](INFO): Sent 332 bytes from file Logs/20130319T183925/shore0000.lzma 2013-03-19T18:48:18.463Z,1363718898.463 [NAL9602](INFO): Packets left to send: 1 2013-03-19T18:48:18.465Z,1363718898.465 [NAL9602](INFO): Stored copy of sent data in Logs/20130319T183925/shore0000.lzma.parts/0001.sbd 2013-03-19T18:48:29.449Z,1363718909.449 [NAL9602](INFO): SBD MO Status=1, MOMSN=8293, MT Status=0, MTMSN=0 2013-03-19T18:48:29.499Z,1363718909.499 [NAL9602](INFO): Sent 56 bytes from file Logs/20130319T183925/shore0000.lzma 2013-03-19T18:48:29.499Z,1363718909.499 [NAL9602](INFO): Packets left to send: 0 2013-03-19T18:48:29.500Z,1363718909.500 [NAL9602](INFO): Stored copy of sent data in Logs/20130319T183925/shore0000.lzma.parts/0000.sbd 2013-03-19T18:48:34.137Z,1363718914.137 [Startup:StartupSatComms:B](INFO): Timed out from 2013-03-19T18:47:33.9Z 2013-03-19T18:48:34.137Z,1363718914.137 [Startup:StartupSatComms:B:A_Timeout] Running Loop=1 2013-03-19T18:48:34.138Z,1363718914.138 [Startup:StartupSatComms:B:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:B:A_Timeout 2013-03-19T18:48:34.138Z,1363718914.138 [Startup:StartupSatComms:B:A_Timeout](INFO): Completed Startup:StartupSatComms:B:A_Timeout 2013-03-19T18:48:34.138Z,1363718914.138 [Startup:StartupSatComms:B] Stopped 2013-03-19T18:48:34.138Z,1363718914.138 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2013-03-19T18:48:34.138Z,1363718914.138 [Startup:StartupSatComms] Stopped 2013-03-19T18:48:34.139Z,1363718914.138 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms 2013-03-19T18:48:34.139Z,1363718914.139 [Startup](INFO): Completed Startup 2013-03-19T18:48:34.140Z,1363718914.139 [Startup] Stopped 2013-03-19T18:48:34.140Z,1363718914.140 [Startup](INFO): Aggregate::uninitialize Startup 2013-03-19T18:48:34.140Z,1363718914.140 [Startup:A.GoToSurface] Stopped 2013-03-19T18:48:34.140Z,1363718914.140 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2013-03-19T18:48:34.538Z,1363718914.538 [MissionManager](IMPORTANT): Started mission Default 2013-03-19T18:48:34.539Z,1363718914.539 [Default] Running Loop=1 2013-03-19T18:48:34.539Z,1363718914.539 [Default](INFO): Aggregate::initialize Default 2013-03-19T18:48:34.539Z,1363718914.539 [Default:D.SetSpeed] Running Loop=1 2013-03-19T18:48:34.539Z,1363718914.539 [Default:D.SetSpeed](DEBUG): Initialize. 2013-03-19T18:48:34.539Z,1363718914.539 [Default:E.GoToSurface] Running Loop=1 2013-03-19T18:48:34.539Z,1363718914.539 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2013-03-19T18:48:34.539Z,1363718914.539 [Default:Iridium] Running Loop=1 2013-03-19T18:48:34.539Z,1363718914.539 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2013-03-19T18:48:34.539Z,1363718914.539 [Default:Iridium:A.SetSpeed] Running Loop=1 2013-03-19T18:48:34.540Z,1363718914.540 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2013-03-19T18:48:34.540Z,1363718914.540 [Default:Iridium:B.GoToSurface] Running Loop=1 2013-03-19T18:48:34.540Z,1363718914.540 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2013-03-19T18:48:34.540Z,1363718914.540 [Default:E.GoToSurface] Running Loop=1 2013-03-19T18:48:34.546Z,1363718914.546 [Default:D.SetSpeed] Running Loop=1 2013-03-19T18:48:34.551Z,1363718914.551 [Default:CallIridium] Running Loop=1 2013-03-19T18:48:34.551Z,1363718914.551 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium 2013-03-19T18:48:34.552Z,1363718914.552 [Default:CallIridium:A] Running Loop=1 2013-03-19T18:48:34.553Z,1363718914.553 [Default:CallIridium:A] Stopped 2013-03-19T18:48:34.553Z,1363718914.553 [Default:CallIridium:B] Running Loop=1 2013-03-19T18:48:34.554Z,1363718914.554 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B 2013-03-19T18:48:34.559Z,1363718914.559 [Default:Iridium:B.GoToSurface] Stopped 2013-03-19T18:48:34.559Z,1363718914.559 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2013-03-19T18:48:34.559Z,1363718914.559 [Default:Iridium:Read_Iridium] Running Loop=1 2013-03-19T18:48:34.559Z,1363718914.559 [Default:Iridium:A.SetSpeed] Running Loop=1 2013-03-19T18:48:34.565Z,1363718914.565 [Default:GPS] Running Loop=1 2013-03-19T18:48:34.565Z,1363718914.565 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2013-03-19T18:48:34.565Z,1363718914.565 [Default:GPS:A.SetSpeed] Running Loop=1 2013-03-19T18:48:34.565Z,1363718914.565 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2013-03-19T18:48:34.565Z,1363718914.565 [Default:GPS:B.GoToSurface] Running Loop=1 2013-03-19T18:48:34.565Z,1363718914.565 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2013-03-19T18:48:34.571Z,1363718914.571 [Default:GPS:B.GoToSurface] Stopped 2013-03-19T18:48:34.571Z,1363718914.571 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2013-03-19T18:48:34.571Z,1363718914.571 [Default:GPS:Read_GPS] Running Loop=1 2013-03-19T18:48:34.571Z,1363718914.571 [Default:GPS:A.SetSpeed] Running Loop=1 2013-03-19T18:48:34.988Z,1363718914.988 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2013-03-19T18:48:34.991Z,1363718914.991 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2013-03-19T18:48:42.517Z,1363718922.517 [NAL9602](INFO): SBD MO Status=1, MOMSN=8294, MT Status=0, MTMSN=0 2013-03-19T18:48:42.571Z,1363718922.571 [NAL9602](INFO): Sent 332 bytes from file Logs/20130319T184042/shore0000.lzma 2013-03-19T18:48:42.571Z,1363718922.571 [NAL9602](INFO): Packets left to send: 2 2013-03-19T18:48:42.573Z,1363718922.573 [NAL9602](INFO): Stored copy of sent data in Logs/20130319T184042/shore0000.lzma.parts/0002.sbd 2013-03-19T18:48:52.774Z,1363718932.773 [NAL9602](INFO): SBD MO Status=1, MOMSN=8295, MT Status=0, MTMSN=0 2013-03-19T18:48:52.823Z,1363718932.823 [NAL9602](INFO): Sent 332 bytes from file Logs/20130319T184042/shore0000.lzma 2013-03-19T18:48:52.823Z,1363718932.823 [NAL9602](INFO): Packets left to send: 1 2013-03-19T18:48:52.824Z,1363718932.824 [NAL9602](INFO): Stored copy of sent data in Logs/20130319T184042/shore0000.lzma.parts/0001.sbd 2013-03-19T18:49:04.573Z,1363718944.573 [NAL9602](INFO): SBD MO Status=1, MOMSN=8296, MT Status=0, MTMSN=0 2013-03-19T18:49:04.623Z,1363718944.623 [NAL9602](INFO): Sent 43 bytes from file Logs/20130319T184042/shore0000.lzma 2013-03-19T18:49:04.623Z,1363718944.623 [NAL9602](INFO): Packets left to send: 0 2013-03-19T18:49:04.953Z,1363718944.953 [NAL9602](INFO): Stored copy of sent data in Logs/20130319T184042/shore0000.lzma.parts/0000.sbd 2013-03-19T18:49:20.722Z,1363718960.722 [NAL9602](INFO): SBD MO Status=2, MOMSN=8297, MT Status=2, MTMSN=0 2013-03-19T18:49:20.722Z,1363718960.722 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2013-03-19T18:49:21.875Z,1363718961.875 [NAL9602](IMPORTANT): GPS fix at: 1363718961.00 2013-03-19T18:49:21.891Z,1363718961.891 [Default:GPS:Read_GPS] Stopped 2013-03-19T18:49:21.891Z,1363718961.891 [Default:GPS:D] Running Loop=1 2013-03-19T18:49:22.310Z,1363718962.310 [Default:GPS:D] Stopped 2013-03-19T18:49:22.310Z,1363718962.310 [Default:GPS](INFO): Completed Default:GPS 2013-03-19T18:49:22.310Z,1363718962.310 [Default:GPS] Stopped 2013-03-19T18:49:22.311Z,1363718962.311 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2013-03-19T18:49:22.311Z,1363718962.311 [Default:GPS:A.SetSpeed] Stopped 2013-03-19T18:49:22.311Z,1363718962.311 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2013-03-19T18:49:42.505Z,1363718982.505 [NAL9602](INFO): SBD MO Status=1, MOMSN=8297, MT Status=0, MTMSN=0 2013-03-19T18:49:42.555Z,1363718982.555 [NAL9602](INFO): Sent 332 bytes from file Logs/20130319T184243/shore0000.lzma 2013-03-19T18:49:42.555Z,1363718982.555 [NAL9602](INFO): Packets left to send: 2 2013-03-19T18:49:42.557Z,1363718982.557 [NAL9602](INFO): Stored copy of sent data in Logs/20130319T184243/shore0000.lzma.parts/0002.sbd 2013-03-19T18:49:53.138Z,1363718993.138 [NAL9602](INFO): SBD MO Status=1, MOMSN=8298, MT Status=0, MTMSN=0 2013-03-19T18:49:53.191Z,1363718993.191 [NAL9602](INFO): Sent 332 bytes from file Logs/20130319T184243/shore0000.lzma 2013-03-19T18:49:53.191Z,1363718993.191 [NAL9602](INFO): Packets left to send: 1 2013-03-19T18:49:53.192Z,1363718993.192 [NAL9602](INFO): Stored copy of sent data in Logs/20130319T184243/shore0000.lzma.parts/0001.sbd 2013-03-19T18:50:01.874Z,1363719001.874 [NAL9602](INFO): SBD MO Status=1, MOMSN=8299, MT Status=0, MTMSN=0 2013-03-19T18:50:01.923Z,1363719001.923 [NAL9602](INFO): Sent 163 bytes from file Logs/20130319T184243/shore0000.lzma 2013-03-19T18:50:01.923Z,1363719001.923 [NAL9602](INFO): Packets left to send: 0 2013-03-19T18:50:01.924Z,1363719001.924 [NAL9602](INFO): Stored copy of sent data in Logs/20130319T184243/shore0000.lzma.parts/0000.sbd 2013-03-19T18:50:10.443Z,1363719010.443 [CommandLine](IMPORTANT): got command get platform_battery_voltage 2013-03-19T18:50:10.444Z,1363719010.444 [CommandLine](IMPORTANT): platform_battery_voltage 16.130001 volt 2013-03-19T18:50:13.633Z,1363719013.633 [NAL9602](INFO): SBD MO Status=1, MOMSN=8300, MT Status=0, MTMSN=0 2013-03-19T18:50:13.683Z,1363719013.683 [NAL9602](INFO): Sent 160 bytes from file Logs/20130319T184243/shore0001.lzma 2013-03-19T18:50:13.683Z,1363719013.683 [NAL9602](INFO): Packets left to send: 0 2013-03-19T18:50:13.685Z,1363719013.685 [NAL9602](INFO): Stored copy of sent data in Logs/20130319T184243/shore0001.lzma.parts/0000.sbd 2013-03-19T18:50:25.488Z,1363719025.488 [NAL9602](INFO): SBD MO Status=1, MOMSN=8301, MT Status=0, MTMSN=0 2013-03-19T18:50:25.539Z,1363719025.539 [NAL9602](INFO): Sent 332 bytes from file Logs/20130319T184515/shore0000.lzma 2013-03-19T18:50:25.539Z,1363719025.539 [NAL9602](INFO): Packets left to send: 2 2013-03-19T18:50:25.541Z,1363719025.541 [NAL9602](INFO): Stored copy of sent data in Logs/20130319T184515/shore0000.lzma.parts/0002.sbd 2013-03-19T18:50:37.337Z,1363719037.337 [NAL9602](INFO): SBD MO Status=1, MOMSN=8302, MT Status=0, MTMSN=0 2013-03-19T18:50:37.391Z,1363719037.391 [NAL9602](INFO): Sent 332 bytes from file Logs/20130319T184515/shore0000.lzma 2013-03-19T18:50:37.391Z,1363719037.391 [NAL9602](INFO): Packets left to send: 1 2013-03-19T18:50:37.392Z,1363719037.392 [NAL9602](INFO): Stored copy of sent data in Logs/20130319T184515/shore0000.lzma.parts/0001.sbd 2013-03-19T18:50:50.009Z,1363719050.009 [NAL9602](INFO): SBD MO Status=1, MOMSN=8303, MT Status=0, MTMSN=0 2013-03-19T18:50:50.059Z,1363719050.059 [NAL9602](INFO): Sent 104 bytes from file Logs/20130319T184515/shore0000.lzma 2013-03-19T18:50:50.059Z,1363719050.059 [NAL9602](INFO): Packets left to send: 0 2013-03-19T18:50:50.060Z,1363719050.060 [NAL9602](INFO): Stored copy of sent data in Logs/20130319T184515/shore0000.lzma.parts/0000.sbd 2013-03-19T18:50:57.653Z,1363719057.653 [NAL9602](INFO): SBD MO Status=0, MOMSN=8304, MT Status=0, MTMSN=0 2013-03-19T18:50:57.743Z,1363719057.743 [Default:Iridium:Read_Iridium] Stopped 2013-03-19T18:50:57.743Z,1363719057.743 [Default:Iridium](INFO): Completed Default:Iridium 2013-03-19T18:50:57.743Z,1363719057.743 [Default:Iridium] Stopped 2013-03-19T18:50:57.743Z,1363719057.743 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2013-03-19T18:50:57.743Z,1363719057.743 [Default:Iridium:A.SetSpeed] Stopped 2013-03-19T18:50:57.743Z,1363719057.743 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2013-03-19T18:50:57.743Z,1363719057.743 [Default:F.Wait] Running Loop=1 2013-03-19T18:50:57.743Z,1363719057.743 [Default:F.Wait](DEBUG): Initialize Wait Component. 2013-03-19T18:50:58.032Z,1363719058.032 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B 2013-03-19T18:50:58.032Z,1363719058.032 [Default:CallIridium:B] Stopped 2013-03-19T18:50:58.032Z,1363719058.032 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B 2013-03-19T18:50:58.032Z,1363719058.032 [Default:CallIridium](INFO): Completed Default:CallIridium 2013-03-19T18:50:58.032Z,1363719058.032 [Default:CallIridium] Stopped 2013-03-19T18:50:58.032Z,1363719058.032 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium 2013-03-19T18:51:08.148Z,1363719068.148 [NAL9602](INFO): Powering down