2012-05-23T16:40:21.439Z,1337791221.439 [Supervisor](DEBUG): Initializing supervisor.
2012-05-23T16:40:21.441Z,1337791221.441 [SyncHandler](DEBUG): Created PCaller Thread at 1077138656
2012-05-23T16:40:21.442Z,1337791221.442 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2012-05-23T16:40:21.443Z,1337791221.443 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 1077335264
2012-05-23T16:40:21.444Z,1337791221.444 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2012-05-23T16:40:21.455Z,1337791221.455 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2012-05-23T16:40:21.456Z,1337791221.456 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 1077531872
2012-05-23T16:40:21.457Z,1337791221.457 [ComponentRegistry](DEBUG): SyncComponent "logger" handled in the control thread.
2012-05-23T16:40:21.458Z,1337791221.458 [Supervisor](INFO): Looking for Config files in directory: Config/
2012-05-23T16:40:21.459Z,1337791221.459 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2012-05-23T16:40:21.737Z,1337791221.737 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2012-05-23T16:40:21.737Z,1337791221.737 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2012-05-23T16:40:21.920Z,1337791221.920 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2012-05-23T16:40:21.920Z,1337791221.920 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2012-05-23T16:40:21.000Z,1337791222.000 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2012-05-23T16:40:22.001Z,1337791222.000 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2012-05-23T16:40:22.187Z,1337791222.187 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2012-05-23T16:40:22.188Z,1337791222.188 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2012-05-23T16:40:22.309Z,1337791222.309 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2012-05-23T16:40:22.310Z,1337791222.310 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2012-05-23T16:40:22.527Z,1337791222.527 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2012-05-23T16:40:22.527Z,1337791222.527 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2012-05-23T16:40:22.672Z,1337791222.672 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2012-05-23T16:40:22.673Z,1337791222.673 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2012-05-23T16:40:22.930Z,1337791222.930 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2012-05-23T16:40:22.930Z,1337791222.930 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2012-05-23T16:40:23.063Z,1337791223.063 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2012-05-23T16:40:23.063Z,1337791223.063 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2012-05-23T16:40:23.449Z,1337791223.449 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2012-05-23T16:40:23.450Z,1337791223.450 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2012-05-23T16:40:23.555Z,1337791223.555 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2012-05-23T16:40:23.555Z,1337791223.555 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2012-05-23T16:40:23.635Z,1337791223.635 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2012-05-23T16:40:23.636Z,1337791223.636 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2012-05-23T16:40:23.734Z,1337791223.734 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2012-05-23T16:40:23.864Z,1337791223.864 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2012-05-23T16:40:23.959Z,1337791223.959 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2012-05-23T16:40:24.049Z,1337791224.049 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2012-05-23T16:40:24.155Z,1337791224.155 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2012-05-23T16:40:24.239Z,1337791224.239 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2012-05-23T16:40:24.321Z,1337791224.321 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2012-05-23T16:40:24.429Z,1337791224.429 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2012-05-23T16:40:24.560Z,1337791224.560 [InternalSim] Loaded
2012-05-23T16:40:24.561Z,1337791224.561 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2012-05-23T16:40:24.562Z,1337791224.562 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2012-05-23T16:40:24.562Z,1337791224.562 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2012-05-23T16:40:24.608Z,1337791224.608 [SBIT](DEBUG): Construct Startup Built In Test.
2012-05-23T16:40:24.619Z,1337791224.619 [SBIT] Loaded
2012-05-23T16:40:24.620Z,1337791224.620 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2012-05-23T16:40:24.621Z,1337791224.621 [IBIT](DEBUG): Construct Initiated Built In Test.
2012-05-23T16:40:24.645Z,1337791224.645 [IBIT] Loaded
2012-05-23T16:40:24.645Z,1337791224.645 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2012-05-23T16:40:24.652Z,1337791224.652 [CBIT](DEBUG): Construct CBIT Built In Test.
2012-05-23T16:40:24.763Z,1337791224.763 [CBIT] Loaded
2012-05-23T16:40:24.764Z,1337791224.764 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2012-05-23T16:40:24.764Z,1337791224.764 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2012-05-23T16:40:24.765Z,1337791224.765 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2012-05-23T16:40:24.897Z,1337791224.897 [BuoyancyServo] Loaded
2012-05-23T16:40:24.897Z,1337791224.898 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2012-05-23T16:40:24.905Z,1337791224.905 [ElevatorServo] Loaded
2012-05-23T16:40:24.905Z,1337791224.905 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2012-05-23T16:40:24.912Z,1337791224.912 [MassServo] Loaded
2012-05-23T16:40:24.912Z,1337791224.912 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2012-05-23T16:40:24.919Z,1337791224.919 [RudderServo] Loaded
2012-05-23T16:40:24.919Z,1337791224.919 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2012-05-23T16:40:24.926Z,1337791224.926 [ThrusterServo] Loaded
2012-05-23T16:40:24.926Z,1337791224.926 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2012-05-23T16:40:24.927Z,1337791224.927 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2012-05-23T16:40:24.928Z,1337791224.928 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2012-05-23T16:40:24.942Z,1337791224.942 [DepthRateCalculator] Loaded
2012-05-23T16:40:24.943Z,1337791224.943 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2012-05-23T16:40:24.959Z,1337791224.959 [NavChart] Loaded
2012-05-23T16:40:24.960Z,1337791224.960 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2012-05-23T16:40:24.965Z,1337791224.965 [PitchRateCalculator] Loaded
2012-05-23T16:40:24.966Z,1337791224.966 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2012-05-23T16:40:24.972Z,1337791224.972 [SpeedCalculator] Loaded
2012-05-23T16:40:24.972Z,1337791224.972 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2012-05-23T16:40:24.987Z,1337791224.987 [TempGradientCalculator] Loaded
2012-05-23T16:40:24.987Z,1337791224.987 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2012-05-23T16:40:24.993Z,1337791224.993 [YawRateCalculator] Loaded
2012-05-23T16:40:24.993Z,1337791224.993 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2012-05-23T16:40:25.024Z,1337791225.024 [Navigation] Loaded
2012-05-23T16:40:25.024Z,1337791225.024 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread.
2012-05-23T16:40:25.025Z,1337791225.025 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2012-05-23T16:40:25.025Z,1337791225.025 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2012-05-23T16:40:25.252Z,1337791225.252 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2012-05-23T16:40:25.253Z,1337791225.253 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2012-05-23T16:40:25.274Z,1337791225.274 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2012-05-23T16:40:25.275Z,1337791225.275 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2012-05-23T16:40:25.315Z,1337791225.315 [VerticalControl](DEBUG): Construct VerticalControl.
2012-05-23T16:40:25.358Z,1337791225.358 [VerticalControl] Loaded
2012-05-23T16:40:25.358Z,1337791225.358 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2012-05-23T16:40:25.359Z,1337791225.359 [HorizontalControl](DEBUG): Construct HorizontalControl.
2012-05-23T16:40:25.379Z,1337791225.379 [HorizontalControl] Loaded
2012-05-23T16:40:25.379Z,1337791225.379 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2012-05-23T16:40:25.380Z,1337791225.380 [SpeedControl](DEBUG): Construct SpeedControl.
2012-05-23T16:40:25.382Z,1337791225.382 [SpeedControl] Loaded
2012-05-23T16:40:25.382Z,1337791225.382 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2012-05-23T16:40:25.383Z,1337791225.383 [LoopControl](DEBUG): Construct LoopControl.
2012-05-23T16:40:25.384Z,1337791225.384 [LoopControl] Loaded
2012-05-23T16:40:25.384Z,1337791225.384 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2012-05-23T16:40:25.384Z,1337791225.384 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2012-05-23T16:40:25.385Z,1337791225.385 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2012-05-23T16:40:25.390Z,1337791225.390 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2012-05-23T16:40:25.395Z,1337791225.395 [AsyncPiEstimator] Loaded
2012-05-23T16:40:25.395Z,1337791225.395 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2012-05-23T16:40:25.396Z,1337791225.397 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 1078994144
2012-05-23T16:40:25.397Z,1337791225.397 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2012-05-23T16:40:25.398Z,1337791225.398 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2012-05-23T16:40:25.492Z,1337791225.492 [AHRS_sp3003D] Loaded
2012-05-23T16:40:25.493Z,1337791225.493 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2012-05-23T16:40:25.746Z,1337791225.746 [Batt_Ocean_Server] Loaded
2012-05-23T16:40:25.747Z,1337791225.747 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread.
2012-05-23T16:40:25.759Z,1337791225.759 [Depth_Keller] Loaded
2012-05-23T16:40:25.759Z,1337791225.759 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2012-05-23T16:40:25.765Z,1337791225.765 [DropWeight] Loaded
2012-05-23T16:40:25.765Z,1337791225.765 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2012-05-23T16:40:25.863Z,1337791225.863 [DVL_micro] Loaded
2012-05-23T16:40:25.863Z,1337791225.863 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2012-05-23T16:40:25.944Z,1337791225.944 [NAL9601] Loaded
2012-05-23T16:40:25.944Z,1337791225.944 [ComponentRegistry](DEBUG): SyncComponent "NAL9601" handled in the control thread.
2012-05-23T16:40:25.995Z,1337791225.995 [Onboard] Loaded
2012-05-23T16:40:25.995Z,1337791225.995 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2012-05-23T16:40:26.001Z,1337791226.001 [Radio_Freewave] Loaded
2012-05-23T16:40:26.002Z,1337791226.001 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread.
2012-05-23T16:40:26.002Z,1337791226.002 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2012-05-23T16:40:26.003Z,1337791226.003 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2012-05-23T16:40:26.048Z,1337791226.048 [CTD_NeilBrown] Loaded
2012-05-23T16:40:26.049Z,1337791226.049 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2012-05-23T16:40:26.050Z,1337791226.050 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 1079645408
2012-05-23T16:40:26.081Z,1337791226.081 [WetLabsBB2FL] Loaded
2012-05-23T16:40:26.081Z,1337791226.081 [ComponentRegistry](DEBUG): SyncComponent "WetLabsBB2FL" handled in the control thread.
2012-05-23T16:40:26.082Z,1337791226.082 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2012-05-23T16:40:26.084Z,1337791226.084 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2012-05-23T16:40:26.085Z,1337791226.085 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2012-05-23T16:40:26.090Z,1337791226.091 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2012-05-23T16:40:26.092Z,1337791226.092 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 1079842016
2012-05-23T16:40:26.092Z,1337791226.092 [Supervisor](DEBUG): Running supervisor.
2012-05-23T16:40:26.096Z,1337791226.096 [controlThread](DEBUG): Initializing ControlThread
2012-05-23T16:40:26.097Z,1337791226.097 [InternalSim](DEBUG):  InternalSim initializing...
2012-05-23T16:40:26.131Z,1337791226.131 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2012-05-23T16:40:26.140Z,1337791226.140 [SBIT](INFO): Initialize SBIT Component.
2012-05-23T16:40:26.141Z,1337791226.141 [SBIT](IMPORTANT): Tethys CM Info:
$Rev: 9636 $
2012-05-23T16:40:26.141Z,1337791226.141 [IBIT](INFO): Initialize IBIT Component.
2012-05-23T16:40:26.144Z,1337791226.144 [CBIT](DEBUG): Initialize CBIT Component.
2012-05-23T16:40:26.145Z,1337791226.145 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2012-05-23T16:40:26.146Z,1337791226.146 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2012-05-23T16:40:26.180Z,1337791226.180 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2012-05-23T16:40:26.180Z,1337791226.180 [NavChart](DEBUG): Initialize NavChart Derivation.
2012-05-23T16:40:26.181Z,1337791226.181 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2012-05-23T16:40:26.181Z,1337791226.181 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2012-05-23T16:40:26.182Z,1337791226.181 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2012-05-23T16:40:26.183Z,1337791226.183 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2012-05-23T16:40:26.183Z,1337791226.183 [Navigation](DEBUG): Initializing Navigation.
2012-05-23T16:40:26.184Z,1337791226.184 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2012-05-23T16:40:26.186Z,1337791226.186 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2012-05-23T16:40:26.186Z,1337791226.187 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2012-05-23T16:40:26.187Z,1337791226.187 [LoopControl](DEBUG): Initialize LoopControlComponent.
2012-05-23T16:40:26.255Z,1337791226.255 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2012-05-23T16:40:26.261Z,1337791226.261 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2012-05-23T16:40:26.265Z,1337791226.265 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2012-05-23T16:40:26.269Z,1337791226.269 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2012-05-23T16:40:26.273Z,1337791226.273 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2012-05-23T16:40:26.274Z,1337791226.274 [NavChartDb](INFO): Creating index for Soundings
2012-05-23T16:40:26.277Z,1337791226.277 [NavChartDb](INFO): Creating index for Contour 0p0
2012-05-23T16:40:26.279Z,1337791226.279 [NavChartDb](INFO): Creating index for Contour 1p8
2012-05-23T16:40:26.281Z,1337791226.281 [NavChartDb](INFO): Creating index for Contour 3p6
2012-05-23T16:40:26.283Z,1337791226.283 [NavChartDb](INFO): Creating index for Contour 5p4
2012-05-23T16:40:26.285Z,1337791226.285 [NavChartDb](INFO): Creating index for Contour 9p1
2012-05-23T16:40:26.287Z,1337791226.287 [NavChartDb](INFO): Creating index for Contour 10p9
2012-05-23T16:40:26.289Z,1337791226.289 [NavChartDb](INFO): Creating index for Contour 18p2
2012-05-23T16:40:26.291Z,1337791226.291 [NavChartDb](INFO): Creating index for Contour 36p5
2012-05-23T16:40:26.293Z,1337791226.293 [NavChartDb](INFO): Creating index for Contour 54p8
2012-05-23T16:40:26.295Z,1337791226.295 [NavChartDb](INFO): Creating index for Contour 73p1
2012-05-23T16:40:26.297Z,1337791226.297 [NavChartDb](INFO): Creating index for Contour 91p4
2012-05-23T16:40:26.299Z,1337791226.299 [NavChartDb](INFO): Creating index for Contour 182p8
2012-05-23T16:40:26.301Z,1337791226.301 [NavChartDb](INFO): Creating index for Contour 365p7
2012-05-23T16:40:26.303Z,1337791226.303 [NavChartDb](INFO): Creating index for Contour 548p6
2012-05-23T16:40:26.305Z,1337791226.305 [NavChartDb](INFO): Creating index for Contour 731p5
2012-05-23T16:40:26.307Z,1337791226.307 [NavChartDb](INFO): Creating index for Contour 914p4
2012-05-23T16:40:26.308Z,1337791226.308 [NavChartDb](INFO): Creating index for Contour 1097p2
2012-05-23T16:40:26.310Z,1337791226.310 [NavChartDb](INFO): Creating index for Contour 1280p1
2012-05-23T16:40:26.312Z,1337791226.312 [NavChartDb](INFO): Creating index for Contour 1463p0
2012-05-23T16:40:26.314Z,1337791226.314 [NavChartDb](INFO): Creating index for Contour 1645p9
2012-05-23T16:40:26.316Z,1337791226.316 [NavChartDb](INFO): Creating index for Contour 1828p8
2012-05-23T16:40:26.318Z,1337791226.318 [NavChartDb](INFO): Creating index for Contour 2011p6
2012-05-23T16:40:26.320Z,1337791226.320 [NavChartDb](INFO): Creating index for Contour 2194p5
2012-05-23T16:40:26.322Z,1337791226.322 [NavChartDb](INFO): Creating index for Contour 2377p4
2012-05-23T16:40:26.324Z,1337791226.324 [NavChartDb](INFO): Creating index for Contour 2560p3
2012-05-23T16:40:26.326Z,1337791226.326 [NavChartDb](INFO): Creating index for Contour 2743p2
2012-05-23T16:40:26.328Z,1337791226.328 [NavChartDb](INFO): Creating index for Contour 2926p0
2012-05-23T16:40:26.329Z,1337791226.329 [NavChartDb](INFO): Creating index for Contour 3108p9
2012-05-23T16:40:26.331Z,1337791226.332 [NavChartDb](INFO): Creating index for Contour 3291p8
2012-05-23T16:40:26.333Z,1337791226.333 [NavChartDb](INFO): Creating index for Contour 3474p7
2012-05-23T16:40:26.335Z,1337791226.335 [NavChartDb](INFO): Creating index for Contour 3657p6
2012-05-23T16:40:27.835Z,1337791227.835 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2012-05-23T16:40:27.845Z,1337791227.845 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2012-05-23T16:40:27.855Z,1337791227.855 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2012-05-23T16:40:27.864Z,1337791227.864 [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-23T16:40:27.865Z,1337791227.865 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2012-05-23T16:40:27.892Z,1337791227.892 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2012-05-23T16:40:27.895Z,1337791227.895 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2012-05-23T16:40:27.898Z,1337791227.898 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2012-05-23T16:40:27.905Z,1337791227.905 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2012-05-23T16:40:27.908Z,1337791227.908 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2012-05-23T16:40:27.914Z,1337791227.914 [Default:Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2012-05-23T16:40:27.923Z,1337791227.923 [Default:D.SetSpeed](DEBUG): Construct.
2012-05-23T16:40:27.926Z,1337791227.926 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2012-05-23T16:40:27.930Z,1337791227.930 [Default:F.Wait](DEBUG): Construct Wait.
2012-05-23T16:40:27.933Z,1337791227.934 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
xmlns:Control="Tethys/Control"
xmlns:Guidance="Tethys/Guidance" 
xmlns:Units="Tethys/Units"
xmlns:Universal="Tethys/Universal"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

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

    <Aggregate Id="GPS" >

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

<!-- Run at high speed -->

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

<!-- Get to the surface -->

        <Guidance:GoToSurface RunIn="Sequence"/>

<!-- Get GPS fix -->

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

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

    </Aggregate>

    <Aggregate Id="Iridium" >

<!-- Run at high speed -->

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

<!-- Get to the surface -->

        <Guidance:GoToSurface RunIn="Sequence"/>

<!-- Do data comms -->

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

    </Aggregate>

    <Aggregate Id="CallIridium" >

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

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

        <Call RefId="Iridium"/>

    </Aggregate>

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

<!-- Run at low speed -->

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

    <Guidance:GoToSurface RunIn="Parallel"/>

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

</Mission>


2012-05-23T16:40:27.939Z,1337791227.939 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,DVL_micro,NAL9601,Onboard,Radio_Freewave,WetLabsBB2FL,Depth_Keller,DepthRateCalculator,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,logger,
2012-05-23T16:40:27.957Z,1337791227.957 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-05-23T16:40:28.065Z,1337791228.065 [DVL_micro](DEBUG): Initializing DVL_micro.
2012-05-23T16:40:28.086Z,1337791228.086 [Radio_Freewave](INFO): Powering up
2012-05-23T16:40:28.091Z,1337791228.091 [WetLabsBB2FL](INFO): Powering down
2012-05-23T16:40:28.160Z,1337791228.160 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2012-05-23T16:40:28.161Z,1337791228.161 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2012-05-23T16:40:28.170Z,1337791228.170 [ElevatorServo](DEBUG): Initializing EZServoServo.
2012-05-23T16:40:28.172Z,1337791228.172 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2012-05-23T16:40:28.179Z,1337791228.180 [MassServo](DEBUG): Initializing EZServoServo.
2012-05-23T16:40:28.180Z,1337791228.180 [MassServo](DEBUG): Initializing MassServo.
2012-05-23T16:40:28.188Z,1337791228.188 [RudderServo](DEBUG): Initializing EZServoServo.
2012-05-23T16:40:28.189Z,1337791228.189 [RudderServo](DEBUG): Initializing RudderServo.
2012-05-23T16:40:28.197Z,1337791228.197 [ThrusterServo](DEBUG): Initializing EZServoServo.
2012-05-23T16:40:28.198Z,1337791228.198 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2012-05-23T16:40:31.089Z,1337791231.089 [NAL9601](INFO): Powering up NAL9601
2012-05-23T16:40:41.737Z,1337791241.737 [SBIT](IMPORTANT): Beginning Startup BIT
2012-05-23T16:40:41.739Z,1337791241.739 [CBIT](IMPORTANT): Beginning GF scan
2012-05-23T16:41:07.969Z,1337791267.969 [CBIT](IMPORTANT): No ground fault detected
2012-05-23T16:41:23.922Z,1337791283.922 [SBIT](IMPORTANT): SBIT PASSED
2012-05-23T16:41:24.284Z,1337791284.284 [MissionManager](IMPORTANT): Started mission Startup
2012-05-23T16:41:24.284Z,1337791284.284 [Startup] Running Loop=1
2012-05-23T16:41:24.284Z,1337791284.284 [Startup](INFO): Aggregate::initialize Startup
2012-05-23T16:41:24.284Z,1337791284.284 [Startup:A.GoToSurface] Running Loop=1
2012-05-23T16:41:24.284Z,1337791284.284 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-23T16:41:24.290Z,1337791284.290 [Startup:StartupSatComms] Running Loop=1
2012-05-23T16:41:24.290Z,1337791284.290 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2012-05-23T16:41:24.290Z,1337791284.290 [Startup:StartupSatComms:A] Running Loop=1
2012-05-23T16:41:24.681Z,1337791284.681 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-05-23T16:41:36.787Z,1337791296.787 [NAL9601](INFO): NAL9601 initialized
2012-05-23T16:42:24.300Z,1337791344.300 [Startup:StartupSatComms:A](INFO): Timed out from 2012-05-23T16:41:24.3Z
2012-05-23T16:42:24.300Z,1337791344.300 [Startup:StartupSatComms:A_Timeout] Running Loop=1
2012-05-23T16:42:24.300Z,1337791344.300 [Startup:StartupSatComms:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:A_Timeout
2012-05-23T16:42:24.301Z,1337791344.300 [Startup:StartupSatComms:A_Timeout](INFO): Completed Startup:StartupSatComms:A_Timeout
2012-05-23T16:42:24.301Z,1337791344.301 [Startup:StartupSatComms:A] Stopped
2012-05-23T16:42:24.301Z,1337791344.301 [Startup:StartupSatComms:B] Running Loop=1
2012-05-23T16:42:24.686Z,1337791344.686 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-05-23T16:43:21.001Z,1337791401.001 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/tank_weight.xml
2012-05-23T16:43:21.002Z,1337791401.001 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/tank_weight.xml
2012-05-23T16:43:21.029Z,1337791401.029 [MissionManager](INFO): DefineArg tank_weight.BuoyancyLimitHiCC = 0.000955 n/a
2012-05-23T16:43:21.032Z,1337791401.032 [MissionManager](INFO): DefineArg tank_weight.BuoyancyLimitLoCC = 0.000080 n/a
2012-05-23T16:43:21.034Z,1337791401.034 [tank_weight:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2012-05-23T16:43:21.041Z,1337791401.042 [tank_weight:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2012-05-23T16:43:21.051Z,1337791401.050 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2012-05-23T16:43:21.097Z,1337791401.097 [MissionManager](INFO): DefineArg tank_weight:Science.SampleAanderaaO2 = 0.000000 n/a
2012-05-23T16:43:21.100Z,1337791401.100 [MissionManager](INFO): DefineArg tank_weight:Science.SampleISUS = 0 bool
2012-05-23T16:43:21.103Z,1337791401.103 [MissionManager](INFO): DefineArg tank_weight:Science.SampleNeilBrown = 1.000000 n/a
2012-05-23T16:43:21.107Z,1337791401.107 [MissionManager](INFO): DefineArg tank_weight:Science.SampleWetLabsBB2FL = 1.000000 n/a
2012-05-23T16:43:21.109Z,1337791401.109 [MissionManager](INFO): DefineArg tank_weight:Science.PeakDetectChlActive = 0 bool
2012-05-23T16:43:21.112Z,1337791401.112 [MissionManager](INFO): DefineArg tank_weight:Science.PeakDetectNO3Active = 0 bool
2012-05-23T16:43:21.115Z,1337791401.115 [MissionManager](INFO): DefineOutput tank_weight:Science.PeakChl = 0.000000 ug/l
2012-05-23T16:43:21.118Z,1337791401.118 [MissionManager](INFO): DefineOutput tank_weight:Science.PeakChlDepth = 0.000000 m
2012-05-23T16:43:21.121Z,1337791401.121 [MissionManager](INFO): DefineOutput tank_weight:Science.PeakChlLatitude = nan arcdeg
2012-05-23T16:43:21.124Z,1337791401.124 [MissionManager](INFO): DefineOutput tank_weight:Science.PeakChlLongitude = nan arcdeg
2012-05-23T16:43:21.127Z,1337791401.127 [MissionManager](INFO): DefineOutput tank_weight:Science.PeakNO3 = 0.000000 uM/l
2012-05-23T16:43:21.130Z,1337791401.130 [MissionManager](INFO): DefineOutput tank_weight:Science.PeakNO3Depth = 0.000000 m
2012-05-23T16:43:21.133Z,1337791401.133 [MissionManager](INFO): DefineOutput tank_weight:Science.PeakNO3Latitude = nan arcdeg
2012-05-23T16:43:21.138Z,1337791401.138 [MissionManager](INFO): DefineOutput tank_weight:Science.PeakNO3Longitude = nan arcdeg
2012-05-23T16:43:21.145Z,1337791401.145 [tank_weight:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2012-05-23T16:43:21.153Z,1337791401.153 [tank_weight:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2012-05-23T16:43:21.162Z,1337791401.162 [MissionManager](INFO): RedefineArg tank_weight:Science.SampleISUS = 1 bool
2012-05-23T16:43:21.164Z,1337791401.164 [tank_weight:D:A.SetSpeed](DEBUG): Construct.
2012-05-23T16:43:21.167Z,1337791401.167 [tank_weight:D:B.Pitch](DEBUG): Construct.
2012-05-23T16:43:21.174Z,1337791401.174 [tank_weight:D:BuoyancyLo:A.Buoyancy](DEBUG): Construct Buoyancy.
2012-05-23T16:43:21.177Z,1337791401.177 [tank_weight:D:BuoyancyLo:B.Wait](DEBUG): Construct Wait.
2012-05-23T16:43:21.180Z,1337791401.180 [tank_weight:D:BuoyancyHi:A.Buoyancy](DEBUG): Construct Buoyancy.
2012-05-23T16:43:21.183Z,1337791401.183 [tank_weight:D:BuoyancyHi:B.Wait](DEBUG): Construct Wait.
2012-05-23T16:43:21.190Z,1337791401.190 [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/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
       Id="tank_weight">

    <DefineArg Name="BuoyancyLimitHiCC"><Control:VerticalControl.buoyancyLimitHiCC/></DefineArg>

    <DefineArg Name="BuoyancyLimitLoCC"><Control:VerticalControl.buoyancyLimitLoCC/></DefineArg>

    <Timeout Duration="P75M"/>

    <Guidance:AltitudeEnvelope RunIn="Parallel">
        <Setting><Guidance:AltitudeEnvelope.minAltitude/><Units:meter/><Value>0</Value></Setting>
    </Guidance:AltitudeEnvelope>

    <Guidance:DepthEnvelope RunIn="Parallel">
        <Setting><Guidance:DepthEnvelope.minDepth/><Units:meter/><Value>0</Value></Setting>
        <Setting><Guidance:DepthEnvelope.maxDepth/><Units:meter/><Value>15</Value></Setting>
    </Guidance:DepthEnvelope>

    <Insert Filename="Insert/Science.xml">
        <RedefineArg Name="SampleISUS"><True/></RedefineArg>
    </Insert>

    <Aggregate>

        <Guidance:SetSpeed RunIn="Parallel">
            <Setting><Guidance:SetSpeed.speed/><Units:meter_per_second/><Value>0</Value></Setting>
        </Guidance:SetSpeed>

        <Guidance:Pitch RunIn="Parallel">
            <Setting><Guidance:Pitch.massPosition/><Units:meter/><Value>0</Value></Setting>
            <Setting><Guidance:Pitch.depth/><Units:meter/><Value>15</Value></Setting>
        </Guidance:Pitch>

        <Aggregate Id="BuoyancyLo">

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

            <Guidance:Wait RunIn="Sequence">
                <Setting><Guidance:Wait.duration/><Units:minute/><Value>65</Value></Setting>
            </Guidance:Wait>

        </Aggregate>

        <Aggregate Id="BuoyancyHi">

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

            <Guidance:Wait RunIn="Sequence">
                <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
            </Guidance:Wait>

        </Aggregate>

    </Aggregate>

</Mission>


2012-05-23T16:43:21.190Z,1337791401.190 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/tank_weight.xml
2012-05-23T16:43:24.599Z,1337791404.599 [Startup:StartupSatComms:B](INFO): Timed out from 2012-05-23T16:42:24.3Z
2012-05-23T16:43:24.599Z,1337791404.599 [Startup:StartupSatComms:A_Timeout] Running Loop=1
2012-05-23T16:43:24.600Z,1337791404.600 [Startup:StartupSatComms:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:A_Timeout
2012-05-23T16:43:24.600Z,1337791404.600 [Startup:StartupSatComms:A_Timeout](INFO): Completed Startup:StartupSatComms:A_Timeout
2012-05-23T16:43:24.600Z,1337791404.600 [Startup:StartupSatComms:B] Stopped
2012-05-23T16:43:24.600Z,1337791404.600 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2012-05-23T16:43:24.600Z,1337791404.600 [Startup:StartupSatComms] Stopped
2012-05-23T16:43:24.600Z,1337791404.600 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2012-05-23T16:43:24.601Z,1337791404.601 [Startup](INFO): Completed Startup
2012-05-23T16:43:24.601Z,1337791404.601 [Startup] Stopped
2012-05-23T16:43:24.601Z,1337791404.601 [Startup](INFO): Aggregate::uninitialize Startup
2012-05-23T16:43:24.601Z,1337791404.601 [Startup:A.GoToSurface] Stopped
2012-05-23T16:43:24.601Z,1337791404.601 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-23T16:43:25.506Z,1337791405.506 [MissionManager](IMPORTANT): Started mission tank_weight
2012-05-23T16:43:25.506Z,1337791405.506 [tank_weight] Running Loop=1
2012-05-23T16:43:25.506Z,1337791405.506 [tank_weight](INFO): Aggregate::initialize tank_weight
2012-05-23T16:43:25.506Z,1337791405.506 [tank_weight:A.AltitudeEnvelope] Running Loop=1
2012-05-23T16:43:25.506Z,1337791405.506 [tank_weight:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2012-05-23T16:43:25.506Z,1337791405.506 [tank_weight:B.DepthEnvelope] Running Loop=1
2012-05-23T16:43:25.507Z,1337791405.507 [tank_weight:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2012-05-23T16:43:25.507Z,1337791405.507 [tank_weight:Science] Running Loop=1
2012-05-23T16:43:25.508Z,1337791405.508 [tank_weight:Science](INFO): Aggregate::initialize tank_weight:Science
2012-05-23T16:43:25.508Z,1337791405.508 [tank_weight:Science:A] Running Loop=1
2012-05-23T16:43:25.508Z,1337791405.508 [tank_weight:Science:B] Running Loop=1
2012-05-23T16:43:25.508Z,1337791405.508 [tank_weight:Science:C] Running Loop=1
2012-05-23T16:43:25.508Z,1337791405.508 [tank_weight:Science:D] Running Loop=1
2012-05-23T16:43:25.508Z,1337791405.508 [tank_weight:D] Running Loop=1
2012-05-23T16:43:25.508Z,1337791405.508 [tank_weight:D](INFO): Aggregate::initialize tank_weight:D
2012-05-23T16:43:25.508Z,1337791405.508 [tank_weight:D:A.SetSpeed] Running Loop=1
2012-05-23T16:43:25.508Z,1337791405.508 [tank_weight:D:A.SetSpeed](DEBUG): Initialize.
2012-05-23T16:43:25.508Z,1337791405.508 [tank_weight:D:B.Pitch] Running Loop=1
2012-05-23T16:43:25.508Z,1337791405.509 [tank_weight:D:B.Pitch](DEBUG): Initialize.
2012-05-23T16:43:25.509Z,1337791405.509 [tank_weight:D:BuoyancyLo] Running Loop=1
2012-05-23T16:43:25.509Z,1337791405.509 [tank_weight:D:BuoyancyLo](INFO): Aggregate::initialize tank_weight:D:BuoyancyLo
2012-05-23T16:43:25.509Z,1337791405.509 [tank_weight:D:BuoyancyLo:A.Buoyancy] Running Loop=1
2012-05-23T16:43:25.509Z,1337791405.509 [tank_weight:D:BuoyancyLo:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2012-05-23T16:43:25.510Z,1337791405.510 [tank_weight:D:BuoyancyLo:B.Wait] Running Loop=1
2012-05-23T16:43:25.510Z,1337791405.510 [tank_weight:D:BuoyancyLo:B.Wait](DEBUG): Initialize Wait Component.
2012-05-23T16:43:25.515Z,1337791405.514 [tank_weight:D:BuoyancyLo:A.Buoyancy] Running Loop=1
2012-05-23T16:43:25.519Z,1337791405.519 [tank_weight:D:B.Pitch] Running Loop=1
2012-05-23T16:43:25.524Z,1337791405.524 [tank_weight:D:A.SetSpeed] Running Loop=1
2012-05-23T16:43:25.528Z,1337791405.528 [tank_weight:Science] Running Loop=1
2012-05-23T16:43:25.528Z,1337791405.528 [tank_weight:Science:D] Running Loop=1
2012-05-23T16:43:25.529Z,1337791405.529 [tank_weight:Science:D](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2012-05-23T16:43:25.530Z,1337791405.530 [tank_weight:Science:C] Running Loop=1
2012-05-23T16:43:25.531Z,1337791405.531 [tank_weight:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2012-05-23T16:43:25.532Z,1337791405.532 [tank_weight:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2012-05-23T16:43:25.533Z,1337791405.533 [tank_weight:Science:B] Running Loop=1
2012-05-23T16:43:25.534Z,1337791405.534 [tank_weight:Science:B](DEBUG): Initialize ReadDataComponent to sense mole_concentration_of_nitrate_in_sea_water
2012-05-23T16:43:25.535Z,1337791405.535 [tank_weight:B.DepthEnvelope] Running Loop=1
2012-05-23T16:43:25.540Z,1337791405.540 [tank_weight:A.AltitudeEnvelope] Running Loop=1
2012-05-23T16:43:25.730Z,1337791405.730 [WetLabsBB2FL](INFO): Powering up
2012-05-23T16:43:26.918Z,1337791406.918 [tank_weight:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2012-05-23T16:43:28.040Z,1337791408.040 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.156190 psu
2012-05-23T16:43:28.247Z,1337791408.247 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.157460 psu
2012-05-23T16:43:28.451Z,1337791408.451 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.095960 psu
2012-05-23T16:43:28.655Z,1337791408.655 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.064280 psu
2012-05-23T16:43:28.843Z,1337791408.843 [CTD_NeilBrown](ERROR): Salinity reading out of range: -37.001419 psu
2012-05-23T16:43:28.844Z,1337791408.844 [CTD_NeilBrown](ERROR): Pressure reading out of range: -243.610001 decibar
2012-05-23T16:43:29.047Z,1337791409.047 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.053940 psu
2012-05-23T16:43:29.251Z,1337791409.251 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.082420 psu
2012-05-23T16:43:29.308Z,1337791409.308 [WetLabsBB2FL](DEBUG): Error parsing data: 
2012-05-23T16:43:29.308Z,1337791409.308 [WetLabsBB2FL] Data Fault, FailCount= 1
2012-05-23T16:43:29.308Z,1337791409.308 [WetLabsBB2FL](ERROR): Data Fault
2012-05-23T16:43:29.350Z,1337791409.351 [CBIT](ERROR): Data Fault in component: WetLabsBB2FL
2012-05-23T16:43:29.459Z,1337791409.459 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.050700 psu
2012-05-23T16:43:29.663Z,1337791409.663 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.063620 psu
2012-05-23T16:43:29.807Z,1337791409.807 [WetLabsBB2FL](INFO): Powering down
2012-05-23T16:43:29.851Z,1337791409.852 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2012-05-23T16:43:29.852Z,1337791409.852 [WetLabsBB2FL] No Fault, FailCount= 1
2012-05-23T16:43:29.867Z,1337791409.867 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.028360 psu
2012-05-23T16:43:30.071Z,1337791410.071 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.042570 psu
2012-05-23T16:43:30.110Z,1337791410.110 [WetLabsBB2FL](INFO): Powering up
2012-05-23T16:43:30.276Z,1337791410.276 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.021340 psu
2012-05-23T16:43:30.480Z,1337791410.480 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.043870 psu
2012-05-23T16:43:30.687Z,1337791410.687 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.050660 psu
2012-05-23T16:43:30.891Z,1337791410.891 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.024050 psu
2012-05-23T16:43:31.095Z,1337791411.095 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.032330 psu
2012-05-23T16:43:31.299Z,1337791411.299 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.033240 psu
2012-05-23T16:43:31.503Z,1337791411.503 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.025390 psu
2012-05-23T16:43:31.715Z,1337791411.715 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.017120 psu
2012-05-23T16:43:31.915Z,1337791411.915 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.023650 psu
2012-05-23T16:43:32.119Z,1337791412.119 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.029540 psu
2012-05-23T16:43:32.323Z,1337791412.323 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.029840 psu
2012-05-23T16:43:32.527Z,1337791412.527 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.032550 psu
2012-05-23T16:43:32.735Z,1337791412.735 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.021190 psu
2012-05-23T16:43:33.035Z,1337791413.035 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.028980 psu
2012-05-23T16:43:33.231Z,1337791413.231 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.009170 psu
2012-05-23T16:43:33.439Z,1337791413.439 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.019670 psu
2012-05-23T16:43:33.643Z,1337791413.643 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.026600 psu
2012-05-23T16:43:33.847Z,1337791413.847 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.010090 psu
2012-05-23T16:43:34.051Z,1337791414.051 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.023660 psu
2012-05-23T16:43:34.255Z,1337791414.255 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.051600 psu
2012-05-23T16:43:34.463Z,1337791414.463 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.034550 psu
2012-05-23T16:43:34.663Z,1337791414.663 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.015340 psu
2012-05-23T16:43:34.867Z,1337791414.867 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.010810 psu
2012-05-23T16:43:35.071Z,1337791415.071 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.026740 psu
2012-05-23T16:43:35.281Z,1337791415.281 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.012150 psu
2012-05-23T16:43:35.479Z,1337791415.479 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.012150 psu
2012-05-23T16:43:35.687Z,1337791415.687 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.027000 psu
2012-05-23T16:43:35.891Z,1337791415.891 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.037350 psu
2012-05-23T16:43:36.095Z,1337791416.095 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.030980 psu
2012-05-23T16:43:36.299Z,1337791416.299 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.012150 psu
2012-05-23T16:43:36.503Z,1337791416.503 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.024040 psu
2012-05-23T16:43:36.707Z,1337791416.707 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.028340 psu
2012-05-23T16:43:36.915Z,1337791416.915 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.030570 psu
2012-05-23T16:43:37.119Z,1337791417.119 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.029500 psu
2012-05-23T16:43:37.323Z,1337791417.323 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.035130 psu
2012-05-23T16:43:37.627Z,1337791417.627 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.014710 psu
2012-05-23T16:43:37.831Z,1337791417.831 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.020490 psu
2012-05-23T16:43:38.035Z,1337791418.035 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.019620 psu
2012-05-23T16:43:38.243Z,1337791418.243 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.030620 psu
2012-05-23T16:43:38.439Z,1337791418.439 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.000980 psu
2012-05-23T16:43:38.643Z,1337791418.643 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.041670 psu
2012-05-23T16:43:38.851Z,1337791418.851 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.016960 psu
2012-05-23T16:43:39.047Z,1337791419.047 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.000960 psu
2012-05-23T16:43:39.255Z,1337791419.255 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.021060 psu
2012-05-23T16:43:39.459Z,1337791419.459 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.033280 psu
2012-05-23T16:43:39.663Z,1337791419.663 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.011670 psu
2012-05-23T16:43:39.863Z,1337791419.863 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.001680 psu
2012-05-23T16:43:40.067Z,1337791420.067 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.013470 psu
2012-05-23T16:43:40.271Z,1337791420.271 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.024010 psu
2012-05-23T16:43:40.479Z,1337791420.479 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.022720 psu
2012-05-23T16:43:40.679Z,1337791420.679 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.011270 psu
2012-05-23T16:43:40.887Z,1337791420.887 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.022770 psu
2012-05-23T16:43:41.091Z,1337791421.091 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.047360 psu
2012-05-23T16:43:41.295Z,1337791421.295 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.022360 psu
2012-05-23T16:43:41.499Z,1337791421.499 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.011730 psu
2012-05-23T16:43:41.707Z,1337791421.707 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.020700 psu
2012-05-23T16:43:41.911Z,1337791421.911 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.018700 psu
2012-05-23T16:43:42.107Z,1337791422.107 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.006090 psu
2012-05-23T16:43:42.312Z,1337791422.312 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.013600 psu
2012-05-23T16:43:42.519Z,1337791422.519 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.020290 psu
2012-05-23T16:43:42.819Z,1337791422.819 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.016440 psu
2012-05-23T16:43:43.023Z,1337791423.023 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.025020 psu
2012-05-23T16:43:43.227Z,1337791423.227 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.021010 psu
2012-05-23T16:43:43.435Z,1337791423.435 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.014770 psu
2012-05-23T16:43:43.639Z,1337791423.639 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.027220 psu
2012-05-23T16:43:43.843Z,1337791423.843 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.027470 psu
2012-05-23T16:43:44.047Z,1337791424.047 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.014180 psu
2012-05-23T16:43:44.251Z,1337791424.251 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.033910 psu
2012-05-23T16:43:44.459Z,1337791424.459 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.022880 psu
2012-05-23T16:43:44.687Z,1337791424.687 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.033360 psu
2012-05-23T16:43:44.867Z,1337791424.867 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.014700 psu
2012-05-23T16:43:45.067Z,1337791425.067 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.008200 psu
2012-05-23T16:43:45.271Z,1337791425.271 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.025530 psu
2012-05-23T16:43:45.470Z,1337791425.470 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.004070 psu
2012-05-23T16:43:45.675Z,1337791425.675 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.010770 psu
2012-05-23T16:43:45.876Z,1337791425.876 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.005510 psu
2012-05-23T16:43:46.079Z,1337791426.079 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.046040 psu
2012-05-23T16:43:46.283Z,1337791426.283 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.020270 psu
2012-05-23T16:43:46.487Z,1337791426.487 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.017310 psu
2012-05-23T16:43:46.691Z,1337791426.691 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.025950 psu
2012-05-23T16:43:46.899Z,1337791426.899 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.012650 psu
2012-05-23T16:43:47.103Z,1337791427.103 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.044680 psu
2012-05-23T16:43:47.307Z,1337791427.307 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.025230 psu
2012-05-23T16:43:47.511Z,1337791427.511 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.030490 psu
2012-05-23T16:43:47.811Z,1337791427.811 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.048170 psu
2012-05-23T16:43:48.015Z,1337791428.015 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.016620 psu
2012-05-23T16:43:48.223Z,1337791428.223 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.031480 psu
2012-05-23T16:43:48.427Z,1337791428.427 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.028980 psu
2012-05-23T16:43:48.631Z,1337791428.631 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.017740 psu
2012-05-23T16:43:48.835Z,1337791428.835 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.016700 psu
2012-05-23T16:43:49.039Z,1337791429.039 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.023570 psu
2012-05-23T16:43:49.239Z,1337791429.239 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.007130 psu
2012-05-23T16:43:49.443Z,1337791429.443 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.020070 psu
2012-05-23T16:43:49.651Z,1337791429.651 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.012930 psu
2012-05-23T16:43:49.855Z,1337791429.855 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.027390 psu
2012-05-23T16:43:50.059Z,1337791430.059 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.012900 psu
2012-05-23T16:43:50.263Z,1337791430.263 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.038100 psu
2012-05-23T16:43:50.470Z,1337791430.470 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.010090 psu
2012-05-23T16:43:50.671Z,1337791430.671 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.015530 psu
2012-05-23T16:43:50.882Z,1337791430.882 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.036110 psu
2012-05-23T16:43:51.083Z,1337791431.083 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.012440 psu
2012-05-23T16:43:51.287Z,1337791431.287 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.014460 psu
2012-05-23T16:43:51.491Z,1337791431.491 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.039080 psu
2012-05-23T16:43:51.695Z,1337791431.695 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.017560 psu
2012-05-23T16:43:51.895Z,1337791431.895 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.002350 psu
2012-05-23T16:43:52.099Z,1337791432.099 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.020150 psu
2012-05-23T16:43:52.407Z,1337791432.407 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.019520 psu
2012-05-23T16:43:52.611Z,1337791432.611 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.036910 psu
2012-05-23T16:43:52.811Z,1337791432.811 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.009960 psu
2012-05-23T16:43:53.015Z,1337791433.015 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.018050 psu
2012-05-23T16:43:53.219Z,1337791433.219 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.014700 psu
2012-05-23T16:43:53.416Z,1337791433.416 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.009920 psu
2012-05-23T16:43:53.623Z,1337791433.623 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.014750 psu
2012-05-23T16:43:53.823Z,1337791433.823 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.004840 psu
2012-05-23T16:43:54.027Z,1337791434.027 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.011400 psu
2012-05-23T16:43:54.231Z,1337791434.231 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.021470 psu
2012-05-23T16:43:54.435Z,1337791434.435 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.010100 psu
2012-05-23T16:43:54.643Z,1337791434.643 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.014640 psu
2012-05-23T16:43:54.839Z,1337791434.839 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.001380 psu
2012-05-23T16:43:55.044Z,1337791435.044 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.012680 psu
2012-05-23T16:43:55.251Z,1337791435.251 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.039080 psu
2012-05-23T16:43:55.455Z,1337791435.455 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.015890 psu
2012-05-23T16:43:55.659Z,1337791435.659 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.020420 psu
2012-05-23T16:43:55.863Z,1337791435.864 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.046320 psu
2012-05-23T16:43:56.067Z,1337791436.067 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.028400 psu
2012-05-23T16:43:56.276Z,1337791436.276 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.015280 psu
2012-05-23T16:43:56.482Z,1337791436.482 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.018760 psu
2012-05-23T16:43:56.683Z,1337791436.683 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.011220 psu
2012-05-23T16:43:56.887Z,1337791436.887 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.038800 psu
2012-05-23T16:43:57.091Z,1337791437.091 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.020420 psu
2012-05-23T16:43:57.291Z,1337791437.291 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.003070 psu
2012-05-23T16:43:57.491Z,1337791437.491 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.003390 psu
2012-05-23T16:43:57.695Z,1337791437.695 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.015300 psu
2012-05-23T16:43:57.999Z,1337791437.999 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.034270 psu
2012-05-23T16:43:58.203Z,1337791438.203 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.039000 psu
2012-05-23T16:43:58.407Z,1337791438.407 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.043200 psu
2012-05-23T16:43:58.607Z,1337791438.607 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.033360 psu
2012-05-23T16:43:58.811Z,1337791438.811 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.021490 psu
2012-05-23T16:43:59.011Z,1337791439.011 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.009190 psu
2012-05-23T16:43:59.211Z,1337791439.211 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.009600 psu
2012-05-23T16:43:59.415Z,1337791439.415 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.041260 psu
2012-05-23T16:43:59.619Z,1337791439.619 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.015810 psu
2012-05-23T16:43:59.823Z,1337791439.823 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.017970 psu
2012-05-23T16:44:00.023Z,1337791440.023 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.001000 psu
2012-05-23T16:44:00.227Z,1337791440.227 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.030350 psu
2012-05-23T16:44:00.431Z,1337791440.431 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.010170 psu
2012-05-23T16:44:00.635Z,1337791440.635 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.010620 psu
2012-05-23T16:44:00.843Z,1337791440.843 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.018450 psu
2012-05-23T16:44:01.047Z,1337791441.047 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.039920 psu
2012-05-23T16:44:01.251Z,1337791441.251 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.023020 psu
2012-05-23T16:44:01.455Z,1337791441.455 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.039620 psu
2012-05-23T16:44:01.659Z,1337791441.659 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.042620 psu
2012-05-23T16:44:01.859Z,1337791441.859 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.001690 psu
2012-05-23T16:44:02.059Z,1337791442.059 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.006150 psu
2012-05-23T16:44:02.263Z,1337791442.263 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.016340 psu
2012-05-23T16:44:02.469Z,1337791442.469 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.029140 psu
2012-05-23T16:44:02.671Z,1337791442.671 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.016320 psu
2012-05-23T16:44:02.871Z,1337791442.871 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.003100 psu
2012-05-23T16:44:03.071Z,1337791443.071 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.007180 psu
2012-05-23T16:44:03.282Z,1337791443.282 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.024090 psu
2012-05-23T16:44:03.479Z,1337791443.479 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.026540 psu
2012-05-23T16:44:03.687Z,1337791443.687 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.016620 psu
2012-05-23T16:44:03.983Z,1337791443.983 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.007180 psu
2012-05-23T16:44:04.188Z,1337791444.188 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.035490 psu
2012-05-23T16:44:04.391Z,1337791444.391 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.025480 psu
2012-05-23T16:44:04.599Z,1337791444.599 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.039690 psu
2012-05-23T16:44:04.802Z,1337791444.802 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.014420 psu
2012-05-23T16:44:05.008Z,1337791445.008 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.022790 psu
2012-05-23T16:44:05.211Z,1337791445.211 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.032990 psu
2012-05-23T16:44:05.411Z,1337791445.411 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.007820 psu
2012-05-23T16:44:05.615Z,1337791445.615 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.018550 psu
2012-05-23T16:44:05.819Z,1337791445.819 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.031060 psu
2012-05-23T16:44:06.023Z,1337791446.023 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.028610 psu
2012-05-23T16:44:06.231Z,1337791446.231 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.015930 psu
2012-05-23T16:44:06.427Z,1337791446.427 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.006860 psu
2012-05-23T16:44:06.635Z,1337791446.635 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.022430 psu
2012-05-23T16:44:06.831Z,1337791446.831 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.007150 psu
2012-05-23T16:44:07.036Z,1337791447.036 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.029180 psu
2012-05-23T16:44:07.243Z,1337791447.243 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.013800 psu
2012-05-23T16:44:07.447Z,1337791447.447 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.052670 psu
2012-05-23T16:44:07.651Z,1337791447.651 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.019070 psu
2012-05-23T16:44:07.855Z,1337791447.855 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.019860 psu
2012-05-23T16:44:08.059Z,1337791448.059 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.017890 psu
2012-05-23T16:44:08.267Z,1337791448.267 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.013890 psu
2012-05-23T16:44:08.463Z,1337791448.463 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.004450 psu
2012-05-23T16:44:08.671Z,1337791448.671 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.010600 psu
2012-05-23T16:44:08.867Z,1337791448.867 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.009530 psu
2012-05-23T16:44:09.067Z,1337791449.067 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.001360 psu
2012-05-23T16:44:09.271Z,1337791449.271 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.012910 psu
2012-05-23T16:44:09.315Z,1337791449.315 [CommandLine](INFO): End of History
2012-05-23T16:44:09.476Z,1337791449.476 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.011270 psu
2012-05-23T16:44:09.779Z,1337791449.779 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.024520 psu
2012-05-23T16:44:09.983Z,1337791449.983 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.029320 psu
2012-05-23T16:44:10.188Z,1337791450.188 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.034980 psu
2012-05-23T16:44:10.395Z,1337791450.395 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.039640 psu
2012-05-23T16:44:10.591Z,1337791450.592 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.000350 psu
2012-05-23T16:44:10.801Z,1337791450.801 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.014550 psu
2012-05-23T16:44:10.999Z,1337791450.999 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.004800 psu
2012-05-23T16:44:11.203Z,1337791451.203 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.012180 psu
2012-05-23T16:44:11.407Z,1337791451.407 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.044220 psu
2012-05-23T16:44:11.611Z,1337791451.611 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.026030 psu
2012-05-23T16:44:11.815Z,1337791451.815 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.010680 psu
2012-05-23T16:44:12.023Z,1337791452.023 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.015060 psu
2012-05-23T16:44:12.232Z,1337791452.232 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.035630 psu
2012-05-23T16:44:12.431Z,1337791452.431 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.033730 psu
2012-05-23T16:44:12.631Z,1337791452.631 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.000350 psu
2012-05-23T16:44:12.835Z,1337791452.835 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.012920 psu
2012-05-23T16:44:13.039Z,1337791453.039 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.014260 psu
2012-05-23T16:44:13.243Z,1337791453.243 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.017450 psu
2012-05-23T16:44:13.451Z,1337791453.451 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.019030 psu
2012-05-23T16:44:13.647Z,1337791453.647 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.009220 psu
2012-05-23T16:44:13.855Z,1337791453.855 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.021490 psu
2012-05-23T16:44:14.059Z,1337791454.059 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.017310 psu
2012-05-23T16:44:14.263Z,1337791454.263 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.015310 psu
2012-05-23T16:44:14.470Z,1337791454.470 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.014950 psu
2012-05-23T16:44:14.667Z,1337791454.667 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.005130 psu
2012-05-23T16:44:14.871Z,1337791454.871 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.011960 psu
2012-05-23T16:44:15.171Z,1337791455.171 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.037910 psu
2012-05-23T16:44:15.375Z,1337791455.375 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.025520 psu
2012-05-23T16:44:15.583Z,1337791455.583 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.054550 psu
2012-05-23T16:44:15.787Z,1337791455.787 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.029110 psu
2012-05-23T16:44:15.991Z,1337791455.991 [CTD_NeilBrown](ERROR): Salinity reading out of range: 0.014950 psu
2012-05-23T16:44:16.144Z,1337791456.144 [CommandLine](IMPORTANT): got command run ./Missions/Default.xml
2012-05-23T16:44:16.144Z,1337791456.144 [tank_weight] Stopped
2012-05-23T16:44:16.144Z,1337791456.144 [tank_weight](INFO): Aggregate::uninitialize tank_weight
2012-05-23T16:44:16.144Z,1337791456.144 [tank_weight:A.AltitudeEnvelope] Stopped
2012-05-23T16:44:16.145Z,1337791456.145 [tank_weight:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2012-05-23T16:44:16.145Z,1337791456.145 [tank_weight:B.DepthEnvelope] Stopped
2012-05-23T16:44:16.145Z,1337791456.145 [tank_weight:B.DepthEnvelope](DEBUG): Uninitialize.
2012-05-23T16:44:16.145Z,1337791456.145 [tank_weight:Science] Stopped
2012-05-23T16:44:16.145Z,1337791456.145 [tank_weight:Science](INFO): Aggregate::uninitialize tank_weight:Science
2012-05-23T16:44:16.145Z,1337791456.145 [tank_weight:Science:A] Stopped
2012-05-23T16:44:16.145Z,1337791456.145 [tank_weight:Science:B] Stopped
2012-05-23T16:44:16.145Z,1337791456.145 [tank_weight:Science:C] Stopped
2012-05-23T16:44:16.145Z,1337791456.145 [tank_weight:Science:D] Stopped
2012-05-23T16:44:16.145Z,1337791456.145 [tank_weight:D] Stopped
2012-05-23T16:44:16.146Z,1337791456.146 [tank_weight:D](INFO): Aggregate::uninitialize tank_weight:D
2012-05-23T16:44:16.146Z,1337791456.146 [tank_weight:D:A.SetSpeed] Stopped
2012-05-23T16:44:16.146Z,1337791456.146 [tank_weight:D:A.SetSpeed](DEBUG): Uninitialize.
2012-05-23T16:44:16.146Z,1337791456.146 [tank_weight:D:B.Pitch] Stopped
2012-05-23T16:44:16.146Z,1337791456.146 [tank_weight:D:BuoyancyLo] Stopped
2012-05-23T16:44:16.146Z,1337791456.146 [tank_weight:D:BuoyancyLo](INFO): Aggregate::uninitialize tank_weight:D:BuoyancyLo
2012-05-23T16:44:16.146Z,1337791456.146 [tank_weight:D:BuoyancyLo:A.Buoyancy] Stopped
2012-05-23T16:44:16.146Z,1337791456.146 [tank_weight:D:BuoyancyLo:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2012-05-23T16:44:16.146Z,1337791456.146 [tank_weight:D:BuoyancyLo:B.Wait] Stopped
2012-05-23T16:44:16.146Z,1337791456.146 [tank_weight:D:BuoyancyLo:B.Wait](DEBUG): Uninitialize Wait Component.
2012-05-23T16:44:16.160Z,1337791456.160 [MissionManager](INFO): Loading Mission: ./Missions/Default.xml
2012-05-23T16:44:16.245Z,1337791456.245 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2012-05-23T16:44:16.255Z,1337791456.255 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2012-05-23T16:44:16.257Z,1337791456.257 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2012-05-23T16:44:16.277Z,1337791456.277 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2012-05-23T16:44:16.281Z,1337791456.281 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2012-05-23T16:44:16.293Z,1337791456.293 [Default:Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2012-05-23T16:44:16.308Z,1337791456.308 [Default:D.SetSpeed](DEBUG): Construct.
2012-05-23T16:44:16.319Z,1337791456.319 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2012-05-23T16:44:16.322Z,1337791456.322 [Default:F.Wait](DEBUG): Construct Wait.
2012-05-23T16:44:16.325Z,1337791456.325 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
xmlns:Control="Tethys/Control"
xmlns:Guidance="Tethys/Guidance" 
xmlns:Units="Tethys/Units"
xmlns:Universal="Tethys/Universal"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

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

    <Aggregate Id="GPS" >

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

<!-- Run at high speed -->

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

<!-- Get to the surface -->

        <Guidance:GoToSurface RunIn="Sequence"/>

<!-- Get GPS fix -->

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

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

    </Aggregate>

    <Aggregate Id="Iridium" >

<!-- Run at high speed -->

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

<!-- Get to the surface -->

        <Guidance:GoToSurface RunIn="Sequence"/>

<!-- Do data comms -->

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

    </Aggregate>

    <Aggregate Id="CallIridium" >

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

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

        <Call RefId="Iridium"/>

    </Aggregate>

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

<!-- Run at low speed -->

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

    <Guidance:GoToSurface RunIn="Parallel"/>

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

</Mission>


2012-05-23T16:44:16.325Z,1337791456.325 [CommandLine](IMPORTANT): Running ./Missions/Default.xml
2012-05-23T16:44:16.607Z,1337791456.607 [WetLabsBB2FL](INFO): Powering down
2012-05-23T16:44:16.619Z,1337791456.619 [MissionManager](IMPORTANT): Started mission Default
2012-05-23T16:44:16.620Z,1337791456.620 [Default] Running Loop=1
2012-05-23T16:44:16.620Z,1337791456.620 [Default](INFO): Aggregate::initialize Default
2012-05-23T16:44:16.620Z,1337791456.620 [Default:D.SetSpeed] Running Loop=1
2012-05-23T16:44:16.620Z,1337791456.620 [Default:D.SetSpeed](DEBUG): Initialize.
2012-05-23T16:44:16.620Z,1337791456.620 [Default:E.GoToSurface] Running Loop=1
2012-05-23T16:44:16.620Z,1337791456.620 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-23T16:44:16.621Z,1337791456.621 [Default:Iridium] Running Loop=1
2012-05-23T16:44:16.621Z,1337791456.621 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-05-23T16:44:16.621Z,1337791456.621 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-05-23T16:44:16.621Z,1337791456.621 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-05-23T16:44:16.621Z,1337791456.621 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-05-23T16:44:16.621Z,1337791456.621 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-23T16:44:16.622Z,1337791456.622 [Default:E.GoToSurface] Running Loop=1
2012-05-23T16:44:16.627Z,1337791456.627 [Default:D.SetSpeed] Running Loop=1
2012-05-23T16:44:16.632Z,1337791456.632 [Default:CallIridium] Running Loop=1
2012-05-23T16:44:16.632Z,1337791456.632 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-05-23T16:44:16.632Z,1337791456.632 [Default:CallIridium:A] Running Loop=1
2012-05-23T16:44:16.634Z,1337791456.634 [Default:CallIridium:A] Stopped
2012-05-23T16:44:16.634Z,1337791456.634 [Default:CallIridium:B] Running Loop=1
2012-05-23T16:44:16.635Z,1337791456.635 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-05-23T16:44:16.640Z,1337791456.640 [Default:Iridium:B.GoToSurface] Stopped
2012-05-23T16:44:16.640Z,1337791456.640 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-23T16:44:16.640Z,1337791456.640 [Default:Iridium:Read_Iridium] Running Loop=1
2012-05-23T16:44:16.640Z,1337791456.640 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-05-23T16:44:16.645Z,1337791456.645 [Default:GPS] Running Loop=1
2012-05-23T16:44:16.645Z,1337791456.645 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-05-23T16:44:16.645Z,1337791456.645 [Default:GPS:A.SetSpeed] Running Loop=1
2012-05-23T16:44:16.645Z,1337791456.645 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-05-23T16:44:16.645Z,1337791456.645 [Default:GPS:B.GoToSurface] Running Loop=1
2012-05-23T16:44:16.645Z,1337791456.645 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-23T16:44:16.651Z,1337791456.651 [Default:GPS:B.GoToSurface] Stopped
2012-05-23T16:44:16.651Z,1337791456.651 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-23T16:44:16.651Z,1337791456.651 [Default:GPS:Read_GPS] Running Loop=1
2012-05-23T16:44:16.651Z,1337791456.651 [Default:GPS:A.SetSpeed] Running Loop=1
2012-05-23T16:44:16.936Z,1337791456.936 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-05-23T16:44:16.938Z,1337791456.938 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-05-23T16:47:24.691Z,1337791644.691 [CommandLine](IMPORTANT): got command quit
2012-05-23T16:47:24.738Z,1337791644.738 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2012-05-23T16:47:24.738Z,1337791644.739 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2012-05-23T16:47:24.854Z,1337791644.853 [Default] Stopped
2012-05-23T16:47:24.854Z,1337791644.854 [Default](INFO): Aggregate::uninitialize Default
2012-05-23T16:47:24.854Z,1337791644.854 [Default:GPS] Stopped
2012-05-23T16:47:24.854Z,1337791644.854 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-05-23T16:47:24.854Z,1337791644.854 [Default:GPS:A.SetSpeed] Stopped
2012-05-23T16:47:24.854Z,1337791644.854 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-05-23T16:47:24.854Z,1337791644.854 [Default:GPS:Read_GPS] Stopped
2012-05-23T16:47:24.854Z,1337791644.854 [Default:Iridium] Stopped
2012-05-23T16:47:24.854Z,1337791644.854 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-05-23T16:47:24.854Z,1337791644.855 [Default:Iridium:A.SetSpeed] Stopped
2012-05-23T16:47:24.854Z,1337791644.855 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-05-23T16:47:24.855Z,1337791644.855 [Default:Iridium:Read_Iridium] Stopped
2012-05-23T16:47:24.855Z,1337791644.855 [Default:CallIridium] Stopped
2012-05-23T16:47:24.855Z,1337791644.855 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-05-23T16:47:24.855Z,1337791644.855 [Default:CallIridium:B] Stopped
2012-05-23T16:47:24.855Z,1337791644.855 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-05-23T16:47:24.855Z,1337791644.855 [Default:D.SetSpeed] Stopped
2012-05-23T16:47:24.855Z,1337791644.855 [Default:D.SetSpeed](DEBUG): Uninitialize.
2012-05-23T16:47:24.855Z,1337791644.855 [Default:E.GoToSurface] Stopped
2012-05-23T16:47:24.855Z,1337791644.855 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-23T16:47:25.075Z,1337791645.075 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2012-05-23T16:47:25.079Z,1337791645.079 [CTD_NeilBrown](INFO): Powering down
2012-05-23T16:47:25.079Z,1337791645.079 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler
2012-05-23T16:47:25.137Z,1337791645.137 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator.
2012-05-23T16:47:25.137Z,1337791645.137 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2012-05-23T16:47:25.137Z,1337791645.137 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2012-05-23T16:47:25.252Z,1337791645.252 [MissionManager](IMPORTANT): Started mission Default
2012-05-23T16:47:25.252Z,1337791645.252 [Default] Running Loop=1
2012-05-23T16:47:25.252Z,1337791645.252 [Default](INFO): Aggregate::initialize Default
2012-05-23T16:47:25.252Z,1337791645.252 [Default:D.SetSpeed] Running Loop=1
2012-05-23T16:47:25.252Z,1337791645.253 [Default:D.SetSpeed](DEBUG): Initialize.
2012-05-23T16:47:25.253Z,1337791645.253 [Default:E.GoToSurface] Running Loop=1
2012-05-23T16:47:25.253Z,1337791645.253 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-23T16:47:25.253Z,1337791645.253 [Default:Iridium] Running Loop=1
2012-05-23T16:47:25.253Z,1337791645.253 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-05-23T16:47:25.253Z,1337791645.253 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-05-23T16:47:25.253Z,1337791645.253 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-05-23T16:47:25.254Z,1337791645.253 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-05-23T16:47:25.254Z,1337791645.254 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-23T16:47:25.254Z,1337791645.254 [Default:E.GoToSurface] Running Loop=1
2012-05-23T16:47:25.255Z,1337791645.256 [Default:D.SetSpeed] Running Loop=1
2012-05-23T16:47:25.256Z,1337791645.256 [Default:CallIridium] Running Loop=1
2012-05-23T16:47:25.257Z,1337791645.257 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-05-23T16:47:25.257Z,1337791645.257 [Default:CallIridium:A] Running Loop=1
2012-05-23T16:47:25.257Z,1337791645.257 [Default:CallIridium:A] Stopped
2012-05-23T16:47:25.258Z,1337791645.258 [Default:CallIridium:B] Running Loop=1
2012-05-23T16:47:25.258Z,1337791645.258 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-05-23T16:47:25.259Z,1337791645.259 [Default:Iridium:B.GoToSurface] Stopped
2012-05-23T16:47:25.259Z,1337791645.259 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-23T16:47:25.259Z,1337791645.259 [Default:Iridium:Read_Iridium] Running Loop=1
2012-05-23T16:47:25.259Z,1337791645.260 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-05-23T16:47:25.260Z,1337791645.260 [Default:GPS] Running Loop=1
2012-05-23T16:47:25.261Z,1337791645.260 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-05-23T16:47:25.261Z,1337791645.261 [Default:GPS:A.SetSpeed] Running Loop=1
2012-05-23T16:47:25.261Z,1337791645.261 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-05-23T16:47:25.261Z,1337791645.261 [Default:GPS:B.GoToSurface] Running Loop=1
2012-05-23T16:47:25.261Z,1337791645.261 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-23T16:47:25.262Z,1337791645.262 [Default:GPS:B.GoToSurface] Stopped
2012-05-23T16:47:25.262Z,1337791645.263 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-23T16:47:25.263Z,1337791645.263 [Default:GPS:Read_GPS] Running Loop=1
2012-05-23T16:47:25.263Z,1337791645.263 [Default:GPS:A.SetSpeed] Running Loop=1
2012-05-23T16:47:25.336Z,1337791645.336 [controlThread](DEBUG): Uninitializing ControlThread
2012-05-23T16:47:25.337Z,1337791645.337 [AHRS_sp3003D](INFO): Powering down
2012-05-23T16:47:25.424Z,1337791645.424 [DVL_micro](INFO): Powering down
2012-05-23T16:47:25.424Z,1337791645.424 [NAL9601](INFO): Powering down
2012-05-23T16:47:25.425Z,1337791645.425 [WetLabsBB2FL](INFO): Powering down
2012-05-23T16:47:25.426Z,1337791645.425 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2012-05-23T16:47:25.426Z,1337791645.426 [NavChart](DEBUG): Uninitialize NavChart Derivation.
2012-05-23T16:47:25.427Z,1337791645.427 [Default] Stopped
2012-05-23T16:47:25.427Z,1337791645.427 [Default](INFO): Aggregate::uninitialize Default
2012-05-23T16:47:25.427Z,1337791645.427 [Default:GPS] Stopped
2012-05-23T16:47:25.427Z,1337791645.427 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-05-23T16:47:25.427Z,1337791645.427 [Default:GPS:A.SetSpeed] Stopped
2012-05-23T16:47:25.428Z,1337791645.428 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-05-23T16:47:25.428Z,1337791645.428 [Default:GPS:Read_GPS] Stopped
2012-05-23T16:47:25.428Z,1337791645.428 [Default:Iridium] Stopped
2012-05-23T16:47:25.428Z,1337791645.428 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-05-23T16:47:25.428Z,1337791645.428 [Default:Iridium:A.SetSpeed] Stopped
2012-05-23T16:47:25.428Z,1337791645.428 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-05-23T16:47:25.428Z,1337791645.428 [Default:Iridium:Read_Iridium] Stopped
2012-05-23T16:47:25.428Z,1337791645.428 [Default:CallIridium] Stopped
2012-05-23T16:47:25.428Z,1337791645.428 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-05-23T16:47:25.428Z,1337791645.428 [Default:CallIridium:B] Stopped
2012-05-23T16:47:25.428Z,1337791645.428 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-05-23T16:47:25.428Z,1337791645.428 [Default:D.SetSpeed] Stopped
2012-05-23T16:47:25.428Z,1337791645.428 [Default:D.SetSpeed](DEBUG): Uninitialize.
2012-05-23T16:47:25.428Z,1337791645.428 [Default:E.GoToSurface] Stopped
2012-05-23T16:47:25.429Z,1337791645.429 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-23T16:47:25.433Z,1337791645.432 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2012-05-23T16:47:25.433Z,1337791645.433 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2012-05-23T16:47:25.433Z,1337791645.433 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2012-05-23T16:47:25.433Z,1337791645.433 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2012-05-23T16:47:25.434Z,1337791645.434 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2012-05-23T16:47:25.434Z,1337791645.434 [BuoyancyServo](INFO): Powering down
2012-05-23T16:47:25.435Z,1337791645.435 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2012-05-23T16:47:25.435Z,1337791645.435 [ElevatorServo](INFO): Powering down
2012-05-23T16:47:25.435Z,1337791645.435 [MassServo](DEBUG): Uninitialize Mass Servo.
2012-05-23T16:47:25.435Z,1337791645.435 [MassServo](INFO): Powering down
2012-05-23T16:47:25.435Z,1337791645.435 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2012-05-23T16:47:25.436Z,1337791645.436 [RudderServo](INFO): Powering down
2012-05-23T16:47:25.436Z,1337791645.436 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2012-05-23T16:47:25.436Z,1337791645.436 [ThrusterServo](INFO): Powering down
2012-05-23T16:47:25.436Z,1337791645.436 [SBIT](DEBUG): Uninitialize SBIT Component.
2012-05-23T16:47:25.437Z,1337791645.437 [IBIT](DEBUG): Uninitialize IBIT Component.
2012-05-23T16:47:25.437Z,1337791645.437 [CBIT](DEBUG): Uninitialize CBIT Component.