2012-06-21T22:48:24.356Z,1340318904.356 [Supervisor](DEBUG): Initializing supervisor. 2012-06-21T22:48:24.358Z,1340318904.358 [SyncHandler](DEBUG): Created PCaller Thread at 1077130464 2012-06-21T22:48:24.359Z,1340318904.359 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2012-06-21T22:48:24.360Z,1340318904.360 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 1077327072 2012-06-21T22:48:24.361Z,1340318904.361 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2012-06-21T22:48:24.372Z,1340318904.372 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2012-06-21T22:48:24.373Z,1340318904.373 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 1077523680 2012-06-21T22:48:24.374Z,1340318904.374 [ComponentRegistry](DEBUG): SyncComponent "logger" handled in the control thread. 2012-06-21T22:48:24.374Z,1340318904.374 [Supervisor](INFO): Looking for Config files in directory: Config/ 2012-06-21T22:48:24.378Z,1340318904.378 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2012-06-21T22:48:24.845Z,1340318904.845 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2012-06-21T22:48:24.846Z,1340318904.846 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2012-06-21T22:48:25.036Z,1340318905.036 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2012-06-21T22:48:25.038Z,1340318905.038 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2012-06-21T22:48:25.124Z,1340318905.124 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2012-06-21T22:48:25.124Z,1340318905.125 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2012-06-21T22:48:25.316Z,1340318905.316 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2012-06-21T22:48:25.317Z,1340318905.317 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2012-06-21T22:48:25.451Z,1340318905.451 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2012-06-21T22:48:25.453Z,1340318905.453 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2012-06-21T22:48:25.676Z,1340318905.676 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2012-06-21T22:48:25.677Z,1340318905.677 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2012-06-21T22:48:25.828Z,1340318905.828 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2012-06-21T22:48:25.828Z,1340318905.828 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2012-06-21T22:48:26.077Z,1340318906.077 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2012-06-21T22:48:26.078Z,1340318906.078 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2012-06-21T22:48:26.180Z,1340318906.180 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2012-06-21T22:48:26.180Z,1340318906.180 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2012-06-21T22:48:27.621Z,1340318907.621 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2012-06-21T22:48:29.946Z,1340318909.946 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2012-06-21T22:48:30.057Z,1340318910.057 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2012-06-21T22:48:30.058Z,1340318910.058 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2012-06-21T22:48:30.812Z,1340318910.812 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2012-06-21T22:48:30.816Z,1340318910.816 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2012-06-21T22:48:30.917Z,1340318910.917 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2012-06-21T22:48:31.055Z,1340318911.055 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2012-06-21T22:48:31.158Z,1340318911.158 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2012-06-21T22:48:31.257Z,1340318911.257 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2012-06-21T22:48:33.357Z,1340318913.357 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2012-06-21T22:48:33.449Z,1340318913.449 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2012-06-21T22:48:33.538Z,1340318913.538 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2012-06-21T22:48:33.632Z,1340318913.632 [Supervisor](IMPORTANT): Reading configuration overrides from Data/config.db 2012-06-21T22:48:33.641Z,1340318913.641 [ComponentRegistry](DEBUG): Loaded Config Component "configDb 2012-06-21T22:48:33.670Z,1340318913.670 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2012-06-21T22:48:33.829Z,1340318913.829 [InternalSim] Loaded 2012-06-21T22:48:33.829Z,1340318913.829 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread. 2012-06-21T22:48:33.830Z,1340318913.830 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2012-06-21T22:48:33.831Z,1340318913.831 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2012-06-21T22:48:33.896Z,1340318913.896 [SBIT](DEBUG): Construct Startup Built In Test. 2012-06-21T22:48:33.908Z,1340318913.908 [SBIT] Loaded 2012-06-21T22:48:33.908Z,1340318913.908 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2012-06-21T22:48:33.909Z,1340318913.909 [IBIT](DEBUG): Construct Initiated Built In Test. 2012-06-21T22:48:33.935Z,1340318913.935 [IBIT] Loaded 2012-06-21T22:48:33.936Z,1340318913.936 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2012-06-21T22:48:33.939Z,1340318913.939 [CBIT](DEBUG): Construct CBIT Built In Test. 2012-06-21T22:48:34.055Z,1340318914.055 [CBIT] Loaded 2012-06-21T22:48:34.055Z,1340318914.055 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2012-06-21T22:48:34.056Z,1340318914.056 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2012-06-21T22:48:34.056Z,1340318914.056 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2012-06-21T22:48:34.205Z,1340318914.205 [BuoyancyServo] Loaded 2012-06-21T22:48:34.205Z,1340318914.205 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2012-06-21T22:48:34.212Z,1340318914.212 [ElevatorServo] Loaded 2012-06-21T22:48:34.213Z,1340318914.213 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2012-06-21T22:48:34.220Z,1340318914.220 [MassServo] Loaded 2012-06-21T22:48:34.220Z,1340318914.220 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2012-06-21T22:48:34.227Z,1340318914.227 [RudderServo] Loaded 2012-06-21T22:48:34.227Z,1340318914.227 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2012-06-21T22:48:34.234Z,1340318914.234 [ThrusterServo] Loaded 2012-06-21T22:48:34.234Z,1340318914.234 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2012-06-21T22:48:34.234Z,1340318914.234 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2012-06-21T22:48:34.235Z,1340318914.235 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2012-06-21T22:48:34.263Z,1340318914.263 [DepthRateCalculator] Loaded 2012-06-21T22:48:34.263Z,1340318914.263 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2012-06-21T22:48:34.279Z,1340318914.279 [NavChart] Loaded 2012-06-21T22:48:34.279Z,1340318914.279 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2012-06-21T22:48:34.285Z,1340318914.285 [PitchRateCalculator] Loaded 2012-06-21T22:48:34.285Z,1340318914.285 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2012-06-21T22:48:34.291Z,1340318914.291 [SpeedCalculator] Loaded 2012-06-21T22:48:34.292Z,1340318914.292 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2012-06-21T22:48:34.306Z,1340318914.306 [TempGradientCalculator] Loaded 2012-06-21T22:48:34.306Z,1340318914.306 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2012-06-21T22:48:34.312Z,1340318914.312 [YawRateCalculator] Loaded 2012-06-21T22:48:34.312Z,1340318914.312 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2012-06-21T22:48:34.342Z,1340318914.342 [Navigation] Loaded 2012-06-21T22:48:34.343Z,1340318914.342 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread. 2012-06-21T22:48:34.343Z,1340318914.343 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2012-06-21T22:48:34.343Z,1340318914.343 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2012-06-21T22:48:34.597Z,1340318914.597 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2012-06-21T22:48:34.598Z,1340318914.598 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2012-06-21T22:48:34.631Z,1340318914.631 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2012-06-21T22:48:34.632Z,1340318914.632 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2012-06-21T22:48:34.683Z,1340318914.683 [VerticalControl](DEBUG): Construct VerticalControl. 2012-06-21T22:48:34.726Z,1340318914.727 [VerticalControl] Loaded 2012-06-21T22:48:34.727Z,1340318914.727 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2012-06-21T22:48:34.728Z,1340318914.728 [HorizontalControl](DEBUG): Construct HorizontalControl. 2012-06-21T22:48:34.748Z,1340318914.747 [HorizontalControl] Loaded 2012-06-21T22:48:34.748Z,1340318914.748 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2012-06-21T22:48:34.749Z,1340318914.749 [SpeedControl](DEBUG): Construct SpeedControl. 2012-06-21T22:48:34.751Z,1340318914.750 [SpeedControl] Loaded 2012-06-21T22:48:34.751Z,1340318914.751 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2012-06-21T22:48:34.752Z,1340318914.752 [LoopControl](DEBUG): Construct LoopControl. 2012-06-21T22:48:34.752Z,1340318914.752 [LoopControl] Loaded 2012-06-21T22:48:34.752Z,1340318914.753 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2012-06-21T22:48:34.753Z,1340318914.753 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2012-06-21T22:48:34.753Z,1340318914.753 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2012-06-21T22:48:34.765Z,1340318914.765 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator. 2012-06-21T22:48:34.772Z,1340318914.772 [AsyncPiEstimator] Loaded 2012-06-21T22:48:34.772Z,1340318914.772 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread. 2012-06-21T22:48:34.773Z,1340318914.773 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 1078998240 2012-06-21T22:48:34.774Z,1340318914.774 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2012-06-21T22:48:34.775Z,1340318914.775 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2012-06-21T22:48:34.946Z,1340318914.946 [AHRS_sp3003D] Loaded 2012-06-21T22:48:34.946Z,1340318914.946 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread. 2012-06-21T22:48:35.204Z,1340318915.204 [Batt_Ocean_Server] Loaded 2012-06-21T22:48:35.204Z,1340318915.204 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread. 2012-06-21T22:48:35.217Z,1340318915.217 [Depth_Keller] Loaded 2012-06-21T22:48:35.217Z,1340318915.217 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2012-06-21T22:48:35.223Z,1340318915.223 [DropWeight] Loaded 2012-06-21T22:48:35.223Z,1340318915.223 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2012-06-21T22:48:35.320Z,1340318915.320 [DVL_micro] Loaded 2012-06-21T22:48:35.320Z,1340318915.320 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2012-06-21T22:48:35.403Z,1340318915.403 [NAL9601] Loaded 2012-06-21T22:48:35.403Z,1340318915.403 [ComponentRegistry](DEBUG): SyncComponent "NAL9601" handled in the control thread. 2012-06-21T22:48:35.454Z,1340318915.454 [Onboard] Loaded 2012-06-21T22:48:35.454Z,1340318915.454 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2012-06-21T22:48:35.461Z,1340318915.461 [Radio_Freewave] Loaded 2012-06-21T22:48:35.461Z,1340318915.461 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread. 2012-06-21T22:48:35.462Z,1340318915.462 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2012-06-21T22:48:35.463Z,1340318915.463 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2012-06-21T22:48:35.528Z,1340318915.528 [Aanderaa_O2] Loaded 2012-06-21T22:48:35.528Z,1340318915.528 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2012-06-21T22:48:35.560Z,1340318915.560 [CTD_NeilBrown] Loaded 2012-06-21T22:48:35.560Z,1340318915.560 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2012-06-21T22:48:35.561Z,1340318915.561 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 1079690464 2012-06-21T22:48:35.592Z,1340318915.592 [WetLabsBB2FL] Loaded 2012-06-21T22:48:35.593Z,1340318915.593 [ComponentRegistry](DEBUG): SyncComponent "WetLabsBB2FL" handled in the control thread. 2012-06-21T22:48:35.593Z,1340318915.593 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2012-06-21T22:48:35.596Z,1340318915.596 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2012-06-21T22:48:35.596Z,1340318915.596 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2012-06-21T22:48:35.602Z,1340318915.602 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2012-06-21T22:48:35.603Z,1340318915.603 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 1079887072 2012-06-21T22:48:35.607Z,1340318915.607 [Supervisor](DEBUG): Running supervisor. 2012-06-21T22:48:35.611Z,1340318915.611 [controlThread](DEBUG): Initializing ControlThread 2012-06-21T22:48:35.612Z,1340318915.612 [InternalSim](DEBUG): InternalSim initializing... 2012-06-21T22:48:35.646Z,1340318915.645 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator. 2012-06-21T22:48:35.654Z,1340318915.654 [SBIT](INFO): Initialize SBIT Component. 2012-06-21T22:48:35.655Z,1340318915.655 [SBIT](IMPORTANT): Tethys CM Info: $Rev: 9708 $ 2012-06-21T22:48:35.655Z,1340318915.655 [IBIT](INFO): Initialize IBIT Component. 2012-06-21T22:48:35.657Z,1340318915.657 [CBIT](DEBUG): Initialize CBIT Component. 2012-06-21T22:48:35.657Z,1340318915.657 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2012-06-21T22:48:35.658Z,1340318915.658 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown. 2012-06-21T22:48:35.688Z,1340318915.688 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2012-06-21T22:48:35.688Z,1340318915.688 [NavChart](DEBUG): Initialize NavChart Derivation. 2012-06-21T22:48:35.688Z,1340318915.688 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2012-06-21T22:48:35.689Z,1340318915.689 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2012-06-21T22:48:35.689Z,1340318915.689 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2012-06-21T22:48:35.691Z,1340318915.691 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2012-06-21T22:48:35.691Z,1340318915.691 [Navigation](DEBUG): Initializing Navigation. 2012-06-21T22:48:35.691Z,1340318915.691 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2012-06-21T22:48:35.693Z,1340318915.694 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2012-06-21T22:48:35.694Z,1340318915.694 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2012-06-21T22:48:35.695Z,1340318915.695 [LoopControl](DEBUG): Initialize LoopControlComponent. 2012-06-21T22:48:35.776Z,1340318915.776 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources 2012-06-21T22:48:35.783Z,1340318915.783 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2012-06-21T22:48:35.787Z,1340318915.787 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2012-06-21T22:48:35.791Z,1340318915.791 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2012-06-21T22:48:35.795Z,1340318915.795 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2012-06-21T22:48:35.796Z,1340318915.796 [NavChartDb](INFO): Creating index for Soundings 2012-06-21T22:48:35.799Z,1340318915.799 [NavChartDb](INFO): Creating index for Contour 0p0 2012-06-21T22:48:35.801Z,1340318915.801 [NavChartDb](INFO): Creating index for Contour 1p8 2012-06-21T22:48:35.803Z,1340318915.803 [NavChartDb](INFO): Creating index for Contour 3p6 2012-06-21T22:48:35.805Z,1340318915.805 [NavChartDb](INFO): Creating index for Contour 5p4 2012-06-21T22:48:35.807Z,1340318915.807 [NavChartDb](INFO): Creating index for Contour 9p1 2012-06-21T22:48:35.809Z,1340318915.809 [NavChartDb](INFO): Creating index for Contour 10p9 2012-06-21T22:48:35.811Z,1340318915.811 [NavChartDb](INFO): Creating index for Contour 18p2 2012-06-21T22:48:35.813Z,1340318915.813 [NavChartDb](INFO): Creating index for Contour 36p5 2012-06-21T22:48:35.815Z,1340318915.815 [NavChartDb](INFO): Creating index for Contour 54p8 2012-06-21T22:48:35.817Z,1340318915.817 [NavChartDb](INFO): Creating index for Contour 73p1 2012-06-21T22:48:35.819Z,1340318915.819 [NavChartDb](INFO): Creating index for Contour 91p4 2012-06-21T22:48:35.821Z,1340318915.820 [NavChartDb](INFO): Creating index for Contour 182p8 2012-06-21T22:48:35.822Z,1340318915.822 [NavChartDb](INFO): Creating index for Contour 365p7 2012-06-21T22:48:35.824Z,1340318915.824 [NavChartDb](INFO): Creating index for Contour 548p6 2012-06-21T22:48:35.826Z,1340318915.826 [NavChartDb](INFO): Creating index for Contour 731p5 2012-06-21T22:48:35.828Z,1340318915.828 [NavChartDb](INFO): Creating index for Contour 914p4 2012-06-21T22:48:35.830Z,1340318915.830 [NavChartDb](INFO): Creating index for Contour 1097p2 2012-06-21T22:48:35.832Z,1340318915.832 [NavChartDb](INFO): Creating index for Contour 1280p1 2012-06-21T22:48:35.834Z,1340318915.834 [NavChartDb](INFO): Creating index for Contour 1463p0 2012-06-21T22:48:35.836Z,1340318915.836 [NavChartDb](INFO): Creating index for Contour 1645p9 2012-06-21T22:48:35.838Z,1340318915.838 [NavChartDb](INFO): Creating index for Contour 1828p8 2012-06-21T22:48:35.840Z,1340318915.840 [NavChartDb](INFO): Creating index for Contour 2011p6 2012-06-21T22:48:35.842Z,1340318915.842 [NavChartDb](INFO): Creating index for Contour 2194p5 2012-06-21T22:48:35.844Z,1340318915.844 [NavChartDb](INFO): Creating index for Contour 2377p4 2012-06-21T22:48:35.845Z,1340318915.845 [NavChartDb](INFO): Creating index for Contour 2560p3 2012-06-21T22:48:35.848Z,1340318915.848 [NavChartDb](INFO): Creating index for Contour 2743p2 2012-06-21T22:48:35.850Z,1340318915.849 [NavChartDb](INFO): Creating index for Contour 2926p0 2012-06-21T22:48:35.852Z,1340318915.852 [NavChartDb](INFO): Creating index for Contour 3108p9 2012-06-21T22:48:35.854Z,1340318915.854 [NavChartDb](INFO): Creating index for Contour 3291p8 2012-06-21T22:48:35.856Z,1340318915.856 [NavChartDb](INFO): Creating index for Contour 3474p7 2012-06-21T22:48:35.858Z,1340318915.858 [NavChartDb](INFO): Creating index for Contour 3657p6 2012-06-21T22:48:37.111Z,1340318917.111 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized 2012-06-21T22:48:37.116Z,1340318917.116 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2012-06-21T22:48:38.017Z,1340318918.017 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2012-06-21T22:48:38.027Z,1340318918.027 [MissionManager](DEBUG): 2012-06-21T22:48:38.027Z,1340318918.027 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2012-06-21T22:48:38.301Z,1340318918.301 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool 2012-06-21T22:48:38.303Z,1340318918.303 [Default:GPS:A.SetSpeed](DEBUG): Construct. 2012-06-21T22:48:38.306Z,1340318918.306 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface. 2012-06-21T22:48:38.314Z,1340318918.314 [Default:Iridium:A.SetSpeed](DEBUG): Construct. 2012-06-21T22:48:38.320Z,1340318918.320 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface. 2012-06-21T22:48:38.326Z,1340318918.326 [Default:Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2012-06-21T22:48:38.335Z,1340318918.335 [Default:D.SetSpeed](DEBUG): Construct. 2012-06-21T22:48:38.338Z,1340318918.338 [Default:E.GoToSurface](DEBUG): Construct GoToSurface. 2012-06-21T22:48:38.343Z,1340318918.342 [Default:F.Wait](DEBUG): Construct Wait. 2012-06-21T22:48:38.346Z,1340318918.346 [MissionManager](DEBUG): 400 400 Burn 300 Dropped drop weight due to communications timeout 5.0 1.0 5 2012-06-21T22:48:38.350Z,1340318918.350 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,DVL_micro,NAL9601,Onboard,Radio_Freewave,Aanderaa_O2,WetLabsBB2FL,Depth_Keller,DepthRateCalculator,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,logger, 2012-06-21T22:48:38.369Z,1340318918.369 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D. 2012-06-21T22:48:38.477Z,1340318918.477 [DVL_micro](DEBUG): Initializing DVL_micro. 2012-06-21T22:48:38.498Z,1340318918.499 [Radio_Freewave](INFO): Powering up 2012-06-21T22:48:38.510Z,1340318918.510 [WetLabsBB2FL](INFO): Powering down 2012-06-21T22:48:38.577Z,1340318918.577 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2012-06-21T22:48:38.578Z,1340318918.578 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2012-06-21T22:48:38.588Z,1340318918.588 [ElevatorServo](DEBUG): Initializing EZServoServo. 2012-06-21T22:48:38.589Z,1340318918.589 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2012-06-21T22:48:38.597Z,1340318918.597 [MassServo](DEBUG): Initializing EZServoServo. 2012-06-21T22:48:38.598Z,1340318918.598 [MassServo](DEBUG): Initializing MassServo. 2012-06-21T22:48:38.606Z,1340318918.606 [RudderServo](DEBUG): Initializing EZServoServo. 2012-06-21T22:48:38.607Z,1340318918.607 [RudderServo](DEBUG): Initializing RudderServo. 2012-06-21T22:48:38.614Z,1340318918.615 [ThrusterServo](DEBUG): Initializing EZServoServo. 2012-06-21T22:48:38.615Z,1340318918.615 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2012-06-21T22:48:41.031Z,1340318921.031 [NAL9601](INFO): Powering up NAL9601 2012-06-21T22:48:42.747Z,1340318922.747 [Aanderaa_O2](INFO): Powering down 2012-06-21T22:48:51.428Z,1340318931.428 [SBIT](IMPORTANT): Beginning Startup BIT 2012-06-21T22:48:51.430Z,1340318931.430 [CBIT](IMPORTANT): Beginning GF scan 2012-06-21T22:49:17.638Z,1340318957.638 [CBIT](IMPORTANT): No ground fault detected 2012-06-21T22:49:33.538Z,1340318973.538 [SBIT](IMPORTANT): SBIT PASSED 2012-06-21T22:49:33.936Z,1340318973.936 [MissionManager](IMPORTANT): Started mission Startup 2012-06-21T22:49:33.936Z,1340318973.936 [Startup] Running Loop=1 2012-06-21T22:49:33.936Z,1340318973.936 [Startup](INFO): Aggregate::initialize Startup 2012-06-21T22:49:33.936Z,1340318973.936 [Startup:A.GoToSurface] Running Loop=1 2012-06-21T22:49:33.936Z,1340318973.936 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-21T22:49:33.942Z,1340318973.942 [Startup:StartupSatComms] Running Loop=1 2012-06-21T22:49:33.942Z,1340318973.942 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms 2012-06-21T22:49:33.942Z,1340318973.942 [Startup:StartupSatComms:A] Running Loop=1 2012-06-21T22:49:34.334Z,1340318974.334 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-06-21T22:49:46.655Z,1340318986.655 [NAL9601](INFO): NAL9601 initialized 2012-06-21T22:49:47.757Z,1340318987.757 [NAL9601](IMPORTANT): GPS fix at: 1340319153.00 2012-06-21T22:49:47.771Z,1340318987.771 [Startup:StartupSatComms:A] Stopped 2012-06-21T22:49:47.771Z,1340318987.771 [Startup:StartupSatComms:B] Running Loop=1 2012-06-21T22:49:48.169Z,1340318988.170 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2012-06-21T22:49:48.199Z,1340318988.199 [NavChartDb](INFO): Charting Soundings 2012-06-21T22:49:49.799Z,1340318989.799 [NavChartDb](INFO): Charting 0p0 2012-06-21T22:50:00.820Z,1340319000.820 [NavChartDb](INFO): Charting 1p8 2012-06-21T22:50:02.422Z,1340319002.422 [NavChartDb](INFO): Charting 3p6 2012-06-21T22:50:04.023Z,1340319004.023 [NavChartDb](INFO): Charting 5p4 2012-06-21T22:50:08.013Z,1340319008.013 [NavChartDb](INFO): Charting 9p1 2012-06-21T22:50:09.987Z,1340319009.987 [NavChartDb](INFO): Charting 10p9 2012-06-21T22:50:11.587Z,1340319011.587 [NavChartDb](INFO): Charting 18p2 2012-06-21T22:50:15.987Z,1340319015.987 [NavChartDb](INFO): Charting 36p5 2012-06-21T22:50:18.906Z,1340319018.906 [NAL9601](IMPORTANT): SBD MO Status=1, MOMSN=10475, MT Status=1, MTMSN=2775 2012-06-21T22:50:19.076Z,1340319019.076 [NavChartDb](INFO): Charting 54p8 2012-06-21T22:50:19.091Z,1340319019.091 [NAL9601](INFO): Sent 186 bytes from file Logs/20120621T160251/shore0025.lzma 2012-06-21T22:50:19.091Z,1340319019.091 [NAL9601](INFO): Packets left to send: 0 2012-06-21T22:50:19.093Z,1340319019.093 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T160251/shore0025.lzma.parts/0000.sbd 2012-06-21T22:50:19.724Z,1340319019.724 [NAL9601](INFO): Received command:load Engineering/triangle_control_speed_onelap.xml;set triangle_control_speed_onelap.MassHold 0 bool;set triangle_control_speed_onelap.ElevatorHold 1 bool;set triangle_control_speed_onelap.StopDepthExcursion 15 meter;run 2012-06-21T22:50:19.768Z,1340319019.768 [CommandLine](IMPORTANT): got command load ./Missions/Engineering/triangle_control_speed_onelap.xml 2012-06-21T22:50:19.768Z,1340319019.768 [MissionManager](INFO): Loading Mission: ./Missions/Engineering/triangle_control_speed_onelap.xml 2012-06-21T22:50:19.913Z,1340319019.913 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Wpt1Lat = 36.815000 arcdeg 2012-06-21T22:50:19.916Z,1340319019.916 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Wpt1Lon = -121.869000 arcdeg 2012-06-21T22:50:19.919Z,1340319019.919 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Speed1 = 1.000000 m/s 2012-06-21T22:50:19.922Z,1340319019.921 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Wpt2Lat = 36.829000 arcdeg 2012-06-21T22:50:19.924Z,1340319019.924 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Wpt2Lon = -121.886000 arcdeg 2012-06-21T22:50:19.927Z,1340319019.927 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Speed2 = 0.750000 m/s 2012-06-21T22:50:19.930Z,1340319019.930 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Wpt3Lat = 36.816000 arcdeg 2012-06-21T22:50:19.933Z,1340319019.933 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Wpt3Lon = -121.883000 arcdeg 2012-06-21T22:50:19.936Z,1340319019.936 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Speed3 = 0.600000 m/s 2012-06-21T22:50:19.939Z,1340319019.939 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.MaxDepth = 37.000000 m 2012-06-21T22:50:19.942Z,1340319019.942 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.MinAltitude = 7.000000 m 2012-06-21T22:50:19.945Z,1340319019.944 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.DepthShallow = 15.000000 m 2012-06-21T22:50:19.947Z,1340319019.947 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.DepthDeep = 32.000000 m 2012-06-21T22:50:19.950Z,1340319019.950 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.NeedCommsTime = 50.000000 min 2012-06-21T22:50:19.953Z,1340319019.953 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.TimeoutDuration = 250.000000 min 2012-06-21T22:50:19.956Z,1340319019.956 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.MassHold = 1 bool 2012-06-21T22:50:19.959Z,1340319019.959 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.MassDefault = -0.007500 n/a 2012-06-21T22:50:19.962Z,1340319019.962 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.massDeadband = 0.000000 mm 2012-06-21T22:50:19.964Z,1340319019.964 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.BuoyancyHold = 1 bool 2012-06-21T22:50:19.968Z,1340319019.968 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.BuoyancyNeutral = 0.000505 n/a 2012-06-21T22:50:19.970Z,1340319019.970 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.ElevatorHold = 0 bool 2012-06-21T22:50:19.973Z,1340319019.973 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.ElevatorDefault = 0.000000 arcdeg 2012-06-21T22:50:19.976Z,1340319019.976 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.elevDeadband = 0.000000 arcdeg 2012-06-21T22:50:19.978Z,1340319019.978 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.ElevIntInDepthMode = 0 bool 2012-06-21T22:50:19.982Z,1340319019.982 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.StopDepthExcursion = 5.000000 n/a 2012-06-21T22:50:19.988Z,1340319019.988 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.kdPitchMass = 0.000000 n/a 2012-06-21T22:50:20.016Z,1340319020.016 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.kiDepthOff = 0.100000 n/a 2012-06-21T22:50:20.020Z,1340319020.020 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.kiPitchMass = 0.001500 n/a 2012-06-21T22:50:20.035Z,1340319020.035 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.kpPitchMass = 0.010000 n/a 2012-06-21T22:50:20.038Z,1340319020.038 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.maxDiveAccel = 0.001000 n/a 2012-06-21T22:50:20.040Z,1340319020.040 [triangle_control_speed_onelap:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2012-06-21T22:50:20.060Z,1340319020.059 [triangle_control_speed_onelap:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2012-06-21T22:50:20.068Z,1340319020.068 [triangle_control_speed_onelap:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2012-06-21T22:50:20.089Z,1340319020.089 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface. 2012-06-21T22:50:20.094Z,1340319020.094 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Construct. 2012-06-21T22:50:20.117Z,1340319020.117 [triangle_control_speed_onelap:WaypointOne:A.Pitch](DEBUG): Construct. 2012-06-21T22:50:20.135Z,1340319020.135 [triangle_control_speed_onelap:WaypointOne:B.SetSpeed](DEBUG): Construct. 2012-06-21T22:50:20.138Z,1340319020.138 [triangle_control_speed_onelap:WaypointOne:WaypointW1.Waypoint](DEBUG): Construct Waypoint. 2012-06-21T22:50:20.158Z,1340319020.158 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold:A.Buoyancy](DEBUG): Construct Buoyancy. 2012-06-21T22:50:20.162Z,1340319020.162 [triangle_control_speed_onelap:ThreeLegs:MassHold:A.Pitch](DEBUG): Construct. 2012-06-21T22:50:20.180Z,1340319020.180 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold:A.Pitch](DEBUG): Construct. 2012-06-21T22:50:20.214Z,1340319020.214 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:A.SetSpeed](DEBUG): Construct. 2012-06-21T22:50:20.217Z,1340319020.217 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:B.Pitch](DEBUG): Construct. 2012-06-21T22:50:20.242Z,1340319020.242 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:Wpt2.Waypoint](DEBUG): Construct Waypoint. 2012-06-21T22:50:20.253Z,1340319020.253 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:A.SetSpeed](DEBUG): Construct. 2012-06-21T22:50:20.269Z,1340319020.269 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:B.Pitch](DEBUG): Construct. 2012-06-21T22:50:20.287Z,1340319020.287 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:Wpt3.Waypoint](DEBUG): Construct Waypoint. 2012-06-21T22:50:20.293Z,1340319020.293 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:A.SetSpeed](DEBUG): Construct. 2012-06-21T22:50:20.312Z,1340319020.312 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:B.Pitch](DEBUG): Construct. 2012-06-21T22:50:20.333Z,1340319020.333 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2012-06-21T22:50:20.524Z,1340319020.524 [MissionManager](DEBUG): 36.815 -121.869 Speed for leg 1 1 36.829 -121.886 Speed for leg 2 .75 36.816 -121.883 Speed for leg 3 .60 Maximum depth for the entire mission. 37 Minimum height above the sea floor for the entire mission. 7.0 Depth for transit 15 Depth for test 32 Elapsed time after previous surface communications when vehicle will begin to ascend for additional surface communications 50 Maximum length of mission 250 Set to True in order to hold mass at user defined position, False to allow mass to run on its own. Static setting for mass when the mass position is held. Sets the value of VerticalControl.massDeadband 0.0 Set to True in order to hold buoyancy at neutral position, False to allow buoyancy to run on its own. Buoyancy bladder position when buoyancy is held. Defaults to buoyancyNeutral setting in the Config/Control.cfg file. Set to True in order to hold the elevators at neutral position, False to allow them to run on their own. Static setting for elevator if elevator position is held. 0.0 Sets the value of VerticalControl.elevDeadband 0.0 Sets VerticalControl.useElevIntInDepthMode value Sets VerticalControl.stopDepthExcursion within the triangle, but not during the transit. Sets the value of VerticalControl.kdPitchMass Sets the value of VerticalControl.kiDepthOff Sets the value of VerticalControl.kiPitchMass Sets the value of VerticalControl.kpPitchMass Sets the value of VerticalControl.maxDiveAccel 1 0 1 2012-06-21T22:50:20.525Z,1340319020.525 [CommandLine](IMPORTANT): Loaded ./Missions/Engineering/triangle_control_speed_onelap.xml 2012-06-21T22:50:20.780Z,1340319020.780 [CommandLine](IMPORTANT): got command set triangle_control_speed_onelap.MassHold 0.000000 bool 2012-06-21T22:50:21.076Z,1340319021.076 [CommandLine](IMPORTANT): got command set triangle_control_speed_onelap.ElevatorHold 1.000000 bool 2012-06-21T22:50:21.396Z,1340319021.395 [CommandLine](IMPORTANT): got command set triangle_control_speed_onelap.StopDepthExcursion 15.000000 meter 2012-06-21T22:50:21.397Z,1340319021.397 [CommandLine](IMPORTANT): got command run 2012-06-21T22:50:21.397Z,1340319021.397 [CommandLine](IMPORTANT): Running 2012-06-21T22:50:22.403Z,1340319022.403 [NavChartDb](INFO): Charting 73p1 2012-06-21T22:50:24.299Z,1340319024.299 [NavChartDb](INFO): Charting 91p4 2012-06-21T22:50:25.899Z,1340319025.898 [NavChartDb](INFO): Charting 182p8 2012-06-21T22:50:35.710Z,1340319035.710 [NAL9601](INFO): SBD MO Status=1, MOMSN=10476, MT Status=0, MTMSN=0 2012-06-21T22:50:35.867Z,1340319035.867 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0000.lzma 2012-06-21T22:50:35.867Z,1340319035.867 [NAL9601](INFO): Packets left to send: 1 2012-06-21T22:50:35.868Z,1340319035.868 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0000.lzma.parts/0001.sbd 2012-06-21T22:50:36.463Z,1340319036.463 [NavChartDb](INFO): Charting 365p7 2012-06-21T22:50:39.747Z,1340319039.747 [NavChartDb](INFO): Charting 548p6 2012-06-21T22:50:41.606Z,1340319041.606 [NavChartDb](INFO): Charting 731p5 2012-06-21T22:50:44.134Z,1340319044.134 [NAL9601](INFO): SBD MO Status=1, MOMSN=10477, MT Status=0, MTMSN=0 2012-06-21T22:50:44.255Z,1340319044.255 [NAL9601](INFO): Sent 293 bytes from file Logs/20120621T224824/shore0000.lzma 2012-06-21T22:50:44.255Z,1340319044.255 [NAL9601](INFO): Packets left to send: 0 2012-06-21T22:50:44.256Z,1340319044.256 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0000.lzma.parts/0000.sbd 2012-06-21T22:50:45.047Z,1340319045.047 [NavChartDb](INFO): Charting 914p4 2012-06-21T22:50:47.790Z,1340319047.790 [Startup:StartupSatComms:B](INFO): Timed out from 2012-06-21T22:49:47.8Z 2012-06-21T22:50:47.790Z,1340319047.790 [Startup:StartupSatComms:A_Timeout] Running Loop=1 2012-06-21T22:50:47.790Z,1340319047.790 [Startup:StartupSatComms:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:A_Timeout 2012-06-21T22:50:47.790Z,1340319047.790 [Startup:StartupSatComms:A_Timeout](INFO): Completed Startup:StartupSatComms:A_Timeout 2012-06-21T22:50:47.790Z,1340319047.790 [Startup:StartupSatComms:B] Stopped 2012-06-21T22:50:47.791Z,1340319047.791 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2012-06-21T22:50:47.791Z,1340319047.791 [Startup:StartupSatComms] Stopped 2012-06-21T22:50:47.791Z,1340319047.791 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms 2012-06-21T22:50:47.792Z,1340319047.792 [Startup](INFO): Completed Startup 2012-06-21T22:50:47.792Z,1340319047.792 [Startup] Stopped 2012-06-21T22:50:47.792Z,1340319047.792 [Startup](INFO): Aggregate::uninitialize Startup 2012-06-21T22:50:47.792Z,1340319047.792 [Startup:A.GoToSurface] Stopped 2012-06-21T22:50:47.792Z,1340319047.792 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-21T22:50:48.185Z,1340319048.185 [MissionManager](IMPORTANT): Started mission triangle_control_speed_onelap 2012-06-21T22:50:48.185Z,1340319048.185 [triangle_control_speed_onelap] Running Loop=1 2012-06-21T22:50:48.186Z,1340319048.186 [triangle_control_speed_onelap](INFO): Aggregate::initialize triangle_control_speed_onelap 2012-06-21T22:50:48.186Z,1340319048.186 [triangle_control_speed_onelap:A.AltitudeEnvelope] Running Loop=1 2012-06-21T22:50:48.186Z,1340319048.186 [triangle_control_speed_onelap:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2012-06-21T22:50:48.190Z,1340319048.190 [triangle_control_speed_onelap:B.DepthEnvelope] Running Loop=1 2012-06-21T22:50:48.191Z,1340319048.191 [triangle_control_speed_onelap:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2012-06-21T22:50:48.192Z,1340319048.192 [triangle_control_speed_onelap:C.OffshoreEnvelope] Running Loop=1 2012-06-21T22:50:48.192Z,1340319048.192 [triangle_control_speed_onelap:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2012-06-21T22:50:48.192Z,1340319048.192 [triangle_control_speed_onelap:D] Running Loop=1 2012-06-21T22:50:48.192Z,1340319048.192 [triangle_control_speed_onelap:SURFACECOMMS] Running Loop=1 2012-06-21T22:50:48.192Z,1340319048.192 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS 2012-06-21T22:50:48.192Z,1340319048.192 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Running Loop=1 2012-06-21T22:50:48.192Z,1340319048.192 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-21T22:50:48.202Z,1340319048.202 [triangle_control_speed_onelap:SURFACECOMMS:B] Running Loop=1 2012-06-21T22:50:48.202Z,1340319048.202 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-21T22:50:48.206Z,1340319048.206 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Running Loop=1 2012-06-21T22:50:48.206Z,1340319048.206 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize. 2012-06-21T22:50:48.207Z,1340319048.207 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Running Loop=1 2012-06-21T22:50:48.207Z,1340319048.207 [triangle_control_speed_onelap:D] Running Loop=1 2012-06-21T22:50:48.207Z,1340319048.207 [triangle_control_speed_onelap:D](DEBUG): Initialize ReadDataComponent to sense platform_orientation 2012-06-21T22:50:48.209Z,1340319048.209 [triangle_control_speed_onelap:C.OffshoreEnvelope] Running Loop=1 2012-06-21T22:50:48.218Z,1340319048.218 [triangle_control_speed_onelap:B.DepthEnvelope] Running Loop=1 2012-06-21T22:50:48.231Z,1340319048.231 [triangle_control_speed_onelap:A.AltitudeEnvelope] Running Loop=1 2012-06-21T22:50:48.559Z,1340319048.559 [triangle_control_speed_onelap:SURFACECOMMS:B:B](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-06-21T22:50:48.561Z,1340319048.561 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Running Loop=1 2012-06-21T22:50:55.034Z,1340319055.034 [NAL9601](INFO): SBD MO Status=0, MOMSN=10478, MT Status=0, MTMSN=0 2012-06-21T22:50:56.435Z,1340319056.435 [NAL9601](IMPORTANT): GPS fix at: 1340319229.00 2012-06-21T22:50:56.477Z,1340319056.477 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Stopped 2012-06-21T22:50:56.477Z,1340319056.477 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Running Loop=1 2012-06-21T22:50:56.823Z,1340319056.823 [triangle_control_speed_onelap:SURFACECOMMS:B:C](DEBUG): Initialize ReadDataComponent to sense platform_communications 2012-06-21T22:51:07.570Z,1340319067.569 [NAL9601](INFO): SBD MO Status=1, MOMSN=10479, MT Status=0, MTMSN=0 2012-06-21T22:51:07.719Z,1340319067.719 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0001.lzma 2012-06-21T22:51:07.719Z,1340319067.719 [NAL9601](INFO): Packets left to send: 1 2012-06-21T22:51:07.720Z,1340319067.720 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0001.lzma.parts/0001.sbd 2012-06-21T22:51:19.670Z,1340319079.670 [NAL9601](INFO): SBD MO Status=1, MOMSN=10480, MT Status=0, MTMSN=0 2012-06-21T22:51:19.799Z,1340319079.799 [NAL9601](INFO): Sent 53 bytes from file Logs/20120621T224824/shore0001.lzma 2012-06-21T22:51:19.799Z,1340319079.799 [NAL9601](INFO): Packets left to send: 0 2012-06-21T22:51:19.800Z,1340319079.800 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0001.lzma.parts/0000.sbd 2012-06-21T22:51:25.710Z,1340319085.710 [NAL9601](INFO): SBD MO Status=0, MOMSN=10481, MT Status=0, MTMSN=0 2012-06-21T22:51:25.922Z,1340319085.922 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Stopped 2012-06-21T22:51:25.922Z,1340319085.922 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Running Loop=1 2012-06-21T22:51:26.163Z,1340319086.163 [triangle_control_speed_onelap:SURFACECOMMS:B:D](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-06-21T22:51:28.071Z,1340319088.071 [NAL9601](IMPORTANT): GPS fix at: 1340319260.00 2012-06-21T22:51:28.087Z,1340319088.087 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Stopped 2012-06-21T22:51:28.087Z,1340319088.087 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-21T22:51:28.087Z,1340319088.087 [triangle_control_speed_onelap:SURFACECOMMS:B] Stopped 2012-06-21T22:51:28.087Z,1340319088.088 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-21T22:51:28.088Z,1340319088.088 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Stopped 2012-06-21T22:51:28.088Z,1340319088.088 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize. 2012-06-21T22:51:28.089Z,1340319088.089 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS 2012-06-21T22:51:28.089Z,1340319088.089 [triangle_control_speed_onelap:SURFACECOMMS] Stopped 2012-06-21T22:51:28.089Z,1340319088.089 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS 2012-06-21T22:51:28.089Z,1340319088.089 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Stopped 2012-06-21T22:51:28.089Z,1340319088.089 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-21T22:51:28.089Z,1340319088.089 [triangle_control_speed_onelap:WaypointOne] Running Loop=1 2012-06-21T22:51:28.089Z,1340319088.089 [triangle_control_speed_onelap:WaypointOne](INFO): Aggregate::initialize triangle_control_speed_onelap:WaypointOne 2012-06-21T22:51:28.089Z,1340319088.089 [triangle_control_speed_onelap:WaypointOne:A.Pitch] Running Loop=1 2012-06-21T22:51:28.089Z,1340319088.089 [triangle_control_speed_onelap:WaypointOne:A.Pitch](DEBUG): Initialize. 2012-06-21T22:51:28.090Z,1340319088.089 [triangle_control_speed_onelap:WaypointOne:B.SetSpeed] Running Loop=1 2012-06-21T22:51:28.090Z,1340319088.090 [triangle_control_speed_onelap:WaypointOne:B.SetSpeed](DEBUG): Initialize. 2012-06-21T22:51:28.090Z,1340319088.090 [triangle_control_speed_onelap:WaypointOne:WaypointW1.Waypoint] Running Loop=1 2012-06-21T22:51:28.090Z,1340319088.090 [triangle_control_speed_onelap:WaypointOne:WaypointW1.Waypoint](DEBUG): Initialize WaypointComponent. 2012-06-21T22:51:28.488Z,1340319088.488 [triangle_control_speed_onelap:WaypointOne:B.SetSpeed] Running Loop=1 2012-06-21T22:51:28.493Z,1340319088.493 [triangle_control_speed_onelap:WaypointOne:A.Pitch] Running Loop=1 2012-06-21T22:51:34.463Z,1340319094.463 [NAL9601](INFO): Powering down 2012-06-21T22:51:40.483Z,1340319100.483 [Radio_Freewave](INFO): Powering down 2012-06-21T22:51:41.686Z,1340319101.686 [Radio_Freewave](INFO): Powering up 2012-06-21T22:51:43.277Z,1340319103.277 [Radio_Freewave](INFO): Powering down 2012-06-21T22:51:44.873Z,1340319104.873 [Radio_Freewave](INFO): Powering up 2012-06-21T22:51:47.721Z,1340319107.721 [Radio_Freewave](INFO): Powering down 2012-06-21T22:51:48.888Z,1340319108.888 [Radio_Freewave](INFO): Powering up 2012-06-21T22:52:02.407Z,1340319122.407 [NavChartDb](INFO): Charting 1097p2 2012-06-21T22:52:05.879Z,1340319125.879 [NavChartDb](INFO): Charting 1280p1 2012-06-21T22:52:07.001Z,1340319127.001 [Radio_Freewave](INFO): Powering down 2012-06-21T22:52:08.882Z,1340319128.882 [NavChartDb](INFO): Charting 1463p0 2012-06-21T22:52:11.227Z,1340319131.227 [NavChartDb](INFO): Charting 1645p9 2012-06-21T22:52:13.423Z,1340319133.423 [NavChartDb](INFO): Charting 1828p8 2012-06-21T22:52:29.040Z,1340319149.040 [NavChartDb](INFO): Charting 2011p6 2012-06-21T22:52:30.831Z,1340319150.831 [NavChartDb](INFO): Charting 2194p5 2012-06-21T22:52:32.650Z,1340319152.649 [NavChartDb](INFO): Charting 2377p4 2012-06-21T22:52:34.675Z,1340319154.675 [NavChartDb](INFO): Charting 2560p3 2012-06-21T22:52:36.499Z,1340319156.499 [NavChartDb](INFO): Charting 2743p2 2012-06-21T22:52:44.663Z,1340319164.663 [NavChartDb](INFO): Charting 2926p0 2012-06-21T22:52:46.375Z,1340319166.375 [NavChartDb](INFO): Charting 3108p9 2012-06-21T22:52:48.827Z,1340319168.827 [NavChartDb](INFO): Charting 3291p8 2012-06-21T22:52:51.047Z,1340319171.047 [NavChartDb](INFO): Charting 3474p7 2012-06-21T22:52:52.835Z,1340319172.835 [NavChartDb](INFO): Charting 3657p6 2012-06-21T22:59:09.400Z,1340319549.400 [triangle_control_speed_onelap:WaypointOne:WaypointW1.Waypoint](INFO): Reached Waypoint: 36.815000,-121.869000 2012-06-21T22:59:09.400Z,1340319549.400 [triangle_control_speed_onelap:WaypointOne:WaypointW1.Waypoint] Stopped 2012-06-21T22:59:09.400Z,1340319549.401 [triangle_control_speed_onelap:WaypointOne:WaypointW1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2012-06-21T22:59:09.401Z,1340319549.401 [triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1] Running Loop=1 2012-06-21T22:59:09.401Z,1340319549.401 [triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1](INFO): Aggregate::initialize triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1 2012-06-21T22:59:09.813Z,1340319549.813 [triangle_control_speed_onelap:SURFACECOMMS] Running Loop=1 2012-06-21T22:59:09.814Z,1340319549.814 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS 2012-06-21T22:59:09.814Z,1340319549.814 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Running Loop=1 2012-06-21T22:59:09.814Z,1340319549.814 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-21T23:02:37.769Z,1340319757.769 [Radio_Freewave](INFO): Powering up 2012-06-21T23:02:37.800Z,1340319757.800 [triangle_control_speed_onelap:SURFACECOMMS:B] Running Loop=1 2012-06-21T23:02:37.800Z,1340319757.800 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-21T23:02:37.800Z,1340319757.800 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Running Loop=1 2012-06-21T23:02:37.800Z,1340319757.800 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize. 2012-06-21T23:02:37.800Z,1340319757.800 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Running Loop=1 2012-06-21T23:02:38.572Z,1340319758.572 [NAL9601](INFO): Powering up 2012-06-21T23:03:44.113Z,1340319824.113 [NAL9601](INFO): NAL9601 initialized 2012-06-21T23:03:45.249Z,1340319825.249 [NAL9601](IMPORTANT): GPS fix at: 1340319999.00 2012-06-21T23:03:45.263Z,1340319825.263 [Navigation](IMPORTANT): Error 9.5 %DT at bearing -117.2 deg., over the last 507.7 m. 2012-06-21T23:03:45.264Z,1340319825.264 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Stopped 2012-06-21T23:03:45.264Z,1340319825.264 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Running Loop=1 2012-06-21T23:04:09.579Z,1340319849.579 [NAL9601](INFO): SBD MO Status=1, MOMSN=10482, MT Status=0, MTMSN=0 2012-06-21T23:04:09.751Z,1340319849.751 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0002.lzma 2012-06-21T23:04:09.751Z,1340319849.751 [NAL9601](INFO): Packets left to send: 4 2012-06-21T23:04:09.752Z,1340319849.752 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0002.lzma.parts/0004.sbd 2012-06-21T23:04:17.209Z,1340319857.209 [NAL9601](INFO): SBD MO Status=1, MOMSN=10483, MT Status=0, MTMSN=0 2012-06-21T23:04:17.335Z,1340319857.335 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0002.lzma 2012-06-21T23:04:17.335Z,1340319857.335 [NAL9601](INFO): Packets left to send: 3 2012-06-21T23:04:17.336Z,1340319857.336 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0002.lzma.parts/0003.sbd 2012-06-21T23:04:29.701Z,1340319869.701 [NAL9601](INFO): SBD MO Status=1, MOMSN=10484, MT Status=0, MTMSN=0 2012-06-21T23:04:29.915Z,1340319869.915 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0002.lzma 2012-06-21T23:04:29.915Z,1340319869.915 [NAL9601](INFO): Packets left to send: 2 2012-06-21T23:04:29.916Z,1340319869.916 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0002.lzma.parts/0002.sbd 2012-06-21T23:04:42.275Z,1340319882.275 [NAL9601](INFO): SBD MO Status=1, MOMSN=10485, MT Status=0, MTMSN=0 2012-06-21T23:04:42.395Z,1340319882.395 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0002.lzma 2012-06-21T23:04:42.395Z,1340319882.395 [NAL9601](INFO): Packets left to send: 1 2012-06-21T23:04:42.396Z,1340319882.396 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0002.lzma.parts/0001.sbd 2012-06-21T23:04:53.568Z,1340319893.569 [NAL9601](INFO): SBD MO Status=1, MOMSN=10486, MT Status=0, MTMSN=0 2012-06-21T23:04:53.779Z,1340319893.779 [NAL9601](INFO): Sent 276 bytes from file Logs/20120621T224824/shore0002.lzma 2012-06-21T23:04:53.779Z,1340319893.779 [NAL9601](INFO): Packets left to send: 0 2012-06-21T23:04:53.780Z,1340319893.780 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0002.lzma.parts/0000.sbd 2012-06-21T23:05:02.387Z,1340319902.387 [NAL9601](INFO): SBD MO Status=0, MOMSN=10487, MT Status=0, MTMSN=0 2012-06-21T23:05:02.577Z,1340319902.577 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Stopped 2012-06-21T23:05:02.577Z,1340319902.577 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Running Loop=1 2012-06-21T23:05:04.789Z,1340319904.789 [NAL9601](IMPORTANT): GPS fix at: 1340320078.00 2012-06-21T23:05:04.819Z,1340319904.819 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Stopped 2012-06-21T23:05:04.819Z,1340319904.819 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-21T23:05:04.819Z,1340319904.819 [triangle_control_speed_onelap:SURFACECOMMS:B] Stopped 2012-06-21T23:05:04.820Z,1340319904.819 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-21T23:05:04.820Z,1340319904.820 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Stopped 2012-06-21T23:05:04.820Z,1340319904.820 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize. 2012-06-21T23:05:04.820Z,1340319904.820 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS 2012-06-21T23:05:04.821Z,1340319904.820 [triangle_control_speed_onelap:SURFACECOMMS] Stopped 2012-06-21T23:05:04.821Z,1340319904.821 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS 2012-06-21T23:05:04.821Z,1340319904.821 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Stopped 2012-06-21T23:05:04.821Z,1340319904.821 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-21T23:05:05.241Z,1340319905.241 [triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1](INFO): Completed triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1 2012-06-21T23:05:05.241Z,1340319905.241 [triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1] Stopped 2012-06-21T23:05:05.241Z,1340319905.241 [triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1](INFO): Aggregate::uninitialize triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1 2012-06-21T23:05:05.243Z,1340319905.243 [triangle_control_speed_onelap:WaypointOne](INFO): Completed triangle_control_speed_onelap:WaypointOne 2012-06-21T23:05:05.243Z,1340319905.243 [triangle_control_speed_onelap:WaypointOne] Stopped 2012-06-21T23:05:05.243Z,1340319905.243 [triangle_control_speed_onelap:WaypointOne](INFO): Aggregate::uninitialize triangle_control_speed_onelap:WaypointOne 2012-06-21T23:05:05.243Z,1340319905.243 [triangle_control_speed_onelap:WaypointOne:A.Pitch] Stopped 2012-06-21T23:05:05.243Z,1340319905.243 [triangle_control_speed_onelap:WaypointOne:B.SetSpeed] Stopped 2012-06-21T23:05:05.243Z,1340319905.243 [triangle_control_speed_onelap:WaypointOne:B.SetSpeed](DEBUG): Uninitialize. 2012-06-21T23:05:05.243Z,1340319905.243 [triangle_control_speed_onelap:ThreeLegs] Running Loop=1 2012-06-21T23:05:05.243Z,1340319905.243 [triangle_control_speed_onelap:ThreeLegs](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs 2012-06-21T23:05:05.243Z,1340319905.243 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold] Running Loop=1 2012-06-21T23:05:05.244Z,1340319905.244 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:BuoyancyHold 2012-06-21T23:05:05.244Z,1340319905.244 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold:A.Buoyancy] Running Loop=1 2012-06-21T23:05:05.244Z,1340319905.244 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2012-06-21T23:05:05.244Z,1340319905.244 [triangle_control_speed_onelap:ThreeLegs:MassHold] Running Loop=1 2012-06-21T23:05:05.244Z,1340319905.244 [triangle_control_speed_onelap:ThreeLegs:MassHold](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:MassHold 2012-06-21T23:05:05.245Z,1340319905.245 [triangle_control_speed_onelap:ThreeLegs:MassHold:A.Pitch] Running Loop=1 2012-06-21T23:05:05.245Z,1340319905.245 [triangle_control_speed_onelap:ThreeLegs:MassHold:A.Pitch](DEBUG): Initialize. 2012-06-21T23:05:05.245Z,1340319905.245 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold] Running Loop=1 2012-06-21T23:05:05.245Z,1340319905.245 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:ElevatorHold 2012-06-21T23:05:05.245Z,1340319905.245 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold:A.Pitch] Running Loop=1 2012-06-21T23:05:05.245Z,1340319905.245 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold:A.Pitch](DEBUG): Initialize. 2012-06-21T23:05:05.245Z,1340319905.245 [triangle_control_speed_onelap:ThreeLegs:D] Running Loop=1 2012-06-21T23:05:05.245Z,1340319905.245 [triangle_control_speed_onelap:ThreeLegs:E] Running Loop=1 2012-06-21T23:05:05.245Z,1340319905.246 [triangle_control_speed_onelap:ThreeLegs:F] Running Loop=1 2012-06-21T23:05:05.245Z,1340319905.246 [triangle_control_speed_onelap:ThreeLegs:G] Running Loop=1 2012-06-21T23:05:05.246Z,1340319905.246 [triangle_control_speed_onelap:ThreeLegs:H] Running Loop=1 2012-06-21T23:05:05.246Z,1340319905.246 [triangle_control_speed_onelap:ThreeLegs:I] Running Loop=1 2012-06-21T23:05:05.246Z,1340319905.246 [triangle_control_speed_onelap:ThreeLegs:J] Running Loop=1 2012-06-21T23:05:05.246Z,1340319905.246 [triangle_control_speed_onelap:ThreeLegs:K] Running Loop=1 2012-06-21T23:05:05.246Z,1340319905.246 [triangle_control_speed_onelap:ThreeLegs:L] Running Loop=1 2012-06-21T23:05:05.246Z,1340319905.246 [triangle_control_speed_onelap:ThreeLegs:TestDrive] Running Loop=1 2012-06-21T23:05:05.246Z,1340319905.246 [triangle_control_speed_onelap:ThreeLegs:TestDrive](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:TestDrive 2012-06-21T23:05:05.247Z,1340319905.247 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1] Running Loop=1 2012-06-21T23:05:05.247Z,1340319905.247 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1 2012-06-21T23:05:05.247Z,1340319905.247 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:A.SetSpeed] Running Loop=1 2012-06-21T23:05:05.247Z,1340319905.247 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:A.SetSpeed](DEBUG): Initialize. 2012-06-21T23:05:05.247Z,1340319905.247 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:B.Pitch] Running Loop=1 2012-06-21T23:05:05.247Z,1340319905.247 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:B.Pitch](DEBUG): Initialize. 2012-06-21T23:05:05.248Z,1340319905.247 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:Wpt2.Waypoint] Running Loop=1 2012-06-21T23:05:05.248Z,1340319905.247 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:Wpt2.Waypoint](DEBUG): Initialize WaypointComponent. 2012-06-21T23:05:05.609Z,1340319905.609 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:B.Pitch] Running Loop=1 2012-06-21T23:05:05.618Z,1340319905.618 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:A.SetSpeed] Running Loop=1 2012-06-21T23:05:05.627Z,1340319905.627 [triangle_control_speed_onelap:ThreeLegs:L] Running Loop=1 2012-06-21T23:05:05.628Z,1340319905.628 [triangle_control_speed_onelap:ThreeLegs:K] Running Loop=1 2012-06-21T23:05:05.630Z,1340319905.630 [triangle_control_speed_onelap:ThreeLegs:J] Running Loop=1 2012-06-21T23:05:05.631Z,1340319905.631 [triangle_control_speed_onelap:ThreeLegs:I] Running Loop=1 2012-06-21T23:05:05.633Z,1340319905.633 [triangle_control_speed_onelap:ThreeLegs:H] Running Loop=1 2012-06-21T23:05:05.634Z,1340319905.634 [triangle_control_speed_onelap:ThreeLegs:G] Running Loop=1 2012-06-21T23:05:05.636Z,1340319905.635 [triangle_control_speed_onelap:ThreeLegs:F] Running Loop=1 2012-06-21T23:05:05.637Z,1340319905.637 [triangle_control_speed_onelap:ThreeLegs:E] Running Loop=1 2012-06-21T23:05:05.638Z,1340319905.638 [triangle_control_speed_onelap:ThreeLegs:D] Running Loop=1 2012-06-21T23:05:05.640Z,1340319905.640 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold] Running Loop=1 2012-06-21T23:05:05.640Z,1340319905.640 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold:A.Pitch] Running Loop=1 2012-06-21T23:05:05.645Z,1340319905.645 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold] Running Loop=1 2012-06-21T23:05:05.645Z,1340319905.645 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold:A.Buoyancy] Running Loop=1 2012-06-21T23:05:11.352Z,1340319911.352 [NAL9601](INFO): Powering down 2012-06-21T23:05:14.800Z,1340319914.800 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-21T23:05:16.797Z,1340319916.796 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-21T23:05:19.196Z,1340319919.196 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-21T23:05:19.219Z,1340319919.219 [Radio_Freewave](INFO): Powering down 2012-06-21T23:05:21.945Z,1340319921.945 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-21T23:05:24.299Z,1340319924.299 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-21T23:05:26.293Z,1340319926.293 [Radio_Freewave](INFO): Powering up 2012-06-21T23:05:26.789Z,1340319926.789 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-21T23:05:29.136Z,1340319929.136 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-21T23:05:31.484Z,1340319931.484 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-21T23:05:33.884Z,1340319933.884 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-21T23:05:33.893Z,1340319933.893 [Radio_Freewave](INFO): Powering down 2012-06-21T23:05:36.284Z,1340319936.284 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-21T23:05:38.692Z,1340319938.692 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-21T23:05:41.007Z,1340319941.007 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-21T23:05:43.404Z,1340319943.404 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-21T23:05:45.804Z,1340319945.804 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-21T23:05:48.156Z,1340319948.156 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-21T23:05:50.556Z,1340319950.556 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-21T23:05:50.971Z,1340319950.971 [Radio_Freewave](INFO): Powering up 2012-06-21T23:05:52.956Z,1340319952.955 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-21T23:05:55.756Z,1340319955.756 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-21T23:05:57.869Z,1340319957.869 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-21T23:06:00.215Z,1340319960.215 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-21T23:06:02.640Z,1340319962.640 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-21T23:06:02.692Z,1340319962.692 [Radio_Freewave](INFO): Powering down 2012-06-21T23:06:05.055Z,1340319965.055 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-21T23:06:16.925Z,1340319976.925 [Radio_Freewave](INFO): Powering up 2012-06-21T23:06:40.517Z,1340320000.517 [Radio_Freewave](INFO): Powering down 2012-06-21T23:06:40.941Z,1340320000.941 [Radio_Freewave](INFO): Powering up 2012-06-21T23:07:02.905Z,1340320022.905 [Radio_Freewave](INFO): Powering down 2012-06-21T23:07:14.205Z,1340320034.205 [Radio_Freewave](INFO): Powering up 2012-06-21T23:07:19.205Z,1340320039.205 [Radio_Freewave](INFO): Powering down 2012-06-21T23:07:27.633Z,1340320047.634 [Radio_Freewave](INFO): Powering up 2012-06-21T23:07:31.642Z,1340320051.642 [Radio_Freewave](INFO): Powering down 2012-06-21T23:07:46.834Z,1340320066.833 [Radio_Freewave](INFO): Powering up 2012-06-21T23:07:56.401Z,1340320076.401 [Radio_Freewave](INFO): Powering down 2012-06-21T23:08:07.953Z,1340320087.953 [Radio_Freewave](INFO): Powering up 2012-06-21T23:08:44.726Z,1340320124.726 [Radio_Freewave](INFO): Powering down 2012-06-21T23:08:51.896Z,1340320131.895 [Radio_Freewave](INFO): Powering up 2012-06-21T23:09:15.045Z,1340320155.045 [Radio_Freewave](INFO): Powering down 2012-06-21T23:09:19.391Z,1340320159.391 [Radio_Freewave](INFO): Powering up 2012-06-21T23:09:25.367Z,1340320165.367 [Radio_Freewave](INFO): Powering down 2012-06-21T23:09:36.519Z,1340320176.519 [Radio_Freewave](INFO): Powering up 2012-06-21T23:09:47.641Z,1340320187.641 [Radio_Freewave](INFO): Powering down 2012-06-21T23:09:49.233Z,1340320189.233 [Radio_Freewave](INFO): Powering up 2012-06-21T23:10:28.609Z,1340320228.609 [Radio_Freewave](INFO): Powering down 2012-06-21T23:10:29.397Z,1340320229.397 [Radio_Freewave](INFO): Powering up 2012-06-21T23:10:40.213Z,1340320240.214 [Radio_Freewave](INFO): Powering down 2012-06-21T23:10:51.736Z,1340320251.736 [Radio_Freewave](INFO): Powering up 2012-06-21T23:12:26.564Z,1340320346.564 [Radio_Freewave](INFO): Powering down 2012-06-21T23:12:35.457Z,1340320355.457 [Radio_Freewave](INFO): Powering up 2012-06-21T23:12:42.945Z,1340320362.945 [Radio_Freewave](INFO): Powering down 2012-06-21T23:12:43.387Z,1340320363.387 [Radio_Freewave](INFO): Powering up 2012-06-21T23:12:44.972Z,1340320364.972 [Radio_Freewave](INFO): Powering down 2012-06-21T23:12:53.749Z,1340320373.749 [Radio_Freewave](INFO): Powering up 2012-06-21T23:13:01.537Z,1340320381.537 [Radio_Freewave](INFO): Powering down 2012-06-21T23:13:06.753Z,1340320386.753 [Radio_Freewave](INFO): Powering up 2012-06-21T23:13:12.320Z,1340320392.320 [Radio_Freewave](INFO): Powering down 2012-06-21T23:13:22.036Z,1340320402.036 [Radio_Freewave](INFO): Powering up 2012-06-21T23:13:39.537Z,1340320419.537 [Radio_Freewave](INFO): Powering down 2012-06-21T23:13:50.282Z,1340320430.282 [Radio_Freewave](INFO): Powering up 2012-06-21T23:13:55.075Z,1340320435.075 [Radio_Freewave](INFO): Powering down 2012-06-21T23:14:05.065Z,1340320445.065 [Radio_Freewave](INFO): Powering up 2012-06-21T23:14:11.875Z,1340320451.875 [Radio_Freewave](INFO): Powering down 2012-06-21T23:14:22.209Z,1340320462.210 [Radio_Freewave](INFO): Powering up 2012-06-21T23:14:26.678Z,1340320466.678 [Radio_Freewave](INFO): Powering down 2012-06-21T23:14:35.061Z,1340320475.061 [Radio_Freewave](INFO): Powering up 2012-06-21T23:14:43.029Z,1340320483.029 [Radio_Freewave](INFO): Powering down 2012-06-21T23:14:50.055Z,1340320490.055 [Radio_Freewave](INFO): Powering up 2012-06-21T23:14:59.936Z,1340320499.936 [Radio_Freewave](INFO): Powering down 2012-06-21T23:15:00.703Z,1340320500.703 [Radio_Freewave](INFO): Powering up 2012-06-21T23:15:06.405Z,1340320506.405 [Radio_Freewave](INFO): Powering down 2012-06-21T23:15:18.734Z,1340320518.734 [Radio_Freewave](INFO): Powering up 2012-06-21T23:16:15.285Z,1340320575.285 [Radio_Freewave](INFO): Powering down 2012-06-21T23:16:24.059Z,1340320584.059 [Radio_Freewave](INFO): Powering up 2012-06-21T23:16:28.863Z,1340320588.863 [Radio_Freewave](INFO): Powering down 2012-06-21T23:16:39.209Z,1340320599.209 [Radio_Freewave](INFO): Powering up 2012-06-21T23:16:56.409Z,1340320616.409 [Radio_Freewave](INFO): Powering down 2012-06-21T23:16:56.822Z,1340320616.822 [Radio_Freewave](INFO): Powering up 2012-06-21T23:16:58.436Z,1340320618.436 [Radio_Freewave](INFO): Powering down 2012-06-21T23:17:06.805Z,1340320626.805 [Radio_Freewave](INFO): Powering up 2012-06-21T23:17:14.186Z,1340320634.187 [Radio_Freewave](INFO): Powering down 2012-06-21T23:17:16.069Z,1340320636.069 [Radio_Freewave](INFO): Powering up 2012-06-21T23:17:26.005Z,1340320646.005 [Radio_Freewave](INFO): Powering down 2012-06-21T23:17:30.798Z,1340320650.797 [Radio_Freewave](INFO): Powering up 2012-06-21T23:17:39.249Z,1340320659.249 [Radio_Freewave](INFO): Powering down 2012-06-21T23:17:41.279Z,1340320661.279 [Radio_Freewave](INFO): Powering up 2012-06-21T23:17:49.201Z,1340320669.201 [Radio_Freewave](INFO): Powering down 2012-06-21T23:17:51.945Z,1340320671.945 [Radio_Freewave](INFO): Powering up 2012-06-21T23:18:25.283Z,1340320705.283 [Radio_Freewave](INFO): Powering down 2012-06-21T23:18:29.681Z,1340320709.681 [Radio_Freewave](INFO): Powering up 2012-06-21T23:18:48.753Z,1340320728.753 [Radio_Freewave](INFO): Powering down 2012-06-21T23:18:49.184Z,1340320729.184 [Radio_Freewave](INFO): Powering up 2012-06-21T23:19:20.388Z,1340320760.388 [Radio_Freewave](INFO): Powering down 2012-06-21T23:19:27.137Z,1340320767.137 [Radio_Freewave](INFO): Powering up 2012-06-21T23:19:37.924Z,1340320777.924 [Radio_Freewave](INFO): Powering down 2012-06-21T23:19:50.795Z,1340320790.795 [Radio_Freewave](INFO): Powering up 2012-06-21T23:19:55.549Z,1340320795.549 [Radio_Freewave](INFO): Powering down 2012-06-21T23:20:09.146Z,1340320809.146 [Radio_Freewave](INFO): Powering up 2012-06-21T23:20:25.457Z,1340320825.457 [Radio_Freewave](INFO): Powering down 2012-06-21T23:20:25.895Z,1340320825.895 [Radio_Freewave](INFO): Powering up 2012-06-21T23:20:46.600Z,1340320846.600 [Radio_Freewave](INFO): Powering down 2012-06-21T23:20:46.976Z,1340320846.977 [Radio_Freewave](INFO): Powering up 2012-06-21T23:21:07.565Z,1340320867.565 [Radio_Freewave](INFO): Powering down 2012-06-21T23:21:12.352Z,1340320872.352 [Radio_Freewave](INFO): Powering up 2012-06-21T23:21:48.049Z,1340320908.049 [Radio_Freewave](INFO): Powering down 2012-06-21T23:21:51.730Z,1340320911.730 [Radio_Freewave](INFO): Powering up 2012-06-21T23:22:33.361Z,1340320953.361 [Radio_Freewave](INFO): Powering down 2012-06-21T23:22:40.881Z,1340320960.881 [Radio_Freewave](INFO): Powering up 2012-06-21T23:23:15.022Z,1340320995.022 [Radio_Freewave](INFO): Powering down 2012-06-21T23:23:16.209Z,1340320996.209 [Radio_Freewave](INFO): Powering up 2012-06-21T23:23:21.409Z,1340321001.409 [Radio_Freewave](INFO): Powering down 2012-06-21T23:23:34.609Z,1340321014.609 [Radio_Freewave](INFO): Powering up 2012-06-21T23:23:40.609Z,1340321020.609 [Radio_Freewave](INFO): Powering down 2012-06-21T23:23:52.961Z,1340321032.961 [Radio_Freewave](INFO): Powering up 2012-06-21T23:24:17.333Z,1340321057.333 [Radio_Freewave](INFO): Powering down 2012-06-21T23:24:21.257Z,1340321061.257 [Radio_Freewave](INFO): Powering up 2012-06-21T23:24:26.852Z,1340321066.852 [Radio_Freewave](INFO): Powering down 2012-06-21T23:24:38.386Z,1340321078.386 [Radio_Freewave](INFO): Powering up 2012-06-21T23:25:08.488Z,1340321108.488 [Radio_Freewave](INFO): Powering down 2012-06-21T23:25:13.625Z,1340321113.625 [Radio_Freewave](INFO): Powering up 2012-06-21T23:25:19.236Z,1340321119.236 [Radio_Freewave](INFO): Powering down 2012-06-21T23:25:37.233Z,1340321137.233 [Radio_Freewave](INFO): Powering up 2012-06-21T23:25:40.378Z,1340321140.378 [Radio_Freewave](INFO): Powering down 2012-06-21T23:25:57.481Z,1340321157.481 [Radio_Freewave](INFO): Powering up 2012-06-21T23:26:03.891Z,1340321163.891 [Radio_Freewave](INFO): Powering down 2012-06-21T23:26:10.281Z,1340321170.281 [Radio_Freewave](INFO): Powering up 2012-06-21T23:26:17.881Z,1340321177.881 [Radio_Freewave](INFO): Powering down 2012-06-21T23:26:28.281Z,1340321188.281 [Radio_Freewave](INFO): Powering up 2012-06-21T23:26:33.904Z,1340321193.904 [Radio_Freewave](INFO): Powering down 2012-06-21T23:26:42.317Z,1340321202.317 [Radio_Freewave](INFO): Powering up 2012-06-21T23:26:49.462Z,1340321209.462 [Radio_Freewave](INFO): Powering down 2012-06-21T23:26:55.975Z,1340321215.975 [Radio_Freewave](INFO): Powering up 2012-06-21T23:27:03.402Z,1340321223.402 [Radio_Freewave](INFO): Powering down 2012-06-21T23:27:16.123Z,1340321236.123 [Radio_Freewave](INFO): Powering up 2012-06-21T23:27:20.505Z,1340321240.505 [Radio_Freewave](INFO): Powering down 2012-06-21T23:27:37.675Z,1340321257.675 [Radio_Freewave](INFO): Powering up 2012-06-21T23:27:43.241Z,1340321263.241 [Radio_Freewave](INFO): Powering down 2012-06-21T23:27:46.045Z,1340321266.045 [Radio_Freewave](INFO): Powering up 2012-06-21T23:27:57.181Z,1340321277.181 [Radio_Freewave](INFO): Powering down 2012-06-21T23:28:03.091Z,1340321283.091 [Radio_Freewave](INFO): Powering up 2012-06-21T23:28:09.478Z,1340321289.478 [Radio_Freewave](INFO): Powering down 2012-06-21T23:28:22.678Z,1340321302.678 [Radio_Freewave](INFO): Powering up 2012-06-21T23:28:27.878Z,1340321307.878 [Radio_Freewave](INFO): Powering down 2012-06-21T23:28:39.873Z,1340321319.873 [Radio_Freewave](INFO): Powering up 2012-06-21T23:28:47.533Z,1340321327.533 [Radio_Freewave](INFO): Powering down 2012-06-21T23:28:59.037Z,1340321339.037 [Radio_Freewave](INFO): Powering up 2012-06-21T23:30:53.407Z,1340321453.407 [Radio_Freewave](INFO): Powering down 2012-06-21T23:31:02.549Z,1340321462.549 [Radio_Freewave](INFO): Powering up 2012-06-21T23:31:19.761Z,1340321479.761 [Radio_Freewave](INFO): Powering down 2012-06-21T23:31:27.777Z,1340321487.777 [Radio_Freewave](INFO): Powering up 2012-06-21T23:31:34.177Z,1340321494.177 [Radio_Freewave](INFO): Powering down 2012-06-21T23:31:41.766Z,1340321501.766 [Radio_Freewave](INFO): Powering up 2012-06-21T23:31:52.158Z,1340321512.158 [Radio_Freewave](INFO): Powering down 2012-06-21T23:31:55.348Z,1340321515.348 [Radio_Freewave](INFO): Powering up 2012-06-21T23:32:11.561Z,1340321531.561 [Radio_Freewave](INFO): Powering down 2012-06-21T23:32:19.961Z,1340321539.961 [Radio_Freewave](INFO): Powering up 2012-06-21T23:32:24.357Z,1340321544.357 [Radio_Freewave](INFO): Powering down 2012-06-21T23:32:38.821Z,1340321558.821 [Radio_Freewave](INFO): Powering up 2012-06-21T23:32:42.440Z,1340321562.440 [Radio_Freewave](INFO): Powering down 2012-06-21T23:32:58.676Z,1340321578.676 [Radio_Freewave](INFO): Powering up 2012-06-21T23:33:05.069Z,1340321585.069 [Radio_Freewave](INFO): Powering down 2012-06-21T23:33:13.065Z,1340321593.065 [Radio_Freewave](INFO): Powering up 2012-06-21T23:33:32.660Z,1340321612.660 [Radio_Freewave](INFO): Powering down 2012-06-21T23:33:39.495Z,1340321619.495 [Radio_Freewave](INFO): Powering up 2012-06-21T23:33:44.577Z,1340321624.577 [Radio_Freewave](INFO): Powering down 2012-06-21T23:33:56.192Z,1340321636.192 [Radio_Freewave](INFO): Powering up 2012-06-21T23:33:58.986Z,1340321638.986 [Radio_Freewave](INFO): Powering down 2012-06-21T23:34:14.945Z,1340321654.945 [Radio_Freewave](INFO): Powering up 2012-06-21T23:34:33.141Z,1340321673.141 [Radio_Freewave](INFO): Powering down 2012-06-21T23:34:43.959Z,1340321683.959 [Radio_Freewave](INFO): Powering up 2012-06-21T23:35:18.409Z,1340321718.409 [Radio_Freewave](INFO): Powering down 2012-06-21T23:35:25.517Z,1340321725.517 [Radio_Freewave](INFO): Powering up 2012-06-21T23:35:48.998Z,1340321748.998 [Radio_Freewave](INFO): Powering down 2012-06-21T23:35:52.134Z,1340321752.134 [Radio_Freewave](INFO): Powering up 2012-06-21T23:36:23.889Z,1340321783.889 [Radio_Freewave](INFO): Powering down 2012-06-21T23:36:24.703Z,1340321784.703 [Radio_Freewave](INFO): Powering up 2012-06-21T23:36:37.417Z,1340321797.418 [Radio_Freewave](INFO): Powering down 2012-06-21T23:36:38.221Z,1340321798.221 [Radio_Freewave](INFO): Powering up 2012-06-21T23:36:44.614Z,1340321804.614 [Radio_Freewave](INFO): Powering down 2012-06-21T23:36:55.831Z,1340321815.831 [Radio_Freewave](INFO): Powering up 2012-06-21T23:37:30.451Z,1340321850.451 [Radio_Freewave](INFO): Powering down 2012-06-21T23:37:30.825Z,1340321850.825 [Radio_Freewave](INFO): Powering up 2012-06-21T23:37:38.296Z,1340321858.296 [Radio_Freewave](INFO): Powering down 2012-06-21T23:37:46.699Z,1340321866.699 [Radio_Freewave](INFO): Powering up 2012-06-21T23:38:41.333Z,1340321921.333 [Radio_Freewave](INFO): Powering down 2012-06-21T23:38:46.509Z,1340321926.509 [Radio_Freewave](INFO): Powering up 2012-06-21T23:38:51.746Z,1340321931.746 [Radio_Freewave](INFO): Powering down 2012-06-21T23:39:06.537Z,1340321946.537 [Radio_Freewave](INFO): Powering up 2012-06-21T23:39:17.701Z,1340321957.701 [Radio_Freewave](INFO): Powering down 2012-06-21T23:39:22.430Z,1340321962.430 [Radio_Freewave](INFO): Powering up 2012-06-21T23:39:27.159Z,1340321967.159 [Radio_Freewave](INFO): Powering down 2012-06-21T23:39:39.591Z,1340321979.591 [Radio_Freewave](INFO): Powering up 2012-06-21T23:39:45.562Z,1340321985.562 [Radio_Freewave](INFO): Powering down 2012-06-21T23:39:55.188Z,1340321995.188 [Radio_Freewave](INFO): Powering up 2012-06-21T23:40:55.250Z,1340322055.250 [Radio_Freewave](INFO): Powering down 2012-06-21T23:41:03.645Z,1340322063.645 [Radio_Freewave](INFO): Powering up 2012-06-21T23:41:10.463Z,1340322070.463 [Radio_Freewave](INFO): Powering down 2012-06-21T23:41:18.445Z,1340322078.445 [Radio_Freewave](INFO): Powering up 2012-06-21T23:41:26.449Z,1340322086.449 [Radio_Freewave](INFO): Powering down 2012-06-21T23:41:34.458Z,1340322094.458 [Radio_Freewave](INFO): Powering up 2012-06-21T23:42:03.289Z,1340322123.289 [Radio_Freewave](INFO): Powering down 2012-06-21T23:42:13.695Z,1340322133.695 [Radio_Freewave](INFO): Powering up 2012-06-21T23:42:18.880Z,1340322138.880 [Radio_Freewave](INFO): Powering down 2012-06-21T23:42:29.683Z,1340322149.683 [Radio_Freewave](INFO): Powering up 2012-06-21T23:42:35.459Z,1340322155.459 [Radio_Freewave](INFO): Powering down 2012-06-21T23:42:44.249Z,1340322164.249 [Radio_Freewave](INFO): Powering up 2012-06-21T23:42:50.618Z,1340322170.618 [Radio_Freewave](INFO): Powering down 2012-06-21T23:42:57.421Z,1340322177.421 [Radio_Freewave](INFO): Powering up 2012-06-21T23:43:19.355Z,1340322199.355 [Radio_Freewave](INFO): Powering down 2012-06-21T23:43:28.104Z,1340322208.104 [Radio_Freewave](INFO): Powering up 2012-06-21T23:43:33.712Z,1340322213.712 [Radio_Freewave](INFO): Powering down 2012-06-21T23:43:45.639Z,1340322225.639 [Radio_Freewave](INFO): Powering up 2012-06-21T23:44:11.545Z,1340322251.546 [Radio_Freewave](INFO): Powering down 2012-06-21T23:44:21.145Z,1340322261.145 [Radio_Freewave](INFO): Powering up 2012-06-21T23:44:51.034Z,1340322291.033 [Radio_Freewave](INFO): Powering down 2012-06-21T23:44:52.141Z,1340322292.141 [Radio_Freewave](INFO): Powering up 2012-06-21T23:45:01.006Z,1340322301.006 [Radio_Freewave](INFO): Powering down 2012-06-21T23:45:06.145Z,1340322306.145 [Radio_Freewave](INFO): Powering up 2012-06-21T23:45:14.155Z,1340322314.155 [Radio_Freewave](INFO): Powering down 2012-06-21T23:45:23.754Z,1340322323.754 [Radio_Freewave](INFO): Powering up 2012-06-21T23:45:32.176Z,1340322332.176 [Radio_Freewave](INFO): Powering down 2012-06-21T23:45:39.730Z,1340322339.730 [Radio_Freewave](INFO): Powering up 2012-06-21T23:45:47.687Z,1340322347.687 [Radio_Freewave](INFO): Powering down 2012-06-21T23:46:01.769Z,1340322361.769 [Radio_Freewave](INFO): Powering up 2012-06-21T23:46:14.174Z,1340322374.174 [Radio_Freewave](INFO): Powering down 2012-06-21T23:46:18.168Z,1340322378.168 [Radio_Freewave](INFO): Powering up 2012-06-21T23:46:45.123Z,1340322405.123 [Radio_Freewave](INFO): Powering down 2012-06-21T23:46:56.705Z,1340322416.705 [Radio_Freewave](INFO): Powering up 2012-06-21T23:47:07.910Z,1340322427.910 [Radio_Freewave](INFO): Powering down 2012-06-21T23:47:10.346Z,1340322430.346 [Radio_Freewave](INFO): Powering up 2012-06-21T23:47:30.604Z,1340322450.604 [Radio_Freewave](INFO): Powering down 2012-06-21T23:47:36.077Z,1340322456.077 [Radio_Freewave](INFO): Powering up 2012-06-21T23:47:42.821Z,1340322462.821 [Radio_Freewave](INFO): Powering down 2012-06-21T23:47:53.303Z,1340322473.303 [Radio_Freewave](INFO): Powering up 2012-06-21T23:48:11.181Z,1340322491.181 [Radio_Freewave](INFO): Powering down 2012-06-21T23:48:21.918Z,1340322501.918 [Radio_Freewave](INFO): Powering up 2012-06-21T23:49:13.930Z,1340322553.930 [Radio_Freewave](INFO): Powering down 2012-06-21T23:49:16.280Z,1340322556.280 [Radio_Freewave](INFO): Powering up 2012-06-21T23:49:23.405Z,1340322563.405 [Radio_Freewave](INFO): Powering down 2012-06-21T23:49:43.766Z,1340322583.766 [Radio_Freewave](INFO): Powering up 2012-06-21T23:49:47.714Z,1340322587.714 [Radio_Freewave](INFO): Powering down 2012-06-21T23:50:06.073Z,1340322606.073 [Radio_Freewave](INFO): Powering up 2012-06-21T23:50:44.959Z,1340322644.959 [Radio_Freewave](INFO): Powering down 2012-06-21T23:50:53.337Z,1340322653.337 [Radio_Freewave](INFO): Powering up 2012-06-21T23:50:58.137Z,1340322658.137 [Radio_Freewave](INFO): Powering down 2012-06-21T23:51:08.137Z,1340322668.136 [Radio_Freewave](INFO): Powering up 2012-06-21T23:52:16.725Z,1340322736.725 [Radio_Freewave](INFO): Powering down 2012-06-21T23:52:17.537Z,1340322737.537 [Radio_Freewave](INFO): Powering up 2012-06-21T23:52:19.501Z,1340322739.501 [Radio_Freewave](INFO): Powering down 2012-06-21T23:52:20.665Z,1340322740.665 [Radio_Freewave](INFO): Powering up 2012-06-21T23:52:27.445Z,1340322747.445 [Radio_Freewave](INFO): Powering down 2012-06-21T23:52:36.263Z,1340322756.263 [Radio_Freewave](INFO): Powering up 2012-06-21T23:53:27.565Z,1340322807.565 [Radio_Freewave](INFO): Powering down 2012-06-21T23:53:35.157Z,1340322815.157 [Radio_Freewave](INFO): Powering up 2012-06-21T23:53:43.134Z,1340322823.134 [Radio_Freewave](INFO): Powering down 2012-06-21T23:53:51.877Z,1340322831.877 [Radio_Freewave](INFO): Powering up 2012-06-21T23:54:16.652Z,1340322856.652 [Radio_Freewave](INFO): Powering down 2012-06-21T23:54:19.811Z,1340322859.811 [Radio_Freewave](INFO): Powering up 2012-06-21T23:54:26.260Z,1340322866.260 [Radio_Freewave](INFO): Powering down 2012-06-21T23:54:39.032Z,1340322879.033 [Radio_Freewave](INFO): Powering up 2012-06-21T23:54:41.761Z,1340322881.761 [Radio_Freewave](INFO): Powering down 2012-06-21T23:54:57.365Z,1340322897.365 [Radio_Freewave](INFO): Powering up 2012-06-21T23:55:05.022Z,1340322905.022 [triangle_control_speed_onelap:NeedComms] Running Loop=1 2012-06-21T23:55:05.022Z,1340322905.022 [triangle_control_speed_onelap:NeedComms](INFO): Aggregate::initialize triangle_control_speed_onelap:NeedComms 2012-06-21T23:55:05.023Z,1340322905.023 [triangle_control_speed_onelap:NeedComms:NEEDCOMMS] Running Loop=1 2012-06-21T23:55:05.023Z,1340322905.023 [triangle_control_speed_onelap:NeedComms:NEEDCOMMS](INFO): Aggregate::initialize triangle_control_speed_onelap:NeedComms:NEEDCOMMS 2012-06-21T23:55:05.379Z,1340322905.379 [triangle_control_speed_onelap:SURFACECOMMS] Running Loop=1 2012-06-21T23:55:05.379Z,1340322905.379 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS 2012-06-21T23:55:05.380Z,1340322905.379 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Running Loop=1 2012-06-21T23:55:05.380Z,1340322905.379 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-21T23:55:05.380Z,1340322905.381 [triangle_control_speed_onelap:SURFACECOMMS:B] Running Loop=1 2012-06-21T23:55:05.381Z,1340322905.381 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-21T23:55:05.381Z,1340322905.381 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Running Loop=1 2012-06-21T23:55:05.381Z,1340322905.381 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize. 2012-06-21T23:55:05.381Z,1340322905.381 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Running Loop=1 2012-06-21T23:55:06.155Z,1340322906.155 [NAL9601](INFO): Powering up 2012-06-21T23:55:10.590Z,1340322910.589 [Radio_Freewave](INFO): Powering down 2012-06-21T23:55:27.761Z,1340322927.761 [Radio_Freewave](INFO): Powering up 2012-06-21T23:55:29.365Z,1340322929.365 [Radio_Freewave](INFO): Powering down 2012-06-21T23:55:29.798Z,1340322929.798 [Radio_Freewave](INFO): Powering up 2012-06-21T23:56:11.879Z,1340322971.879 [NAL9601](INFO): NAL9601 initialized 2012-06-21T23:56:18.993Z,1340322978.993 [NAL9601](IMPORTANT): GPS fix at: 1340323150.00 2012-06-21T23:56:19.007Z,1340322979.007 [Navigation](IMPORTANT): Error 6.5 %DT at bearing -120.5 deg., over the last 2311.2 m. 2012-06-21T23:56:19.008Z,1340322979.008 [Navigation](IMPORTANT): Distance made good 2075.3 m, at (36.8290, -121.8837). 2012-06-21T23:56:19.009Z,1340322979.009 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Stopped 2012-06-21T23:56:19.009Z,1340322979.009 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Running Loop=1 2012-06-21T23:56:34.438Z,1340322994.438 [NAL9601](IMPORTANT): SBD MO Status=1, MOMSN=10488, MT Status=1, MTMSN=2776 2012-06-21T23:56:34.631Z,1340322994.631 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0003.lzma 2012-06-21T23:56:34.631Z,1340322994.631 [NAL9601](INFO): Packets left to send: 15 2012-06-21T23:56:34.633Z,1340322994.633 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0003.lzma.parts/0015.sbd 2012-06-21T23:56:35.076Z,1340322995.076 [NAL9601](INFO): Received command:get VerticalControl.massDefault;get VerticalControl.buoyancyNeutral 2012-06-21T23:56:35.208Z,1340322995.208 [CommandLine](IMPORTANT): got command get VerticalControl.massDefault 2012-06-21T23:56:35.209Z,1340322995.209 [CommandLine](IMPORTANT): VerticalControl.massDefault -0.750000 centimeter 2012-06-21T23:56:35.329Z,1340322995.329 [CommandLine](IMPORTANT): got command get VerticalControl.buoyancyNeutral 2012-06-21T23:56:35.330Z,1340322995.330 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral 505.000004 cubic_centimeter 2012-06-21T23:56:47.910Z,1340323007.910 [NAL9601](INFO): SBD MO Status=1, MOMSN=10489, MT Status=0, MTMSN=0 2012-06-21T23:56:48.111Z,1340323008.111 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0003.lzma 2012-06-21T23:56:48.111Z,1340323008.111 [NAL9601](INFO): Packets left to send: 14 2012-06-21T23:56:48.112Z,1340323008.112 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0003.lzma.parts/0014.sbd 2012-06-21T23:56:57.240Z,1340323017.240 [NAL9601](INFO): SBD MO Status=1, MOMSN=10490, MT Status=0, MTMSN=0 2012-06-21T23:56:57.399Z,1340323017.399 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0003.lzma 2012-06-21T23:56:57.399Z,1340323017.399 [NAL9601](INFO): Packets left to send: 13 2012-06-21T23:56:57.400Z,1340323017.400 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0003.lzma.parts/0013.sbd 2012-06-21T23:57:13.002Z,1340323033.001 [NAL9601](INFO): SBD MO Status=1, MOMSN=10491, MT Status=0, MTMSN=0 2012-06-21T23:57:13.171Z,1340323033.171 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0003.lzma 2012-06-21T23:57:13.171Z,1340323033.171 [NAL9601](INFO): Packets left to send: 12 2012-06-21T23:57:13.172Z,1340323033.172 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0003.lzma.parts/0012.sbd 2012-06-21T23:57:20.746Z,1340323040.746 [NAL9601](INFO): SBD MO Status=1, MOMSN=10492, MT Status=0, MTMSN=0 2012-06-21T23:57:20.959Z,1340323040.959 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0003.lzma 2012-06-21T23:57:20.959Z,1340323040.959 [NAL9601](INFO): Packets left to send: 11 2012-06-21T23:57:20.960Z,1340323040.960 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0003.lzma.parts/0011.sbd 2012-06-21T23:57:28.962Z,1340323048.962 [NAL9601](INFO): SBD MO Status=1, MOMSN=10493, MT Status=0, MTMSN=0 2012-06-21T23:57:29.147Z,1340323049.147 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0003.lzma 2012-06-21T23:57:29.147Z,1340323049.147 [NAL9601](INFO): Packets left to send: 10 2012-06-21T23:57:29.148Z,1340323049.148 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0003.lzma.parts/0010.sbd 2012-06-21T23:57:38.788Z,1340323058.788 [NAL9601](INFO): SBD MO Status=1, MOMSN=10494, MT Status=0, MTMSN=0 2012-06-21T23:57:38.931Z,1340323058.931 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0003.lzma 2012-06-21T23:57:38.931Z,1340323058.931 [NAL9601](INFO): Packets left to send: 9 2012-06-21T23:57:38.932Z,1340323058.932 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0003.lzma.parts/0009.sbd 2012-06-21T23:57:48.094Z,1340323068.094 [NAL9601](INFO): SBD MO Status=1, MOMSN=10495, MT Status=0, MTMSN=0 2012-06-21T23:57:48.219Z,1340323068.219 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0003.lzma 2012-06-21T23:57:48.219Z,1340323068.219 [NAL9601](INFO): Packets left to send: 8 2012-06-21T23:57:48.220Z,1340323068.220 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0003.lzma.parts/0008.sbd 2012-06-21T23:57:55.846Z,1340323075.846 [NAL9601](INFO): SBD MO Status=1, MOMSN=10496, MT Status=0, MTMSN=0 2012-06-21T23:57:56.007Z,1340323076.007 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0003.lzma 2012-06-21T23:57:56.007Z,1340323076.007 [NAL9601](INFO): Packets left to send: 7 2012-06-21T23:57:56.009Z,1340323076.009 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0003.lzma.parts/0007.sbd 2012-06-21T23:58:04.008Z,1340323084.008 [NAL9601](INFO): SBD MO Status=1, MOMSN=10497, MT Status=0, MTMSN=0 2012-06-21T23:58:04.195Z,1340323084.195 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0003.lzma 2012-06-21T23:58:04.195Z,1340323084.195 [NAL9601](INFO): Packets left to send: 6 2012-06-21T23:58:04.196Z,1340323084.196 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0003.lzma.parts/0006.sbd 2012-06-21T23:58:16.513Z,1340323096.513 [Radio_Freewave](INFO): Powering down 2012-06-21T23:58:16.919Z,1340323096.919 [NAL9601](INFO): SBD MO Status=2, MOMSN=10498, MT Status=2, MTMSN=0 2012-06-21T23:58:16.919Z,1340323096.919 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2 2012-06-21T23:58:16.925Z,1340323096.925 [Radio_Freewave](INFO): Powering up 2012-06-21T23:58:18.905Z,1340323098.905 [Radio_Freewave](INFO): Powering down 2012-06-21T23:58:19.335Z,1340323099.335 [Radio_Freewave](INFO): Powering up 2012-06-21T23:58:20.965Z,1340323100.965 [Radio_Freewave](INFO): Powering down 2012-06-21T23:58:21.812Z,1340323101.812 [Radio_Freewave](INFO): Powering up 2012-06-21T23:58:23.270Z,1340323103.270 [Radio_Freewave](INFO): Powering down 2012-06-21T23:58:24.408Z,1340323104.408 [Radio_Freewave](INFO): Powering up 2012-06-21T23:58:31.673Z,1340323111.673 [Radio_Freewave](INFO): Powering down 2012-06-21T23:58:32.865Z,1340323112.865 [Radio_Freewave](INFO): Powering up 2012-06-21T23:58:35.102Z,1340323115.102 [Radio_Freewave](INFO): Powering down 2012-06-21T23:58:37.463Z,1340323117.463 [Radio_Freewave](INFO): Powering up 2012-06-21T23:59:02.341Z,1340323142.341 [NAL9601](INFO): SBD MO Status=1, MOMSN=10498, MT Status=0, MTMSN=0 2012-06-21T23:59:02.507Z,1340323142.507 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0003.lzma 2012-06-21T23:59:02.507Z,1340323142.507 [NAL9601](INFO): Packets left to send: 5 2012-06-21T23:59:02.508Z,1340323142.508 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0003.lzma.parts/0005.sbd 2012-06-21T23:59:14.413Z,1340323154.413 [NAL9601](INFO): SBD MO Status=1, MOMSN=10499, MT Status=0, MTMSN=0 2012-06-21T23:59:14.587Z,1340323154.587 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0003.lzma 2012-06-21T23:59:14.587Z,1340323154.587 [NAL9601](INFO): Packets left to send: 4 2012-06-21T23:59:14.588Z,1340323154.588 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0003.lzma.parts/0004.sbd 2012-06-21T23:59:35.106Z,1340323175.106 [NAL9601](INFO): SBD MO Status=2, MOMSN=10500, MT Status=2, MTMSN=0 2012-06-21T23:59:35.106Z,1340323175.106 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2 2012-06-21T23:59:55.861Z,1340323195.861 [NAL9601](INFO): SBD MO Status=1, MOMSN=10500, MT Status=0, MTMSN=0 2012-06-21T23:59:56.023Z,1340323196.023 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0003.lzma 2012-06-21T23:59:56.023Z,1340323196.023 [NAL9601](INFO): Packets left to send: 3 2012-06-21T23:59:56.024Z,1340323196.024 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0003.lzma.parts/0003.sbd 2012-06-22T00:00:07.201Z,1340323207.201 [NAL9601](INFO): SBD MO Status=1, MOMSN=10501, MT Status=0, MTMSN=0 2012-06-22T00:00:07.407Z,1340323207.407 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0003.lzma 2012-06-22T00:00:07.407Z,1340323207.407 [NAL9601](INFO): Packets left to send: 2 2012-06-22T00:00:07.408Z,1340323207.408 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0003.lzma.parts/0002.sbd 2012-06-22T00:00:21.379Z,1340323221.379 [NAL9601](INFO): SBD MO Status=1, MOMSN=10502, MT Status=0, MTMSN=0 2012-06-22T00:00:21.583Z,1340323221.583 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0003.lzma 2012-06-22T00:00:21.583Z,1340323221.583 [NAL9601](INFO): Packets left to send: 1 2012-06-22T00:00:21.584Z,1340323221.584 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0003.lzma.parts/0001.sbd 2012-06-22T00:00:27.497Z,1340323227.497 [NAL9601](INFO): SBD MO Status=1, MOMSN=10503, MT Status=0, MTMSN=0 2012-06-22T00:00:27.675Z,1340323227.675 [NAL9601](INFO): Sent 35 bytes from file Logs/20120621T224824/shore0003.lzma 2012-06-22T00:00:27.675Z,1340323227.675 [NAL9601](INFO): Packets left to send: 0 2012-06-22T00:00:27.676Z,1340323227.676 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0003.lzma.parts/0000.sbd 2012-06-22T00:00:37.937Z,1340323237.937 [NAL9601](INFO): SBD MO Status=0, MOMSN=10504, MT Status=0, MTMSN=0 2012-06-22T00:00:58.249Z,1340323258.249 [NAL9601](INFO): SBD MO Status=1, MOMSN=10505, MT Status=0, MTMSN=0 2012-06-22T00:00:58.423Z,1340323258.423 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0004.lzma 2012-06-22T00:00:58.423Z,1340323258.423 [NAL9601](INFO): Packets left to send: 1 2012-06-22T00:00:58.425Z,1340323258.425 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0004.lzma.parts/0001.sbd 2012-06-22T00:01:06.854Z,1340323266.854 [NAL9601](INFO): SBD MO Status=1, MOMSN=10506, MT Status=0, MTMSN=0 2012-06-22T00:01:07.011Z,1340323267.011 [NAL9601](INFO): Sent 180 bytes from file Logs/20120621T224824/shore0004.lzma 2012-06-22T00:01:07.011Z,1340323267.011 [NAL9601](INFO): Packets left to send: 0 2012-06-22T00:01:07.012Z,1340323267.012 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0004.lzma.parts/0000.sbd 2012-06-22T00:01:14.049Z,1340323274.049 [NAL9601](INFO): SBD MO Status=0, MOMSN=10507, MT Status=0, MTMSN=0 2012-06-22T00:01:14.223Z,1340323274.224 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Stopped 2012-06-22T00:01:14.224Z,1340323274.224 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Running Loop=1 2012-06-22T00:01:16.411Z,1340323276.411 [NAL9601](IMPORTANT): GPS fix at: 1340323448.00 2012-06-22T00:01:16.454Z,1340323276.454 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Stopped 2012-06-22T00:01:16.459Z,1340323276.459 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-22T00:01:16.459Z,1340323276.459 [triangle_control_speed_onelap:SURFACECOMMS:B] Stopped 2012-06-22T00:01:16.459Z,1340323276.459 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-22T00:01:16.459Z,1340323276.459 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Stopped 2012-06-22T00:01:16.459Z,1340323276.459 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T00:01:16.460Z,1340323276.460 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS 2012-06-22T00:01:16.460Z,1340323276.460 [triangle_control_speed_onelap:SURFACECOMMS] Stopped 2012-06-22T00:01:16.460Z,1340323276.460 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS 2012-06-22T00:01:16.460Z,1340323276.460 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Stopped 2012-06-22T00:01:16.460Z,1340323276.460 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-22T00:01:16.874Z,1340323276.874 [triangle_control_speed_onelap:NeedComms:NEEDCOMMS](INFO): Completed triangle_control_speed_onelap:NeedComms:NEEDCOMMS 2012-06-22T00:01:16.874Z,1340323276.874 [triangle_control_speed_onelap:NeedComms:NEEDCOMMS] Stopped 2012-06-22T00:01:16.874Z,1340323276.874 [triangle_control_speed_onelap:NeedComms:NEEDCOMMS](INFO): Aggregate::uninitialize triangle_control_speed_onelap:NeedComms:NEEDCOMMS 2012-06-22T00:01:16.874Z,1340323276.875 [triangle_control_speed_onelap:NeedComms](INFO): Completed triangle_control_speed_onelap:NeedComms 2012-06-22T00:01:16.874Z,1340323276.875 [triangle_control_speed_onelap:NeedComms] Stopped 2012-06-22T00:01:16.875Z,1340323276.875 [triangle_control_speed_onelap:NeedComms](INFO): Aggregate::uninitialize triangle_control_speed_onelap:NeedComms 2012-06-22T00:01:22.766Z,1340323282.766 [NAL9601](INFO): Powering down 2012-06-22T00:01:27.890Z,1340323287.890 [Radio_Freewave](INFO): Powering down 2012-06-22T00:01:41.279Z,1340323301.279 [Radio_Freewave](INFO): Powering up 2012-06-22T00:01:54.137Z,1340323314.137 [Radio_Freewave](INFO): Powering down 2012-06-22T00:02:12.625Z,1340323332.625 [Radio_Freewave](INFO): Powering up 2012-06-22T00:02:21.421Z,1340323341.421 [Radio_Freewave](INFO): Powering down 2012-06-22T00:02:44.273Z,1340323364.273 [Radio_Freewave](INFO): Powering up 2012-06-22T00:03:28.377Z,1340323408.378 [Radio_Freewave](INFO): Powering down 2012-06-22T00:03:31.589Z,1340323411.589 [Radio_Freewave](INFO): Powering up 2012-06-22T00:04:14.832Z,1340323454.832 [Radio_Freewave](INFO): Powering down 2012-06-22T00:04:18.341Z,1340323458.341 [Radio_Freewave](INFO): Powering up 2012-06-22T00:04:57.861Z,1340323497.861 [Radio_Freewave](INFO): Powering down 2012-06-22T00:05:06.484Z,1340323506.484 [Radio_Freewave](INFO): Powering up 2012-06-22T00:05:21.974Z,1340323521.974 [Radio_Freewave](INFO): Powering down 2012-06-22T00:05:28.725Z,1340323528.725 [Radio_Freewave](INFO): Powering up 2012-06-22T00:05:33.949Z,1340323533.949 [Radio_Freewave](INFO): Powering down 2012-06-22T00:05:49.125Z,1340323549.125 [Radio_Freewave](INFO): Powering up 2012-06-22T00:05:59.947Z,1340323559.947 [Radio_Freewave](INFO): Powering down 2012-06-22T00:06:15.023Z,1340323575.023 [Radio_Freewave](INFO): Powering up 2012-06-22T00:06:20.245Z,1340323580.245 [Radio_Freewave](INFO): Powering down 2012-06-22T00:06:25.455Z,1340323585.455 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:Wpt2.Waypoint](INFO): Reached Waypoint: 36.829000,-121.886000 2012-06-22T00:06:25.455Z,1340323585.455 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:Wpt2.Waypoint] Stopped 2012-06-22T00:06:25.455Z,1340323585.455 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:Wpt2.Waypoint](DEBUG): Uninitialize WaypointComponent. 2012-06-22T00:06:25.456Z,1340323585.455 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2] Running Loop=1 2012-06-22T00:06:25.456Z,1340323585.456 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2 2012-06-22T00:06:25.838Z,1340323585.838 [triangle_control_speed_onelap:SURFACECOMMS] Running Loop=1 2012-06-22T00:06:25.838Z,1340323585.838 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS 2012-06-22T00:06:25.838Z,1340323585.838 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Running Loop=1 2012-06-22T00:06:25.842Z,1340323585.842 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-22T00:07:54.732Z,1340323674.732 [Radio_Freewave](INFO): Powering up 2012-06-22T00:07:54.746Z,1340323674.746 [triangle_control_speed_onelap:SURFACECOMMS:B] Running Loop=1 2012-06-22T00:07:54.746Z,1340323674.746 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-22T00:07:54.746Z,1340323674.746 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Running Loop=1 2012-06-22T00:07:54.746Z,1340323674.746 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize. 2012-06-22T00:07:54.746Z,1340323674.746 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Running Loop=1 2012-06-22T00:07:55.439Z,1340323675.439 [NAL9601](INFO): Powering up 2012-06-22T00:09:01.163Z,1340323741.163 [NAL9601](INFO): NAL9601 initialized 2012-06-22T00:09:10.245Z,1340323750.245 [NAL9601](IMPORTANT): GPS fix at: 1340323923.00 2012-06-22T00:09:10.260Z,1340323750.260 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Stopped 2012-06-22T00:09:10.260Z,1340323750.260 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Running Loop=1 2012-06-22T00:09:37.389Z,1340323777.389 [NAL9601](INFO): SBD MO Status=1, MOMSN=10508, MT Status=0, MTMSN=0 2012-06-22T00:09:37.595Z,1340323777.595 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0005.lzma 2012-06-22T00:09:37.595Z,1340323777.595 [NAL9601](INFO): Packets left to send: 3 2012-06-22T00:09:37.597Z,1340323777.597 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0005.lzma.parts/0003.sbd 2012-06-22T00:09:48.346Z,1340323788.346 [NAL9601](INFO): SBD MO Status=1, MOMSN=10509, MT Status=0, MTMSN=0 2012-06-22T00:09:48.479Z,1340323788.479 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0005.lzma 2012-06-22T00:09:48.479Z,1340323788.479 [NAL9601](INFO): Packets left to send: 2 2012-06-22T00:09:48.480Z,1340323788.480 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0005.lzma.parts/0002.sbd 2012-06-22T00:10:08.095Z,1340323808.095 [NAL9601](INFO): SBD MO Status=1, MOMSN=10510, MT Status=0, MTMSN=0 2012-06-22T00:10:08.247Z,1340323808.247 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0005.lzma 2012-06-22T00:10:08.247Z,1340323808.247 [NAL9601](INFO): Packets left to send: 1 2012-06-22T00:10:08.248Z,1340323808.248 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0005.lzma.parts/0001.sbd 2012-06-22T00:10:20.648Z,1340323820.648 [NAL9601](INFO): SBD MO Status=1, MOMSN=10511, MT Status=0, MTMSN=0 2012-06-22T00:10:20.827Z,1340323820.827 [NAL9601](INFO): Sent 205 bytes from file Logs/20120621T224824/shore0005.lzma 2012-06-22T00:10:20.827Z,1340323820.827 [NAL9601](INFO): Packets left to send: 0 2012-06-22T00:10:20.828Z,1340323820.828 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0005.lzma.parts/0000.sbd 2012-06-22T00:10:31.045Z,1340323831.045 [NAL9601](INFO): SBD MO Status=0, MOMSN=10512, MT Status=0, MTMSN=0 2012-06-22T00:10:31.234Z,1340323831.234 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Stopped 2012-06-22T00:10:31.235Z,1340323831.234 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Running Loop=1 2012-06-22T00:10:33.447Z,1340323833.447 [NAL9601](IMPORTANT): GPS fix at: 1340324006.00 2012-06-22T00:10:33.461Z,1340323833.461 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Stopped 2012-06-22T00:10:33.462Z,1340323833.462 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-22T00:10:33.462Z,1340323833.462 [triangle_control_speed_onelap:SURFACECOMMS:B] Stopped 2012-06-22T00:10:33.463Z,1340323833.463 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-22T00:10:33.463Z,1340323833.463 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Stopped 2012-06-22T00:10:33.463Z,1340323833.463 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T00:10:33.463Z,1340323833.463 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS 2012-06-22T00:10:33.463Z,1340323833.464 [triangle_control_speed_onelap:SURFACECOMMS] Stopped 2012-06-22T00:10:33.464Z,1340323833.464 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS 2012-06-22T00:10:33.464Z,1340323833.464 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Stopped 2012-06-22T00:10:33.464Z,1340323833.464 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-22T00:10:33.871Z,1340323833.871 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2](INFO): Completed triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2 2012-06-22T00:10:33.871Z,1340323833.871 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2] Stopped 2012-06-22T00:10:33.871Z,1340323833.871 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2 2012-06-22T00:10:33.872Z,1340323833.872 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1](INFO): Completed triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1 2012-06-22T00:10:33.872Z,1340323833.872 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1] Stopped 2012-06-22T00:10:33.872Z,1340323833.872 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1 2012-06-22T00:10:33.872Z,1340323833.872 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:A.SetSpeed] Stopped 2012-06-22T00:10:33.872Z,1340323833.872 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T00:10:33.872Z,1340323833.872 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:B.Pitch] Stopped 2012-06-22T00:10:33.873Z,1340323833.872 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2] Running Loop=1 2012-06-22T00:10:33.873Z,1340323833.873 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2 2012-06-22T00:10:33.873Z,1340323833.873 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:A.SetSpeed] Running Loop=1 2012-06-22T00:10:33.873Z,1340323833.873 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:A.SetSpeed](DEBUG): Initialize. 2012-06-22T00:10:33.873Z,1340323833.873 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:B.Pitch] Running Loop=1 2012-06-22T00:10:33.873Z,1340323833.873 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:B.Pitch](DEBUG): Initialize. 2012-06-22T00:10:33.873Z,1340323833.873 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:Wpt3.Waypoint] Running Loop=1 2012-06-22T00:10:33.873Z,1340323833.874 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:Wpt3.Waypoint](DEBUG): Initialize WaypointComponent. 2012-06-22T00:10:34.273Z,1340323834.273 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:B.Pitch] Running Loop=1 2012-06-22T00:10:34.278Z,1340323834.277 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:A.SetSpeed] Running Loop=1 2012-06-22T00:10:39.868Z,1340323839.868 [NAL9601](INFO): Powering down 2012-06-22T00:11:36.301Z,1340323896.301 [Radio_Freewave](INFO): Powering down 2012-06-22T00:20:19.419Z,1340324419.419 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T00:20:21.736Z,1340324421.736 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T00:20:24.440Z,1340324424.440 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T00:20:26.812Z,1340324426.812 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T00:20:29.568Z,1340324429.568 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T00:20:31.539Z,1340324431.539 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T00:20:33.951Z,1340324433.951 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T00:20:36.268Z,1340324436.268 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T00:20:39.071Z,1340324439.071 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T00:20:41.452Z,1340324441.452 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T00:20:43.815Z,1340324443.815 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T00:20:46.111Z,1340324446.111 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T00:20:48.443Z,1340324448.443 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T00:20:50.748Z,1340324450.748 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T00:20:53.527Z,1340324453.527 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T00:20:55.820Z,1340324455.820 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T00:20:58.508Z,1340324458.508 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T00:21:00.508Z,1340324460.508 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T00:21:02.867Z,1340324462.867 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T00:21:05.544Z,1340324465.544 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535 2012-06-22T00:39:05.904Z,1340325545.904 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:Wpt3.Waypoint](INFO): Reached Waypoint: 36.816000,-121.883000 2012-06-22T00:39:05.904Z,1340325545.904 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:Wpt3.Waypoint] Stopped 2012-06-22T00:39:05.905Z,1340325545.905 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:Wpt3.Waypoint](DEBUG): Uninitialize WaypointComponent. 2012-06-22T00:39:05.905Z,1340325545.905 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3] Running Loop=1 2012-06-22T00:39:05.905Z,1340325545.905 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3 2012-06-22T00:39:06.191Z,1340325546.191 [triangle_control_speed_onelap:SURFACECOMMS] Running Loop=1 2012-06-22T00:39:06.191Z,1340325546.191 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS 2012-06-22T00:39:06.191Z,1340325546.191 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Running Loop=1 2012-06-22T00:39:06.191Z,1340325546.191 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-22T00:41:28.736Z,1340325688.736 [Radio_Freewave](INFO): Powering up 2012-06-22T00:41:28.767Z,1340325688.767 [triangle_control_speed_onelap:SURFACECOMMS:B] Running Loop=1 2012-06-22T00:41:28.767Z,1340325688.767 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-22T00:41:28.767Z,1340325688.767 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Running Loop=1 2012-06-22T00:41:28.767Z,1340325688.767 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize. 2012-06-22T00:41:28.767Z,1340325688.767 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Running Loop=1 2012-06-22T00:41:29.465Z,1340325689.465 [NAL9601](INFO): Powering up 2012-06-22T00:42:35.091Z,1340325755.091 [NAL9601](INFO): NAL9601 initialized 2012-06-22T00:42:36.171Z,1340325756.171 [NAL9601](IMPORTANT): GPS fix at: 1340325932.00 2012-06-22T00:42:36.186Z,1340325756.186 [Navigation](IMPORTANT): Error 5.5 %DT at bearing -61.4 deg., over the last 1578.4 m. 2012-06-22T00:42:36.186Z,1340325756.186 [Navigation](IMPORTANT): Distance made good 1583.4 m, at (36.8148, -121.8823). 2012-06-22T00:42:36.187Z,1340325756.187 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Stopped 2012-06-22T00:42:36.187Z,1340325756.187 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Running Loop=1 2012-06-22T00:42:57.658Z,1340325777.658 [NAL9601](INFO): SBD MO Status=1, MOMSN=10513, MT Status=0, MTMSN=0 2012-06-22T00:42:57.843Z,1340325777.843 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0006.lzma 2012-06-22T00:42:57.843Z,1340325777.843 [NAL9601](INFO): Packets left to send: 7 2012-06-22T00:42:57.844Z,1340325777.844 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0006.lzma.parts/0007.sbd 2012-06-22T00:43:12.584Z,1340325792.584 [NAL9601](INFO): SBD MO Status=1, MOMSN=10514, MT Status=0, MTMSN=0 2012-06-22T00:43:12.719Z,1340325792.719 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0006.lzma 2012-06-22T00:43:12.719Z,1340325792.719 [NAL9601](INFO): Packets left to send: 6 2012-06-22T00:43:12.720Z,1340325792.720 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0006.lzma.parts/0006.sbd 2012-06-22T00:43:25.940Z,1340325805.940 [NAL9601](INFO): SBD MO Status=1, MOMSN=10515, MT Status=0, MTMSN=0 2012-06-22T00:43:26.099Z,1340325806.099 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0006.lzma 2012-06-22T00:43:26.099Z,1340325806.099 [NAL9601](INFO): Packets left to send: 5 2012-06-22T00:43:26.101Z,1340325806.101 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0006.lzma.parts/0005.sbd 2012-06-22T00:43:38.427Z,1340325818.427 [NAL9601](INFO): SBD MO Status=1, MOMSN=10516, MT Status=0, MTMSN=0 2012-06-22T00:43:38.579Z,1340325818.579 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0006.lzma 2012-06-22T00:43:38.579Z,1340325818.579 [NAL9601](INFO): Packets left to send: 4 2012-06-22T00:43:38.580Z,1340325818.580 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0006.lzma.parts/0004.sbd 2012-06-22T00:43:50.155Z,1340325830.155 [NAL9601](INFO): SBD MO Status=1, MOMSN=10517, MT Status=0, MTMSN=0 2012-06-22T00:43:50.363Z,1340325830.363 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0006.lzma 2012-06-22T00:43:50.363Z,1340325830.363 [NAL9601](INFO): Packets left to send: 3 2012-06-22T00:43:50.364Z,1340325830.364 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0006.lzma.parts/0003.sbd 2012-06-22T00:44:04.671Z,1340325844.671 [NAL9601](INFO): SBD MO Status=1, MOMSN=10518, MT Status=0, MTMSN=0 2012-06-22T00:44:04.839Z,1340325844.839 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0006.lzma 2012-06-22T00:44:04.839Z,1340325844.839 [NAL9601](INFO): Packets left to send: 2 2012-06-22T00:44:04.840Z,1340325844.840 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0006.lzma.parts/0002.sbd 2012-06-22T00:44:23.546Z,1340325863.546 [NAL9601](INFO): SBD MO Status=1, MOMSN=10519, MT Status=0, MTMSN=0 2012-06-22T00:44:23.711Z,1340325863.711 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0006.lzma 2012-06-22T00:44:23.711Z,1340325863.711 [NAL9601](INFO): Packets left to send: 1 2012-06-22T00:44:23.712Z,1340325863.712 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0006.lzma.parts/0001.sbd 2012-06-22T00:44:31.259Z,1340325871.259 [NAL9601](INFO): SBD MO Status=1, MOMSN=10520, MT Status=0, MTMSN=0 2012-06-22T00:44:31.399Z,1340325871.399 [NAL9601](INFO): Sent 249 bytes from file Logs/20120621T224824/shore0006.lzma 2012-06-22T00:44:31.399Z,1340325871.399 [NAL9601](INFO): Packets left to send: 0 2012-06-22T00:44:31.400Z,1340325871.400 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0006.lzma.parts/0000.sbd 2012-06-22T00:44:39.790Z,1340325879.790 [NAL9601](INFO): SBD MO Status=0, MOMSN=10521, MT Status=0, MTMSN=0 2012-06-22T00:44:39.998Z,1340325879.998 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Stopped 2012-06-22T00:44:39.998Z,1340325879.998 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Running Loop=1 2012-06-22T00:44:42.191Z,1340325882.191 [NAL9601](IMPORTANT): GPS fix at: 1340326058.00 2012-06-22T00:44:42.206Z,1340325882.206 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Stopped 2012-06-22T00:44:42.207Z,1340325882.207 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-22T00:44:42.207Z,1340325882.207 [triangle_control_speed_onelap:SURFACECOMMS:B] Stopped 2012-06-22T00:44:42.207Z,1340325882.207 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-22T00:44:42.207Z,1340325882.207 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Stopped 2012-06-22T00:44:42.207Z,1340325882.207 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T00:44:42.208Z,1340325882.208 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS 2012-06-22T00:44:42.208Z,1340325882.208 [triangle_control_speed_onelap:SURFACECOMMS] Stopped 2012-06-22T00:44:42.208Z,1340325882.208 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS 2012-06-22T00:44:42.208Z,1340325882.208 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Stopped 2012-06-22T00:44:42.208Z,1340325882.208 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-22T00:44:42.604Z,1340325882.604 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3](INFO): Completed triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3 2012-06-22T00:44:42.604Z,1340325882.604 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3] Stopped 2012-06-22T00:44:42.604Z,1340325882.604 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3 2012-06-22T00:44:42.605Z,1340325882.605 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2](INFO): Completed triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2 2012-06-22T00:44:42.605Z,1340325882.605 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2] Stopped 2012-06-22T00:44:42.605Z,1340325882.605 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2 2012-06-22T00:44:42.605Z,1340325882.605 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:A.SetSpeed] Stopped 2012-06-22T00:44:42.605Z,1340325882.605 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T00:44:42.605Z,1340325882.605 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:B.Pitch] Stopped 2012-06-22T00:44:42.606Z,1340325882.606 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3] Running Loop=1 2012-06-22T00:44:42.606Z,1340325882.606 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3 2012-06-22T00:44:42.606Z,1340325882.606 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:A.SetSpeed] Running Loop=1 2012-06-22T00:44:42.606Z,1340325882.606 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:A.SetSpeed](DEBUG): Initialize. 2012-06-22T00:44:42.606Z,1340325882.606 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:B.Pitch] Running Loop=1 2012-06-22T00:44:42.607Z,1340325882.606 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:B.Pitch](DEBUG): Initialize. 2012-06-22T00:44:42.607Z,1340325882.607 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:Wpt1.Waypoint] Running Loop=1 2012-06-22T00:44:42.607Z,1340325882.607 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2012-06-22T00:44:42.994Z,1340325882.994 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:B.Pitch] Running Loop=1 2012-06-22T00:44:42.998Z,1340325882.998 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:A.SetSpeed] Running Loop=1 2012-06-22T00:44:48.652Z,1340325888.652 [NAL9601](INFO): Powering down 2012-06-22T00:45:50.532Z,1340325950.532 [Radio_Freewave](INFO): Powering down 2012-06-22T00:45:50.905Z,1340325950.905 [Radio_Freewave](INFO): Powering up 2012-06-22T00:45:52.449Z,1340325952.449 [Radio_Freewave](INFO): Powering down 2012-06-22T01:17:32.431Z,1340327852.431 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:Wpt1.Waypoint](INFO): Reached Waypoint: 36.815000,-121.869000 2012-06-22T01:17:32.431Z,1340327852.431 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:Wpt1.Waypoint] Stopped 2012-06-22T01:17:32.431Z,1340327852.431 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2012-06-22T01:17:32.431Z,1340327852.431 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1] Running Loop=1 2012-06-22T01:17:32.431Z,1340327852.431 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1 2012-06-22T01:17:32.810Z,1340327852.810 [triangle_control_speed_onelap:SURFACECOMMS] Running Loop=1 2012-06-22T01:17:32.810Z,1340327852.810 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS 2012-06-22T01:17:32.810Z,1340327852.810 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Running Loop=1 2012-06-22T01:17:32.810Z,1340327852.810 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-22T01:22:58.612Z,1340328178.612 [Radio_Freewave](INFO): Powering up 2012-06-22T01:22:58.626Z,1340328178.626 [triangle_control_speed_onelap:SURFACECOMMS:B] Running Loop=1 2012-06-22T01:22:58.627Z,1340328178.626 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-22T01:22:58.627Z,1340328178.626 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Running Loop=1 2012-06-22T01:22:58.627Z,1340328178.627 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize. 2012-06-22T01:22:58.627Z,1340328178.627 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Running Loop=1 2012-06-22T01:22:59.400Z,1340328179.400 [NAL9601](INFO): Powering up 2012-06-22T01:23:08.893Z,1340328188.893 [Radio_Freewave](INFO): Powering down 2012-06-22T01:23:09.309Z,1340328189.309 [Radio_Freewave](INFO): Powering up 2012-06-22T01:24:05.023Z,1340328245.023 [NAL9601](INFO): NAL9601 initialized 2012-06-22T01:24:06.091Z,1340328246.091 [NAL9601](IMPORTANT): GPS fix at: 1340328426.00 2012-06-22T01:24:06.105Z,1340328246.105 [Navigation](IMPORTANT): Error 4.9 %DT at bearing -73.1 deg., over the last 1373.3 m. 2012-06-22T01:24:06.106Z,1340328246.106 [Navigation](IMPORTANT): Distance made good 1270.3 m, at (36.8139, -121.8681). 2012-06-22T01:24:06.107Z,1340328246.107 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Stopped 2012-06-22T01:24:06.107Z,1340328246.107 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Running Loop=1 2012-06-22T01:24:23.389Z,1340328263.389 [NAL9601](INFO): SBD MO Status=1, MOMSN=10522, MT Status=0, MTMSN=0 2012-06-22T01:24:23.595Z,1340328263.595 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0007.lzma 2012-06-22T01:24:23.595Z,1340328263.595 [NAL9601](INFO): Packets left to send: 10 2012-06-22T01:24:23.597Z,1340328263.597 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0007.lzma.parts/0010.sbd 2012-06-22T01:24:35.509Z,1340328275.509 [NAL9601](INFO): SBD MO Status=1, MOMSN=10523, MT Status=0, MTMSN=0 2012-06-22T01:24:35.667Z,1340328275.667 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0007.lzma 2012-06-22T01:24:35.667Z,1340328275.667 [NAL9601](INFO): Packets left to send: 9 2012-06-22T01:24:35.668Z,1340328275.668 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0007.lzma.parts/0009.sbd 2012-06-22T01:24:47.601Z,1340328287.601 [NAL9601](INFO): SBD MO Status=1, MOMSN=10524, MT Status=0, MTMSN=0 2012-06-22T01:24:47.747Z,1340328287.747 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0007.lzma 2012-06-22T01:24:47.747Z,1340328287.747 [NAL9601](INFO): Packets left to send: 8 2012-06-22T01:24:47.748Z,1340328287.748 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0007.lzma.parts/0008.sbd 2012-06-22T01:24:55.370Z,1340328295.369 [NAL9601](INFO): SBD MO Status=1, MOMSN=10525, MT Status=0, MTMSN=0 2012-06-22T01:24:55.535Z,1340328295.535 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0007.lzma 2012-06-22T01:24:55.535Z,1340328295.535 [NAL9601](INFO): Packets left to send: 7 2012-06-22T01:24:55.536Z,1340328295.536 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0007.lzma.parts/0007.sbd 2012-06-22T01:25:04.280Z,1340328304.280 [NAL9601](INFO): SBD MO Status=1, MOMSN=10526, MT Status=0, MTMSN=0 2012-06-22T01:25:04.419Z,1340328304.419 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0007.lzma 2012-06-22T01:25:04.419Z,1340328304.419 [NAL9601](INFO): Packets left to send: 6 2012-06-22T01:25:04.420Z,1340328304.420 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0007.lzma.parts/0006.sbd 2012-06-22T01:25:12.014Z,1340328312.013 [NAL9601](INFO): SBD MO Status=1, MOMSN=10527, MT Status=0, MTMSN=0 2012-06-22T01:25:12.207Z,1340328312.207 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0007.lzma 2012-06-22T01:25:12.207Z,1340328312.207 [NAL9601](INFO): Packets left to send: 5 2012-06-22T01:25:12.208Z,1340328312.208 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0007.lzma.parts/0005.sbd 2012-06-22T01:25:21.791Z,1340328321.791 [NAL9601](INFO): SBD MO Status=1, MOMSN=10528, MT Status=0, MTMSN=0 2012-06-22T01:25:21.995Z,1340328321.995 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0007.lzma 2012-06-22T01:25:21.995Z,1340328321.995 [NAL9601](INFO): Packets left to send: 4 2012-06-22T01:25:21.996Z,1340328321.996 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0007.lzma.parts/0004.sbd 2012-06-22T01:25:30.357Z,1340328330.357 [NAL9601](INFO): SBD MO Status=1, MOMSN=10529, MT Status=0, MTMSN=0 2012-06-22T01:25:30.483Z,1340328330.483 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0007.lzma 2012-06-22T01:25:30.483Z,1340328330.483 [NAL9601](INFO): Packets left to send: 3 2012-06-22T01:25:30.484Z,1340328330.484 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0007.lzma.parts/0003.sbd 2012-06-22T01:25:40.825Z,1340328340.825 [NAL9601](INFO): SBD MO Status=1, MOMSN=10530, MT Status=0, MTMSN=0 2012-06-22T01:25:40.963Z,1340328340.963 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0007.lzma 2012-06-22T01:25:40.963Z,1340328340.963 [NAL9601](INFO): Packets left to send: 2 2012-06-22T01:25:40.964Z,1340328340.964 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0007.lzma.parts/0002.sbd 2012-06-22T01:25:53.335Z,1340328353.335 [NAL9601](INFO): SBD MO Status=1, MOMSN=10531, MT Status=0, MTMSN=0 2012-06-22T01:25:53.543Z,1340328353.543 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0007.lzma 2012-06-22T01:25:53.543Z,1340328353.543 [NAL9601](INFO): Packets left to send: 1 2012-06-22T01:25:53.544Z,1340328353.544 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0007.lzma.parts/0001.sbd 2012-06-22T01:26:03.414Z,1340328363.414 [NAL9601](INFO): SBD MO Status=1, MOMSN=10532, MT Status=0, MTMSN=0 2012-06-22T01:26:03.631Z,1340328363.631 [NAL9601](INFO): Sent 30 bytes from file Logs/20120621T224824/shore0007.lzma 2012-06-22T01:26:03.631Z,1340328363.631 [NAL9601](INFO): Packets left to send: 0 2012-06-22T01:26:03.632Z,1340328363.632 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0007.lzma.parts/0000.sbd 2012-06-22T01:26:11.031Z,1340328371.031 [NAL9601](INFO): SBD MO Status=0, MOMSN=10533, MT Status=0, MTMSN=0 2012-06-22T01:26:11.243Z,1340328371.243 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Stopped 2012-06-22T01:26:11.243Z,1340328371.243 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Running Loop=1 2012-06-22T01:26:13.428Z,1340328373.428 [NAL9601](IMPORTANT): GPS fix at: 1340328553.00 2012-06-22T01:26:13.468Z,1340328373.468 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Stopped 2012-06-22T01:26:13.468Z,1340328373.468 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-22T01:26:13.468Z,1340328373.468 [triangle_control_speed_onelap:SURFACECOMMS:B] Stopped 2012-06-22T01:26:13.468Z,1340328373.468 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS:B 2012-06-22T01:26:13.468Z,1340328373.468 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Stopped 2012-06-22T01:26:13.468Z,1340328373.468 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T01:26:13.469Z,1340328373.469 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS 2012-06-22T01:26:13.469Z,1340328373.469 [triangle_control_speed_onelap:SURFACECOMMS] Stopped 2012-06-22T01:26:13.469Z,1340328373.469 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS 2012-06-22T01:26:13.470Z,1340328373.470 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Stopped 2012-06-22T01:26:13.470Z,1340328373.470 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-22T01:26:13.827Z,1340328373.828 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1](INFO): Completed triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1 2012-06-22T01:26:13.828Z,1340328373.828 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1] Stopped 2012-06-22T01:26:13.828Z,1340328373.828 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1 2012-06-22T01:26:13.829Z,1340328373.829 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3](INFO): Completed triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3 2012-06-22T01:26:13.829Z,1340328373.829 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3] Stopped 2012-06-22T01:26:13.829Z,1340328373.829 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3 2012-06-22T01:26:13.829Z,1340328373.829 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:A.SetSpeed] Stopped 2012-06-22T01:26:13.829Z,1340328373.829 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T01:26:13.829Z,1340328373.829 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:B.Pitch] Stopped 2012-06-22T01:26:13.829Z,1340328373.829 [triangle_control_speed_onelap:ThreeLegs:TestDrive](INFO): Completed triangle_control_speed_onelap:ThreeLegs:TestDrive 2012-06-22T01:26:13.829Z,1340328373.829 [triangle_control_speed_onelap:ThreeLegs:TestDrive] Stopped 2012-06-22T01:26:13.830Z,1340328373.829 [triangle_control_speed_onelap:ThreeLegs:TestDrive](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:TestDrive 2012-06-22T01:26:13.833Z,1340328373.833 [triangle_control_speed_onelap:ThreeLegs](INFO): Completed triangle_control_speed_onelap:ThreeLegs 2012-06-22T01:26:13.833Z,1340328373.833 [triangle_control_speed_onelap:ThreeLegs] Stopped 2012-06-22T01:26:13.834Z,1340328373.833 [triangle_control_speed_onelap:ThreeLegs](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs 2012-06-22T01:26:13.834Z,1340328373.834 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold] Stopped 2012-06-22T01:26:13.834Z,1340328373.834 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:BuoyancyHold 2012-06-22T01:26:13.834Z,1340328373.834 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold:A.Buoyancy] Stopped 2012-06-22T01:26:13.834Z,1340328373.834 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2012-06-22T01:26:13.834Z,1340328373.834 [triangle_control_speed_onelap:ThreeLegs:MassHold] Stopped 2012-06-22T01:26:13.850Z,1340328373.850 [triangle_control_speed_onelap:ThreeLegs:MassHold](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:MassHold 2012-06-22T01:26:13.850Z,1340328373.850 [triangle_control_speed_onelap:ThreeLegs:MassHold:A.Pitch] Stopped 2012-06-22T01:26:13.850Z,1340328373.850 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold] Stopped 2012-06-22T01:26:13.851Z,1340328373.851 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:ElevatorHold 2012-06-22T01:26:13.851Z,1340328373.851 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold:A.Pitch] Stopped 2012-06-22T01:26:13.851Z,1340328373.851 [triangle_control_speed_onelap:ThreeLegs:D] Stopped 2012-06-22T01:26:13.851Z,1340328373.851 [triangle_control_speed_onelap:ThreeLegs:E] Stopped 2012-06-22T01:26:13.851Z,1340328373.851 [triangle_control_speed_onelap:ThreeLegs:F] Stopped 2012-06-22T01:26:13.851Z,1340328373.851 [triangle_control_speed_onelap:ThreeLegs:G] Stopped 2012-06-22T01:26:13.851Z,1340328373.851 [triangle_control_speed_onelap:ThreeLegs:H] Stopped 2012-06-22T01:26:13.851Z,1340328373.851 [triangle_control_speed_onelap:ThreeLegs:I] Stopped 2012-06-22T01:26:13.851Z,1340328373.851 [triangle_control_speed_onelap:ThreeLegs:J] Stopped 2012-06-22T01:26:13.851Z,1340328373.851 [triangle_control_speed_onelap:ThreeLegs:K] Stopped 2012-06-22T01:26:13.851Z,1340328373.851 [triangle_control_speed_onelap:ThreeLegs:L] Stopped 2012-06-22T01:26:13.853Z,1340328373.853 [triangle_control_speed_onelap](INFO): Completed triangle_control_speed_onelap 2012-06-22T01:26:13.853Z,1340328373.853 [triangle_control_speed_onelap] Stopped 2012-06-22T01:26:13.853Z,1340328373.853 [triangle_control_speed_onelap](INFO): Aggregate::uninitialize triangle_control_speed_onelap 2012-06-22T01:26:13.853Z,1340328373.853 [triangle_control_speed_onelap:A.AltitudeEnvelope] Stopped 2012-06-22T01:26:13.853Z,1340328373.853 [triangle_control_speed_onelap:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2012-06-22T01:26:13.853Z,1340328373.853 [triangle_control_speed_onelap:B.DepthEnvelope] Stopped 2012-06-22T01:26:13.853Z,1340328373.853 [triangle_control_speed_onelap:B.DepthEnvelope](DEBUG): Uninitialize. 2012-06-22T01:26:13.853Z,1340328373.853 [triangle_control_speed_onelap:C.OffshoreEnvelope] Stopped 2012-06-22T01:26:13.853Z,1340328373.853 [triangle_control_speed_onelap:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2012-06-22T01:26:13.853Z,1340328373.853 [triangle_control_speed_onelap:D] Stopped 2012-06-22T01:26:14.225Z,1340328374.225 [MissionManager](IMPORTANT): Started mission Default 2012-06-22T01:26:14.226Z,1340328374.226 [Default] Running Loop=1 2012-06-22T01:26:14.226Z,1340328374.226 [Default](INFO): Aggregate::initialize Default 2012-06-22T01:26:14.226Z,1340328374.226 [Default:D.SetSpeed] Running Loop=1 2012-06-22T01:26:14.226Z,1340328374.226 [Default:D.SetSpeed](DEBUG): Initialize. 2012-06-22T01:26:14.226Z,1340328374.226 [Default:E.GoToSurface] Running Loop=1 2012-06-22T01:26:14.226Z,1340328374.226 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-22T01:26:14.226Z,1340328374.226 [Default:Iridium] Running Loop=1 2012-06-22T01:26:14.226Z,1340328374.226 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2012-06-22T01:26:14.227Z,1340328374.227 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-06-22T01:26:14.227Z,1340328374.227 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2012-06-22T01:26:14.227Z,1340328374.227 [Default:Iridium:B.GoToSurface] Running Loop=1 2012-06-22T01:26:14.227Z,1340328374.227 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-22T01:26:14.227Z,1340328374.227 [Default:E.GoToSurface] Running Loop=1 2012-06-22T01:26:14.232Z,1340328374.232 [Default:D.SetSpeed] Running Loop=1 2012-06-22T01:26:14.283Z,1340328374.283 [Default:Iridium:B.GoToSurface] Stopped 2012-06-22T01:26:14.283Z,1340328374.283 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-22T01:26:14.283Z,1340328374.283 [Default:Iridium:Read_Iridium] Running Loop=1 2012-06-22T01:26:14.283Z,1340328374.283 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-06-22T01:26:14.288Z,1340328374.288 [Default:GPS] Running Loop=1 2012-06-22T01:26:14.288Z,1340328374.288 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2012-06-22T01:26:14.288Z,1340328374.288 [Default:GPS:A.SetSpeed] Running Loop=1 2012-06-22T01:26:14.288Z,1340328374.288 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2012-06-22T01:26:14.289Z,1340328374.289 [Default:GPS:B.GoToSurface] Running Loop=1 2012-06-22T01:26:14.289Z,1340328374.289 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-22T01:26:14.294Z,1340328374.294 [Default:GPS:B.GoToSurface] Stopped 2012-06-22T01:26:14.294Z,1340328374.294 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-22T01:26:14.294Z,1340328374.294 [Default:GPS:Read_GPS] Running Loop=1 2012-06-22T01:26:14.294Z,1340328374.294 [Default:GPS:A.SetSpeed] Running Loop=1 2012-06-22T01:26:14.624Z,1340328374.624 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2012-06-22T01:26:14.627Z,1340328374.627 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2012-06-22T01:26:17.059Z,1340328377.059 [NAL9601](IMPORTANT): GPS fix at: 1340328557.00 2012-06-22T01:26:17.086Z,1340328377.086 [Default:GPS:Read_GPS] Stopped 2012-06-22T01:26:17.086Z,1340328377.086 [Default:GPS:D] Running Loop=1 2012-06-22T01:26:17.493Z,1340328377.493 [Default:GPS:D] Stopped 2012-06-22T01:26:17.493Z,1340328377.493 [Default:GPS](INFO): Completed Default:GPS 2012-06-22T01:26:17.493Z,1340328377.493 [Default:GPS] Stopped 2012-06-22T01:26:17.494Z,1340328377.494 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2012-06-22T01:26:17.494Z,1340328377.494 [Default:GPS:A.SetSpeed] Stopped 2012-06-22T01:26:17.494Z,1340328377.494 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T01:26:40.941Z,1340328400.941 [NAL9601](INFO): SBD MO Status=1, MOMSN=10534, MT Status=0, MTMSN=0 2012-06-22T01:26:41.075Z,1340328401.075 [NAL9601](INFO): Sent 332 bytes from file Logs/20120621T224824/shore0008.lzma 2012-06-22T01:26:41.075Z,1340328401.075 [NAL9601](INFO): Packets left to send: 1 2012-06-22T01:26:41.076Z,1340328401.076 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0008.lzma.parts/0001.sbd 2012-06-22T01:26:54.633Z,1340328414.633 [NAL9601](INFO): SBD MO Status=1, MOMSN=10535, MT Status=0, MTMSN=0 2012-06-22T01:26:54.851Z,1340328414.851 [NAL9601](INFO): Sent 102 bytes from file Logs/20120621T224824/shore0008.lzma 2012-06-22T01:26:54.851Z,1340328414.851 [NAL9601](INFO): Packets left to send: 0 2012-06-22T01:26:54.852Z,1340328414.852 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0008.lzma.parts/0000.sbd 2012-06-22T01:27:12.237Z,1340328432.237 [NAL9601](INFO): SBD MO Status=0, MOMSN=10536, MT Status=0, MTMSN=0 2012-06-22T01:27:12.437Z,1340328432.437 [Default:Iridium:Read_Iridium] Stopped 2012-06-22T01:27:12.438Z,1340328432.438 [Default:Iridium](INFO): Completed Default:Iridium 2012-06-22T01:27:12.438Z,1340328432.438 [Default:Iridium] Stopped 2012-06-22T01:27:12.438Z,1340328432.438 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2012-06-22T01:27:12.438Z,1340328432.438 [Default:Iridium:A.SetSpeed] Stopped 2012-06-22T01:27:12.438Z,1340328432.438 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T01:27:12.438Z,1340328432.438 [Default:F.Wait] Running Loop=1 2012-06-22T01:27:12.438Z,1340328432.438 [Default:F.Wait](DEBUG): Initialize Wait Component. 2012-06-22T01:27:22.822Z,1340328442.822 [NAL9601](INFO): Powering down 2012-06-22T01:32:12.779Z,1340328732.779 [Default:CallIridium] Running Loop=1 2012-06-22T01:32:12.780Z,1340328732.780 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium 2012-06-22T01:32:12.780Z,1340328732.780 [Default:CallIridium:A] Running Loop=1 2012-06-22T01:32:12.782Z,1340328732.782 [Default:CallIridium:A] Stopped 2012-06-22T01:32:12.782Z,1340328732.782 [Default:CallIridium:B] Running Loop=1 2012-06-22T01:32:12.782Z,1340328732.782 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B 2012-06-22T01:32:18.043Z,1340328738.043 [Default:Iridium] Running Loop=1 2012-06-22T01:32:18.043Z,1340328738.043 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2012-06-22T01:32:18.043Z,1340328738.043 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-06-22T01:32:18.043Z,1340328738.043 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2012-06-22T01:32:18.043Z,1340328738.043 [Default:Iridium:B.GoToSurface] Running Loop=1 2012-06-22T01:32:18.043Z,1340328738.043 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-22T01:32:18.044Z,1340328738.044 [Default:Iridium:B.GoToSurface] Stopped 2012-06-22T01:32:18.044Z,1340328738.044 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-22T01:32:18.044Z,1340328738.044 [Default:Iridium:Read_Iridium] Running Loop=1 2012-06-22T01:32:18.044Z,1340328738.044 [Default:GPS] Running Loop=1 2012-06-22T01:32:18.045Z,1340328738.045 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2012-06-22T01:32:18.045Z,1340328738.045 [Default:GPS:A.SetSpeed] Running Loop=1 2012-06-22T01:32:18.045Z,1340328738.045 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2012-06-22T01:32:18.045Z,1340328738.045 [Default:GPS:B.GoToSurface] Running Loop=1 2012-06-22T01:32:18.045Z,1340328738.045 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-22T01:32:18.046Z,1340328738.046 [Default:GPS:B.GoToSurface] Stopped 2012-06-22T01:32:18.046Z,1340328738.046 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-22T01:32:18.046Z,1340328738.046 [Default:GPS:Read_GPS] Running Loop=1 2012-06-22T01:32:18.636Z,1340328738.636 [NAL9601](INFO): Powering up 2012-06-22T01:33:24.363Z,1340328804.363 [NAL9601](INFO): NAL9601 initialized 2012-06-22T01:33:38.949Z,1340328818.949 [NAL9601](INFO): SBD MO Status=1, MOMSN=10537, MT Status=0, MTMSN=0 2012-06-22T01:33:39.127Z,1340328819.127 [NAL9601](INFO): Sent 102 bytes from file Logs/20120621T224824/shore0009.lzma 2012-06-22T01:33:39.127Z,1340328819.127 [NAL9601](INFO): Packets left to send: 0 2012-06-22T01:33:39.128Z,1340328819.128 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0009.lzma.parts/0000.sbd 2012-06-22T01:33:48.945Z,1340328828.945 [NAL9601](INFO): SBD MO Status=0, MOMSN=10538, MT Status=0, MTMSN=0 2012-06-22T01:33:49.135Z,1340328829.135 [Default:Iridium:Read_Iridium] Stopped 2012-06-22T01:33:49.135Z,1340328829.135 [Default:Iridium](INFO): Completed Default:Iridium 2012-06-22T01:33:49.135Z,1340328829.135 [Default:Iridium] Stopped 2012-06-22T01:33:49.135Z,1340328829.135 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2012-06-22T01:33:49.135Z,1340328829.135 [Default:Iridium:A.SetSpeed] Stopped 2012-06-22T01:33:49.135Z,1340328829.135 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T01:33:49.362Z,1340328829.362 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B 2012-06-22T01:33:49.362Z,1340328829.362 [Default:CallIridium:B] Stopped 2012-06-22T01:33:49.362Z,1340328829.362 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B 2012-06-22T01:33:49.363Z,1340328829.363 [Default:CallIridium](INFO): Completed Default:CallIridium 2012-06-22T01:33:49.363Z,1340328829.363 [Default:CallIridium] Stopped 2012-06-22T01:33:49.363Z,1340328829.363 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium 2012-06-22T01:35:04.151Z,1340328904.151 [NAL9601](IMPORTANT): GPS fix at: 1340329085.00 2012-06-22T01:35:04.166Z,1340328904.166 [Default:GPS:Read_GPS] Stopped 2012-06-22T01:35:04.167Z,1340328904.167 [Default:GPS:D] Running Loop=1 2012-06-22T01:35:04.600Z,1340328904.600 [Default:GPS:D] Stopped 2012-06-22T01:35:04.601Z,1340328904.601 [Default:GPS](INFO): Completed Default:GPS 2012-06-22T01:35:04.601Z,1340328904.601 [Default:GPS] Stopped 2012-06-22T01:35:04.601Z,1340328904.601 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2012-06-22T01:35:04.601Z,1340328904.601 [Default:GPS:A.SetSpeed] Stopped 2012-06-22T01:35:04.601Z,1340328904.601 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T01:35:20.078Z,1340328920.078 [NAL9601](INFO): Powering down 2012-06-22T01:38:50.113Z,1340329130.113 [Default:CallIridium] Running Loop=1 2012-06-22T01:38:50.113Z,1340329130.114 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium 2012-06-22T01:38:50.114Z,1340329130.114 [Default:CallIridium:A] Running Loop=1 2012-06-22T01:38:50.114Z,1340329130.114 [Default:CallIridium:A] Stopped 2012-06-22T01:38:50.114Z,1340329130.114 [Default:CallIridium:B] Running Loop=1 2012-06-22T01:38:50.114Z,1340329130.114 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B 2012-06-22T01:38:55.321Z,1340329135.321 [Default:Iridium] Running Loop=1 2012-06-22T01:38:55.321Z,1340329135.321 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2012-06-22T01:38:55.321Z,1340329135.321 [Default:Iridium:A.SetSpeed] Running Loop=1 2012-06-22T01:38:55.322Z,1340329135.322 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2012-06-22T01:38:55.322Z,1340329135.322 [Default:Iridium:B.GoToSurface] Running Loop=1 2012-06-22T01:38:55.322Z,1340329135.322 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-22T01:38:55.327Z,1340329135.327 [Default:Iridium:B.GoToSurface] Stopped 2012-06-22T01:38:55.327Z,1340329135.327 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-22T01:38:55.327Z,1340329135.327 [Default:Iridium:Read_Iridium] Running Loop=1 2012-06-22T01:38:55.327Z,1340329135.327 [Default:GPS] Running Loop=1 2012-06-22T01:38:55.327Z,1340329135.328 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2012-06-22T01:38:55.327Z,1340329135.328 [Default:GPS:A.SetSpeed] Running Loop=1 2012-06-22T01:38:55.328Z,1340329135.328 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2012-06-22T01:38:55.328Z,1340329135.328 [Default:GPS:B.GoToSurface] Running Loop=1 2012-06-22T01:38:55.328Z,1340329135.328 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2012-06-22T01:38:55.329Z,1340329135.329 [Default:GPS:B.GoToSurface] Stopped 2012-06-22T01:38:55.329Z,1340329135.329 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2012-06-22T01:38:55.329Z,1340329135.329 [Default:GPS:Read_GPS] Running Loop=1 2012-06-22T01:38:55.923Z,1340329135.923 [NAL9601](INFO): Powering up 2012-06-22T01:40:01.647Z,1340329201.647 [NAL9601](INFO): NAL9601 initialized 2012-06-22T01:40:02.874Z,1340329202.874 [NAL9601](IMPORTANT): GPS fix at: 1340329384.00 2012-06-22T01:40:02.923Z,1340329202.923 [Default:GPS:Read_GPS] Stopped 2012-06-22T01:40:02.923Z,1340329202.923 [Default:GPS:D] Running Loop=1 2012-06-22T01:40:03.248Z,1340329203.248 [Default:GPS:D] Stopped 2012-06-22T01:40:03.249Z,1340329203.249 [Default:GPS](INFO): Completed Default:GPS 2012-06-22T01:40:03.249Z,1340329203.249 [Default:GPS] Stopped 2012-06-22T01:40:03.249Z,1340329203.249 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2012-06-22T01:40:03.249Z,1340329203.249 [Default:GPS:A.SetSpeed] Stopped 2012-06-22T01:40:03.249Z,1340329203.249 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2012-06-22T01:40:19.701Z,1340329219.701 [NAL9601](IMPORTANT): SBD MO Status=1, MOMSN=10539, MT Status=1, MTMSN=2777 2012-06-22T01:40:19.907Z,1340329219.907 [NAL9601](INFO): Sent 196 bytes from file Logs/20120621T224824/shore0010.lzma 2012-06-22T01:40:19.907Z,1340329219.907 [NAL9601](INFO): Packets left to send: 0 2012-06-22T01:40:19.909Z,1340329219.908 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0010.lzma.parts/0000.sbd 2012-06-22T01:40:20.319Z,1340329220.319 [NAL9601](INFO): Received command:restart logs 2012-06-22T01:40:20.355Z,1340329220.355 [CommandLine](IMPORTANT): got command restart logs