2012-05-07T23:21:51.167Z,1336432911.167 [Supervisor](DEBUG): Initializing supervisor.
2012-05-07T23:21:51.167Z,1336432911.167 [SyncHandler](DEBUG): Created PCaller Thread at -1218393232
2012-05-07T23:21:51.167Z,1336432911.167 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2012-05-07T23:21:51.167Z,1336432911.167 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at -1218593936
2012-05-07T23:21:51.167Z,1336432911.167 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2012-05-07T23:21:51.167Z,1336432911.167 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2012-05-07T23:21:51.167Z,1336432911.167 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at -1218794640
2012-05-07T23:21:51.167Z,1336432911.167 [ComponentRegistry](DEBUG): SyncComponent "logger" handled in the control thread.
2012-05-07T23:21:51.167Z,1336432911.167 [Supervisor](INFO): Looking for Config files in directory: Config/
2012-05-07T23:21:51.167Z,1336432911.167 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2012-05-07T23:21:51.172Z,1336432911.172 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2012-05-07T23:21:51.172Z,1336432911.172 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2012-05-07T23:21:51.176Z,1336432911.176 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2012-05-07T23:21:51.176Z,1336432911.176 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2012-05-07T23:21:51.179Z,1336432911.179 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2012-05-07T23:21:51.179Z,1336432911.179 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2012-05-07T23:21:51.183Z,1336432911.183 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2012-05-07T23:21:51.183Z,1336432911.183 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2012-05-07T23:21:51.187Z,1336432911.187 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2012-05-07T23:21:51.187Z,1336432911.187 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2012-05-07T23:21:51.191Z,1336432911.191 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2012-05-07T23:21:51.191Z,1336432911.191 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2012-05-07T23:21:51.195Z,1336432911.195 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2012-05-07T23:21:51.195Z,1336432911.195 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2012-05-07T23:21:51.199Z,1336432911.199 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2012-05-07T23:21:51.199Z,1336432911.199 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2012-05-07T23:21:51.202Z,1336432911.202 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2012-05-07T23:21:51.202Z,1336432911.202 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2012-05-07T23:21:51.208Z,1336432911.208 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2012-05-07T23:21:51.208Z,1336432911.208 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2012-05-07T23:21:51.211Z,1336432911.211 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2012-05-07T23:21:51.211Z,1336432911.211 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2012-05-07T23:21:51.214Z,1336432911.214 [Supervisor](INFO): Looking for Config files in directory: Config/tellum/
2012-05-07T23:21:51.214Z,1336432911.214 [Supervisor](INFO): Opening Config file at: Config/tellum/vehicle.cfg
2012-05-07T23:21:51.217Z,1336432911.217 [Supervisor](INFO): Looking for Config files in directory: Config/tellum/bkieft/
2012-05-07T23:21:51.217Z,1336432911.217 [Supervisor](INFO): Looking for Config files in directory: Config/sim/
2012-05-07T23:21:51.217Z,1336432911.217 [Supervisor](INFO): Opening Config file at: Config/sim/vehicle.cfg
2012-05-07T23:21:51.220Z,1336432911.220 [Supervisor](INFO): Opening Config file at: Config/sim/Sensor.cfg
2012-05-07T23:21:51.223Z,1336432911.223 [Supervisor](INFO): Opening Config file at: Config/sim/Servo.cfg
2012-05-07T23:21:51.226Z,1336432911.226 [Supervisor](INFO): Opening Config file at: Config/sim/Science.cfg
2012-05-07T23:21:51.229Z,1336432911.229 [Supervisor](INFO): Opening Config file at: Config/sim/Simulator.cfg
2012-05-07T23:21:51.232Z,1336432911.232 [Supervisor](INFO): Opening Config file at: Config/sim/Derivation.cfg
2012-05-07T23:21:51.235Z,1336432911.235 [Supervisor](INFO): Looking for Config files in directory: Config/sim/bkieft/
2012-05-07T23:21:51.235Z,1336432911.235 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2012-05-07T23:21:51.237Z,1336432911.237 [ExternalSim] Loaded
2012-05-07T23:21:51.237Z,1336432911.237 [ComponentRegistry](DEBUG): SyncComponent "ExternalSim" handled in the control thread.
2012-05-07T23:21:51.238Z,1336432911.238 [InternalSim] Loaded
2012-05-07T23:21:51.238Z,1336432911.238 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2012-05-07T23:21:51.238Z,1336432911.238 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2012-05-07T23:21:51.238Z,1336432911.238 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2012-05-07T23:21:51.238Z,1336432911.238 [SBIT](DEBUG): Construct Startup Built In Test.
2012-05-07T23:21:51.238Z,1336432911.238 [SBIT] Loaded
2012-05-07T23:21:51.238Z,1336432911.238 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2012-05-07T23:21:51.238Z,1336432911.238 [IBIT](DEBUG): Construct Initiated Built In Test.
2012-05-07T23:21:51.238Z,1336432911.238 [IBIT] Loaded
2012-05-07T23:21:51.238Z,1336432911.238 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2012-05-07T23:21:51.238Z,1336432911.238 [CBIT](DEBUG): Construct CBIT Built In Test.
2012-05-07T23:21:51.239Z,1336432911.239 [CBIT] Loaded
2012-05-07T23:21:51.239Z,1336432911.239 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2012-05-07T23:21:51.239Z,1336432911.239 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2012-05-07T23:21:51.239Z,1336432911.239 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2012-05-07T23:21:51.292Z,1336432911.292 [BuoyancyServo] Loaded
2012-05-07T23:21:51.292Z,1336432911.292 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2012-05-07T23:21:51.292Z,1336432911.292 [ElevatorServo] Loaded
2012-05-07T23:21:51.292Z,1336432911.292 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2012-05-07T23:21:51.292Z,1336432911.292 [MassServo] Loaded
2012-05-07T23:21:51.292Z,1336432911.292 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2012-05-07T23:21:51.292Z,1336432911.292 [RudderServo] Loaded
2012-05-07T23:21:51.292Z,1336432911.292 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2012-05-07T23:21:51.292Z,1336432911.292 [ThrusterServo] Loaded
2012-05-07T23:21:51.292Z,1336432911.292 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2012-05-07T23:21:51.292Z,1336432911.292 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2012-05-07T23:21:51.292Z,1336432911.292 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2012-05-07T23:21:51.293Z,1336432911.293 [DepthRateCalculator] Loaded
2012-05-07T23:21:51.293Z,1336432911.293 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2012-05-07T23:21:51.293Z,1336432911.293 [NavChart] Loaded
2012-05-07T23:21:51.293Z,1336432911.293 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2012-05-07T23:21:51.293Z,1336432911.293 [PitchRateCalculator] Loaded
2012-05-07T23:21:51.293Z,1336432911.293 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2012-05-07T23:21:51.293Z,1336432911.293 [SpeedCalculator] Loaded
2012-05-07T23:21:51.293Z,1336432911.293 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2012-05-07T23:21:51.293Z,1336432911.293 [TempGradientCalculator] Loaded
2012-05-07T23:21:51.293Z,1336432911.293 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2012-05-07T23:21:51.293Z,1336432911.293 [YawRateCalculator] Loaded
2012-05-07T23:21:51.293Z,1336432911.293 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2012-05-07T23:21:51.293Z,1336432911.293 [Navigation] Loaded
2012-05-07T23:21:51.293Z,1336432911.293 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread.
2012-05-07T23:21:51.293Z,1336432911.293 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2012-05-07T23:21:51.293Z,1336432911.293 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2012-05-07T23:21:51.295Z,1336432911.295 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2012-05-07T23:21:51.295Z,1336432911.295 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2012-05-07T23:21:51.295Z,1336432911.295 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2012-05-07T23:21:51.295Z,1336432911.295 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2012-05-07T23:21:51.295Z,1336432911.295 [VerticalControl](DEBUG): Construct VerticalControl.
2012-05-07T23:21:51.296Z,1336432911.296 [VerticalControl] Loaded
2012-05-07T23:21:51.296Z,1336432911.296 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2012-05-07T23:21:51.296Z,1336432911.296 [HorizontalControl](DEBUG): Construct HorizontalControl.
2012-05-07T23:21:51.296Z,1336432911.296 [HorizontalControl] Loaded
2012-05-07T23:21:51.296Z,1336432911.296 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2012-05-07T23:21:51.296Z,1336432911.296 [SpeedControl](DEBUG): Construct SpeedControl.
2012-05-07T23:21:51.296Z,1336432911.296 [SpeedControl] Loaded
2012-05-07T23:21:51.296Z,1336432911.296 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2012-05-07T23:21:51.296Z,1336432911.296 [LoopControl](DEBUG): Construct LoopControl.
2012-05-07T23:21:51.296Z,1336432911.296 [LoopControl] Loaded
2012-05-07T23:21:51.296Z,1336432911.296 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2012-05-07T23:21:51.296Z,1336432911.296 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2012-05-07T23:21:51.296Z,1336432911.296 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2012-05-07T23:21:51.296Z,1336432911.296 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2012-05-07T23:21:51.296Z,1336432911.296 [AsyncPiEstimator] Loaded
2012-05-07T23:21:51.296Z,1336432911.296 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2012-05-07T23:21:51.296Z,1336432911.296 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at -1219941520
2012-05-07T23:21:51.296Z,1336432911.296 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2012-05-07T23:21:51.296Z,1336432911.296 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2012-05-07T23:21:51.297Z,1336432911.297 [AHRS_sp3003D] Loaded
2012-05-07T23:21:51.297Z,1336432911.297 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2012-05-07T23:21:51.297Z,1336432911.297 [AHRS_3DMGX3] Loaded
2012-05-07T23:21:51.297Z,1336432911.297 [ComponentRegistry](DEBUG): SyncComponent "AHRS_3DMGX3" handled in the control thread.
2012-05-07T23:21:51.297Z,1336432911.297 [Depth_Keller] Loaded
2012-05-07T23:21:51.298Z,1336432911.298 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2012-05-07T23:21:51.298Z,1336432911.298 [DropWeight] Loaded
2012-05-07T23:21:51.298Z,1336432911.298 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2012-05-07T23:21:51.298Z,1336432911.298 [DVL_micro] Loaded
2012-05-07T23:21:51.298Z,1336432911.298 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2012-05-07T23:21:51.299Z,1336432911.299 [NAL9601] Loaded
2012-05-07T23:21:51.299Z,1336432911.299 [ComponentRegistry](DEBUG): SyncComponent "NAL9601" handled in the control thread.
2012-05-07T23:21:51.299Z,1336432911.299 [Onboard] Loaded
2012-05-07T23:21:51.299Z,1336432911.299 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2012-05-07T23:21:51.299Z,1336432911.299 [Radio_Freewave] Loaded
2012-05-07T23:21:51.299Z,1336432911.299 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread.
2012-05-07T23:21:51.300Z,1336432911.300 [DAT] Loaded
2012-05-07T23:21:51.300Z,1336432911.300 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2012-05-07T23:21:51.300Z,1336432911.300 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2012-05-07T23:21:51.300Z,1336432911.300 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2012-05-07T23:21:51.300Z,1336432911.300 [Aanderaa_O2] Loaded
2012-05-07T23:21:51.300Z,1336432911.300 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2012-05-07T23:21:51.301Z,1336432911.301 [CTD_NeilBrown] Loaded
2012-05-07T23:21:51.301Z,1336432911.301 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2012-05-07T23:21:51.301Z,1336432911.301 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at -1220523152
2012-05-07T23:21:51.301Z,1336432911.301 [ISUS] Loaded
2012-05-07T23:21:51.301Z,1336432911.301 [ComponentRegistry](DEBUG): SyncComponent "ISUS" handled in the control thread.
2012-05-07T23:21:51.301Z,1336432911.301 [Turbulence_NPS] Loaded
2012-05-07T23:21:51.301Z,1336432911.301 [ComponentRegistry](DEBUG): Component "Turbulence_NPS" handled in its own thread.
2012-05-07T23:21:51.301Z,1336432911.301 [Turbulence_NPS ThreadHandler](DEBUG): Created PCaller Thread at -1220723856
2012-05-07T23:21:51.301Z,1336432911.301 [WetLabsBB2FL] Loaded
2012-05-07T23:21:51.301Z,1336432911.301 [ComponentRegistry](DEBUG): SyncComponent "WetLabsBB2FL" handled in the control thread.
2012-05-07T23:21:51.301Z,1336432911.301 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2012-05-07T23:21:51.301Z,1336432911.301 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2012-05-07T23:21:51.301Z,1336432911.301 [ComponentRegistry](DEBUG): SyncComponent "Maintainer" handled in the control thread.
2012-05-07T23:21:51.301Z,1336432911.301 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2012-05-07T23:21:51.301Z,1336432911.301 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2012-05-07T23:21:51.301Z,1336432911.301 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at -1220924560
2012-05-07T23:21:51.301Z,1336432911.301 [Supervisor](DEBUG): Running supervisor.
2012-05-07T23:21:51.301Z,1336432911.301 [controlThread](DEBUG): Initializing ControlThread
2012-05-07T23:21:51.301Z,1336432911.301 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2012-05-07T23:21:51.301Z,1336432911.301 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2012-05-07T23:21:51.301Z,1336432911.301 [ExternalSim](INFO): ExternalSim initializing...
2012-05-07T23:21:51.301Z,1336432911.301 [Turbulence_NPS](DEBUG): Initializing Turbulence_NPS.
2012-05-07T23:21:51.302Z,1336432911.302 [ExternalSim](DEBUG): beaconLat = 36.81340
2012-05-07T23:21:51.302Z,1336432911.302 [ExternalSim](DEBUG): beaconLon = -121.82240
2012-05-07T23:21:51.302Z,1336432911.302 [ExternalSim](DEBUG): beaconDepth = 25.00
2012-05-07T23:21:51.303Z,1336432911.303 [ExternalSim](DEBUG): Simulator initialized
2012-05-07T23:21:51.303Z,1336432911.303 [InternalSim](DEBUG):  InternalSim initializing...
2012-05-07T23:21:51.303Z,1336432911.303 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2012-05-07T23:21:51.303Z,1336432911.303 [SBIT](INFO): Initialize SBIT Component.
2012-05-07T23:21:51.303Z,1336432911.303 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2012-05-07T23:21:51.303Z,1336432911.303 [SBIT](IMPORTANT): Tethys CM Info:
$Rev: 9626 $
2012-05-07T23:21:51.303Z,1336432911.303 [IBIT](INFO): Initialize IBIT Component.
2012-05-07T23:21:51.303Z,1336432911.303 [CBIT](DEBUG): Initialize CBIT Component.
2012-05-07T23:21:51.303Z,1336432911.303 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2012-05-07T23:21:51.303Z,1336432911.303 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2012-05-07T23:21:51.303Z,1336432911.303 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2012-05-07T23:21:51.303Z,1336432911.303 [NavChart](DEBUG): Initialize NavChart Derivation.
2012-05-07T23:21:51.303Z,1336432911.303 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2012-05-07T23:21:51.303Z,1336432911.303 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2012-05-07T23:21:51.303Z,1336432911.303 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2012-05-07T23:21:51.303Z,1336432911.303 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2012-05-07T23:21:51.303Z,1336432911.303 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2012-05-07T23:21:51.303Z,1336432911.303 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2012-05-07T23:21:51.303Z,1336432911.303 [Navigation](DEBUG): Initializing Navigation.
2012-05-07T23:21:51.303Z,1336432911.303 [NavChartDb](INFO): Creating index for Soundings
2012-05-07T23:21:51.303Z,1336432911.303 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2012-05-07T23:21:51.303Z,1336432911.303 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2012-05-07T23:21:51.303Z,1336432911.303 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2012-05-07T23:21:51.303Z,1336432911.303 [LoopControl](DEBUG): Initialize LoopControlComponent.
2012-05-07T23:21:51.303Z,1336432911.303 [NavChartDb](INFO): Creating index for Contour 0p0
2012-05-07T23:21:51.303Z,1336432911.303 [NavChartDb](INFO): Creating index for Contour 1p8
2012-05-07T23:21:51.303Z,1336432911.303 [NavChartDb](INFO): Creating index for Contour 3p6
2012-05-07T23:21:51.303Z,1336432911.303 [NavChartDb](INFO): Creating index for Contour 5p4
2012-05-07T23:21:51.303Z,1336432911.303 [NavChartDb](INFO): Creating index for Contour 9p1
2012-05-07T23:21:51.303Z,1336432911.303 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2012-05-07T23:21:51.303Z,1336432911.303 [NavChartDb](INFO): Creating index for Contour 10p9
2012-05-07T23:21:51.303Z,1336432911.303 [NavChartDb](INFO): Creating index for Contour 18p2
2012-05-07T23:21:51.303Z,1336432911.303 [NavChartDb](INFO): Creating index for Contour 36p5
2012-05-07T23:21:51.303Z,1336432911.303 [NavChartDb](INFO): Creating index for Contour 54p8
2012-05-07T23:21:51.303Z,1336432911.303 [NavChartDb](INFO): Creating index for Contour 73p1
2012-05-07T23:21:51.303Z,1336432911.303 [NavChartDb](INFO): Creating index for Contour 91p4
2012-05-07T23:21:51.303Z,1336432911.303 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2012-05-07T23:21:51.304Z,1336432911.304 [NavChartDb](INFO): Creating index for Contour 182p8
2012-05-07T23:21:51.304Z,1336432911.304 [NavChartDb](INFO): Creating index for Contour 365p7
2012-05-07T23:21:51.304Z,1336432911.304 [NavChartDb](INFO): Creating index for Contour 548p6
2012-05-07T23:21:51.304Z,1336432911.304 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface RunIn="Progression"/>

    <Aggregate Id="StartupSatComms" >

        <ReadDatum>
            <Timeout Duration="P1M" /><Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" /><Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2012-05-07T23:21:51.304Z,1336432911.304 [NavChartDb](INFO): Creating index for Contour 731p5
2012-05-07T23:21:51.304Z,1336432911.304 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2012-05-07T23:21:51.304Z,1336432911.304 [NavChartDb](INFO): Creating index for Contour 914p4
2012-05-07T23:21:51.304Z,1336432911.304 [NavChartDb](INFO): Creating index for Contour 1097p2
2012-05-07T23:21:51.304Z,1336432911.304 [NavChartDb](INFO): Creating index for Contour 1280p1
2012-05-07T23:21:51.304Z,1336432911.304 [NavChartDb](INFO): Creating index for Contour 1463p0
2012-05-07T23:21:51.304Z,1336432911.304 [NavChartDb](INFO): Creating index for Contour 1645p9
2012-05-07T23:21:51.304Z,1336432911.304 [NavChartDb](INFO): Creating index for Contour 1828p8
2012-05-07T23:21:51.304Z,1336432911.304 [NavChartDb](INFO): Creating index for Contour 2011p6
2012-05-07T23:21:51.304Z,1336432911.304 [NavChartDb](INFO): Creating index for Contour 2194p5
2012-05-07T23:21:51.304Z,1336432911.304 [NavChartDb](INFO): Creating index for Contour 2377p4
2012-05-07T23:21:51.304Z,1336432911.304 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2012-05-07T23:21:51.304Z,1336432911.304 [NavChartDb](INFO): Creating index for Contour 2560p3
2012-05-07T23:21:51.304Z,1336432911.304 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2012-05-07T23:21:51.304Z,1336432911.304 [NavChartDb](INFO): Creating index for Contour 2743p2
2012-05-07T23:21:51.304Z,1336432911.304 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2012-05-07T23:21:51.304Z,1336432911.304 [NavChartDb](INFO): Creating index for Contour 2926p0
2012-05-07T23:21:51.304Z,1336432911.304 [NavChartDb](INFO): Creating index for Contour 3108p9
2012-05-07T23:21:51.304Z,1336432911.304 [NavChartDb](INFO): Creating index for Contour 3291p8
2012-05-07T23:21:51.304Z,1336432911.304 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2012-05-07T23:21:51.304Z,1336432911.304 [NavChartDb](INFO): Creating index for Contour 3474p7
2012-05-07T23:21:51.304Z,1336432911.304 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2012-05-07T23:21:51.304Z,1336432911.304 [NavChartDb](INFO): Creating index for Contour 3657p6
2012-05-07T23:21:51.304Z,1336432911.304 [Default:Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2012-05-07T23:21:51.304Z,1336432911.304 [Default:D.SetSpeed](DEBUG): Construct.
2012-05-07T23:21:51.304Z,1336432911.304 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2012-05-07T23:21:51.304Z,1336432911.304 [Default:F.Wait](DEBUG): Construct Wait.
2012-05-07T23:21:51.304Z,1336432911.304 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
xmlns:Control="Tethys/Control"
xmlns:Guidance="Tethys/Guidance" 
xmlns:Units="Tethys/Units"
xmlns:Universal="Tethys/Universal"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

    <DefineArg Name="NeedGPS"><True/></DefineArg>

    <Aggregate Id="GPS" >

        <When><Arg Name="NeedGPS"/>
        </When>

<!-- Run at high speed -->

        <Guidance:SetSpeed RunIn="Parallel">
            <Setting><Guidance:SetSpeed.period/><Units:millisecond/><Value>400</Value></Setting>
        </Guidance:SetSpeed>

<!-- Get to the surface -->

        <Guidance:GoToSurface RunIn="Sequence"/>

<!-- Get GPS fix -->

        <ReadDatum Id="Read_GPS"><Universal:latitude_fix/>
        </ReadDatum>

        <Assign><Arg Name="NeedGPS"/><False/></Assign>

    </Aggregate>

    <Aggregate Id="Iridium" >

<!-- Run at high speed -->

        <Guidance:SetSpeed RunIn="Parallel">
            <Setting><Guidance:SetSpeed.period/><Units:millisecond/><Value>400</Value></Setting>
        </Guidance:SetSpeed>

<!-- Get to the surface -->

        <Guidance:GoToSurface RunIn="Sequence"/>

<!-- Do data comms -->

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute RunIn="Sequence">
                    <Setting>
                    <Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped drop weight due to communications timeout</Syslog>
            </Timeout><Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

    <Aggregate Id="CallIridium" >

        <When>
            <Elapsed><Universal:platform_communications/></Elapsed>
            <Gt><Units:minute/><Value>5.0</Value></Gt>
            <And><Universal:platform_conversation/><Eq><True/></Eq></And>
            <Or>
            <Elapsed><Universal:platform_communications/></Elapsed><Gt><Units:hour/><Value>1.0</Value></Gt></Or>
        </When>

        <Assign><Arg Name="NeedGPS"/><True/></Assign>

        <Call RefId="Iridium"/>

    </Aggregate>

<!-- Wait forever at the surface-->

<!-- Run at low speed -->

    <Guidance:SetSpeed RunIn="Parallel">
        <Setting><Guidance:SetSpeed.period/><Units:second/><Value>5</Value></Setting>
    </Guidance:SetSpeed>

    <Guidance:GoToSurface RunIn="Parallel"/>

    <Guidance:Wait RunIn="Sequence"></Guidance:Wait>

</Mission>


2012-05-07T23:21:51.304Z,1336432911.304 [controlThread](DEBUG): Component order: CycleStarter,ExternalSim,InternalSim,AHRS_sp3003D,AHRS_3DMGX3,Depth_Keller,DropWeight,DVL_micro,NAL9601,Onboard,Radio_Freewave,DAT,Aanderaa_O2,ISUS,WetLabsBB2FL,Depth_Keller,DepthRateCalculator,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintainer,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,logger,
2012-05-07T23:21:51.306Z,1336432911.306 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2012-05-07T23:21:51.306Z,1336432911.306 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2012-05-07T23:21:51.306Z,1336432911.306 [MassServo](DEBUG): Initializing MassServo.
2012-05-07T23:21:51.306Z,1336432911.306 [RudderServo](DEBUG): Initializing RudderServo.
2012-05-07T23:21:51.306Z,1336432911.306 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2012-05-07T23:21:51.402Z,1336432911.402 [CommandLine](IMPORTANT): got command load ./Missions/Science/turbulence.xml
2012-05-07T23:21:51.402Z,1336432911.402 [MissionManager](INFO): Loading Mission: ./Missions/Science/turbulence.xml
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.MassDefault = 0.000000 n/a
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.ApproachDepth = 5.000000 m
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.MinAltitude = 3.000000 m
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.MaxDepth = 105.000000 m
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.MinOffshore = 2000.000000 m
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.MaxOffshore = 12000.000000 m
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.YoYoMinAltitude = 3.500000 m
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.BottomFollowingAltitude = 5.000000 m
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.BottomFollowingMinAltitude = 3.500000 m
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.BottomFollowingMaxDepth = 100.000000 m
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.kpAltitude = -0.080000 n/a
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.kiAltitude = 0.000000 n/a
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.kdAltitude = 0.000000 n/a
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.ScienceOnDepth = 1.000000 m
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.NitrateOnPitch = 90.000000 arcdeg
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.YoYoMinDepth = 3.000000 m
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.YoYoMaxDepth = 100.000000 m
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.YoYoUpPitch = 30.000000 arcdeg
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.YoYoDownPitch = -15.000000 arcdeg
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.Speed = 1.000000 m/s
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.NeedCommsTime = 180.000000 min
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.TimeoutDuration = 30.000000 h
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.Repeat = 5.000000 count
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.BuoyancyNeutral = 0.000500 n/a
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.Wpt1Lat = 36.719000 arcdeg
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.Wpt1Lon = -122.010000 arcdeg
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.Wpt1aLat = 36.719000 arcdeg
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.Wpt1aLon = -121.947000 arcdeg
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.Wpt1bLat = 36.719000 arcdeg
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.Wpt1bLon = -121.884500 arcdeg
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.Wpt2Lat = 36.719000 arcdeg
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.Wpt2Lon = -121.836600 arcdeg
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.Wpt2aLat = 36.719000 arcdeg
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.Wpt2aLon = -121.852700 arcdeg
2012-05-07T23:21:51.403Z,1336432911.403 [MissionManager](INFO): DefineArg turbulence.Wpt2bLat = 36.719000 arcdeg
2012-05-07T23:21:51.404Z,1336432911.404 [MissionManager](INFO): DefineArg turbulence.Wpt2bLon = -121.930400 arcdeg
2012-05-07T23:21:51.404Z,1336432911.404 [turbulence:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2012-05-07T23:21:51.404Z,1336432911.404 [turbulence:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2012-05-07T23:21:51.404Z,1336432911.404 [turbulence:C.Buoyancy](DEBUG): Construct Buoyancy.
2012-05-07T23:21:51.404Z,1336432911.404 [turbulence:D.Pitch](DEBUG): Construct.
2012-05-07T23:21:51.404Z,1336432911.404 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml
2012-05-07T23:21:51.404Z,1336432911.404 [MissionManager](INFO): DefineArg turbulence:SURFACECOMMS.SurfaceDepthRate = nan m/s
2012-05-07T23:21:51.404Z,1336432911.404 [MissionManager](INFO): DefineArg turbulence:SURFACECOMMS.SurfacePitch = nan arcdeg
2012-05-07T23:21:51.404Z,1336432911.404 [MissionManager](INFO): DefineArg turbulence:SURFACECOMMS.SurfaceSpeed = 0.500000 m/s
2012-05-07T23:21:51.404Z,1336432911.404 [MissionManager](INFO): DefineArg turbulence:SURFACECOMMS.IridiumTimeout = 30.000000 min
2012-05-07T23:21:51.404Z,1336432911.404 [turbulence:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface.
2012-05-07T23:21:51.404Z,1336432911.404 [MissionManager](INFO): Inserting Stack: Missions/Insert/SurfaceNavUpdate.xml
2012-05-07T23:21:51.404Z,1336432911.404 [turbulence:SURFACENAV:A.GoToSurface](DEBUG): Construct GoToSurface.
2012-05-07T23:21:51.404Z,1336432911.404 [turbulence:G.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2012-05-07T23:21:51.404Z,1336432911.404 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2012-05-07T23:21:51.405Z,1336432911.405 [MissionManager](INFO): DefineArg turbulence:Science.SampleAanderaaO2 = 1.000000 n/a
2012-05-07T23:21:51.405Z,1336432911.405 [MissionManager](INFO): DefineArg turbulence:Science.SampleISUS = 0 bool
2012-05-07T23:21:51.405Z,1336432911.405 [MissionManager](INFO): DefineArg turbulence:Science.SampleNeilBrown = 1.000000 n/a
2012-05-07T23:21:51.405Z,1336432911.405 [MissionManager](INFO): DefineArg turbulence:Science.SampleWetLabsBB2FL = 1.000000 n/a
2012-05-07T23:21:51.405Z,1336432911.405 [MissionManager](INFO): DefineArg turbulence:Science.PeakDetectChlActive = 0 bool
2012-05-07T23:21:51.405Z,1336432911.405 [MissionManager](INFO): DefineArg turbulence:Science.PeakDetectNO3Active = 0 bool
2012-05-07T23:21:51.405Z,1336432911.405 [MissionManager](INFO): DefineOutput turbulence:Science.PeakChl = 0.000000 ug/l
2012-05-07T23:21:51.405Z,1336432911.405 [MissionManager](INFO): DefineOutput turbulence:Science.PeakChlDepth = 0.000000 m
2012-05-07T23:21:51.405Z,1336432911.405 [MissionManager](INFO): DefineOutput turbulence:Science.PeakChlLatitude = nan arcdeg
2012-05-07T23:21:51.405Z,1336432911.405 [MissionManager](INFO): DefineOutput turbulence:Science.PeakChlLongitude = nan arcdeg
2012-05-07T23:21:51.405Z,1336432911.405 [MissionManager](INFO): DefineOutput turbulence:Science.PeakNO3 = 0.000000 uM/l
2012-05-07T23:21:51.405Z,1336432911.405 [MissionManager](INFO): DefineOutput turbulence:Science.PeakNO3Depth = 0.000000 m
2012-05-07T23:21:51.405Z,1336432911.405 [MissionManager](INFO): DefineOutput turbulence:Science.PeakNO3Latitude = nan arcdeg
2012-05-07T23:21:51.405Z,1336432911.405 [MissionManager](INFO): DefineOutput turbulence:Science.PeakNO3Longitude = nan arcdeg
2012-05-07T23:21:51.405Z,1336432911.405 [turbulence:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2012-05-07T23:21:51.405Z,1336432911.405 [turbulence:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2012-05-07T23:21:51.405Z,1336432911.405 [MissionManager](INFO): RedefineArg turbulence:Science.PeakDetectChlActive = 0 bool
2012-05-07T23:21:51.405Z,1336432911.405 [turbulence:TransitWpt1:A.Pitch](DEBUG): Construct.
2012-05-07T23:21:51.405Z,1336432911.405 [turbulence:TransitWpt1:B.SetSpeed](DEBUG): Construct.
2012-05-07T23:21:51.405Z,1336432911.405 [turbulence:TransitWpt1:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2012-05-07T23:21:51.405Z,1336432911.405 [turbulence:RepeatOps:YoYoWpt2:A.SetSpeed](DEBUG): Construct.
2012-05-07T23:21:51.405Z,1336432911.405 [turbulence:RepeatOps:YoYoWpt2:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2012-05-07T23:21:51.405Z,1336432911.405 [turbulence:RepeatOps:YoYoWpt2:C.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2012-05-07T23:21:51.406Z,1336432911.406 [turbulence:RepeatOps:YoYoWpt2:D.YoYo](DEBUG): Construct YoYo.
2012-05-07T23:21:51.406Z,1336432911.406 [turbulence:RepeatOps:YoYoWpt2:Wpt1a.Waypoint](DEBUG): Construct Waypoint.
2012-05-07T23:21:51.406Z,1336432911.406 [turbulence:RepeatOps:YoYoWpt2:Wpt1b.Waypoint](DEBUG): Construct Waypoint.
2012-05-07T23:21:51.406Z,1336432911.406 [turbulence:RepeatOps:YoYoWpt2:Wpt2.Waypoint](DEBUG): Construct Waypoint.
2012-05-07T23:21:51.406Z,1336432911.406 [turbulence:RepeatOps:BottomFollowWpt1:A:B.Wait](DEBUG): Construct Wait.
2012-05-07T23:21:51.406Z,1336432911.406 [turbulence:RepeatOps:BottomFollowWpt1:B.SetSpeed](DEBUG): Construct.
2012-05-07T23:21:51.406Z,1336432911.406 [turbulence:RepeatOps:BottomFollowWpt1:C.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2012-05-07T23:21:51.406Z,1336432911.406 [turbulence:RepeatOps:BottomFollowWpt1:D.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2012-05-07T23:21:51.406Z,1336432911.406 [turbulence:RepeatOps:BottomFollowWpt1:LimitDeepDownPitch.Pitch](DEBUG): Construct.
2012-05-07T23:21:51.406Z,1336432911.406 [turbulence:RepeatOps:BottomFollowWpt1:LimitDownPitch.Pitch](DEBUG): Construct.
2012-05-07T23:21:51.406Z,1336432911.406 [turbulence:RepeatOps:BottomFollowWpt1:G.PitchServo](DEBUG): Construct.
2012-05-07T23:21:51.406Z,1336432911.406 [turbulence:RepeatOps:BottomFollowWpt1:Wpt2a.Waypoint](DEBUG): Construct Waypoint.
2012-05-07T23:21:51.406Z,1336432911.406 [turbulence:RepeatOps:BottomFollowWpt1:Wpt2b.Waypoint](DEBUG): Construct Waypoint.
2012-05-07T23:21:51.406Z,1336432911.406 [turbulence:RepeatOps:BottomFollowWpt1:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2012-05-07T23:21:51.407Z,1336432911.407 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Trigger="Tethys/Trigger" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Trigger http://aosn.mbari.org/tethys/Xml/Trigger.xsd
                           Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
       Id="turbulence">

    <Description>
        Runs from deep water to shallow water doing yo-yo's. Then heads out to
        deep water following the bottom. Repeats.
    </Description>

    <DefineArg Name="MassDefault"><Description>
        Static setting for mass during the mission.
    </Description><Control:VerticalControl.massDefault/></DefineArg>

    <DefineArg Name="ApproachDepth"><Description>
        Depth for initial approach to Wpt1.
    </Description><Units:meter/><Value>5.0</Value></DefineArg>

    <DefineArg Name="MinAltitude"><Description>
        Minimum altitude for the entire mission.
    </Description><Units:meter/><Value>3</Value></DefineArg>

    <DefineArg Name="MaxDepth"><Description>
        Maximum depth for the entire mission.
    </Description><Units:meter/><Value>105</Value></DefineArg>

    <DefineArg Name="MinOffshore"><Description>
        Minimum offshore distance for the entire mission.
    </Description><Units:meter/><Value>2000</Value></DefineArg>

    <DefineArg Name="MaxOffshore"><Description>
        Maximum offshore distance for the entire mission.
    </Description><Units:meter/><Value>12000</Value></DefineArg>

    <DefineArg Name="YoYoMinAltitude"><Description>
        Minimum altitude while yo-yo-ing.
    </Description><Units:meter/><Value>3.5</Value></DefineArg>

    <DefineArg Name="BottomFollowingAltitude"><Description>
        Altitude while following the bottom.
    </Description><Units:meter/><Value>5</Value></DefineArg>

    <DefineArg Name="BottomFollowingMinAltitude"><Description>
        Minimum altitude while following the bottom.
    </Description><Units:meter/><Value>3.5</Value></DefineArg>

    <DefineArg Name="BottomFollowingMaxDepth"><Description>
        Maximum depth while following the bottom.
    </Description><Units:meter/><Value>100.0</Value></DefineArg>

    <DefineArg Name="kpAltitude"><Description>
        PID loop parameter for following the bottom.
    </Description><Units:none/><Value>-0.08</Value></DefineArg>

    <DefineArg Name="kiAltitude"><Description>
        PID loop parameter for following the bottom.
    </Description><Units:none/><Value>0</Value></DefineArg>

    <DefineArg Name="kdAltitude"><Description>
        PID loop parameter for following the bottom.
    </Description><Units:none/><Value>0</Value></DefineArg>

    <DefineArg Name="ScienceOnDepth"><Description>
        Turn on science when below this depth. Only applies to Nitrate.
    </Description><Units:meter/><Value>1</Value></DefineArg>

    <DefineArg Name="NitrateOnPitch"><Description>
        Turn on Nitrate when pitch exceeds this value.
    </Description><Units:degree/><Value>90</Value></DefineArg>

    <DefineArg Name="YoYoMinDepth"><Description>
        Minimum depth during yo-yo's.
    </Description><Units:meter/><Value>3.0</Value></DefineArg>

    <DefineArg Name="YoYoMaxDepth"><Description>
        Maximum depth during yo-yo's.
    </Description><Units:meter/><Value>100.0</Value></DefineArg>

    <DefineArg Name="YoYoUpPitch"><Description>
        Pitch when yo-yo's are ascending.
    </Description><Units:degree/><Value>30.0</Value></DefineArg>

    <DefineArg Name="YoYoDownPitch"><Description>
        Pitch when yo-yo's are descending.
    </Description><Units:degree/><Value>-15.0</Value></DefineArg>

    <DefineArg Name="Speed"><Description>
        Vehicle speed.
    </Description><Units:meter_per_second /><Value>1</Value></DefineArg>

    <DefineArg Name="NeedCommsTime"><Description>
        Elapsed time after previous surface communications when vehicle will
        begin to ascend for additional surface communications, if the vehicle
        has a positive pitch. If the vehicle has a negative pitch, it will wait
        up to 20 minutes more to begin ascent.
    </Description><Units:minute/><Value>180</Value></DefineArg>

    <DefineArg Name="TimeoutDuration"><Description>
        Maximum length of mission
    </Description><Units:hour/><Value>30</Value></DefineArg>

    <DefineArg Name="Repeat"><Description>
        Number of times to repeat the loop
    </Description><Units:count/><Value>5</Value></DefineArg>

    <DefineArg Name="BuoyancyNeutral"><Description>
        Buoyancy bladder position while performing the YoYo behavior. Defaults
        to buoyancyNeutral setting in the Config/Control.cfg file.
    </Description><Control:VerticalControl.buoyancyNeutral/></DefineArg>

<!-- Waypoint list -->

    <DefineArg Name="Wpt1Lat"><Description>
        Latitude of deep waypoint.
    </Description><Units:degree/><Value>36.719</Value></DefineArg>

    <DefineArg Name="Wpt1Lon"><Description>
        Longitude of deep waypoint.
    </Description><Units:degree/><Value>-122.0100</Value></DefineArg>

    <DefineArg Name="Wpt1aLat"><Description>
        Latitude of 1st shallowing waypoint.
    </Description><Units:degree/><Value>36.719</Value></DefineArg>

    <DefineArg Name="Wpt1aLon"><Description>
        Longitude of 1st shallowing waypoint.
    </Description><Units:degree/><Value>-121.9470</Value></DefineArg>

    <DefineArg Name="Wpt1bLat"><Description>
        Latitude of 2nd shallowing waypoint.
    </Description><Units:degree/><Value>36.719</Value></DefineArg>

    <DefineArg Name="Wpt1bLon"><Description>
        Longitude of 1st shallowing waypoint.
    </Description><Units:degree/><Value>-121.8845</Value></DefineArg>

    <DefineArg Name="Wpt2Lat"><Description>
        Latitude of shallow waypoint - GPS update only, no Iridium!
    </Description><Units:degree/><Value>36.719</Value></DefineArg>

    <DefineArg Name="Wpt2Lon"><Description>
        Longitude of shallow waypoint - GPS update only, no Iridium!
    </Description><Units:degree/><Value>-121.8366</Value></DefineArg>

    <DefineArg Name="Wpt2aLat"><Description>
        Latitude of 1st deepening waypoint.
    </Description><Units:degree/><Value>36.719</Value></DefineArg>

    <DefineArg Name="Wpt2aLon"><Description>
        Longitude of 1st deepening waypoint.
    </Description><Units:degree/><Value>-121.8527</Value></DefineArg>

    <DefineArg Name="Wpt2bLat"><Description>
        Latitude of 2nd deepening waypoint.
    </Description><Units:degree/><Value>36.719</Value></DefineArg>

    <DefineArg Name="Wpt2bLon"><Description>
        Longitude of 2nd deepening waypoint.
    </Description><Units:degree/><Value>-121.9304</Value></DefineArg>

<!-- The following arguments are output arguments -->

    <Timeout Duration="TimeoutDuration"/>

    <Guidance:AltitudeEnvelope RunIn="Parallel">
        <Setting><Guidance:AltitudeEnvelope.minAltitude/><Arg Name="MinAltitude"/></Setting>
    </Guidance:AltitudeEnvelope>

    <Guidance:DepthEnvelope RunIn="Parallel">
        <Setting><Guidance:DepthEnvelope.maxDepth/><Arg Name="MaxDepth"/></Setting>
    </Guidance:DepthEnvelope>

    <Guidance:Buoyancy RunIn="Parallel">
        <Setting><Guidance:Buoyancy.position /><Arg Name="BuoyancyNeutral"/></Setting>
    </Guidance:Buoyancy>

    <Guidance:Pitch RunIn="Parallel">
        <Setting><Guidance:Pitch.massPosition/><Arg Name="MassDefault"/></Setting>
    </Guidance:Pitch>

    <Insert Filename="Insert/Surface.xml" Id="SURFACECOMMS" />

    <Insert Filename="Insert/SurfaceNavUpdate.xml" Id="SURFACENAV" />

    <Guidance:OffshoreEnvelope RunIn="Parallel">
        <Setting><Guidance:OffshoreEnvelope.minOffshore/><Arg Name="MinOffshore"/></Setting>
        <Setting><Guidance:OffshoreEnvelope.maxOffshore/><Arg Name="MaxOffshore"/></Setting>
    </Guidance:OffshoreEnvelope>

<!-- GPS Update.  Don't go too long w/o a GPS fix. -->

    <Aggregate Id="NeedComms">

        <When><Universal:platform_pitch_angle/>
            <Gt><Units:degree/><Value>0</Value></Gt>
            <And>
            <Elapsed><Universal:platform_communications/></Elapsed><Gt><Arg Name="NeedCommsTime"/></Gt></And>
            <Or>
            <Elapsed><Universal:platform_communications/></Elapsed><Gt><Arg Name="NeedCommsTime"/><Add><Units:minute/><Value>20</Value></Add></Gt></Or>
        </When>

        <Call RefId="SURFACECOMMS"/>

    </Aggregate>

<!-- If we're executing here or below, lets' get science data! -->

    <Insert Filename="Insert/Science.xml">
        <RedefineArg Name="PeakDetectChlActive"><False/></RedefineArg>
    </Insert>

<!-- Get to start point  -->

    <Aggregate Id="TransitWpt1">

        <Guidance:Pitch RunIn="Parallel">
            <Setting><Guidance:Pitch.depth/><Arg Name="ApproachDepth"/></Setting>
        </Guidance:Pitch>

        <Guidance:SetSpeed RunIn="Parallel">
            <Setting><Guidance:SetSpeed.speed/><Arg Name="Speed"/></Setting>
        </Guidance:SetSpeed>

        <Guidance:Waypoint Id="Wpt1" RunIn="Sequence">
            <Setting><Guidance:Waypoint.latitude/><Arg Name="Wpt1Lat"/></Setting>
            <Setting><Guidance:Waypoint.longitude/><Arg Name="Wpt1Lon"/></Setting>
        </Guidance:Waypoint>

        <Call Id="PHONEHOMETransitWPT1" RefId="SURFACECOMMS"/>

    </Aggregate>

<!-- ****** Repeat the following loop in to Wpt2 and out to Wpt1 for up to 30 hours or 5 round trips  ****** -->

    <Aggregate Id="RepeatOps" Repeat="Repeat" >

<!-- ********************* Go to Wpt2  ********************* -->

        <Aggregate Id="YoYoWpt2">

            <Guidance:SetSpeed RunIn="Parallel">
                <Setting><Guidance:SetSpeed.speed/><Arg Name="Speed"/></Setting>
            </Guidance:SetSpeed>

            <Guidance:DepthEnvelope RunIn="Parallel">
                <Setting><Guidance:DepthEnvelope.minDepth/><Arg Name="YoYoMinDepth"/></Setting>
                <Setting><Guidance:DepthEnvelope.maxDepth/><Arg Name="YoYoMaxDepth"/></Setting>
                <Setting><Guidance:DepthEnvelope.downPitch/><Arg Name="YoYoDownPitch"/></Setting>
                <Setting><Guidance:DepthEnvelope.upPitch/><Arg Name="YoYoUpPitch"/></Setting>
            </Guidance:DepthEnvelope>

            <Guidance:AltitudeEnvelope RunIn="Parallel">
                <Setting><Guidance:AltitudeEnvelope.minAltitude/><Arg Name="YoYoMinAltitude"/></Setting>
                <Setting><Guidance:AltitudeEnvelope.upPitch/><Arg Name="YoYoUpPitch"/></Setting>
            </Guidance:AltitudeEnvelope>

            <Guidance:YoYo RunIn="Parallel">
                <Setting><Guidance:YoYo.downPitch/><Arg Name="YoYoDownPitch"/></Setting>
                <Setting><Guidance:YoYo.upPitch/><Arg Name="YoYoUpPitch"/></Setting>
            </Guidance:YoYo>

            <Guidance:Waypoint Id="Wpt1a" RunIn="Sequence">
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Wpt1aLat"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Wpt1aLon"/></Setting>
            </Guidance:Waypoint>

            <Call RefId="SURFACECOMMS"/>

            <Guidance:Waypoint Id="Wpt1b" RunIn="Sequence">
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Wpt1bLat"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Wpt1bLon"/></Setting>
            </Guidance:Waypoint>

            <Call RefId="SURFACECOMMS"/>

            <Guidance:Waypoint Id="Wpt2" RunIn="Sequence">
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Wpt2Lat"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Wpt2Lon"/></Setting>
            </Guidance:Waypoint>

            <Aggregate>

                <Timeout Duration="P5M"/>

                <Call Id="NavUpdateWPT2" RefId="SURFACENAV"/>

            </Aggregate>

        </Aggregate>

<!-- ********************* Go back to Wpt1  ********************* -->

        <Aggregate Id="BottomFollowWpt1">

<!-- Limit the time that ISUS is on -->

            <Aggregate>

                <Until><Universal:platform_pitch_angle/>
                    <Lt><Arg Name="NitrateOnPitch"/></Lt>
                    <Or><Universal:depth/><Lt><Arg Name="ScienceOnDepth"/></Lt></Or>
                </Until>

                <When><Universal:platform_pitch_angle/>
                    <Ge><Arg Name="NitrateOnPitch"/></Ge>
                    <And><Universal:depth/><Ge><Arg Name="ScienceOnDepth"/></Ge></And>
                </When>

                <ReadData>
                    <Universal:mole_concentration_of_nitrate_in_sea_water/>
                </ReadData>

                <Guidance:Wait RunIn="Sequence"/>

            </Aggregate>

            <Guidance:SetSpeed RunIn="Parallel">
                <Setting><Guidance:SetSpeed.speed/><Arg Name="Speed"/></Setting>
            </Guidance:SetSpeed>

            <Guidance:DepthEnvelope RunIn="Parallel">
                <Setting><Guidance:DepthEnvelope.maxDepth/><Arg Name="BottomFollowingMaxDepth"/></Setting>
                <Setting><Guidance:DepthEnvelope.pitch/><Units:degree/><Value>0.5</Value></Setting>
            </Guidance:DepthEnvelope>

            <Guidance:AltitudeEnvelope RunIn="Parallel">
                <Setting><Guidance:AltitudeEnvelope.minAltitude/><Arg Name="BottomFollowingMinAltitude"/></Setting>
                <Setting><Guidance:AltitudeEnvelope.upPitch/><Arg Name="YoYoUpPitch"/></Setting>
            </Guidance:AltitudeEnvelope>

            <Guidance:Pitch Id="LimitDeepDownPitch">
                <While><Control:VerticalControl.pitchCmd/>
                    <Lt><Units:degree/><Value>0</Value></Lt>
                    <And><Universal:depth/><Ge><Arg Name="BottomFollowingMaxDepth"/><Sub><Units:meter/><Value>1</Value></Sub></Ge></And>
                </While>
                <Setting><Guidance:Pitch.pitch/><Units:degree/><Value>-0.5</Value></Setting>
            </Guidance:Pitch>

            <Guidance:Pitch Id="LimitDownPitch">
                <While><Control:VerticalControl.pitchCmd/>
                    <Lt><Arg Name="YoYoDownPitch"/></Lt>
                </While>
                <Setting><Guidance:Pitch.pitch/><Arg Name="YoYoDownPitch"></Arg></Setting>
            </Guidance:Pitch>

            <Guidance:PitchServo RunIn="Parallel">
                <Setting>
                <Guidance:PitchServo.uri/><Universal:height_above_sea_floor/></Setting>
                <Setting><Guidance:PitchServo.holdValue/><Arg Name="BottomFollowingAltitude"/></Setting>
                <Setting><Guidance:PitchServo.proportionalGain/><Arg Name="kpAltitude"/></Setting>
                <Setting><Guidance:PitchServo.integralGain/><Arg Name="kiAltitude"/></Setting>
                <Setting><Guidance:PitchServo.derivativeGain/><Arg Name="kdAltitude"/></Setting>
                <Setting><Guidance:PitchServo.changePerMeter/><Units:meter/><Value>-1</Value></Setting>
            </Guidance:PitchServo>

            <Guidance:Waypoint Id="Wpt2a" RunIn="Sequence">
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Wpt2aLat"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Wpt2aLon"/></Setting>
            </Guidance:Waypoint>

            <Call RefId="SURFACECOMMS"/>

            <Guidance:Waypoint Id="Wpt2b" RunIn="Sequence">
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Wpt2bLat"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Wpt2bLon"/></Setting>
            </Guidance:Waypoint>

            <Call RefId="SURFACECOMMS"/>

            <Guidance:Waypoint Id="Wpt1" RunIn="Sequence">
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Wpt1Lat"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Wpt1Lon"/></Setting>
            </Guidance:Waypoint>

            <Call Id="PHONEHOMEYoYoWPT1" RefId="SURFACECOMMS"/>

        </Aggregate>

    </Aggregate>

</Mission>


2012-05-07T23:21:51.407Z,1336432911.407 [CommandLine](IMPORTANT): Loaded ./Missions/Science/turbulence.xml
2012-05-07T23:21:53.671Z,1336432913.671 [CommandLine](IMPORTANT): got command set turbulence.YoYoMinAltitude 8.000000 meter
2012-05-07T23:21:53.671Z,1336432913.671 [CommandLine](IMPORTANT): got command set turbulence.BottomFollowingAltitude 8.000000 meter
2012-05-07T23:21:53.671Z,1336432913.671 [CommandLine](IMPORTANT): got command set turbulence.MinAltitude 7.000000 meter
2012-05-07T23:21:53.671Z,1336432913.671 [CommandLine](IMPORTANT): got command set turbulence.BottomFollowingMinAltitude 7.000000 meter
2012-05-07T23:21:53.671Z,1336432913.671 [CommandLine](IMPORTANT): got command set turbulence.BottomFollowingMaxDepth 60.000000 meter
2012-05-07T23:21:53.671Z,1336432913.671 [CommandLine](IMPORTANT): got command set turbulence.YoYoMaxDepth 60.000000 meter
2012-05-07T23:21:53.671Z,1336432913.671 [CommandLine](IMPORTANT): got command set turbulence.MaxDepth 80.000000 meter
2012-05-07T23:21:53.671Z,1336432913.671 [CommandLine](IMPORTANT): got command set turbulence.Wpt1Lon -121.974998 degree
2012-05-07T23:21:55.983Z,1336432915.983 [CommandLine](IMPORTANT): got command run
2012-05-07T23:21:55.983Z,1336432915.983 [CommandLine](IMPORTANT): Running 
2012-05-07T23:22:00.905Z,1336432920.905 [NAL9601](IMPORTANT): GPS fix at: 1336432920.90
2012-05-07T23:22:06.905Z,1336432926.905 [NAL9601](INFO): Powering down
2012-05-07T23:22:06.908Z,1336432926.908 [SBIT](IMPORTANT): Beginning Startup BIT
2012-05-07T23:22:48.967Z,1336432968.967 [SBIT](IMPORTANT): SBIT PASSED
2012-05-07T23:22:49.312Z,1336432969.312 [MissionManager](IMPORTANT): Started mission Startup
2012-05-07T23:22:49.312Z,1336432969.312 [Startup] Running Loop=1
2012-05-07T23:22:49.312Z,1336432969.312 [Startup](INFO): Aggregate::initialize Startup
2012-05-07T23:22:49.312Z,1336432969.312 [Startup:A.GoToSurface] Running Loop=1
2012-05-07T23:22:49.312Z,1336432969.312 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-07T23:22:49.312Z,1336432969.312 [Startup:StartupSatComms] Running Loop=1
2012-05-07T23:22:49.312Z,1336432969.312 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2012-05-07T23:22:49.312Z,1336432969.312 [Startup:StartupSatComms:A] Running Loop=1
2012-05-07T23:22:49.708Z,1336432969.708 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-05-07T23:22:50.105Z,1336432970.105 [NAL9601](INFO): Powering up
2012-05-07T23:22:55.305Z,1336432975.305 [NAL9601](IMPORTANT): GPS fix at: 1336432975.30
2012-05-07T23:22:55.305Z,1336432975.305 [Startup:StartupSatComms:A] Stopped
2012-05-07T23:22:55.305Z,1336432975.305 [Startup:StartupSatComms:B] Running Loop=1
2012-05-07T23:22:55.710Z,1336432975.710 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-05-07T23:22:58.105Z,1336432978.105 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T231404/shore0008.lzma
2012-05-07T23:22:58.105Z,1336432978.105 [NAL9601](INFO): Packets left to send: 6
2012-05-07T23:22:58.105Z,1336432978.105 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000000
2012-05-07T23:22:59.305Z,1336432979.305 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T231404/shore0008.lzma
2012-05-07T23:22:59.305Z,1336432979.305 [NAL9601](INFO): Packets left to send: 5
2012-05-07T23:22:59.305Z,1336432979.305 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000001
2012-05-07T23:23:00.505Z,1336432980.505 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T231404/shore0008.lzma
2012-05-07T23:23:00.505Z,1336432980.505 [NAL9601](INFO): Packets left to send: 4
2012-05-07T23:23:00.505Z,1336432980.505 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000002
2012-05-07T23:23:01.705Z,1336432981.705 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T231404/shore0008.lzma
2012-05-07T23:23:01.705Z,1336432981.705 [NAL9601](INFO): Packets left to send: 3
2012-05-07T23:23:01.705Z,1336432981.705 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000003
2012-05-07T23:23:02.905Z,1336432982.905 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T231404/shore0008.lzma
2012-05-07T23:23:02.905Z,1336432982.905 [NAL9601](INFO): Packets left to send: 2
2012-05-07T23:23:02.905Z,1336432982.905 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000004
2012-05-07T23:23:04.105Z,1336432984.105 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T231404/shore0008.lzma
2012-05-07T23:23:04.105Z,1336432984.105 [NAL9601](INFO): Packets left to send: 1
2012-05-07T23:23:04.105Z,1336432984.105 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000005
2012-05-07T23:23:05.305Z,1336432985.305 [NAL9601](INFO): Sent 149 bytes from file Logs/20120507T231404/shore0008.lzma
2012-05-07T23:23:05.305Z,1336432985.305 [NAL9601](INFO): Packets left to send: 0
2012-05-07T23:23:05.305Z,1336432985.305 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000006
2012-05-07T23:23:06.505Z,1336432986.505 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0000.lzma
2012-05-07T23:23:06.505Z,1336432986.505 [NAL9601](INFO): Packets left to send: 2
2012-05-07T23:23:06.505Z,1336432986.505 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000007
2012-05-07T23:23:07.705Z,1336432987.705 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0000.lzma
2012-05-07T23:23:07.705Z,1336432987.705 [NAL9601](INFO): Packets left to send: 1
2012-05-07T23:23:07.705Z,1336432987.705 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000008
2012-05-07T23:23:08.905Z,1336432988.905 [NAL9601](INFO): Sent 32 bytes from file Logs/20120507T232151/shore0000.lzma
2012-05-07T23:23:08.905Z,1336432988.905 [NAL9601](INFO): Packets left to send: 0
2012-05-07T23:23:08.905Z,1336432988.905 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000009
2012-05-07T23:23:09.707Z,1336432989.707 [Startup:StartupSatComms:B] Stopped
2012-05-07T23:23:09.707Z,1336432989.707 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2012-05-07T23:23:09.707Z,1336432989.707 [Startup:StartupSatComms] Stopped
2012-05-07T23:23:09.707Z,1336432989.707 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2012-05-07T23:23:09.707Z,1336432989.707 [Startup](INFO): Completed Startup
2012-05-07T23:23:09.707Z,1336432989.707 [Startup] Stopped
2012-05-07T23:23:09.707Z,1336432989.707 [Startup](INFO): Aggregate::uninitialize Startup
2012-05-07T23:23:09.707Z,1336432989.707 [Startup:A.GoToSurface] Stopped
2012-05-07T23:23:09.707Z,1336432989.707 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-07T23:23:10.105Z,1336432990.105 [MissionManager](IMPORTANT): Started mission turbulence
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence] Running Loop=1
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence](INFO): Aggregate::initialize turbulence
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:A.AltitudeEnvelope] Running Loop=1
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:B.DepthEnvelope] Running Loop=1
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:C.Buoyancy] Running Loop=1
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:C.Buoyancy](DEBUG): Initialize Buoyancy Component.
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:D.Pitch] Running Loop=1
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:D.Pitch](DEBUG): Initialize.
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:G.OffshoreEnvelope] Running Loop=1
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:G.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:Science] Running Loop=1
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:Science](INFO): Aggregate::initialize turbulence:Science
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:Science:A] Running Loop=1
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:Science:B] Running Loop=1
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:Science:C] Running Loop=1
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:Science:D] Running Loop=1
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:SURFACECOMMS] Running Loop=1
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:SURFACECOMMS](INFO): Aggregate::initialize turbulence:SURFACECOMMS
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:Science] Running Loop=1
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:Science:D] Running Loop=1
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:Science:D](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:Science:C] Running Loop=1
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:Science:A] Running Loop=1
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:Science:A](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_oxygen_in_sea_water
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:G.OffshoreEnvelope] Running Loop=1
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:SURFACECOMMS:B] Running Loop=1
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:SURFACECOMMS:B](INFO): Aggregate::initialize turbulence:SURFACECOMMS:B
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:SURFACECOMMS:B:A] Running Loop=1
2012-05-07T23:23:10.105Z,1336432990.105 [turbulence:D.Pitch] Running Loop=1
2012-05-07T23:23:10.106Z,1336432990.106 [turbulence:C.Buoyancy] Running Loop=1
2012-05-07T23:23:10.106Z,1336432990.106 [turbulence:B.DepthEnvelope] Running Loop=1
2012-05-07T23:23:10.106Z,1336432990.106 [turbulence:A.AltitudeEnvelope] Running Loop=1
2012-05-07T23:23:10.505Z,1336432990.505 [turbulence:Science] Preempted
2012-05-07T23:23:10.505Z,1336432990.505 [turbulence:Science:A] Preempted
2012-05-07T23:23:10.505Z,1336432990.505 [turbulence:Science:C] Preempted
2012-05-07T23:23:10.505Z,1336432990.505 [turbulence:Science:D] Preempted
2012-05-07T23:23:10.505Z,1336432990.505 [turbulence:G.OffshoreEnvelope] Preempted
2012-05-07T23:23:10.505Z,1336432990.505 [turbulence:SURFACECOMMS:B:A](DEBUG): Initialize ReadDataComponent to sense time_fix
2012-05-07T23:23:14.905Z,1336432994.905 [NAL9601](IMPORTANT): GPS fix at: 1336432994.90
2012-05-07T23:23:14.905Z,1336432994.905 [turbulence:SURFACECOMMS:B:A] Stopped
2012-05-07T23:23:14.905Z,1336432994.905 [turbulence:SURFACECOMMS:B:B] Running Loop=1
2012-05-07T23:23:15.306Z,1336432995.306 [turbulence:SURFACECOMMS:B:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-05-07T23:23:17.705Z,1336432997.705 [NAL9601](INFO): Sent 281 bytes from file Logs/20120507T232151/shore0001.lzma
2012-05-07T23:23:17.705Z,1336432997.705 [NAL9601](INFO): Packets left to send: 0
2012-05-07T23:23:17.705Z,1336432997.705 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000010
2012-05-07T23:23:18.505Z,1336432998.505 [turbulence:SURFACECOMMS:B:B] Stopped
2012-05-07T23:23:18.505Z,1336432998.505 [turbulence:SURFACECOMMS:B:C] Running Loop=1
2012-05-07T23:23:18.905Z,1336432998.905 [turbulence:SURFACECOMMS:B:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2012-05-07T23:23:23.705Z,1336433003.705 [NAL9601](IMPORTANT): GPS fix at: 1336433003.70
2012-05-07T23:23:23.706Z,1336433003.706 [turbulence:SURFACECOMMS:B:C] Stopped
2012-05-07T23:23:23.706Z,1336433003.706 [turbulence:SURFACECOMMS:B](INFO): Completed turbulence:SURFACECOMMS:B
2012-05-07T23:23:23.706Z,1336433003.706 [turbulence:SURFACECOMMS:B] Stopped
2012-05-07T23:23:23.706Z,1336433003.706 [turbulence:SURFACECOMMS:B](INFO): Aggregate::uninitialize turbulence:SURFACECOMMS:B
2012-05-07T23:23:23.706Z,1336433003.706 [turbulence:SURFACECOMMS](INFO): Completed turbulence:SURFACECOMMS
2012-05-07T23:23:23.706Z,1336433003.706 [turbulence:SURFACECOMMS] Stopped
2012-05-07T23:23:23.706Z,1336433003.706 [turbulence:SURFACECOMMS](INFO): Aggregate::uninitialize turbulence:SURFACECOMMS
2012-05-07T23:23:23.706Z,1336433003.706 [turbulence:SURFACECOMMS:A.GoToSurface] Stopped
2012-05-07T23:23:23.706Z,1336433003.706 [turbulence:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-07T23:23:23.706Z,1336433003.706 [turbulence:SURFACENAV] Running Loop=1
2012-05-07T23:23:23.706Z,1336433003.706 [turbulence:SURFACENAV](INFO): Aggregate::initialize turbulence:SURFACENAV
2012-05-07T23:23:23.706Z,1336433003.706 [turbulence:SURFACENAV:A.GoToSurface] Running Loop=1
2012-05-07T23:23:23.706Z,1336433003.706 [turbulence:SURFACENAV:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-07T23:23:24.105Z,1336433004.105 [turbulence:Science] Running Loop=1
2012-05-07T23:23:24.105Z,1336433004.105 [turbulence:Science:D] Running Loop=1
2012-05-07T23:23:24.105Z,1336433004.105 [turbulence:Science:C] Running Loop=1
2012-05-07T23:23:24.105Z,1336433004.105 [turbulence:Science:A] Running Loop=1
2012-05-07T23:23:24.105Z,1336433004.105 [turbulence:G.OffshoreEnvelope] Running Loop=1
2012-05-07T23:23:24.105Z,1336433004.105 [turbulence:SURFACENAV:B] Running Loop=1
2012-05-07T23:23:24.105Z,1336433004.105 [turbulence:SURFACENAV:B](INFO): Aggregate::initialize turbulence:SURFACENAV:B
2012-05-07T23:23:24.105Z,1336433004.105 [turbulence:SURFACENAV:B:A] Running Loop=1
2012-05-07T23:23:24.505Z,1336433004.505 [turbulence:SURFACENAV:B:A](DEBUG): Initialize ReadDataComponent to sense time_fix
2012-05-07T23:23:29.305Z,1336433009.305 [NAL9601](IMPORTANT): GPS fix at: 1336433009.30
2012-05-07T23:23:29.305Z,1336433009.305 [turbulence:SURFACENAV:B:A] Stopped
2012-05-07T23:23:29.305Z,1336433009.305 [turbulence:SURFACENAV:B](INFO): Completed turbulence:SURFACENAV:B
2012-05-07T23:23:29.305Z,1336433009.305 [turbulence:SURFACENAV:B] Stopped
2012-05-07T23:23:29.305Z,1336433009.305 [turbulence:SURFACENAV:B](INFO): Aggregate::uninitialize turbulence:SURFACENAV:B
2012-05-07T23:23:29.305Z,1336433009.305 [turbulence:SURFACENAV:B](INFO): Running loop #2
2012-05-07T23:23:29.305Z,1336433009.305 [turbulence:SURFACENAV:B] Running Loop=2
2012-05-07T23:23:29.305Z,1336433009.305 [turbulence:SURFACENAV:B](INFO): Aggregate::initialize turbulence:SURFACENAV:B
2012-05-07T23:23:29.305Z,1336433009.305 [turbulence:SURFACENAV:B:A] Running Loop=1
2012-05-07T23:23:33.705Z,1336433013.705 [NAL9601](IMPORTANT): GPS fix at: 1336433013.70
2012-05-07T23:23:33.705Z,1336433013.705 [turbulence:SURFACENAV:B:A] Stopped
2012-05-07T23:23:33.705Z,1336433013.705 [turbulence:SURFACENAV:B](INFO): Completed turbulence:SURFACENAV:B
2012-05-07T23:23:33.705Z,1336433013.705 [turbulence:SURFACENAV:B] Stopped
2012-05-07T23:23:33.705Z,1336433013.705 [turbulence:SURFACENAV:B](INFO): Aggregate::uninitialize turbulence:SURFACENAV:B
2012-05-07T23:23:33.705Z,1336433013.705 [turbulence:SURFACENAV](INFO): Completed turbulence:SURFACENAV
2012-05-07T23:23:33.705Z,1336433013.705 [turbulence:SURFACENAV] Stopped
2012-05-07T23:23:33.705Z,1336433013.705 [turbulence:SURFACENAV](INFO): Aggregate::uninitialize turbulence:SURFACENAV
2012-05-07T23:23:33.705Z,1336433013.705 [turbulence:SURFACENAV:A.GoToSurface] Stopped
2012-05-07T23:23:33.705Z,1336433013.705 [turbulence:SURFACENAV:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-07T23:23:33.705Z,1336433013.705 [turbulence:TransitWpt1] Running Loop=1
2012-05-07T23:23:33.705Z,1336433013.705 [turbulence:TransitWpt1](INFO): Aggregate::initialize turbulence:TransitWpt1
2012-05-07T23:23:33.705Z,1336433013.705 [turbulence:TransitWpt1:A.Pitch] Running Loop=1
2012-05-07T23:23:33.705Z,1336433013.705 [turbulence:TransitWpt1:A.Pitch](DEBUG): Initialize.
2012-05-07T23:23:33.705Z,1336433013.705 [turbulence:TransitWpt1:B.SetSpeed] Running Loop=1
2012-05-07T23:23:33.705Z,1336433013.705 [turbulence:TransitWpt1:B.SetSpeed](DEBUG): Initialize.
2012-05-07T23:23:33.705Z,1336433013.705 [turbulence:TransitWpt1:Wpt1.Waypoint] Running Loop=1
2012-05-07T23:23:33.705Z,1336433013.705 [turbulence:TransitWpt1:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2012-05-07T23:23:34.105Z,1336433014.105 [turbulence:TransitWpt1:B.SetSpeed] Running Loop=1
2012-05-07T23:23:34.105Z,1336433014.105 [turbulence:TransitWpt1:A.Pitch] Running Loop=1
2012-05-07T23:23:39.705Z,1336433019.705 [NAL9601](INFO): Powering down
2012-05-08T02:23:18.511Z,1336443798.511 [turbulence:NeedComms] Running Loop=1
2012-05-08T02:23:18.511Z,1336443798.511 [turbulence:NeedComms](INFO): Aggregate::initialize turbulence:NeedComms
2012-05-08T02:23:18.511Z,1336443798.511 [turbulence:NeedComms:A] Running Loop=1
2012-05-08T02:23:18.511Z,1336443798.511 [turbulence:NeedComms:A](INFO): Aggregate::initialize turbulence:NeedComms:A
2012-05-08T02:23:18.906Z,1336443798.906 [turbulence:SURFACECOMMS] Running Loop=1
2012-05-08T02:23:18.906Z,1336443798.906 [turbulence:SURFACECOMMS](INFO): Aggregate::initialize turbulence:SURFACECOMMS
2012-05-08T02:23:18.906Z,1336443798.906 [turbulence:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-05-08T02:23:18.906Z,1336443798.906 [turbulence:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-08T02:25:02.905Z,1336443902.905 [turbulence:SURFACECOMMS:B] Running Loop=1
2012-05-08T02:25:02.905Z,1336443902.905 [turbulence:SURFACECOMMS:B](INFO): Aggregate::initialize turbulence:SURFACECOMMS:B
2012-05-08T02:25:02.905Z,1336443902.905 [turbulence:SURFACECOMMS:B:A] Running Loop=1
2012-05-08T02:25:03.305Z,1336443903.305 [turbulence:Science] Preempted
2012-05-08T02:25:03.305Z,1336443903.305 [turbulence:Science:A] Preempted
2012-05-08T02:25:03.305Z,1336443903.305 [turbulence:Science:C] Preempted
2012-05-08T02:25:03.305Z,1336443903.305 [turbulence:Science:D] Preempted
2012-05-08T02:25:03.305Z,1336443903.305 [turbulence:G.OffshoreEnvelope] Preempted
2012-05-08T02:25:03.705Z,1336443903.705 [NAL9601](INFO): Powering up
2012-05-08T02:25:08.905Z,1336443908.905 [NAL9601](IMPORTANT): GPS fix at: 1336443908.90
2012-05-08T02:25:08.905Z,1336443908.905 [Navigation](IMPORTANT): Navigation Error 0.0 %DT

2012-05-08T02:25:08.905Z,1336443908.905 [turbulence:SURFACECOMMS:B:A] Stopped
2012-05-08T02:25:08.905Z,1336443908.905 [turbulence:SURFACECOMMS:B:B] Running Loop=1
2012-05-08T02:25:11.705Z,1336443911.705 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0002.lzma
2012-05-08T02:25:11.705Z,1336443911.705 [NAL9601](INFO): Packets left to send: 5
2012-05-08T02:25:11.705Z,1336443911.705 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000011
2012-05-08T02:25:12.905Z,1336443912.905 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0002.lzma
2012-05-08T02:25:12.905Z,1336443912.905 [NAL9601](INFO): Packets left to send: 4
2012-05-08T02:25:12.905Z,1336443912.905 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000012
2012-05-08T02:25:14.105Z,1336443914.105 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0002.lzma
2012-05-08T02:25:14.105Z,1336443914.105 [NAL9601](INFO): Packets left to send: 3
2012-05-08T02:25:14.105Z,1336443914.105 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000013
2012-05-08T02:25:15.305Z,1336443915.305 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0002.lzma
2012-05-08T02:25:15.305Z,1336443915.305 [NAL9601](INFO): Packets left to send: 2
2012-05-08T02:25:15.305Z,1336443915.305 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000014
2012-05-08T02:25:16.505Z,1336443916.505 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0002.lzma
2012-05-08T02:25:16.505Z,1336443916.505 [NAL9601](INFO): Packets left to send: 1
2012-05-08T02:25:16.505Z,1336443916.505 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000015
2012-05-08T02:25:17.705Z,1336443917.705 [NAL9601](INFO): Sent 151 bytes from file Logs/20120507T232151/shore0002.lzma
2012-05-08T02:25:17.705Z,1336443917.705 [NAL9601](INFO): Packets left to send: 0
2012-05-08T02:25:17.705Z,1336443917.705 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000016
2012-05-08T02:25:18.505Z,1336443918.505 [turbulence:SURFACECOMMS:B:B] Stopped
2012-05-08T02:25:18.505Z,1336443918.505 [turbulence:SURFACECOMMS:B:C] Running Loop=1
2012-05-08T02:25:23.705Z,1336443923.705 [NAL9601](IMPORTANT): GPS fix at: 1336443923.70
2012-05-08T02:25:23.705Z,1336443923.705 [turbulence:SURFACECOMMS:B:C] Stopped
2012-05-08T02:25:23.705Z,1336443923.705 [turbulence:SURFACECOMMS:B](INFO): Completed turbulence:SURFACECOMMS:B
2012-05-08T02:25:23.705Z,1336443923.705 [turbulence:SURFACECOMMS:B] Stopped
2012-05-08T02:25:23.705Z,1336443923.705 [turbulence:SURFACECOMMS:B](INFO): Aggregate::uninitialize turbulence:SURFACECOMMS:B
2012-05-08T02:25:23.705Z,1336443923.705 [turbulence:SURFACECOMMS](INFO): Completed turbulence:SURFACECOMMS
2012-05-08T02:25:23.705Z,1336443923.705 [turbulence:SURFACECOMMS] Stopped
2012-05-08T02:25:23.705Z,1336443923.705 [turbulence:SURFACECOMMS](INFO): Aggregate::uninitialize turbulence:SURFACECOMMS
2012-05-08T02:25:23.705Z,1336443923.705 [turbulence:SURFACECOMMS:A.GoToSurface] Stopped
2012-05-08T02:25:23.705Z,1336443923.705 [turbulence:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-08T02:25:24.105Z,1336443924.105 [turbulence:Science] Running Loop=1
2012-05-08T02:25:24.105Z,1336443924.105 [turbulence:Science:D] Running Loop=1
2012-05-08T02:25:24.105Z,1336443924.105 [turbulence:Science:C] Running Loop=1
2012-05-08T02:25:24.105Z,1336443924.105 [turbulence:Science:A] Running Loop=1
2012-05-08T02:25:24.105Z,1336443924.105 [turbulence:NeedComms:A](INFO): Completed turbulence:NeedComms:A
2012-05-08T02:25:24.105Z,1336443924.105 [turbulence:NeedComms:A] Stopped
2012-05-08T02:25:24.105Z,1336443924.105 [turbulence:NeedComms:A](INFO): Aggregate::uninitialize turbulence:NeedComms:A
2012-05-08T02:25:24.105Z,1336443924.105 [turbulence:NeedComms](INFO): Completed turbulence:NeedComms
2012-05-08T02:25:24.105Z,1336443924.105 [turbulence:NeedComms] Stopped
2012-05-08T02:25:24.105Z,1336443924.105 [turbulence:NeedComms](INFO): Aggregate::uninitialize turbulence:NeedComms
2012-05-08T02:25:24.105Z,1336443924.105 [turbulence:G.OffshoreEnvelope] Running Loop=1
2012-05-08T02:25:29.705Z,1336443929.705 [NAL9601](INFO): Powering down
2012-05-08T03:46:32.905Z,1336448792.905 [turbulence:TransitWpt1:Wpt1.Waypoint](INFO): Reached Waypoint: 36.719000,-121.974994
2012-05-08T03:46:32.905Z,1336448792.905 [turbulence:TransitWpt1:Wpt1.Waypoint] Stopped
2012-05-08T03:46:32.905Z,1336448792.905 [turbulence:TransitWpt1:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-05-08T03:46:32.905Z,1336448792.905 [turbulence:TransitWpt1:PHONEHOMETransitWPT1] Running Loop=1
2012-05-08T03:46:32.905Z,1336448792.905 [turbulence:TransitWpt1:PHONEHOMETransitWPT1](INFO): Aggregate::initialize turbulence:TransitWpt1:PHONEHOMETransitWPT1
2012-05-08T03:46:33.305Z,1336448793.305 [turbulence:SURFACECOMMS] Running Loop=1
2012-05-08T03:46:33.305Z,1336448793.305 [turbulence:SURFACECOMMS](INFO): Aggregate::initialize turbulence:SURFACECOMMS
2012-05-08T03:46:33.305Z,1336448793.305 [turbulence:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-05-08T03:46:33.305Z,1336448793.305 [turbulence:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-08T03:48:17.305Z,1336448897.305 [turbulence:SURFACECOMMS:B] Running Loop=1
2012-05-08T03:48:17.305Z,1336448897.305 [turbulence:SURFACECOMMS:B](INFO): Aggregate::initialize turbulence:SURFACECOMMS:B
2012-05-08T03:48:17.305Z,1336448897.305 [turbulence:SURFACECOMMS:B:A] Running Loop=1
2012-05-08T03:48:17.705Z,1336448897.705 [turbulence:Science] Preempted
2012-05-08T03:48:17.705Z,1336448897.705 [turbulence:Science:A] Preempted
2012-05-08T03:48:17.705Z,1336448897.705 [turbulence:Science:C] Preempted
2012-05-08T03:48:17.705Z,1336448897.705 [turbulence:Science:D] Preempted
2012-05-08T03:48:17.705Z,1336448897.705 [turbulence:G.OffshoreEnvelope] Preempted
2012-05-08T03:48:18.105Z,1336448898.105 [NAL9601](INFO): Powering up
2012-05-08T03:48:23.305Z,1336448903.305 [NAL9601](IMPORTANT): GPS fix at: 1336448903.30
2012-05-08T03:48:23.305Z,1336448903.305 [Navigation](IMPORTANT): Navigation Error 0.0 %DT

2012-05-08T03:48:23.305Z,1336448903.305 [turbulence:SURFACECOMMS:B:A] Stopped
2012-05-08T03:48:23.305Z,1336448903.305 [turbulence:SURFACECOMMS:B:B] Running Loop=1
2012-05-08T03:48:26.105Z,1336448906.105 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0003.lzma
2012-05-08T03:48:26.105Z,1336448906.105 [NAL9601](INFO): Packets left to send: 1
2012-05-08T03:48:26.105Z,1336448906.105 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000017
2012-05-08T03:48:27.305Z,1336448907.305 [NAL9601](INFO): Sent 315 bytes from file Logs/20120507T232151/shore0003.lzma
2012-05-08T03:48:27.305Z,1336448907.305 [NAL9601](INFO): Packets left to send: 0
2012-05-08T03:48:27.305Z,1336448907.305 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000018
2012-05-08T03:48:28.105Z,1336448908.105 [turbulence:SURFACECOMMS:B:B] Stopped
2012-05-08T03:48:28.105Z,1336448908.105 [turbulence:SURFACECOMMS:B:C] Running Loop=1
2012-05-08T03:48:33.305Z,1336448913.305 [NAL9601](IMPORTANT): GPS fix at: 1336448913.30
2012-05-08T03:48:33.305Z,1336448913.305 [turbulence:SURFACECOMMS:B:C] Stopped
2012-05-08T03:48:33.305Z,1336448913.305 [turbulence:SURFACECOMMS:B](INFO): Completed turbulence:SURFACECOMMS:B
2012-05-08T03:48:33.305Z,1336448913.305 [turbulence:SURFACECOMMS:B] Stopped
2012-05-08T03:48:33.305Z,1336448913.305 [turbulence:SURFACECOMMS:B](INFO): Aggregate::uninitialize turbulence:SURFACECOMMS:B
2012-05-08T03:48:33.305Z,1336448913.305 [turbulence:SURFACECOMMS](INFO): Completed turbulence:SURFACECOMMS
2012-05-08T03:48:33.305Z,1336448913.305 [turbulence:SURFACECOMMS] Stopped
2012-05-08T03:48:33.305Z,1336448913.305 [turbulence:SURFACECOMMS](INFO): Aggregate::uninitialize turbulence:SURFACECOMMS
2012-05-08T03:48:33.305Z,1336448913.305 [turbulence:SURFACECOMMS:A.GoToSurface] Stopped
2012-05-08T03:48:33.305Z,1336448913.305 [turbulence:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-08T03:48:33.706Z,1336448913.706 [turbulence:TransitWpt1:PHONEHOMETransitWPT1](INFO): Completed turbulence:TransitWpt1:PHONEHOMETransitWPT1
2012-05-08T03:48:33.706Z,1336448913.706 [turbulence:TransitWpt1:PHONEHOMETransitWPT1] Stopped
2012-05-08T03:48:33.706Z,1336448913.706 [turbulence:TransitWpt1:PHONEHOMETransitWPT1](INFO): Aggregate::uninitialize turbulence:TransitWpt1:PHONEHOMETransitWPT1
2012-05-08T03:48:33.706Z,1336448913.706 [turbulence:TransitWpt1](INFO): Completed turbulence:TransitWpt1
2012-05-08T03:48:33.706Z,1336448913.706 [turbulence:TransitWpt1] Stopped
2012-05-08T03:48:33.706Z,1336448913.706 [turbulence:TransitWpt1](INFO): Aggregate::uninitialize turbulence:TransitWpt1
2012-05-08T03:48:33.706Z,1336448913.706 [turbulence:TransitWpt1:A.Pitch] Stopped
2012-05-08T03:48:33.706Z,1336448913.706 [turbulence:TransitWpt1:B.SetSpeed] Stopped
2012-05-08T03:48:33.706Z,1336448913.706 [turbulence:TransitWpt1:B.SetSpeed](DEBUG): Uninitialize.
2012-05-08T03:48:33.706Z,1336448913.706 [turbulence:RepeatOps] Running Loop=1
2012-05-08T03:48:33.706Z,1336448913.706 [turbulence:RepeatOps](INFO): Aggregate::initialize turbulence:RepeatOps
2012-05-08T03:48:33.706Z,1336448913.706 [turbulence:RepeatOps:YoYoWpt2] Running Loop=1
2012-05-08T03:48:33.706Z,1336448913.706 [turbulence:RepeatOps:YoYoWpt2](INFO): Aggregate::initialize turbulence:RepeatOps:YoYoWpt2
2012-05-08T03:48:33.706Z,1336448913.706 [turbulence:RepeatOps:YoYoWpt2:A.SetSpeed] Running Loop=1
2012-05-08T03:48:33.706Z,1336448913.706 [turbulence:RepeatOps:YoYoWpt2:A.SetSpeed](DEBUG): Initialize.
2012-05-08T03:48:33.706Z,1336448913.706 [turbulence:RepeatOps:YoYoWpt2:B.DepthEnvelope] Running Loop=1
2012-05-08T03:48:33.706Z,1336448913.706 [turbulence:RepeatOps:YoYoWpt2:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2012-05-08T03:48:33.706Z,1336448913.706 [turbulence:RepeatOps:YoYoWpt2:C.AltitudeEnvelope] Running Loop=1
2012-05-08T03:48:33.706Z,1336448913.706 [turbulence:RepeatOps:YoYoWpt2:C.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2012-05-08T03:48:33.706Z,1336448913.706 [turbulence:RepeatOps:YoYoWpt2:D.YoYo] Running Loop=1
2012-05-08T03:48:33.706Z,1336448913.706 [turbulence:RepeatOps:YoYoWpt2:D.YoYo](DEBUG): Initialize YoYoComponent.
2012-05-08T03:48:33.706Z,1336448913.706 [turbulence:RepeatOps:YoYoWpt2:Wpt1a.Waypoint] Running Loop=1
2012-05-08T03:48:33.706Z,1336448913.706 [turbulence:RepeatOps:YoYoWpt2:Wpt1a.Waypoint](DEBUG): Initialize WaypointComponent.
2012-05-08T03:48:33.706Z,1336448913.706 [turbulence:Science] Running Loop=1
2012-05-08T03:48:33.706Z,1336448913.706 [turbulence:Science:D] Running Loop=1
2012-05-08T03:48:33.706Z,1336448913.706 [turbulence:Science:C] Running Loop=1
2012-05-08T03:48:33.706Z,1336448913.706 [turbulence:Science:A] Running Loop=1
2012-05-08T03:48:33.706Z,1336448913.706 [turbulence:G.OffshoreEnvelope] Running Loop=1
2012-05-08T03:48:34.105Z,1336448914.105 [turbulence:RepeatOps:YoYoWpt2:D.YoYo] Running Loop=1
2012-05-08T03:48:34.105Z,1336448914.105 [turbulence:RepeatOps:YoYoWpt2:C.AltitudeEnvelope] Running Loop=1
2012-05-08T03:48:34.105Z,1336448914.105 [turbulence:RepeatOps:YoYoWpt2:B.DepthEnvelope] Running Loop=1
2012-05-08T03:48:34.105Z,1336448914.105 [turbulence:RepeatOps:YoYoWpt2:A.SetSpeed] Running Loop=1
2012-05-08T03:48:39.305Z,1336448919.305 [NAL9601](INFO): Powering down
2012-05-08T04:35:00.105Z,1336451700.105 [turbulence:RepeatOps:YoYoWpt2:Wpt1a.Waypoint](INFO): Reached Waypoint: 36.719000,-121.947000
2012-05-08T04:35:00.105Z,1336451700.105 [turbulence:RepeatOps:YoYoWpt2:Wpt1a.Waypoint] Stopped
2012-05-08T04:35:00.105Z,1336451700.105 [turbulence:RepeatOps:YoYoWpt2:Wpt1a.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-05-08T04:35:00.105Z,1336451700.105 [turbulence:RepeatOps:YoYoWpt2:F] Running Loop=1
2012-05-08T04:35:00.105Z,1336451700.105 [turbulence:RepeatOps:YoYoWpt2:F](INFO): Aggregate::initialize turbulence:RepeatOps:YoYoWpt2:F
2012-05-08T04:35:00.505Z,1336451700.505 [turbulence:SURFACECOMMS] Running Loop=1
2012-05-08T04:35:00.505Z,1336451700.505 [turbulence:SURFACECOMMS](INFO): Aggregate::initialize turbulence:SURFACECOMMS
2012-05-08T04:35:00.505Z,1336451700.505 [turbulence:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-05-08T04:35:00.505Z,1336451700.505 [turbulence:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-08T04:39:49.305Z,1336451989.305 [turbulence:SURFACECOMMS:B] Running Loop=1
2012-05-08T04:39:49.305Z,1336451989.305 [turbulence:SURFACECOMMS:B](INFO): Aggregate::initialize turbulence:SURFACECOMMS:B
2012-05-08T04:39:49.305Z,1336451989.305 [turbulence:SURFACECOMMS:B:A] Running Loop=1
2012-05-08T04:39:49.705Z,1336451989.705 [turbulence:Science] Preempted
2012-05-08T04:39:49.705Z,1336451989.705 [turbulence:Science:A] Preempted
2012-05-08T04:39:49.705Z,1336451989.705 [turbulence:Science:C] Preempted
2012-05-08T04:39:49.705Z,1336451989.705 [turbulence:Science:D] Preempted
2012-05-08T04:39:49.705Z,1336451989.705 [turbulence:G.OffshoreEnvelope] Preempted
2012-05-08T04:39:50.105Z,1336451990.105 [NAL9601](INFO): Powering up
2012-05-08T04:39:55.305Z,1336451995.305 [NAL9601](IMPORTANT): GPS fix at: 1336451995.30
2012-05-08T04:39:55.305Z,1336451995.305 [Navigation](IMPORTANT): Navigation Error 0.0 %DT

2012-05-08T04:39:55.305Z,1336451995.305 [turbulence:SURFACECOMMS:B:A] Stopped
2012-05-08T04:39:55.305Z,1336451995.305 [turbulence:SURFACECOMMS:B:B] Running Loop=1
2012-05-08T04:39:58.105Z,1336451998.105 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0004.lzma
2012-05-08T04:39:58.105Z,1336451998.105 [NAL9601](INFO): Packets left to send: 4
2012-05-08T04:39:58.105Z,1336451998.105 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000019
2012-05-08T04:39:59.305Z,1336451999.305 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0004.lzma
2012-05-08T04:39:59.305Z,1336451999.305 [NAL9601](INFO): Packets left to send: 3
2012-05-08T04:39:59.305Z,1336451999.305 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000020
2012-05-08T04:40:00.505Z,1336452000.505 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0004.lzma
2012-05-08T04:40:00.505Z,1336452000.505 [NAL9601](INFO): Packets left to send: 2
2012-05-08T04:40:00.505Z,1336452000.505 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000021
2012-05-08T04:40:01.705Z,1336452001.705 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0004.lzma
2012-05-08T04:40:01.705Z,1336452001.705 [NAL9601](INFO): Packets left to send: 1
2012-05-08T04:40:01.705Z,1336452001.705 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000022
2012-05-08T04:40:02.905Z,1336452002.905 [NAL9601](INFO): Sent 110 bytes from file Logs/20120507T232151/shore0004.lzma
2012-05-08T04:40:02.905Z,1336452002.905 [NAL9601](INFO): Packets left to send: 0
2012-05-08T04:40:02.905Z,1336452002.905 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000023
2012-05-08T04:40:03.705Z,1336452003.705 [turbulence:SURFACECOMMS:B:B] Stopped
2012-05-08T04:40:03.705Z,1336452003.705 [turbulence:SURFACECOMMS:B:C] Running Loop=1
2012-05-08T04:40:08.905Z,1336452008.905 [NAL9601](IMPORTANT): GPS fix at: 1336452008.90
2012-05-08T04:40:08.905Z,1336452008.905 [turbulence:SURFACECOMMS:B:C] Stopped
2012-05-08T04:40:08.905Z,1336452008.905 [turbulence:SURFACECOMMS:B](INFO): Completed turbulence:SURFACECOMMS:B
2012-05-08T04:40:08.905Z,1336452008.905 [turbulence:SURFACECOMMS:B] Stopped
2012-05-08T04:40:08.905Z,1336452008.905 [turbulence:SURFACECOMMS:B](INFO): Aggregate::uninitialize turbulence:SURFACECOMMS:B
2012-05-08T04:40:08.905Z,1336452008.905 [turbulence:SURFACECOMMS](INFO): Completed turbulence:SURFACECOMMS
2012-05-08T04:40:08.905Z,1336452008.905 [turbulence:SURFACECOMMS] Stopped
2012-05-08T04:40:08.905Z,1336452008.905 [turbulence:SURFACECOMMS](INFO): Aggregate::uninitialize turbulence:SURFACECOMMS
2012-05-08T04:40:08.905Z,1336452008.905 [turbulence:SURFACECOMMS:A.GoToSurface] Stopped
2012-05-08T04:40:08.905Z,1336452008.905 [turbulence:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-08T04:40:09.305Z,1336452009.305 [turbulence:RepeatOps:YoYoWpt2:F](INFO): Completed turbulence:RepeatOps:YoYoWpt2:F
2012-05-08T04:40:09.305Z,1336452009.305 [turbulence:RepeatOps:YoYoWpt2:F] Stopped
2012-05-08T04:40:09.305Z,1336452009.305 [turbulence:RepeatOps:YoYoWpt2:F](INFO): Aggregate::uninitialize turbulence:RepeatOps:YoYoWpt2:F
2012-05-08T04:40:09.305Z,1336452009.305 [turbulence:RepeatOps:YoYoWpt2:Wpt1b.Waypoint] Running Loop=1
2012-05-08T04:40:09.305Z,1336452009.305 [turbulence:RepeatOps:YoYoWpt2:Wpt1b.Waypoint](DEBUG): Initialize WaypointComponent.
2012-05-08T04:40:09.305Z,1336452009.305 [turbulence:Science] Running Loop=1
2012-05-08T04:40:09.305Z,1336452009.305 [turbulence:Science:D] Running Loop=1
2012-05-08T04:40:09.305Z,1336452009.305 [turbulence:Science:C] Running Loop=1
2012-05-08T04:40:09.305Z,1336452009.305 [turbulence:Science:A] Running Loop=1
2012-05-08T04:40:09.305Z,1336452009.305 [turbulence:G.OffshoreEnvelope] Running Loop=1
2012-05-08T04:40:14.905Z,1336452014.905 [NAL9601](INFO): Powering down
2012-05-08T06:17:18.105Z,1336457838.105 [turbulence:RepeatOps:YoYoWpt2:Wpt1b.Waypoint](INFO): Reached Waypoint: 36.719000,-121.884500
2012-05-08T06:17:18.105Z,1336457838.105 [turbulence:RepeatOps:YoYoWpt2:Wpt1b.Waypoint] Stopped
2012-05-08T06:17:18.105Z,1336457838.105 [turbulence:RepeatOps:YoYoWpt2:Wpt1b.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-05-08T06:17:18.105Z,1336457838.105 [turbulence:RepeatOps:YoYoWpt2:H] Running Loop=1
2012-05-08T06:17:18.105Z,1336457838.105 [turbulence:RepeatOps:YoYoWpt2:H](INFO): Aggregate::initialize turbulence:RepeatOps:YoYoWpt2:H
2012-05-08T06:17:18.505Z,1336457838.505 [turbulence:SURFACECOMMS] Running Loop=1
2012-05-08T06:17:18.505Z,1336457838.505 [turbulence:SURFACECOMMS](INFO): Aggregate::initialize turbulence:SURFACECOMMS
2012-05-08T06:17:18.505Z,1336457838.505 [turbulence:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-05-08T06:17:18.505Z,1336457838.505 [turbulence:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-08T06:20:24.508Z,1336458024.508 [turbulence:SURFACECOMMS:B] Running Loop=1
2012-05-08T06:20:24.508Z,1336458024.508 [turbulence:SURFACECOMMS:B](INFO): Aggregate::initialize turbulence:SURFACECOMMS:B
2012-05-08T06:20:24.508Z,1336458024.508 [turbulence:SURFACECOMMS:B:A] Running Loop=1
2012-05-08T06:20:24.907Z,1336458024.907 [turbulence:Science] Preempted
2012-05-08T06:20:24.907Z,1336458024.907 [turbulence:Science:A] Preempted
2012-05-08T06:20:24.907Z,1336458024.907 [turbulence:Science:C] Preempted
2012-05-08T06:20:24.907Z,1336458024.907 [turbulence:Science:D] Preempted
2012-05-08T06:20:24.907Z,1336458024.907 [turbulence:G.OffshoreEnvelope] Preempted
2012-05-08T06:20:25.305Z,1336458025.305 [NAL9601](INFO): Powering up
2012-05-08T06:20:30.505Z,1336458030.505 [NAL9601](IMPORTANT): GPS fix at: 1336458030.50
2012-05-08T06:20:30.505Z,1336458030.505 [Navigation](IMPORTANT): Navigation Error 0.0 %DT

2012-05-08T06:20:30.505Z,1336458030.505 [turbulence:SURFACECOMMS:B:A] Stopped
2012-05-08T06:20:30.505Z,1336458030.505 [turbulence:SURFACECOMMS:B:B] Running Loop=1
2012-05-08T06:20:33.305Z,1336458033.305 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0005.lzma
2012-05-08T06:20:33.305Z,1336458033.305 [NAL9601](INFO): Packets left to send: 7
2012-05-08T06:20:33.305Z,1336458033.305 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000024
2012-05-08T06:20:34.505Z,1336458034.505 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0005.lzma
2012-05-08T06:20:34.505Z,1336458034.505 [NAL9601](INFO): Packets left to send: 6
2012-05-08T06:20:34.505Z,1336458034.505 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000025
2012-05-08T06:20:35.705Z,1336458035.705 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0005.lzma
2012-05-08T06:20:35.705Z,1336458035.705 [NAL9601](INFO): Packets left to send: 5
2012-05-08T06:20:35.705Z,1336458035.705 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000026
2012-05-08T06:20:36.905Z,1336458036.905 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0005.lzma
2012-05-08T06:20:36.905Z,1336458036.905 [NAL9601](INFO): Packets left to send: 4
2012-05-08T06:20:36.905Z,1336458036.905 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000027
2012-05-08T06:20:38.105Z,1336458038.105 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0005.lzma
2012-05-08T06:20:38.105Z,1336458038.105 [NAL9601](INFO): Packets left to send: 3
2012-05-08T06:20:38.105Z,1336458038.105 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000028
2012-05-08T06:20:39.305Z,1336458039.305 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0005.lzma
2012-05-08T06:20:39.305Z,1336458039.305 [NAL9601](INFO): Packets left to send: 2
2012-05-08T06:20:39.305Z,1336458039.305 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000029
2012-05-08T06:20:40.505Z,1336458040.505 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0005.lzma
2012-05-08T06:20:40.505Z,1336458040.505 [NAL9601](INFO): Packets left to send: 1
2012-05-08T06:20:40.505Z,1336458040.505 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000030
2012-05-08T06:20:41.705Z,1336458041.705 [NAL9601](INFO): Sent 124 bytes from file Logs/20120507T232151/shore0005.lzma
2012-05-08T06:20:41.705Z,1336458041.705 [NAL9601](INFO): Packets left to send: 0
2012-05-08T06:20:41.705Z,1336458041.705 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000031
2012-05-08T06:20:42.505Z,1336458042.505 [turbulence:SURFACECOMMS:B:B] Stopped
2012-05-08T06:20:42.505Z,1336458042.505 [turbulence:SURFACECOMMS:B:C] Running Loop=1
2012-05-08T06:20:47.705Z,1336458047.705 [NAL9601](IMPORTANT): GPS fix at: 1336458047.70
2012-05-08T06:20:47.705Z,1336458047.705 [turbulence:SURFACECOMMS:B:C] Stopped
2012-05-08T06:20:47.705Z,1336458047.705 [turbulence:SURFACECOMMS:B](INFO): Completed turbulence:SURFACECOMMS:B
2012-05-08T06:20:47.705Z,1336458047.705 [turbulence:SURFACECOMMS:B] Stopped
2012-05-08T06:20:47.705Z,1336458047.705 [turbulence:SURFACECOMMS:B](INFO): Aggregate::uninitialize turbulence:SURFACECOMMS:B
2012-05-08T06:20:47.705Z,1336458047.705 [turbulence:SURFACECOMMS](INFO): Completed turbulence:SURFACECOMMS
2012-05-08T06:20:47.705Z,1336458047.705 [turbulence:SURFACECOMMS] Stopped
2012-05-08T06:20:47.705Z,1336458047.705 [turbulence:SURFACECOMMS](INFO): Aggregate::uninitialize turbulence:SURFACECOMMS
2012-05-08T06:20:47.705Z,1336458047.705 [turbulence:SURFACECOMMS:A.GoToSurface] Stopped
2012-05-08T06:20:47.705Z,1336458047.705 [turbulence:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-08T06:20:48.105Z,1336458048.105 [turbulence:RepeatOps:YoYoWpt2:H](INFO): Completed turbulence:RepeatOps:YoYoWpt2:H
2012-05-08T06:20:48.105Z,1336458048.105 [turbulence:RepeatOps:YoYoWpt2:H] Stopped
2012-05-08T06:20:48.105Z,1336458048.105 [turbulence:RepeatOps:YoYoWpt2:H](INFO): Aggregate::uninitialize turbulence:RepeatOps:YoYoWpt2:H
2012-05-08T06:20:48.105Z,1336458048.105 [turbulence:RepeatOps:YoYoWpt2:Wpt2.Waypoint] Running Loop=1
2012-05-08T06:20:48.105Z,1336458048.105 [turbulence:RepeatOps:YoYoWpt2:Wpt2.Waypoint](DEBUG): Initialize WaypointComponent.
2012-05-08T06:20:48.105Z,1336458048.105 [turbulence:Science] Running Loop=1
2012-05-08T06:20:48.105Z,1336458048.105 [turbulence:Science:D] Running Loop=1
2012-05-08T06:20:48.105Z,1336458048.105 [turbulence:Science:C] Running Loop=1
2012-05-08T06:20:48.105Z,1336458048.105 [turbulence:Science:A] Running Loop=1
2012-05-08T06:20:48.105Z,1336458048.105 [turbulence:G.OffshoreEnvelope] Running Loop=1
2012-05-08T06:20:53.705Z,1336458053.705 [NAL9601](INFO): Powering down
2012-05-08T07:34:51.705Z,1336462491.705 [turbulence:RepeatOps:YoYoWpt2:Wpt2.Waypoint](INFO): Reached Waypoint: 36.719000,-121.836600
2012-05-08T07:34:51.705Z,1336462491.705 [turbulence:RepeatOps:YoYoWpt2:Wpt2.Waypoint] Stopped
2012-05-08T07:34:51.705Z,1336462491.705 [turbulence:RepeatOps:YoYoWpt2:Wpt2.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-05-08T07:34:51.705Z,1336462491.705 [turbulence:RepeatOps:YoYoWpt2:J] Running Loop=1
2012-05-08T07:34:51.705Z,1336462491.705 [turbulence:RepeatOps:YoYoWpt2:J](INFO): Aggregate::initialize turbulence:RepeatOps:YoYoWpt2:J
2012-05-08T07:34:51.705Z,1336462491.705 [turbulence:RepeatOps:YoYoWpt2:J:NavUpdateWPT2] Running Loop=1
2012-05-08T07:34:51.705Z,1336462491.705 [turbulence:RepeatOps:YoYoWpt2:J:NavUpdateWPT2](INFO): Aggregate::initialize turbulence:RepeatOps:YoYoWpt2:J:NavUpdateWPT2
2012-05-08T07:34:52.105Z,1336462492.105 [turbulence:SURFACENAV] Running Loop=1
2012-05-08T07:34:52.105Z,1336462492.105 [turbulence:SURFACENAV](INFO): Aggregate::initialize turbulence:SURFACENAV
2012-05-08T07:34:52.105Z,1336462492.105 [turbulence:SURFACENAV:A.GoToSurface] Running Loop=1
2012-05-08T07:34:52.105Z,1336462492.105 [turbulence:SURFACENAV:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-08T07:36:51.705Z,1336462611.705 [turbulence:SURFACENAV:B] Running Loop=1
2012-05-08T07:36:51.705Z,1336462611.705 [turbulence:SURFACENAV:B](INFO): Aggregate::initialize turbulence:SURFACENAV:B
2012-05-08T07:36:51.705Z,1336462611.705 [turbulence:SURFACENAV:B:A] Running Loop=1
2012-05-08T07:36:52.505Z,1336462612.505 [NAL9601](INFO): Powering up
2012-05-08T07:36:57.705Z,1336462617.705 [NAL9601](IMPORTANT): GPS fix at: 1336462617.70
2012-05-08T07:36:57.705Z,1336462617.705 [Navigation](IMPORTANT): Navigation Error 0.0 %DT

2012-05-08T07:36:57.705Z,1336462617.705 [turbulence:SURFACENAV:B:A] Stopped
2012-05-08T07:36:57.705Z,1336462617.705 [turbulence:SURFACENAV:B](INFO): Completed turbulence:SURFACENAV:B
2012-05-08T07:36:57.705Z,1336462617.705 [turbulence:SURFACENAV:B] Stopped
2012-05-08T07:36:57.705Z,1336462617.705 [turbulence:SURFACENAV:B](INFO): Aggregate::uninitialize turbulence:SURFACENAV:B
2012-05-08T07:36:57.705Z,1336462617.705 [turbulence:SURFACENAV:B](INFO): Running loop #2
2012-05-08T07:36:57.705Z,1336462617.705 [turbulence:SURFACENAV:B] Running Loop=2
2012-05-08T07:36:57.705Z,1336462617.705 [turbulence:SURFACENAV:B](INFO): Aggregate::initialize turbulence:SURFACENAV:B
2012-05-08T07:36:57.705Z,1336462617.705 [turbulence:SURFACENAV:B:A] Running Loop=1
2012-05-08T07:37:02.105Z,1336462622.105 [NAL9601](IMPORTANT): GPS fix at: 1336462622.10
2012-05-08T07:37:02.105Z,1336462622.105 [turbulence:SURFACENAV:B:A] Stopped
2012-05-08T07:37:02.105Z,1336462622.105 [turbulence:SURFACENAV:B](INFO): Completed turbulence:SURFACENAV:B
2012-05-08T07:37:02.105Z,1336462622.105 [turbulence:SURFACENAV:B] Stopped
2012-05-08T07:37:02.105Z,1336462622.105 [turbulence:SURFACENAV:B](INFO): Aggregate::uninitialize turbulence:SURFACENAV:B
2012-05-08T07:37:02.105Z,1336462622.105 [turbulence:SURFACENAV](INFO): Completed turbulence:SURFACENAV
2012-05-08T07:37:02.105Z,1336462622.105 [turbulence:SURFACENAV] Stopped
2012-05-08T07:37:02.105Z,1336462622.105 [turbulence:SURFACENAV](INFO): Aggregate::uninitialize turbulence:SURFACENAV
2012-05-08T07:37:02.105Z,1336462622.105 [turbulence:SURFACENAV:A.GoToSurface] Stopped
2012-05-08T07:37:02.105Z,1336462622.105 [turbulence:SURFACENAV:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:YoYoWpt2:J:NavUpdateWPT2](INFO): Completed turbulence:RepeatOps:YoYoWpt2:J:NavUpdateWPT2
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:YoYoWpt2:J:NavUpdateWPT2] Stopped
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:YoYoWpt2:J:NavUpdateWPT2](INFO): Aggregate::uninitialize turbulence:RepeatOps:YoYoWpt2:J:NavUpdateWPT2
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:YoYoWpt2:J](INFO): Completed turbulence:RepeatOps:YoYoWpt2:J
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:YoYoWpt2:J] Stopped
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:YoYoWpt2:J](INFO): Aggregate::uninitialize turbulence:RepeatOps:YoYoWpt2:J
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:YoYoWpt2](INFO): Completed turbulence:RepeatOps:YoYoWpt2
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:YoYoWpt2] Stopped
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:YoYoWpt2](INFO): Aggregate::uninitialize turbulence:RepeatOps:YoYoWpt2
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:YoYoWpt2:A.SetSpeed] Stopped
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:YoYoWpt2:A.SetSpeed](DEBUG): Uninitialize.
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:YoYoWpt2:B.DepthEnvelope] Stopped
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:YoYoWpt2:B.DepthEnvelope](DEBUG): Uninitialize.
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:YoYoWpt2:C.AltitudeEnvelope] Stopped
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:YoYoWpt2:C.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:YoYoWpt2:D.YoYo] Stopped
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:YoYoWpt2:D.YoYo](DEBUG): Uninitialize YoYoComponent.
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:BottomFollowWpt1] Running Loop=1
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:BottomFollowWpt1](INFO): Aggregate::initialize turbulence:RepeatOps:BottomFollowWpt1
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:BottomFollowWpt1:B.SetSpeed] Running Loop=1
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:BottomFollowWpt1:B.SetSpeed](DEBUG): Initialize.
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:BottomFollowWpt1:C.DepthEnvelope] Running Loop=1
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:BottomFollowWpt1:C.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:BottomFollowWpt1:D.AltitudeEnvelope] Running Loop=1
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:BottomFollowWpt1:D.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:BottomFollowWpt1:LimitDeepDownPitch.Pitch] Running Loop=1
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:BottomFollowWpt1:LimitDeepDownPitch.Pitch](DEBUG): Initialize.
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:BottomFollowWpt1:LimitDownPitch.Pitch] Running Loop=1
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:BottomFollowWpt1:LimitDownPitch.Pitch](DEBUG): Initialize.
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:BottomFollowWpt1:G.PitchServo] Running Loop=1
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:BottomFollowWpt1:G.PitchServo](DEBUG): Initialize.
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:BottomFollowWpt1:G.PitchServo](INFO): Initialize with holdValue=height_above_sea_floor
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:BottomFollowWpt1:Wpt2a.Waypoint] Running Loop=1
2012-05-08T07:37:02.505Z,1336462622.505 [turbulence:RepeatOps:BottomFollowWpt1:Wpt2a.Waypoint](DEBUG): Initialize WaypointComponent.
2012-05-08T07:37:02.905Z,1336462622.905 [turbulence:RepeatOps:BottomFollowWpt1:G.PitchServo] Running Loop=1
2012-05-08T07:37:02.905Z,1336462622.905 [turbulence:RepeatOps:BottomFollowWpt1:LimitDownPitch.Pitch] Running Loop=1
2012-05-08T07:37:02.905Z,1336462622.905 [turbulence:RepeatOps:BottomFollowWpt1:D.AltitudeEnvelope] Running Loop=1
2012-05-08T07:37:02.905Z,1336462622.905 [turbulence:RepeatOps:BottomFollowWpt1:C.DepthEnvelope] Running Loop=1
2012-05-08T07:37:02.905Z,1336462622.905 [turbulence:RepeatOps:BottomFollowWpt1:B.SetSpeed] Running Loop=1
2012-05-08T07:37:08.105Z,1336462628.105 [NAL9601](INFO): Powering down
2012-05-08T08:03:26.905Z,1336464206.905 [turbulence:RepeatOps:BottomFollowWpt1:Wpt2a.Waypoint](INFO): Reached Waypoint: 36.719000,-121.852700
2012-05-08T08:03:26.905Z,1336464206.905 [turbulence:RepeatOps:BottomFollowWpt1:Wpt2a.Waypoint] Stopped
2012-05-08T08:03:26.905Z,1336464206.905 [turbulence:RepeatOps:BottomFollowWpt1:Wpt2a.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-05-08T08:03:26.905Z,1336464206.905 [turbulence:RepeatOps:BottomFollowWpt1:I] Running Loop=1
2012-05-08T08:03:26.905Z,1336464206.905 [turbulence:RepeatOps:BottomFollowWpt1:I](INFO): Aggregate::initialize turbulence:RepeatOps:BottomFollowWpt1:I
2012-05-08T08:03:27.305Z,1336464207.305 [turbulence:SURFACECOMMS] Running Loop=1
2012-05-08T08:03:27.305Z,1336464207.305 [turbulence:SURFACECOMMS](INFO): Aggregate::initialize turbulence:SURFACECOMMS
2012-05-08T08:03:27.305Z,1336464207.305 [turbulence:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-05-08T08:03:27.305Z,1336464207.305 [turbulence:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-08T08:06:40.905Z,1336464400.905 [turbulence:SURFACECOMMS:B] Running Loop=1
2012-05-08T08:06:40.905Z,1336464400.905 [turbulence:SURFACECOMMS:B](INFO): Aggregate::initialize turbulence:SURFACECOMMS:B
2012-05-08T08:06:40.905Z,1336464400.905 [turbulence:SURFACECOMMS:B:A] Running Loop=1
2012-05-08T08:06:41.305Z,1336464401.305 [turbulence:Science] Preempted
2012-05-08T08:06:41.305Z,1336464401.305 [turbulence:Science:A] Preempted
2012-05-08T08:06:41.305Z,1336464401.305 [turbulence:Science:C] Preempted
2012-05-08T08:06:41.305Z,1336464401.305 [turbulence:Science:D] Preempted
2012-05-08T08:06:41.305Z,1336464401.305 [turbulence:G.OffshoreEnvelope] Preempted
2012-05-08T08:06:41.705Z,1336464401.705 [NAL9601](INFO): Powering up
2012-05-08T08:06:46.905Z,1336464406.905 [NAL9601](IMPORTANT): GPS fix at: 1336464406.90
2012-05-08T08:06:46.905Z,1336464406.905 [Navigation](IMPORTANT): Navigation Error 0.0 %DT

2012-05-08T08:06:46.905Z,1336464406.905 [turbulence:SURFACECOMMS:B:A] Stopped
2012-05-08T08:06:46.905Z,1336464406.905 [turbulence:SURFACECOMMS:B:B] Running Loop=1
2012-05-08T08:06:49.705Z,1336464409.705 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0006.lzma
2012-05-08T08:06:49.705Z,1336464409.705 [NAL9601](INFO): Packets left to send: 7
2012-05-08T08:06:49.705Z,1336464409.705 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000032
2012-05-08T08:06:50.905Z,1336464410.905 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0006.lzma
2012-05-08T08:06:50.905Z,1336464410.905 [NAL9601](INFO): Packets left to send: 6
2012-05-08T08:06:50.905Z,1336464410.905 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000033
2012-05-08T08:06:52.105Z,1336464412.105 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0006.lzma
2012-05-08T08:06:52.105Z,1336464412.105 [NAL9601](INFO): Packets left to send: 5
2012-05-08T08:06:52.105Z,1336464412.105 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000034
2012-05-08T08:06:53.305Z,1336464413.305 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0006.lzma
2012-05-08T08:06:53.305Z,1336464413.305 [NAL9601](INFO): Packets left to send: 4
2012-05-08T08:06:53.305Z,1336464413.305 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000035
2012-05-08T08:06:54.505Z,1336464414.505 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0006.lzma
2012-05-08T08:06:54.505Z,1336464414.505 [NAL9601](INFO): Packets left to send: 3
2012-05-08T08:06:54.505Z,1336464414.505 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000036
2012-05-08T08:06:55.705Z,1336464415.705 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0006.lzma
2012-05-08T08:06:55.705Z,1336464415.705 [NAL9601](INFO): Packets left to send: 2
2012-05-08T08:06:55.705Z,1336464415.705 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000037
2012-05-08T08:06:56.905Z,1336464416.905 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0006.lzma
2012-05-08T08:06:56.905Z,1336464416.905 [NAL9601](INFO): Packets left to send: 1
2012-05-08T08:06:56.905Z,1336464416.905 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000038
2012-05-08T08:06:58.105Z,1336464418.105 [NAL9601](INFO): Sent 154 bytes from file Logs/20120507T232151/shore0006.lzma
2012-05-08T08:06:58.105Z,1336464418.105 [NAL9601](INFO): Packets left to send: 0
2012-05-08T08:06:58.105Z,1336464418.105 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000039
2012-05-08T08:06:58.905Z,1336464418.905 [turbulence:SURFACECOMMS:B:B] Stopped
2012-05-08T08:06:58.905Z,1336464418.905 [turbulence:SURFACECOMMS:B:C] Running Loop=1
2012-05-08T08:07:04.105Z,1336464424.105 [NAL9601](IMPORTANT): GPS fix at: 1336464424.10
2012-05-08T08:07:04.105Z,1336464424.105 [turbulence:SURFACECOMMS:B:C] Stopped
2012-05-08T08:07:04.105Z,1336464424.105 [turbulence:SURFACECOMMS:B](INFO): Completed turbulence:SURFACECOMMS:B
2012-05-08T08:07:04.105Z,1336464424.105 [turbulence:SURFACECOMMS:B] Stopped
2012-05-08T08:07:04.105Z,1336464424.105 [turbulence:SURFACECOMMS:B](INFO): Aggregate::uninitialize turbulence:SURFACECOMMS:B
2012-05-08T08:07:04.105Z,1336464424.105 [turbulence:SURFACECOMMS](INFO): Completed turbulence:SURFACECOMMS
2012-05-08T08:07:04.105Z,1336464424.105 [turbulence:SURFACECOMMS] Stopped
2012-05-08T08:07:04.105Z,1336464424.105 [turbulence:SURFACECOMMS](INFO): Aggregate::uninitialize turbulence:SURFACECOMMS
2012-05-08T08:07:04.105Z,1336464424.105 [turbulence:SURFACECOMMS:A.GoToSurface] Stopped
2012-05-08T08:07:04.105Z,1336464424.105 [turbulence:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-08T08:07:04.505Z,1336464424.505 [turbulence:RepeatOps:BottomFollowWpt1:I](INFO): Completed turbulence:RepeatOps:BottomFollowWpt1:I
2012-05-08T08:07:04.505Z,1336464424.505 [turbulence:RepeatOps:BottomFollowWpt1:I] Stopped
2012-05-08T08:07:04.505Z,1336464424.505 [turbulence:RepeatOps:BottomFollowWpt1:I](INFO): Aggregate::uninitialize turbulence:RepeatOps:BottomFollowWpt1:I
2012-05-08T08:07:04.505Z,1336464424.505 [turbulence:RepeatOps:BottomFollowWpt1:Wpt2b.Waypoint] Running Loop=1
2012-05-08T08:07:04.505Z,1336464424.505 [turbulence:RepeatOps:BottomFollowWpt1:Wpt2b.Waypoint](DEBUG): Initialize WaypointComponent.
2012-05-08T08:07:04.505Z,1336464424.505 [turbulence:Science] Running Loop=1
2012-05-08T08:07:04.505Z,1336464424.505 [turbulence:Science:D] Running Loop=1
2012-05-08T08:07:04.505Z,1336464424.505 [turbulence:Science:C] Running Loop=1
2012-05-08T08:07:04.505Z,1336464424.505 [turbulence:Science:A] Running Loop=1
2012-05-08T08:07:04.505Z,1336464424.505 [turbulence:G.OffshoreEnvelope] Running Loop=1
2012-05-08T08:07:10.105Z,1336464430.105 [NAL9601](INFO): Powering down
2012-05-08T08:17:53.705Z,1336465073.705 [turbulence:RepeatOps:BottomFollowWpt1:LimitDeepDownPitch.Pitch] Running Loop=1
2012-05-08T09:59:00.505Z,1336471140.505 [turbulence:RepeatOps:BottomFollowWpt1:Wpt2b.Waypoint](INFO): Reached Waypoint: 36.719000,-121.930400
2012-05-08T09:59:00.505Z,1336471140.505 [turbulence:RepeatOps:BottomFollowWpt1:Wpt2b.Waypoint] Stopped
2012-05-08T09:59:00.505Z,1336471140.505 [turbulence:RepeatOps:BottomFollowWpt1:Wpt2b.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-05-08T09:59:00.505Z,1336471140.505 [turbulence:RepeatOps:BottomFollowWpt1:K] Running Loop=1
2012-05-08T09:59:00.505Z,1336471140.505 [turbulence:RepeatOps:BottomFollowWpt1:K](INFO): Aggregate::initialize turbulence:RepeatOps:BottomFollowWpt1:K
2012-05-08T09:59:00.905Z,1336471140.905 [turbulence:SURFACECOMMS] Running Loop=1
2012-05-08T09:59:00.905Z,1336471140.905 [turbulence:SURFACECOMMS](INFO): Aggregate::initialize turbulence:SURFACECOMMS
2012-05-08T09:59:00.905Z,1336471140.905 [turbulence:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-05-08T09:59:00.905Z,1336471140.905 [turbulence:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-08T10:02:54.106Z,1336471374.106 [turbulence:SURFACECOMMS:B] Running Loop=1
2012-05-08T10:02:54.106Z,1336471374.106 [turbulence:SURFACECOMMS:B](INFO): Aggregate::initialize turbulence:SURFACECOMMS:B
2012-05-08T10:02:54.106Z,1336471374.106 [turbulence:SURFACECOMMS:B:A] Running Loop=1
2012-05-08T10:02:54.505Z,1336471374.505 [turbulence:Science] Preempted
2012-05-08T10:02:54.505Z,1336471374.505 [turbulence:Science:A] Preempted
2012-05-08T10:02:54.505Z,1336471374.505 [turbulence:Science:C] Preempted
2012-05-08T10:02:54.505Z,1336471374.505 [turbulence:Science:D] Preempted
2012-05-08T10:02:54.505Z,1336471374.505 [turbulence:G.OffshoreEnvelope] Preempted
2012-05-08T10:02:54.905Z,1336471374.905 [NAL9601](INFO): Powering up
2012-05-08T10:03:00.105Z,1336471380.105 [NAL9601](IMPORTANT): GPS fix at: 1336471380.10
2012-05-08T10:03:00.105Z,1336471380.105 [Navigation](IMPORTANT): Navigation Error 0.0 %DT

2012-05-08T10:03:00.105Z,1336471380.105 [turbulence:SURFACECOMMS:B:A] Stopped
2012-05-08T10:03:00.105Z,1336471380.105 [turbulence:SURFACECOMMS:B:B] Running Loop=1
2012-05-08T10:03:02.905Z,1336471382.905 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0007.lzma
2012-05-08T10:03:02.905Z,1336471382.905 [NAL9601](INFO): Packets left to send: 3
2012-05-08T10:03:02.905Z,1336471382.905 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000040
2012-05-08T10:03:04.105Z,1336471384.105 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0007.lzma
2012-05-08T10:03:04.105Z,1336471384.105 [NAL9601](INFO): Packets left to send: 2
2012-05-08T10:03:04.105Z,1336471384.105 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000041
2012-05-08T10:03:05.305Z,1336471385.305 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0007.lzma
2012-05-08T10:03:05.305Z,1336471385.305 [NAL9601](INFO): Packets left to send: 1
2012-05-08T10:03:05.305Z,1336471385.305 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000042
2012-05-08T10:03:06.505Z,1336471386.505 [NAL9601](INFO): Sent 65 bytes from file Logs/20120507T232151/shore0007.lzma
2012-05-08T10:03:06.505Z,1336471386.505 [NAL9601](INFO): Packets left to send: 0
2012-05-08T10:03:06.505Z,1336471386.505 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000043
2012-05-08T10:03:07.305Z,1336471387.305 [turbulence:SURFACECOMMS:B:B] Stopped
2012-05-08T10:03:07.305Z,1336471387.305 [turbulence:SURFACECOMMS:B:C] Running Loop=1
2012-05-08T10:03:12.505Z,1336471392.505 [NAL9601](IMPORTANT): GPS fix at: 1336471392.50
2012-05-08T10:03:12.505Z,1336471392.505 [turbulence:SURFACECOMMS:B:C] Stopped
2012-05-08T10:03:12.505Z,1336471392.505 [turbulence:SURFACECOMMS:B](INFO): Completed turbulence:SURFACECOMMS:B
2012-05-08T10:03:12.505Z,1336471392.505 [turbulence:SURFACECOMMS:B] Stopped
2012-05-08T10:03:12.505Z,1336471392.505 [turbulence:SURFACECOMMS:B](INFO): Aggregate::uninitialize turbulence:SURFACECOMMS:B
2012-05-08T10:03:12.505Z,1336471392.505 [turbulence:SURFACECOMMS](INFO): Completed turbulence:SURFACECOMMS
2012-05-08T10:03:12.505Z,1336471392.505 [turbulence:SURFACECOMMS] Stopped
2012-05-08T10:03:12.505Z,1336471392.505 [turbulence:SURFACECOMMS](INFO): Aggregate::uninitialize turbulence:SURFACECOMMS
2012-05-08T10:03:12.505Z,1336471392.505 [turbulence:SURFACECOMMS:A.GoToSurface] Stopped
2012-05-08T10:03:12.505Z,1336471392.505 [turbulence:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-08T10:03:12.905Z,1336471392.905 [turbulence:RepeatOps:BottomFollowWpt1:K](INFO): Completed turbulence:RepeatOps:BottomFollowWpt1:K
2012-05-08T10:03:12.905Z,1336471392.905 [turbulence:RepeatOps:BottomFollowWpt1:K] Stopped
2012-05-08T10:03:12.905Z,1336471392.905 [turbulence:RepeatOps:BottomFollowWpt1:K](INFO): Aggregate::uninitialize turbulence:RepeatOps:BottomFollowWpt1:K
2012-05-08T10:03:12.905Z,1336471392.905 [turbulence:RepeatOps:BottomFollowWpt1:Wpt1.Waypoint] Running Loop=1
2012-05-08T10:03:12.905Z,1336471392.905 [turbulence:RepeatOps:BottomFollowWpt1:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2012-05-08T10:03:12.905Z,1336471392.905 [turbulence:Science] Running Loop=1
2012-05-08T10:03:12.905Z,1336471392.905 [turbulence:Science:D] Running Loop=1
2012-05-08T10:03:12.905Z,1336471392.905 [turbulence:Science:C] Running Loop=1
2012-05-08T10:03:12.905Z,1336471392.905 [turbulence:Science:A] Running Loop=1
2012-05-08T10:03:12.905Z,1336471392.905 [turbulence:G.OffshoreEnvelope] Running Loop=1
2012-05-08T10:03:18.505Z,1336471398.505 [NAL9601](INFO): Powering down
2012-05-08T11:08:10.105Z,1336475290.105 [turbulence:RepeatOps:BottomFollowWpt1:Wpt1.Waypoint](INFO): Reached Waypoint: 36.719000,-121.974994
2012-05-08T11:08:10.105Z,1336475290.105 [turbulence:RepeatOps:BottomFollowWpt1:Wpt1.Waypoint] Stopped
2012-05-08T11:08:10.105Z,1336475290.105 [turbulence:RepeatOps:BottomFollowWpt1:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-05-08T11:08:10.105Z,1336475290.105 [turbulence:RepeatOps:BottomFollowWpt1:PHONEHOMEYoYoWPT1] Running Loop=1
2012-05-08T11:08:10.105Z,1336475290.105 [turbulence:RepeatOps:BottomFollowWpt1:PHONEHOMEYoYoWPT1](INFO): Aggregate::initialize turbulence:RepeatOps:BottomFollowWpt1:PHONEHOMEYoYoWPT1
2012-05-08T11:08:10.505Z,1336475290.505 [turbulence:SURFACECOMMS] Running Loop=1
2012-05-08T11:08:10.505Z,1336475290.505 [turbulence:SURFACECOMMS](INFO): Aggregate::initialize turbulence:SURFACECOMMS
2012-05-08T11:08:10.505Z,1336475290.505 [turbulence:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-05-08T11:08:10.505Z,1336475290.505 [turbulence:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-08T11:12:04.105Z,1336475524.105 [turbulence:SURFACECOMMS:B] Running Loop=1
2012-05-08T11:12:04.105Z,1336475524.105 [turbulence:SURFACECOMMS:B](INFO): Aggregate::initialize turbulence:SURFACECOMMS:B
2012-05-08T11:12:04.105Z,1336475524.105 [turbulence:SURFACECOMMS:B:A] Running Loop=1
2012-05-08T11:12:04.505Z,1336475524.505 [turbulence:Science] Preempted
2012-05-08T11:12:04.505Z,1336475524.505 [turbulence:Science:A] Preempted
2012-05-08T11:12:04.505Z,1336475524.505 [turbulence:Science:C] Preempted
2012-05-08T11:12:04.505Z,1336475524.505 [turbulence:Science:D] Preempted
2012-05-08T11:12:04.505Z,1336475524.505 [turbulence:G.OffshoreEnvelope] Preempted
2012-05-08T11:12:04.905Z,1336475524.905 [NAL9601](INFO): Powering up
2012-05-08T11:12:10.105Z,1336475530.105 [NAL9601](IMPORTANT): GPS fix at: 1336475530.10
2012-05-08T11:12:10.105Z,1336475530.105 [Navigation](IMPORTANT): Navigation Error 0.0 %DT

2012-05-08T11:12:10.105Z,1336475530.105 [turbulence:SURFACECOMMS:B:A] Stopped
2012-05-08T11:12:10.105Z,1336475530.105 [turbulence:SURFACECOMMS:B:B] Running Loop=1
2012-05-08T11:12:12.905Z,1336475532.905 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0008.lzma
2012-05-08T11:12:12.905Z,1336475532.905 [NAL9601](INFO): Packets left to send: 2
2012-05-08T11:12:12.905Z,1336475532.905 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000044
2012-05-08T11:12:14.105Z,1336475534.105 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0008.lzma
2012-05-08T11:12:14.105Z,1336475534.105 [NAL9601](INFO): Packets left to send: 1
2012-05-08T11:12:14.105Z,1336475534.105 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000045
2012-05-08T11:12:15.305Z,1336475535.305 [NAL9601](INFO): Sent 34 bytes from file Logs/20120507T232151/shore0008.lzma
2012-05-08T11:12:15.305Z,1336475535.305 [NAL9601](INFO): Packets left to send: 0
2012-05-08T11:12:15.305Z,1336475535.305 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000046
2012-05-08T11:12:16.105Z,1336475536.105 [turbulence:SURFACECOMMS:B:B] Stopped
2012-05-08T11:12:16.105Z,1336475536.105 [turbulence:SURFACECOMMS:B:C] Running Loop=1
2012-05-08T11:12:21.305Z,1336475541.305 [NAL9601](IMPORTANT): GPS fix at: 1336475541.30
2012-05-08T11:12:21.305Z,1336475541.305 [turbulence:SURFACECOMMS:B:C] Stopped
2012-05-08T11:12:21.305Z,1336475541.305 [turbulence:SURFACECOMMS:B](INFO): Completed turbulence:SURFACECOMMS:B
2012-05-08T11:12:21.305Z,1336475541.305 [turbulence:SURFACECOMMS:B] Stopped
2012-05-08T11:12:21.305Z,1336475541.305 [turbulence:SURFACECOMMS:B](INFO): Aggregate::uninitialize turbulence:SURFACECOMMS:B
2012-05-08T11:12:21.305Z,1336475541.305 [turbulence:SURFACECOMMS](INFO): Completed turbulence:SURFACECOMMS
2012-05-08T11:12:21.305Z,1336475541.305 [turbulence:SURFACECOMMS] Stopped
2012-05-08T11:12:21.305Z,1336475541.305 [turbulence:SURFACECOMMS](INFO): Aggregate::uninitialize turbulence:SURFACECOMMS
2012-05-08T11:12:21.305Z,1336475541.305 [turbulence:SURFACECOMMS:A.GoToSurface] Stopped
2012-05-08T11:12:21.305Z,1336475541.305 [turbulence:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps:BottomFollowWpt1:PHONEHOMEYoYoWPT1](INFO): Completed turbulence:RepeatOps:BottomFollowWpt1:PHONEHOMEYoYoWPT1
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps:BottomFollowWpt1:PHONEHOMEYoYoWPT1] Stopped
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps:BottomFollowWpt1:PHONEHOMEYoYoWPT1](INFO): Aggregate::uninitialize turbulence:RepeatOps:BottomFollowWpt1:PHONEHOMEYoYoWPT1
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps:BottomFollowWpt1](INFO): Completed turbulence:RepeatOps:BottomFollowWpt1
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps:BottomFollowWpt1] Stopped
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps:BottomFollowWpt1](INFO): Aggregate::uninitialize turbulence:RepeatOps:BottomFollowWpt1
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps:BottomFollowWpt1:B.SetSpeed] Stopped
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps:BottomFollowWpt1:B.SetSpeed](DEBUG): Uninitialize.
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps:BottomFollowWpt1:C.DepthEnvelope] Stopped
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps:BottomFollowWpt1:C.DepthEnvelope](DEBUG): Uninitialize.
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps:BottomFollowWpt1:D.AltitudeEnvelope] Stopped
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps:BottomFollowWpt1:D.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps:BottomFollowWpt1:LimitDeepDownPitch.Pitch] Stopped
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps:BottomFollowWpt1:LimitDownPitch.Pitch] Stopped
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps:BottomFollowWpt1:G.PitchServo] Stopped
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps:BottomFollowWpt1:G.PitchServo](DEBUG): Uninitialize.
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps](INFO): Completed turbulence:RepeatOps
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps] Stopped
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps](INFO): Aggregate::uninitialize turbulence:RepeatOps
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps](INFO): Running loop #2
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps] Running Loop=2
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps](INFO): Aggregate::initialize turbulence:RepeatOps
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps:YoYoWpt2] Running Loop=1
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps:YoYoWpt2](INFO): Aggregate::initialize turbulence:RepeatOps:YoYoWpt2
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps:YoYoWpt2:A.SetSpeed] Running Loop=1
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps:YoYoWpt2:A.SetSpeed](DEBUG): Initialize.
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps:YoYoWpt2:B.DepthEnvelope] Running Loop=1
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps:YoYoWpt2:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps:YoYoWpt2:C.AltitudeEnvelope] Running Loop=1
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps:YoYoWpt2:C.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps:YoYoWpt2:D.YoYo] Running Loop=1
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps:YoYoWpt2:D.YoYo](DEBUG): Initialize YoYoComponent.
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps:YoYoWpt2:Wpt1a.Waypoint] Running Loop=1
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:RepeatOps:YoYoWpt2:Wpt1a.Waypoint](DEBUG): Initialize WaypointComponent.
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:Science] Running Loop=1
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:Science:D] Running Loop=1
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:Science:C] Running Loop=1
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:Science:A] Running Loop=1
2012-05-08T11:12:21.705Z,1336475541.705 [turbulence:G.OffshoreEnvelope] Running Loop=1
2012-05-08T11:12:27.305Z,1336475547.305 [NAL9601](INFO): Powering down
2012-05-08T11:59:29.705Z,1336478369.705 [turbulence:RepeatOps:YoYoWpt2:Wpt1a.Waypoint](INFO): Reached Waypoint: 36.719000,-121.947000
2012-05-08T11:59:29.705Z,1336478369.705 [turbulence:RepeatOps:YoYoWpt2:Wpt1a.Waypoint] Stopped
2012-05-08T11:59:29.705Z,1336478369.705 [turbulence:RepeatOps:YoYoWpt2:Wpt1a.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-05-08T11:59:29.705Z,1336478369.705 [turbulence:RepeatOps:YoYoWpt2:F] Running Loop=1
2012-05-08T11:59:29.705Z,1336478369.705 [turbulence:RepeatOps:YoYoWpt2:F](INFO): Aggregate::initialize turbulence:RepeatOps:YoYoWpt2:F
2012-05-08T11:59:30.105Z,1336478370.105 [turbulence:SURFACECOMMS] Running Loop=1
2012-05-08T11:59:30.105Z,1336478370.105 [turbulence:SURFACECOMMS](INFO): Aggregate::initialize turbulence:SURFACECOMMS
2012-05-08T11:59:30.105Z,1336478370.105 [turbulence:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-05-08T11:59:30.105Z,1336478370.105 [turbulence:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-08T12:02:40.505Z,1336478560.505 [turbulence:SURFACECOMMS:B] Running Loop=1
2012-05-08T12:02:40.505Z,1336478560.505 [turbulence:SURFACECOMMS:B](INFO): Aggregate::initialize turbulence:SURFACECOMMS:B
2012-05-08T12:02:40.505Z,1336478560.505 [turbulence:SURFACECOMMS:B:A] Running Loop=1
2012-05-08T12:02:40.905Z,1336478560.905 [turbulence:Science] Preempted
2012-05-08T12:02:40.905Z,1336478560.905 [turbulence:Science:A] Preempted
2012-05-08T12:02:40.905Z,1336478560.905 [turbulence:Science:C] Preempted
2012-05-08T12:02:40.905Z,1336478560.905 [turbulence:Science:D] Preempted
2012-05-08T12:02:40.905Z,1336478560.905 [turbulence:G.OffshoreEnvelope] Preempted
2012-05-08T12:02:41.305Z,1336478561.305 [NAL9601](INFO): Powering up
2012-05-08T12:02:46.505Z,1336478566.505 [NAL9601](IMPORTANT): GPS fix at: 1336478566.50
2012-05-08T12:02:46.505Z,1336478566.505 [Navigation](IMPORTANT): Navigation Error 0.0 %DT

2012-05-08T12:02:46.505Z,1336478566.505 [turbulence:SURFACECOMMS:B:A] Stopped
2012-05-08T12:02:46.505Z,1336478566.505 [turbulence:SURFACECOMMS:B:B] Running Loop=1
2012-05-08T12:02:49.305Z,1336478569.305 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0009.lzma
2012-05-08T12:02:49.305Z,1336478569.305 [NAL9601](INFO): Packets left to send: 4
2012-05-08T12:02:49.305Z,1336478569.305 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000047
2012-05-08T12:02:50.505Z,1336478570.505 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0009.lzma
2012-05-08T12:02:50.505Z,1336478570.505 [NAL9601](INFO): Packets left to send: 3
2012-05-08T12:02:50.505Z,1336478570.505 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000048
2012-05-08T12:02:51.705Z,1336478571.705 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0009.lzma
2012-05-08T12:02:51.705Z,1336478571.705 [NAL9601](INFO): Packets left to send: 2
2012-05-08T12:02:51.705Z,1336478571.705 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000049
2012-05-08T12:02:52.905Z,1336478572.905 [NAL9601](INFO): Sent 332 bytes from file Logs/20120507T232151/shore0009.lzma
2012-05-08T12:02:52.905Z,1336478572.905 [NAL9601](INFO): Packets left to send: 1
2012-05-08T12:02:52.905Z,1336478572.905 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000050
2012-05-08T12:02:54.105Z,1336478574.105 [NAL9601](INFO): Sent 75 bytes from file Logs/20120507T232151/shore0009.lzma
2012-05-08T12:02:54.105Z,1336478574.105 [NAL9601](INFO): Packets left to send: 0
2012-05-08T12:02:54.105Z,1336478574.105 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000051
2012-05-08T12:02:54.905Z,1336478574.905 [turbulence:SURFACECOMMS:B:B] Stopped
2012-05-08T12:02:54.905Z,1336478574.905 [turbulence:SURFACECOMMS:B:C] Running Loop=1
2012-05-08T12:03:00.105Z,1336478580.105 [NAL9601](IMPORTANT): GPS fix at: 1336478580.10
2012-05-08T12:03:00.105Z,1336478580.105 [turbulence:SURFACECOMMS:B:C] Stopped
2012-05-08T12:03:00.105Z,1336478580.105 [turbulence:SURFACECOMMS:B](INFO): Completed turbulence:SURFACECOMMS:B
2012-05-08T12:03:00.105Z,1336478580.105 [turbulence:SURFACECOMMS:B] Stopped
2012-05-08T12:03:00.105Z,1336478580.105 [turbulence:SURFACECOMMS:B](INFO): Aggregate::uninitialize turbulence:SURFACECOMMS:B
2012-05-08T12:03:00.105Z,1336478580.105 [turbulence:SURFACECOMMS](INFO): Completed turbulence:SURFACECOMMS
2012-05-08T12:03:00.105Z,1336478580.105 [turbulence:SURFACECOMMS] Stopped
2012-05-08T12:03:00.105Z,1336478580.105 [turbulence:SURFACECOMMS](INFO): Aggregate::uninitialize turbulence:SURFACECOMMS
2012-05-08T12:03:00.105Z,1336478580.105 [turbulence:SURFACECOMMS:A.GoToSurface] Stopped
2012-05-08T12:03:00.105Z,1336478580.105 [turbulence:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-08T12:03:00.505Z,1336478580.505 [turbulence:RepeatOps:YoYoWpt2:F](INFO): Completed turbulence:RepeatOps:YoYoWpt2:F
2012-05-08T12:03:00.505Z,1336478580.505 [turbulence:RepeatOps:YoYoWpt2:F] Stopped
2012-05-08T12:03:00.505Z,1336478580.505 [turbulence:RepeatOps:YoYoWpt2:F](INFO): Aggregate::uninitialize turbulence:RepeatOps:YoYoWpt2:F
2012-05-08T12:03:00.505Z,1336478580.505 [turbulence:RepeatOps:YoYoWpt2:Wpt1b.Waypoint] Running Loop=1
2012-05-08T12:03:00.505Z,1336478580.505 [turbulence:RepeatOps:YoYoWpt2:Wpt1b.Waypoint](DEBUG): Initialize WaypointComponent.
2012-05-08T12:03:00.505Z,1336478580.505 [turbulence:Science] Running Loop=1
2012-05-08T12:03:00.505Z,1336478580.505 [turbulence:Science:D] Running Loop=1
2012-05-08T12:03:00.505Z,1336478580.505 [turbulence:Science:C] Running Loop=1
2012-05-08T12:03:00.505Z,1336478580.505 [turbulence:Science:A] Running Loop=1
2012-05-08T12:03:00.505Z,1336478580.505 [turbulence:G.OffshoreEnvelope] Running Loop=1
2012-05-08T12:03:06.105Z,1336478586.105 [NAL9601](INFO): Powering down
2012-05-08T12:10:30.905Z,1336479030.905 [CommandLine](IMPORTANT): got command stop
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence] Stopped
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence](INFO): Aggregate::uninitialize turbulence
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:A.AltitudeEnvelope] Stopped
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:B.DepthEnvelope] Stopped
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:B.DepthEnvelope](DEBUG): Uninitialize.
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:C.Buoyancy] Stopped
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:C.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:D.Pitch] Stopped
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:G.OffshoreEnvelope] Stopped
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:G.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:Science] Stopped
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:Science](INFO): Aggregate::uninitialize turbulence:Science
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:Science:A] Stopped
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:Science:B] Stopped
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:Science:C] Stopped
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:Science:D] Stopped
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:RepeatOps] Stopped
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:RepeatOps](INFO): Aggregate::uninitialize turbulence:RepeatOps
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:RepeatOps:YoYoWpt2] Stopped
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:RepeatOps:YoYoWpt2](INFO): Aggregate::uninitialize turbulence:RepeatOps:YoYoWpt2
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:RepeatOps:YoYoWpt2:A.SetSpeed] Stopped
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:RepeatOps:YoYoWpt2:A.SetSpeed](DEBUG): Uninitialize.
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:RepeatOps:YoYoWpt2:B.DepthEnvelope] Stopped
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:RepeatOps:YoYoWpt2:B.DepthEnvelope](DEBUG): Uninitialize.
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:RepeatOps:YoYoWpt2:C.AltitudeEnvelope] Stopped
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:RepeatOps:YoYoWpt2:C.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:RepeatOps:YoYoWpt2:D.YoYo] Stopped
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:RepeatOps:YoYoWpt2:D.YoYo](DEBUG): Uninitialize YoYoComponent.
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:RepeatOps:YoYoWpt2:Wpt1b.Waypoint] Stopped
2012-05-08T12:10:31.305Z,1336479031.305 [turbulence:RepeatOps:YoYoWpt2:Wpt1b.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-05-08T12:10:31.705Z,1336479031.705 [MissionManager](IMPORTANT): Started mission Default
2012-05-08T12:10:31.705Z,1336479031.705 [Default] Running Loop=1
2012-05-08T12:10:31.705Z,1336479031.705 [Default](INFO): Aggregate::initialize Default
2012-05-08T12:10:31.705Z,1336479031.705 [Default:D.SetSpeed] Running Loop=1
2012-05-08T12:10:31.705Z,1336479031.705 [Default:D.SetSpeed](DEBUG): Initialize.
2012-05-08T12:10:31.705Z,1336479031.705 [Default:E.GoToSurface] Running Loop=1
2012-05-08T12:10:31.705Z,1336479031.705 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-08T12:10:31.705Z,1336479031.705 [Default:Iridium] Running Loop=1
2012-05-08T12:10:31.705Z,1336479031.705 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-05-08T12:10:31.705Z,1336479031.705 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-05-08T12:10:31.705Z,1336479031.705 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-05-08T12:10:31.705Z,1336479031.705 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-05-08T12:10:31.705Z,1336479031.705 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-08T12:10:31.705Z,1336479031.705 [Default:E.GoToSurface] Running Loop=1
2012-05-08T12:10:31.705Z,1336479031.705 [Default:D.SetSpeed] Running Loop=1
2012-05-08T12:10:31.705Z,1336479031.705 [Default:CallIridium] Running Loop=1
2012-05-08T12:10:31.705Z,1336479031.705 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-05-08T12:10:31.705Z,1336479031.705 [Default:CallIridium:A] Running Loop=1
2012-05-08T12:10:31.705Z,1336479031.705 [Default:CallIridium:A] Stopped
2012-05-08T12:10:31.705Z,1336479031.705 [Default:CallIridium:B] Running Loop=1
2012-05-08T12:10:31.705Z,1336479031.705 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-05-08T12:10:31.705Z,1336479031.705 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-05-08T12:10:31.705Z,1336479031.705 [Default:GPS] Running Loop=1
2012-05-08T12:10:31.705Z,1336479031.705 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-05-08T12:10:31.705Z,1336479031.705 [Default:GPS:A.SetSpeed] Running Loop=1
2012-05-08T12:10:31.705Z,1336479031.705 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-05-08T12:10:31.705Z,1336479031.705 [Default:GPS:B.GoToSurface] Running Loop=1
2012-05-08T12:10:31.705Z,1336479031.705 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-08T12:10:31.705Z,1336479031.705 [Default:GPS:A.SetSpeed] Running Loop=1
2012-05-08T12:10:32.487Z,1336479032.487 [CommandLine](IMPORTANT): got command quit
2012-05-08T12:10:32.525Z,1336479032.525 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2012-05-08T12:10:32.525Z,1336479032.525 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2012-05-08T12:10:32.716Z,1336479032.716 [ComponentRegistry](INFO): Shutting down Turbulence_NPS ThreadHandler
2012-05-08T12:10:32.725Z,1336479032.725 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2012-05-08T12:10:32.752Z,1336479032.752 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler
2012-05-08T12:10:32.932Z,1336479032.932 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator.
2012-05-08T12:10:32.932Z,1336479032.932 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2012-05-08T12:10:32.932Z,1336479032.932 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2012-05-08T12:10:33.306Z,1336479033.306 [controlThread](DEBUG): Uninitializing ControlThread
2012-05-08T12:10:33.306Z,1336479033.306 [NAL9601](INFO): Powering down
2012-05-08T12:10:33.306Z,1336479033.306 [NavChart](DEBUG): Uninitialize NavChart Derivation.
2012-05-08T12:10:33.306Z,1336479033.306 [Default] Stopped
2012-05-08T12:10:33.306Z,1336479033.306 [Default](INFO): Aggregate::uninitialize Default
2012-05-08T12:10:33.306Z,1336479033.306 [Default:GPS] Stopped
2012-05-08T12:10:33.306Z,1336479033.306 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-05-08T12:10:33.306Z,1336479033.306 [Default:GPS:A.SetSpeed] Stopped
2012-05-08T12:10:33.306Z,1336479033.306 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-05-08T12:10:33.306Z,1336479033.306 [Default:GPS:B.GoToSurface] Stopped
2012-05-08T12:10:33.306Z,1336479033.306 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-08T12:10:33.306Z,1336479033.306 [Default:Iridium] Stopped
2012-05-08T12:10:33.306Z,1336479033.306 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-05-08T12:10:33.306Z,1336479033.306 [Default:Iridium:A.SetSpeed] Stopped
2012-05-08T12:10:33.306Z,1336479033.306 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-05-08T12:10:33.306Z,1336479033.306 [Default:Iridium:B.GoToSurface] Stopped
2012-05-08T12:10:33.306Z,1336479033.306 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-08T12:10:33.306Z,1336479033.306 [Default:CallIridium] Stopped
2012-05-08T12:10:33.306Z,1336479033.306 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-05-08T12:10:33.306Z,1336479033.306 [Default:CallIridium:B] Stopped
2012-05-08T12:10:33.306Z,1336479033.306 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-05-08T12:10:33.306Z,1336479033.306 [Default:D.SetSpeed] Stopped
2012-05-08T12:10:33.306Z,1336479033.306 [Default:D.SetSpeed](DEBUG): Uninitialize.
2012-05-08T12:10:33.306Z,1336479033.306 [Default:E.GoToSurface] Stopped
2012-05-08T12:10:33.306Z,1336479033.306 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-08T12:10:33.306Z,1336479033.306 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2012-05-08T12:10:33.306Z,1336479033.306 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2012-05-08T12:10:33.306Z,1336479033.306 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2012-05-08T12:10:33.306Z,1336479033.306 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2012-05-08T12:10:33.306Z,1336479033.306 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2012-05-08T12:10:33.306Z,1336479033.306 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2012-05-08T12:10:33.306Z,1336479033.306 [MassServo](DEBUG): Uninitialize Mass Servo.
2012-05-08T12:10:33.306Z,1336479033.306 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2012-05-08T12:10:33.306Z,1336479033.306 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2012-05-08T12:10:33.306Z,1336479033.306 [SBIT](DEBUG): Uninitialize SBIT Component.
2012-05-08T12:10:33.306Z,1336479033.306 [IBIT](DEBUG): Uninitialize IBIT Component.
2012-05-08T12:10:33.306Z,1336479033.306 [CBIT](DEBUG): Uninitialize CBIT Component.