2014-04-12T20:54:48.471Z,1397336088.471 [Supervisor](DEBUG): Initializing supervisor.
2014-04-12T20:54:48.475Z,1397336088.475 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2014-04-12T20:54:48.479Z,1397336088.479 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2014-04-12T20:54:48.482Z,1397336088.482 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0
2014-04-12T20:54:48.491Z,1397336088.491 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2014-04-12T20:54:48.514Z,1397336088.514 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2014-04-12T20:54:48.518Z,1397336088.518 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0
2014-04-12T20:54:48.522Z,1397336088.522 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2014-04-12T20:54:48.524Z,1397336088.524 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403CB4E0
2014-04-12T20:54:48.534Z,1397336088.534 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2014-04-12T20:54:48.535Z,1397336088.535 [Supervisor](INFO): Looking for Config files in directory: Config/
2014-04-12T20:54:48.542Z,1397336088.542 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2014-04-12T20:54:49.279Z,1397336089.279 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2014-04-12T20:54:49.280Z,1397336089.280 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2014-04-12T20:54:49.553Z,1397336089.553 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2014-04-12T20:54:49.554Z,1397336089.554 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2014-04-12T20:54:49.641Z,1397336089.641 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2014-04-12T20:54:49.643Z,1397336089.643 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2014-04-12T20:54:49.839Z,1397336089.839 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2014-04-12T20:54:49.839Z,1397336089.839 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2014-04-12T20:54:49.999Z,1397336089.999 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2014-04-12T20:54:50.001Z,1397336090.001 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2014-04-12T20:54:50.303Z,1397336090.303 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2014-04-12T20:54:50.304Z,1397336090.304 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2014-04-12T20:54:50.486Z,1397336090.486 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2014-04-12T20:54:50.489Z,1397336090.489 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2014-04-12T20:54:50.824Z,1397336090.824 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2014-04-12T20:54:50.827Z,1397336090.827 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2014-04-12T20:54:50.940Z,1397336090.940 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2014-04-12T20:54:50.941Z,1397336090.941 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2014-04-12T20:54:51.443Z,1397336091.443 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2014-04-12T20:54:51.444Z,1397336091.444 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2014-04-12T20:54:51.556Z,1397336091.556 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2014-04-12T20:54:51.556Z,1397336091.556 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2014-04-12T20:54:51.643Z,1397336091.643 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2014-04-12T20:54:51.751Z,1397336091.751 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2014-04-12T20:54:51.754Z,1397336091.754 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2014-04-12T20:54:51.859Z,1397336091.859 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2014-04-12T20:54:51.859Z,1397336091.859 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2014-04-12T20:54:51.983Z,1397336091.983 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2014-04-12T20:54:51.985Z,1397336091.985 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2014-04-12T20:54:51.989Z,1397336091.989 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2014-04-12T20:54:52.099Z,1397336092.099 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2014-04-12T20:54:52.229Z,1397336092.229 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2014-04-12T20:54:52.326Z,1397336092.326 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2014-04-12T20:54:52.420Z,1397336092.420 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2014-04-12T20:54:52.572Z,1397336092.572 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2014-04-12T20:54:52.690Z,1397336092.690 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2014-04-12T20:54:52.790Z,1397336092.790 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2014-04-12T20:54:52.894Z,1397336092.894 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2014-04-12T20:54:52.996Z,1397336092.996 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2014-04-12T20:54:53.008Z,1397336093.008 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2014-04-12T20:54:53.918Z,1397336093.918 [AHRS_sp3003D] Loaded
2014-04-12T20:54:53.918Z,1397336093.918 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2014-04-12T20:54:54.208Z,1397336094.208 [Batt_Ocean_Server] Loaded
2014-04-12T20:54:54.209Z,1397336094.209 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread.
2014-04-12T20:54:54.222Z,1397336094.222 [Depth_Keller] Loaded
2014-04-12T20:54:54.222Z,1397336094.222 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2014-04-12T20:54:54.228Z,1397336094.228 [DropWeight] Loaded
2014-04-12T20:54:54.228Z,1397336094.228 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2014-04-12T20:54:54.365Z,1397336094.365 [DVL_micro] Loaded
2014-04-12T20:54:54.365Z,1397336094.365 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2014-04-12T20:54:54.464Z,1397336094.464 [NAL9602] Loaded
2014-04-12T20:54:54.464Z,1397336094.464 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2014-04-12T20:54:54.515Z,1397336094.515 [Onboard] Loaded
2014-04-12T20:54:54.515Z,1397336094.515 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2014-04-12T20:54:54.516Z,1397336094.516 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2014-04-12T20:54:54.517Z,1397336094.517 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2014-04-12T20:54:54.711Z,1397336094.711 [InternalSim] Loaded
2014-04-12T20:54:54.711Z,1397336094.711 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2014-04-12T20:54:54.712Z,1397336094.712 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2014-04-12T20:54:54.713Z,1397336094.713 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2014-04-12T20:54:54.991Z,1397336094.991 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2014-04-12T20:54:54.992Z,1397336094.992 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2014-04-12T20:54:55.001Z,1397336095.001 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2014-04-12T20:54:55.006Z,1397336095.006 [AsyncPiEstimator] Loaded
2014-04-12T20:54:55.006Z,1397336095.006 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2014-04-12T20:54:55.007Z,1397336095.007 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 4069E4E0
2014-04-12T20:54:55.008Z,1397336095.008 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2014-04-12T20:54:55.008Z,1397336095.008 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2014-04-12T20:54:55.100Z,1397336095.100 [DeadReckonUsingMultipleVelocitySources] Loaded
2014-04-12T20:54:55.101Z,1397336095.101 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2014-04-12T20:54:55.117Z,1397336095.117 [NavChart] Loaded
2014-04-12T20:54:55.117Z,1397336095.117 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2014-04-12T20:54:55.118Z,1397336095.118 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2014-04-12T20:54:55.118Z,1397336095.118 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2014-04-12T20:54:55.186Z,1397336095.186 [VerticalControl](DEBUG): Construct VerticalControl.
2014-04-12T20:54:55.294Z,1397336095.294 [VerticalControl] Loaded
2014-04-12T20:54:55.294Z,1397336095.294 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2014-04-12T20:54:55.295Z,1397336095.295 [HorizontalControl](DEBUG): Construct HorizontalControl.
2014-04-12T20:54:55.360Z,1397336095.360 [HorizontalControl] Loaded
2014-04-12T20:54:55.360Z,1397336095.360 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2014-04-12T20:54:55.361Z,1397336095.361 [SpeedControl](DEBUG): Construct SpeedControl.
2014-04-12T20:54:55.363Z,1397336095.363 [SpeedControl] Loaded
2014-04-12T20:54:55.363Z,1397336095.363 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2014-04-12T20:54:55.364Z,1397336095.364 [LoopControl](DEBUG): Construct LoopControl.
2014-04-12T20:54:55.364Z,1397336095.364 [LoopControl] Loaded
2014-04-12T20:54:55.365Z,1397336095.365 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2014-04-12T20:54:55.365Z,1397336095.365 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2014-04-12T20:54:55.366Z,1397336095.366 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2014-04-12T20:54:55.395Z,1397336095.395 [DepthRateCalculator] Loaded
2014-04-12T20:54:55.395Z,1397336095.395 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2014-04-12T20:54:55.401Z,1397336095.401 [PitchRateCalculator] Loaded
2014-04-12T20:54:55.401Z,1397336095.401 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2014-04-12T20:54:55.408Z,1397336095.408 [SpeedCalculator] Loaded
2014-04-12T20:54:55.408Z,1397336095.408 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2014-04-12T20:54:55.424Z,1397336095.424 [TempGradientCalculator] Loaded
2014-04-12T20:54:55.425Z,1397336095.425 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2014-04-12T20:54:55.430Z,1397336095.430 [YawRateCalculator] Loaded
2014-04-12T20:54:55.430Z,1397336095.430 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2014-04-12T20:54:55.431Z,1397336095.431 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2014-04-12T20:54:55.432Z,1397336095.432 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2014-04-12T20:54:55.557Z,1397336095.557 [CTD_NeilBrown] Loaded
2014-04-12T20:54:55.558Z,1397336095.558 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2014-04-12T20:54:55.559Z,1397336095.559 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 407404E0
2014-04-12T20:54:55.594Z,1397336095.594 [WetLabsBB2FL] Loaded
2014-04-12T20:54:55.594Z,1397336095.594 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2014-04-12T20:54:55.595Z,1397336095.595 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407704E0
2014-04-12T20:54:55.596Z,1397336095.596 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2014-04-12T20:54:55.598Z,1397336095.598 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2014-04-12T20:54:55.713Z,1397336095.713 [SBIT](DEBUG): Construct Startup Built In Test.
2014-04-12T20:54:55.725Z,1397336095.725 [SBIT] Loaded
2014-04-12T20:54:55.725Z,1397336095.725 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2014-04-12T20:54:55.726Z,1397336095.726 [IBIT](DEBUG): Construct Initiated Built In Test.
2014-04-12T20:54:55.738Z,1397336095.738 [IBIT] Loaded
2014-04-12T20:54:55.739Z,1397336095.739 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2014-04-12T20:54:55.741Z,1397336095.741 [CBIT](DEBUG): Construct CBIT Built In Test.
2014-04-12T20:54:55.809Z,1397336095.809 [CBIT] Loaded
2014-04-12T20:54:55.809Z,1397336095.809 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2014-04-12T20:54:55.810Z,1397336095.810 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2014-04-12T20:54:55.812Z,1397336095.812 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2014-04-12T20:54:55.880Z,1397336095.880 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2014-04-12T20:54:55.881Z,1397336095.881 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2014-04-12T20:54:55.999Z,1397336095.999 [BuoyancyServo] Loaded
2014-04-12T20:54:55.999Z,1397336095.999 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2014-04-12T20:54:56.011Z,1397336096.011 [ElevatorServo] Loaded
2014-04-12T20:54:56.011Z,1397336096.011 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2014-04-12T20:54:56.023Z,1397336096.023 [MassServo] Loaded
2014-04-12T20:54:56.023Z,1397336096.023 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2014-04-12T20:54:56.035Z,1397336096.035 [RudderServo] Loaded
2014-04-12T20:54:56.035Z,1397336096.035 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2014-04-12T20:54:56.046Z,1397336096.046 [ThrusterServo] Loaded
2014-04-12T20:54:56.046Z,1397336096.046 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2014-04-12T20:54:56.047Z,1397336096.047 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2014-04-12T20:54:56.048Z,1397336096.048 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2014-04-12T20:54:56.086Z,1397336096.086 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2014-04-12T20:54:56.088Z,1397336096.088 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2014-04-12T20:54:56.089Z,1397336096.089 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2014-04-12T20:54:56.095Z,1397336096.095 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2014-04-12T20:54:56.096Z,1397336096.096 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 4083E4E0
2014-04-12T20:54:56.101Z,1397336096.101 [Supervisor](DEBUG): Running supervisor.
2014-04-12T20:54:56.102Z,1397336096.102 [CommandLine](INFO): Thread ID is 776
2014-04-12T20:54:56.106Z,1397336096.106 [controlThread](INFO): Thread ID is 775
2014-04-12T20:54:56.106Z,1397336096.106 [controlThread](DEBUG): Initializing ControlThread
2014-04-12T20:54:56.106Z,1397336096.106 [CycleStarter](INFO): Thread ID is 774
2014-04-12T20:54:56.109Z,1397336096.109 [logger](INFO): Thread ID is 777
2014-04-12T20:54:56.131Z,1397336096.131 [AsyncPiEstimator](INFO): Thread ID is 851
2014-04-12T20:54:56.131Z,1397336096.131 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2014-04-12T20:54:56.159Z,1397336096.159 [CTD_NeilBrown](INFO): Thread ID is 852
2014-04-12T20:54:56.159Z,1397336096.159 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2014-04-12T20:54:56.162Z,1397336096.162 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-04-12T20:54:56.164Z,1397336096.164 [WetLabsBB2FL](INFO): Thread ID is 853
2014-04-12T20:54:56.165Z,1397336096.165 [WetLabsBB2FL](INFO): Powering down
2014-04-12T20:54:56.192Z,1397336096.192 [NavChartDb](INFO): Thread ID is 854
2014-04-12T20:54:56.201Z,1397336096.201 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2014-04-12T20:54:56.204Z,1397336096.204 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2014-04-12T20:54:56.205Z,1397336096.205 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2014-04-12T20:54:56.205Z,1397336096.205 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2014-04-12T20:54:56.205Z,1397336096.205 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2014-04-12T20:54:56.206Z,1397336096.206 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2014-04-12T20:54:56.206Z,1397336096.206 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2014-04-12T20:54:56.206Z,1397336096.206 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2014-04-12T20:54:56.207Z,1397336096.207 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2014-04-12T20:54:57.621Z,1397336097.621 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2014-04-12T20:54:57.651Z,1397336097.651 [InternalSim](DEBUG):  InternalSim initializing...
2014-04-12T20:54:57.948Z,1397336097.948 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-04-12T20:54:57.948Z,1397336097.948 [NavChart](DEBUG): Initialize NavChart Navigation.
2014-04-12T20:54:57.949Z,1397336097.949 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2014-04-12T20:54:57.951Z,1397336097.951 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2014-04-12T20:54:57.951Z,1397336097.951 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2014-04-12T20:54:57.952Z,1397336097.952 [LoopControl](DEBUG): Initialize LoopControlComponent.
2014-04-12T20:54:57.952Z,1397336097.952 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-04-12T20:54:57.953Z,1397336097.953 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2014-04-12T20:54:57.953Z,1397336097.953 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2014-04-12T20:54:57.953Z,1397336097.953 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2014-04-12T20:54:57.954Z,1397336097.954 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2014-04-12T20:54:57.954Z,1397336097.954 [SBIT](INFO): Initialize SBIT Component.
2014-04-12T20:54:57.955Z,1397336097.955 [SBIT](IMPORTANT): Tethys CM Info: 
 $Rev:11196
2014-04-12T20:54:57.955Z,1397336097.955 [SBIT](IMPORTANT): Kernel Release:2.6.27.8
2014-04-12T20:54:57.955Z,1397336097.955 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2014-04-12T20:54:57.956Z,1397336097.956 [IBIT](INFO): Initialize IBIT Component.
2014-04-12T20:54:57.966Z,1397336097.966 [CBIT](DEBUG): Initialize CBIT Component.
2014-04-12T20:54:57.966Z,1397336097.966 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2014-04-12T20:54:57.991Z,1397336097.991 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2014-04-12T20:54:58.037Z,1397336098.037 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-04-12T20:54:58.065Z,1397336098.065 [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>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2014-04-12T20:54:58.066Z,1397336098.066 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2014-04-12T20:54:58.153Z,1397336098.153 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2014-04-12T20:54:58.156Z,1397336098.156 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2014-04-12T20:54:58.171Z,1397336098.171 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-04-12T20:54:58.193Z,1397336098.193 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2014-04-12T20:54:58.196Z,1397336098.196 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-04-12T20:54:58.207Z,1397336098.207 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2014-04-12T20:54:58.253Z,1397336098.253 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed](DEBUG): Construct.
2014-04-12T20:54:58.256Z,1397336098.256 [Default:WaitAtTheSurface:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-04-12T20:54:58.275Z,1397336098.275 [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>
            <Parallel/>
            <Setting><Guidance:SetSpeed.period/><Units:millisecond/><Value>400</Value></Setting>
        </Guidance:SetSpeed>

<!-- Get to the surface -->

        <Guidance:GoToSurface>
            <Sequence/>
        </Guidance:GoToSurface>

<!-- Get GPS fix -->

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

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

    </Aggregate>

    <Aggregate Id="Iridium">

        <Sequence/>

<!-- Run at high speed -->

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

<!-- Get to the surface -->

        <Guidance:GoToSurface>
            <Sequence/>
        </Guidance:GoToSurface>

<!-- Do data comms -->

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <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><Sequence/><Arg Name="NeedGPS"/><True/></Assign>

        <Call RefId="Iridium"/>

    </Aggregate>

    <Aggregate Id="WaitAtTheSurface">

        <Sequence/>

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

        <Guidance:GoToSurface>
            <Parallel/>
        </Guidance:GoToSurface>

    </Aggregate>

</Mission>


2014-04-12T20:54:58.281Z,1397336098.281 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2014-04-12T20:54:58.367Z,1397336098.367 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-04-12T20:54:58.545Z,1397336098.545 [DVL_micro](INFO): Initializing
2014-04-12T20:54:58.745Z,1397336098.745 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:54:58.807Z,1397336098.807 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-04-12T20:54:58.830Z,1397336098.830 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-04-12T20:54:58.857Z,1397336098.857 [ElevatorServo](DEBUG): Initializing EZServoServo.
2014-04-12T20:54:58.863Z,1397336098.863 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2014-04-12T20:54:58.869Z,1397336098.869 [MassServo](DEBUG): Initializing EZServoServo.
2014-04-12T20:54:58.875Z,1397336098.875 [MassServo](DEBUG): Initializing MassServo.
2014-04-12T20:54:58.885Z,1397336098.885 [RudderServo](DEBUG): Initializing EZServoServo.
2014-04-12T20:54:58.891Z,1397336098.891 [RudderServo](DEBUG): Initializing RudderServo.
2014-04-12T20:54:58.896Z,1397336098.896 [ThrusterServo](DEBUG): Initializing EZServoServo.
2014-04-12T20:54:58.903Z,1397336098.903 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2014-04-12T20:54:59.302Z,1397336099.302 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:54:59.738Z,1397336099.738 [NAL9602](INFO): Powering up NAL9602
2014-04-12T20:54:59.803Z,1397336099.803 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:00.560Z,1397336100.560 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:01.144Z,1397336101.144 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:01.594Z,1397336101.594 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:02.195Z,1397336102.195 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:02.633Z,1397336102.633 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:03.398Z,1397336103.398 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:04.308Z,1397336104.308 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:04.831Z,1397336104.831 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:05.451Z,1397336105.451 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:05.980Z,1397336105.980 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:06.486Z,1397336106.486 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:06.997Z,1397336106.997 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:07.581Z,1397336107.581 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:08.241Z,1397336108.241 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:08.805Z,1397336108.805 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:09.271Z,1397336109.271 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:09.756Z,1397336109.756 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:10.206Z,1397336110.206 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:10.721Z,1397336110.721 [NAL9602](INFO): NAL9602 initialized
2014-04-12T20:55:10.765Z,1397336110.765 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:11.257Z,1397336111.257 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:11.722Z,1397336111.722 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:12.179Z,1397336112.179 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:12.704Z,1397336112.704 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:13.495Z,1397336113.495 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:14.052Z,1397336114.052 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:14.073Z,1397336114.073 [SBIT](IMPORTANT): Beginning Startup BIT
2014-04-12T20:55:14.080Z,1397336114.080 [CBIT](IMPORTANT): Beginning GF scan
2014-04-12T20:55:14.492Z,1397336114.492 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:14.949Z,1397336114.949 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:15.408Z,1397336115.408 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:16.012Z,1397336116.012 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:16.959Z,1397336116.959 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:17.573Z,1397336117.573 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:18.135Z,1397336118.135 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:18.896Z,1397336118.896 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:19.455Z,1397336119.455 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:20.030Z,1397336120.030 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:20.658Z,1397336120.658 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:21.101Z,1397336121.101 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:21.633Z,1397336121.633 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:22.066Z,1397336122.066 [NAL9602](INFO): SBD MO Status=0, MOMSN=24960, MT Status=0, MTMSN=0
2014-04-12T20:55:22.067Z,1397336122.067 [NAL9602](INFO): No messages in MT queue
2014-04-12T20:55:22.080Z,1397336122.080 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:22.560Z,1397336122.560 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:23.148Z,1397336123.148 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:23.773Z,1397336123.773 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:24.240Z,1397336124.240 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:24.749Z,1397336124.749 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:25.163Z,1397336125.163 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:25.571Z,1397336125.571 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:26.012Z,1397336126.012 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:26.481Z,1397336126.481 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:27.005Z,1397336127.005 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:27.449Z,1397336127.449 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:27.883Z,1397336127.883 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:28.308Z,1397336128.308 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:28.957Z,1397336128.957 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:29.457Z,1397336129.457 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:30.458Z,1397336130.458 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:31.044Z,1397336131.044 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:31.576Z,1397336131.576 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:32.090Z,1397336132.090 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:32.586Z,1397336132.586 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:33.179Z,1397336133.179 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:33.752Z,1397336133.752 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T20:55:34.403Z,1397336134.403 [NAL9602](IMPORTANT): GPS fix at: 1397336101.00
2014-04-12T20:55:34.446Z,1397336134.446 [DeadReckonUsingMultipleVelocitySources](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07
2014-04-12T20:55:40.752Z,1397336140.752 [CBIT](IMPORTANT): No ground fault detected
2014-04-12T20:55:42.572Z,1397336142.572 [NAL9602](INFO): Powering down
2014-04-12T20:56:08.096Z,1397336168.096 [SBIT](IMPORTANT): SBIT PASSED
2014-04-12T20:56:08.487Z,1397336168.487 [MissionManager](IMPORTANT): Started mission Startup
2014-04-12T20:56:08.487Z,1397336168.487 [Startup] Running Loop=1
2014-04-12T20:56:08.487Z,1397336168.487 [Startup](INFO): Aggregate::initialize Startup
2014-04-12T20:56:08.487Z,1397336168.487 [Startup:A.GoToSurface] Running Loop=1
2014-04-12T20:56:08.487Z,1397336168.487 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-04-12T20:56:08.493Z,1397336168.493 [Startup:StartupSatComms] Running Loop=1
2014-04-12T20:56:08.494Z,1397336168.494 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2014-04-12T20:56:08.494Z,1397336168.494 [Startup:StartupSatComms:A] Running Loop=1
2014-04-12T20:56:08.901Z,1397336168.901 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2014-04-12T20:56:09.335Z,1397336169.335 [NAL9602](INFO): Powering up
2014-04-12T20:56:20.052Z,1397336180.052 [NAL9602](INFO): NAL9602 initialized
2014-04-12T20:56:35.986Z,1397336195.986 [NAL9602](INFO): SBD MO Status=0, MOMSN=24961, MT Status=0, MTMSN=0
2014-04-12T20:56:35.986Z,1397336195.986 [NAL9602](INFO): No messages in MT queue
2014-04-12T20:56:37.309Z,1397336197.309 [NAL9602](IMPORTANT): GPS fix at: 1397336164.00
2014-04-12T20:56:37.336Z,1397336197.336 [DeadReckonUsingMultipleVelocitySources](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07
2014-04-12T20:56:37.338Z,1397336197.338 [Startup:StartupSatComms:A] Stopped
2014-04-12T20:56:37.338Z,1397336197.338 [Startup:StartupSatComms:B] Running Loop=1
2014-04-12T20:56:37.772Z,1397336197.772 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2014-04-12T20:56:57.762Z,1397336217.762 [NAL9602](INFO): SBD MO Status=1, MOMSN=24962, MT Status=0, MTMSN=0
2014-04-12T20:56:57.827Z,1397336217.827 [NAL9602](INFO): Sent 262 bytes from file Logs/20140412T204020/Courier0008.lzma
2014-04-12T20:56:57.827Z,1397336217.827 [NAL9602](INFO): Packets left to send: 0
2014-04-12T20:56:57.829Z,1397336217.829 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T204020/Courier0008.lzma.parts/0000.sbd
2014-04-12T20:56:57.829Z,1397336217.829 [NAL9602](INFO): Completed sending Logs/20140412T204020/Courier0008.lzma
2014-04-12T20:57:07.043Z,1397336227.043 [NAL9602](INFO): SBD MO Status=1, MOMSN=24963, MT Status=0, MTMSN=0
2014-04-12T20:57:07.089Z,1397336227.089 [NAL9602](INFO): Sent 295 bytes from file Logs/20140412T205448/Courier0000.lzma
2014-04-12T20:57:07.090Z,1397336227.090 [NAL9602](INFO): Packets left to send: 0
2014-04-12T20:57:07.091Z,1397336227.091 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T205448/Courier0000.lzma.parts/0000.sbd
2014-04-12T20:57:07.091Z,1397336227.091 [NAL9602](INFO): Completed sending Logs/20140412T205448/Courier0000.lzma
2014-04-12T20:57:15.094Z,1397336235.094 [NAL9602](INFO): SBD MO Status=1, MOMSN=24964, MT Status=0, MTMSN=0
2014-04-12T20:57:15.150Z,1397336235.150 [NAL9602](INFO): Sent 70 bytes from file Logs/20140412T204020/Express0005.lzma
2014-04-12T20:57:15.150Z,1397336235.150 [NAL9602](INFO): Packets left to send: 0
2014-04-12T20:57:15.152Z,1397336235.152 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T204020/Express0005.lzma.parts/0000.sbd
2014-04-12T20:57:15.152Z,1397336235.152 [NAL9602](INFO): Completed sending Logs/20140412T204020/Express0005.lzma
2014-04-12T20:57:23.137Z,1397336243.137 [NAL9602](INFO): SBD MO Status=1, MOMSN=24965, MT Status=0, MTMSN=0
2014-04-12T20:57:23.186Z,1397336243.186 [NAL9602](INFO): Sent 332 bytes from file Logs/20140412T204020/Express0009.lzma
2014-04-12T20:57:23.187Z,1397336243.187 [NAL9602](INFO): Packets left to send: 1
2014-04-12T20:57:23.188Z,1397336243.188 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T204020/Express0009.lzma.parts/0001.sbd
2014-04-12T20:57:30.574Z,1397336250.574 [NAL9602](INFO): SBD MO Status=1, MOMSN=24966, MT Status=0, MTMSN=0
2014-04-12T20:57:30.622Z,1397336250.622 [NAL9602](INFO): Sent 200 bytes from file Logs/20140412T204020/Express0009.lzma
2014-04-12T20:57:30.622Z,1397336250.622 [NAL9602](INFO): Packets left to send: 0
2014-04-12T20:57:30.623Z,1397336250.623 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T204020/Express0009.lzma.parts/0000.sbd
2014-04-12T20:57:30.624Z,1397336250.624 [NAL9602](INFO): Completed sending Logs/20140412T204020/Express0009.lzma
2014-04-12T20:57:37.368Z,1397336257.368 [Startup:StartupSatComms:B](INFO): Timed out from 2014-04-12T20:56:37.3Z
2014-04-12T20:57:37.368Z,1397336257.368 [Startup:StartupSatComms:B] Stopped
2014-04-12T20:57:37.368Z,1397336257.368 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2014-04-12T20:57:37.368Z,1397336257.368 [Startup:StartupSatComms] Stopped
2014-04-12T20:57:37.368Z,1397336257.368 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2014-04-12T20:57:37.369Z,1397336257.369 [Startup](INFO): Completed Startup
2014-04-12T20:57:37.369Z,1397336257.369 [Startup] Stopped
2014-04-12T20:57:37.370Z,1397336257.370 [Startup](INFO): Aggregate::uninitialize Startup
2014-04-12T20:57:37.370Z,1397336257.370 [Startup:A.GoToSurface] Stopped
2014-04-12T20:57:37.370Z,1397336257.370 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-04-12T20:57:37.759Z,1397336257.759 [MissionManager](IMPORTANT): Started mission Default
2014-04-12T20:57:37.759Z,1397336257.759 [Default] Running Loop=1
2014-04-12T20:57:37.759Z,1397336257.759 [Default](INFO): Aggregate::initialize Default
2014-04-12T20:57:37.760Z,1397336257.760 [Default:Iridium] Running Loop=1
2014-04-12T20:57:37.760Z,1397336257.760 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2014-04-12T20:57:37.760Z,1397336257.760 [Default:Iridium:A.SetSpeed] Running Loop=1
2014-04-12T20:57:37.760Z,1397336257.760 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2014-04-12T20:57:37.760Z,1397336257.760 [Default:Iridium:B.GoToSurface] Running Loop=1
2014-04-12T20:57:37.760Z,1397336257.760 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-04-12T20:57:37.761Z,1397336257.761 [Default:CallIridium] Running Loop=1
2014-04-12T20:57:37.761Z,1397336257.761 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2014-04-12T20:57:37.761Z,1397336257.761 [Default:CallIridium:A] Running Loop=1
2014-04-12T20:57:37.763Z,1397336257.763 [Default:CallIridium:A] Stopped
2014-04-12T20:57:37.763Z,1397336257.763 [Default:CallIridium:B] Running Loop=1
2014-04-12T20:57:37.763Z,1397336257.763 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2014-04-12T20:57:37.769Z,1397336257.769 [Default:Iridium:B.GoToSurface] Stopped
2014-04-12T20:57:37.769Z,1397336257.769 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-04-12T20:57:37.769Z,1397336257.769 [Default:Iridium:Read_Iridium] Running Loop=1
2014-04-12T20:57:37.769Z,1397336257.769 [Default:Iridium:A.SetSpeed] Running Loop=1
2014-04-12T20:57:37.775Z,1397336257.775 [Default:GPS] Running Loop=1
2014-04-12T20:57:37.775Z,1397336257.775 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2014-04-12T20:57:37.775Z,1397336257.775 [Default:GPS:A.SetSpeed] Running Loop=1
2014-04-12T20:57:37.775Z,1397336257.775 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2014-04-12T20:57:37.775Z,1397336257.775 [Default:GPS:B.GoToSurface] Running Loop=1
2014-04-12T20:57:37.775Z,1397336257.775 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-04-12T20:57:37.781Z,1397336257.781 [Default:GPS:B.GoToSurface] Stopped
2014-04-12T20:57:37.781Z,1397336257.781 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-04-12T20:57:37.782Z,1397336257.782 [Default:GPS:Read_GPS] Running Loop=1
2014-04-12T20:57:37.782Z,1397336257.782 [Default:GPS:A.SetSpeed] Running Loop=1
2014-04-12T20:57:38.386Z,1397336258.386 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2014-04-12T20:57:38.389Z,1397336258.389 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2014-04-12T20:57:39.426Z,1397336259.426 [NAL9602](INFO): SBD MO Status=1, MOMSN=24967, MT Status=0, MTMSN=0
2014-04-12T20:57:39.476Z,1397336259.476 [NAL9602](INFO): Sent 332 bytes from file Logs/20140412T205448/Express0001.lzma
2014-04-12T20:57:39.476Z,1397336259.476 [NAL9602](INFO): Packets left to send: 1
2014-04-12T20:57:39.477Z,1397336259.477 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T205448/Express0001.lzma.parts/0001.sbd
2014-04-12T20:57:46.230Z,1397336266.230 [NAL9602](INFO): SBD MO Status=1, MOMSN=24968, MT Status=0, MTMSN=0
2014-04-12T20:57:46.278Z,1397336266.278 [NAL9602](INFO): Sent 77 bytes from file Logs/20140412T205448/Courier0004.lzma
2014-04-12T20:57:46.278Z,1397336266.278 [NAL9602](INFO): Packets left to send: 0
2014-04-12T20:57:46.281Z,1397336266.281 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T205448/Courier0004.lzma.parts/0000.sbd
2014-04-12T20:57:46.281Z,1397336266.281 [NAL9602](INFO): Completed sending Logs/20140412T205448/Courier0004.lzma
2014-04-12T20:57:56.854Z,1397336276.854 [NAL9602](INFO): SBD MO Status=1, MOMSN=24969, MT Status=0, MTMSN=0
2014-04-12T20:57:56.903Z,1397336276.903 [NAL9602](INFO): Sent 241 bytes from file Logs/20140412T205448/Express0001.lzma
2014-04-12T20:57:56.903Z,1397336276.903 [NAL9602](INFO): Packets left to send: 0
2014-04-12T20:57:56.905Z,1397336276.905 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T205448/Express0001.lzma.parts/0000.sbd
2014-04-12T20:57:56.905Z,1397336276.905 [NAL9602](INFO): Completed sending Logs/20140412T205448/Express0001.lzma
2014-04-12T20:58:04.403Z,1397336284.403 [NAL9602](INFO): SBD MO Status=1, MOMSN=24970, MT Status=0, MTMSN=0
2014-04-12T20:58:04.458Z,1397336284.458 [NAL9602](INFO): Sent 126 bytes from file Logs/20140412T205448/Express0005.lzma
2014-04-12T20:58:04.459Z,1397336284.459 [NAL9602](INFO): Packets left to send: 0
2014-04-12T20:58:04.460Z,1397336284.460 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T205448/Express0005.lzma.parts/0000.sbd
2014-04-12T20:58:04.460Z,1397336284.460 [NAL9602](INFO): Completed sending Logs/20140412T205448/Express0005.lzma
2014-04-12T20:58:15.193Z,1397336295.193 [NAL9602](INFO): SBD MO Status=0, MOMSN=24971, MT Status=0, MTMSN=0
2014-04-12T20:58:15.273Z,1397336295.273 [Default:Iridium:Read_Iridium] Stopped
2014-04-12T20:58:15.274Z,1397336295.274 [Default:Iridium](INFO): Completed Default:Iridium
2014-04-12T20:58:15.274Z,1397336295.274 [Default:Iridium] Stopped
2014-04-12T20:58:15.274Z,1397336295.274 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2014-04-12T20:58:15.274Z,1397336295.274 [Default:Iridium:A.SetSpeed] Stopped
2014-04-12T20:58:15.274Z,1397336295.274 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2014-04-12T20:58:15.274Z,1397336295.274 [Default:WaitAtTheSurface] Running Loop=1
2014-04-12T20:58:15.274Z,1397336295.274 [Default:WaitAtTheSurface](INFO): Aggregate::initialize Default:WaitAtTheSurface
2014-04-12T20:58:15.275Z,1397336295.275 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed] Running Loop=1
2014-04-12T20:58:15.275Z,1397336295.275 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed](DEBUG): Initialize.
2014-04-12T20:58:15.275Z,1397336295.275 [Default:WaitAtTheSurface:B.GoToSurface] Running Loop=1
2014-04-12T20:58:15.275Z,1397336295.275 [Default:WaitAtTheSurface:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-04-12T20:58:15.659Z,1397336295.659 [Default:WaitAtTheSurface:B.GoToSurface] Running Loop=1
2014-04-12T20:58:15.664Z,1397336295.664 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed] Running Loop=1
2014-04-12T20:58:15.670Z,1397336295.670 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2014-04-12T20:58:15.670Z,1397336295.670 [Default:CallIridium:B] Stopped
2014-04-12T20:58:15.670Z,1397336295.670 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2014-04-12T20:58:15.670Z,1397336295.670 [Default:CallIridium](INFO): Completed Default:CallIridium
2014-04-12T20:58:15.670Z,1397336295.670 [Default:CallIridium] Stopped
2014-04-12T20:58:15.670Z,1397336295.670 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2014-04-12T20:58:16.541Z,1397336296.541 [NAL9602](IMPORTANT): GPS fix at: 1397336265.00
2014-04-12T20:58:16.553Z,1397336296.553 [DeadReckonUsingMultipleVelocitySources](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07
2014-04-12T20:58:16.557Z,1397336296.557 [Default:GPS:Read_GPS] Stopped
2014-04-12T20:58:16.557Z,1397336296.557 [Default:GPS:D] Running Loop=1
2014-04-12T20:58:16.979Z,1397336296.979 [Default:GPS:D] Stopped
2014-04-12T20:58:16.980Z,1397336296.980 [Default:GPS](INFO): Completed Default:GPS
2014-04-12T20:58:16.980Z,1397336296.980 [Default:GPS] Stopped
2014-04-12T20:58:16.980Z,1397336296.980 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2014-04-12T20:58:16.980Z,1397336296.980 [Default:GPS:A.SetSpeed] Stopped
2014-04-12T20:58:16.980Z,1397336296.980 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2014-04-12T20:58:37.585Z,1397336317.585 [NAL9602](INFO): Powering down
2014-04-12T21:03:17.605Z,1397336597.605 [Default:CallIridium] Running Loop=1
2014-04-12T21:03:17.606Z,1397336597.606 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2014-04-12T21:03:17.606Z,1397336597.606 [Default:CallIridium:A] Running Loop=1
2014-04-12T21:03:17.606Z,1397336597.606 [Default:CallIridium:A] Stopped
2014-04-12T21:03:17.606Z,1397336597.606 [Default:CallIridium:B] Running Loop=1
2014-04-12T21:03:17.606Z,1397336597.606 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2014-04-12T21:03:22.553Z,1397336602.553 [Default:Iridium] Running Loop=1
2014-04-12T21:03:22.553Z,1397336602.553 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2014-04-12T21:03:22.553Z,1397336602.553 [Default:Iridium:A.SetSpeed] Running Loop=1
2014-04-12T21:03:22.554Z,1397336602.554 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2014-04-12T21:03:22.554Z,1397336602.554 [Default:Iridium:B.GoToSurface] Running Loop=1
2014-04-12T21:03:22.554Z,1397336602.554 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-04-12T21:03:22.554Z,1397336602.554 [Default:Iridium:B.GoToSurface] Stopped
2014-04-12T21:03:22.555Z,1397336602.555 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-04-12T21:03:22.555Z,1397336602.555 [Default:Iridium:Read_Iridium] Running Loop=1
2014-04-12T21:03:22.559Z,1397336602.559 [Default:GPS] Running Loop=1
2014-04-12T21:03:22.559Z,1397336602.559 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2014-04-12T21:03:22.559Z,1397336602.559 [Default:GPS:A.SetSpeed] Running Loop=1
2014-04-12T21:03:22.560Z,1397336602.560 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2014-04-12T21:03:22.560Z,1397336602.560 [Default:GPS:B.GoToSurface] Running Loop=1
2014-04-12T21:03:22.560Z,1397336602.560 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-04-12T21:03:22.561Z,1397336602.561 [Default:GPS:B.GoToSurface] Stopped
2014-04-12T21:03:22.561Z,1397336602.561 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-04-12T21:03:22.561Z,1397336602.561 [Default:GPS:Read_GPS] Running Loop=1
2014-04-12T21:03:23.981Z,1397336603.981 [NAL9602](INFO): Powering up
2014-04-12T21:03:34.634Z,1397336614.634 [NAL9602](INFO): NAL9602 initialized
2014-04-12T21:04:03.755Z,1397336643.755 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=24972, MT Status=1, MTMSN=1706
2014-04-12T21:04:03.803Z,1397336643.803 [NAL9602](INFO): Sent 75 bytes from file Logs/20140412T205448/Courier0008.lzma
2014-04-12T21:04:03.804Z,1397336643.804 [NAL9602](INFO): Packets left to send: 0
2014-04-12T21:04:03.805Z,1397336643.805 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T205448/Courier0008.lzma.parts/0000.sbd
2014-04-12T21:04:03.806Z,1397336643.806 [NAL9602](INFO): Completed sending Logs/20140412T205448/Courier0008.lzma
2014-04-12T21:04:04.758Z,1397336644.758 [NAL9602](INFO): Received command:sched asap "load Science/science_to.xml;set science_to.MaxDepth 50 meter;set science_to.YoYoMaxDepth 40 meter;set science_to.Wpt1Lat 33.6065 degree;set science_to.Wpt1Lon -118.0764 degree;set science_to.Wpt2Lat 33.6273 degree" 3qy7k 1 2
2014-04-12T21:04:04.838Z,1397336644.838 [CommandLine](IMPORTANT): got command schedule asap "load Science/science_to.xml;set science_to.MaxDepth 50 meter;set science_to.YoYoMaxDepth 40 meter;set science_to.Wpt1Lat 33.6065 degree;set science_to.Wpt1Lon -118.0764 degree;set science_to.Wpt2Lat 33.6273 degree" 3qy7k 1 2.000000
2014-04-12T21:04:04.838Z,1397336644.838 [CommandLine](IMPORTANT): Scheduling command #1 of 2 with id=3qy7k
2014-04-12T21:04:04.839Z,1397336644.839 [CommandLine](IMPORTANT): Scheduled #1 (#1 of 2 with id='3qy7k'): "load Science/science_to.xml;set science_to.MaxDepth 50 meter;set science_to.YoYoMaxDepth 40 meter;set science_to.Wpt1Lat 33.6065 degree;set science_to.Wpt1Lon -118.0764 degree;set science_to.Wpt2Lat 33.6273 degree" ASAP
2014-04-12T21:04:25.329Z,1397336665.329 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=24973, MT Status=1, MTMSN=1707
2014-04-12T21:04:25.377Z,1397336665.377 [NAL9602](INFO): Sent 96 bytes from file Logs/20140412T205448/Express0009.lzma
2014-04-12T21:04:25.377Z,1397336665.377 [NAL9602](INFO): Packets left to send: 0
2014-04-12T21:04:25.379Z,1397336665.379 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T205448/Express0009.lzma.parts/0000.sbd
2014-04-12T21:04:25.379Z,1397336665.379 [NAL9602](INFO): Completed sending Logs/20140412T205448/Express0009.lzma
2014-04-12T21:04:26.138Z,1397336666.138 [NAL9602](INFO): Received command:sched asap "set science_to.Wpt2Lon -118.0603 degree;set science_to.NeedCommsTime 45 minute;set science_to.Timeout 3 hour;run ; (); (Working on the third waypoint!!!)" 3qy7k 2 2
2014-04-12T21:04:26.176Z,1397336666.176 [CommandLine](IMPORTANT): got command schedule asap "set science_to.Wpt2Lon -118.0603 degree;set science_to.NeedCommsTime 45 minute;set science_to.Timeout 3 hour;run ; (); (Working on the third waypoint!!!)" 3qy7k 2 2.000000
2014-04-12T21:04:26.176Z,1397336666.176 [CommandLine](IMPORTANT): Scheduling command #2 of 2 with id=3qy7k
2014-04-12T21:04:26.177Z,1397336666.177 [CommandLine](IMPORTANT): Scheduled #2 (#2 of 2 with id='3qy7k'): "set science_to.Wpt2Lon -118.0603 degree;set science_to.NeedCommsTime 45 minute;set science_to.Timeout 3 hour;run ; (); (Working on the third waypoint!!!)" ASAP
2014-04-12T21:04:26.375Z,1397336666.375 [CommandLine](IMPORTANT): got command load ./Missions/Science/science_to.xml
2014-04-12T21:04:26.375Z,1397336666.375 [MissionManager](INFO): Loading Mission: ./Missions/Science/science_to.xml
2014-04-12T21:04:26.540Z,1397336666.540 [MissionManager](INFO): DefineArg science_to.MinAltitude = 10.000000 m
2014-04-12T21:04:26.568Z,1397336666.568 [MissionManager](INFO): DefineArg science_to.MaxDepth = 105.000000 m
2014-04-12T21:04:26.571Z,1397336666.571 [MissionManager](INFO): DefineArg science_to.MinOffshore = 2000.000000 m
2014-04-12T21:04:26.573Z,1397336666.573 [MissionManager](INFO): DefineArg science_to.YoYoMinDepth = 3.000000 m
2014-04-12T21:04:26.576Z,1397336666.576 [MissionManager](INFO): DefineArg science_to.YoYoMaxDepth = 100.000000 m
2014-04-12T21:04:26.591Z,1397336666.591 [MissionManager](INFO): DefineArg science_to.YoYoUpPitch = 20.000000 arcdeg
2014-04-12T21:04:26.594Z,1397336666.594 [MissionManager](INFO): DefineArg science_to.YoYoDownPitch = -20.000000 arcdeg
2014-04-12T21:04:26.597Z,1397336666.597 [MissionManager](INFO): DefineArg science_to.Wpt1Lat = 0.000000 arcdeg
2014-04-12T21:04:26.600Z,1397336666.600 [MissionManager](INFO): DefineArg science_to.Wpt1Lon = 0.000000 arcdeg
2014-04-12T21:04:26.631Z,1397336666.631 [MissionManager](INFO): DefineArg science_to.Wpt2Lat = 0.000000 arcdeg
2014-04-12T21:04:26.641Z,1397336666.641 [MissionManager](INFO): DefineArg science_to.Wpt2Lon = 0.000000 arcdeg
2014-04-12T21:04:26.644Z,1397336666.644 [MissionManager](INFO): DefineArg science_to.Wpt3Lat = 0.000000 arcdeg
2014-04-12T21:04:26.651Z,1397336666.651 [MissionManager](INFO): DefineArg science_to.Wpt3Lon = 0.000000 arcdeg
2014-04-12T21:04:26.654Z,1397336666.654 [MissionManager](INFO): DefineArg science_to.Wpt4Lat = 0.000000 arcdeg
2014-04-12T21:04:26.657Z,1397336666.657 [MissionManager](INFO): DefineArg science_to.Wpt4Lon = 0.000000 arcdeg
2014-04-12T21:04:26.660Z,1397336666.660 [MissionManager](INFO): DefineArg science_to.NeedCommsTime = 120.000000 min
2014-04-12T21:04:26.683Z,1397336666.683 [MissionManager](INFO): DefineArg science_to.Speed = 1.000000 m/s
2014-04-12T21:04:26.687Z,1397336666.687 [MissionManager](INFO): DefineArg science_to.Timeout = 36.000000 h
2014-04-12T21:04:26.691Z,1397336666.691 [MissionManager](INFO): DefineArg science_to.BuoyancyNeutral = 0.000300 n/a
2014-04-12T21:04:26.694Z,1397336666.694 [MissionManager](INFO): DefineArg science_to.MassDefault = 0.000000 n/a
2014-04-12T21:04:26.699Z,1397336666.699 [MissionManager](INFO): DefineArg science_to.ZeroDeg = 0.000000 arcdeg
2014-04-12T21:04:26.701Z,1397336666.701 [science_to:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2014-04-12T21:04:26.741Z,1397336666.741 [science_to:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2014-04-12T21:04:26.772Z,1397336666.772 [science_to:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2014-04-12T21:04:26.784Z,1397336666.784 [science_to:D.Pitch](DEBUG): Construct.
2014-04-12T21:04:26.789Z,1397336666.789 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml
2014-04-12T21:04:26.869Z,1397336666.869 [MissionManager](INFO): DefineArg science_to:SURFACECOMMS.SurfaceDepthRate = nan m/s
2014-04-12T21:04:26.872Z,1397336666.872 [MissionManager](INFO): DefineArg science_to:SURFACECOMMS.SurfacePitch = 20.000000 arcdeg
2014-04-12T21:04:26.876Z,1397336666.876 [MissionManager](INFO): DefineArg science_to:SURFACECOMMS.SurfaceSpeed = 1.000000 m/s
2014-04-12T21:04:26.880Z,1397336666.880 [MissionManager](INFO): DefineArg science_to:SURFACECOMMS.IridiumTimeout = 30.000000 min
2014-04-12T21:04:26.883Z,1397336666.883 [MissionManager](INFO): DefineArg science_to:SURFACECOMMS.UnpackSplitAtSurface = 0 bool
2014-04-12T21:04:26.885Z,1397336666.885 [science_to:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-04-12T21:04:26.897Z,1397336666.897 [MissionManager](INFO): RedefineArg science_to:SURFACECOMMS.SurfacePitch = value:20.000000 arcdeg
2014-04-12T21:04:26.899Z,1397336666.899 [MissionManager](INFO): RedefineArg science_to:SURFACECOMMS.SurfaceSpeed = reader:science_to.Speed
2014-04-12T21:04:26.905Z,1397336666.905 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2014-04-12T21:04:27.033Z,1397336667.033 [MissionManager](INFO): DefineArg science_to:Science.SampleISUS = 0 bool
2014-04-12T21:04:27.037Z,1397336667.037 [MissionManager](INFO): DefineArg science_to:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2014-04-12T21:04:27.045Z,1397336667.045 [MissionManager](INFO): DefineArg science_to:Science.SampleISUSminPitch = -60.000000 arcdeg
2014-04-12T21:04:27.049Z,1397336667.049 [MissionManager](INFO): DefineArg science_to:Science.PeakDetectChlActive = 0 bool
2014-04-12T21:04:27.053Z,1397336667.053 [MissionManager](INFO): DefineArg science_to:Science.PeakDetectNO3Active = 0 bool
2014-04-12T21:04:27.057Z,1397336667.057 [MissionManager](INFO): DefineArg science_to:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2014-04-12T21:04:27.063Z,1397336667.063 [MissionManager](INFO): DefineArg science_to:Science.ComputeWaterCurrent = 0 bool
2014-04-12T21:04:27.066Z,1397336667.066 [MissionManager](INFO): DefineArg science_to:Science.ComputeWaterCurrentMinDepth = 2.000000 m
2014-04-12T21:04:27.069Z,1397336667.069 [MissionManager](INFO): DefineArg science_to:Science.ComputeWaterCurrentMaxDepth = 5.000000 m
2014-04-12T21:04:27.073Z,1397336667.073 [MissionManager](INFO): DefineArg science_to:Science.EnabledAanderaaO2 = 0.000000 n/a
2014-04-12T21:04:27.077Z,1397336667.077 [MissionManager](INFO): DefineArg science_to:Science.EnabledNeilBrown = 1.000000 n/a
2014-04-12T21:04:27.080Z,1397336667.080 [MissionManager](INFO): DefineArg science_to:Science.EnabledTurbulence_NPS = 0.000000 n/a
2014-04-12T21:04:27.084Z,1397336667.084 [MissionManager](INFO): DefineArg science_to:Science.EnabledWetLabsBB2FL = 1.000000 n/a
2014-04-12T21:04:27.087Z,1397336667.087 [MissionManager](INFO): DefineArg science_to:Science.EnabledDVL = 1.000000 n/a
2014-04-12T21:04:27.090Z,1397336667.090 [MissionManager](INFO): DefineArg science_to:Science.EnabledISUS = 0.000000 n/a
2014-04-12T21:04:27.094Z,1397336667.094 [MissionManager](INFO): DefineOutput science_to:Science.PeakChl = 0.000000 ug/l
2014-04-12T21:04:27.097Z,1397336667.097 [MissionManager](INFO): DefineOutput science_to:Science.PeakChlDepth = 0.000000 m
2014-04-12T21:04:27.100Z,1397336667.100 [MissionManager](INFO): DefineOutput science_to:Science.PeakChlLatitude = nan arcdeg
2014-04-12T21:04:27.103Z,1397336667.103 [MissionManager](INFO): DefineOutput science_to:Science.PeakChlLongitude = nan arcdeg
2014-04-12T21:04:27.106Z,1397336667.106 [MissionManager](INFO): DefineOutput science_to:Science.PeakNO3 = 0.000000 umol/l
2014-04-12T21:04:27.140Z,1397336667.140 [MissionManager](INFO): DefineOutput science_to:Science.PeakNO3Depth = 0.000000 m
2014-04-12T21:04:27.148Z,1397336667.148 [MissionManager](INFO): DefineOutput science_to:Science.PeakNO3Latitude = nan arcdeg
2014-04-12T21:04:27.151Z,1397336667.151 [MissionManager](INFO): DefineOutput science_to:Science.PeakNO3Longitude = nan arcdeg
2014-04-12T21:04:27.170Z,1397336667.170 [science_to:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2014-04-12T21:04:27.179Z,1397336667.179 [science_to:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2014-04-12T21:04:27.190Z,1397336667.190 [MissionManager](INFO): RedefineArg science_to:Science.SampleISUS = value:1 bool
2014-04-12T21:04:27.192Z,1397336667.192 [MissionManager](INFO): RedefineArg science_to:Science.PeakDetectChlActive = value:1 bool
2014-04-12T21:04:27.193Z,1397336667.193 [science_to:H.Buoyancy](DEBUG): Construct Buoyancy.
2014-04-12T21:04:27.203Z,1397336667.203 [science_to:I.SetSpeed](DEBUG): Construct.
2014-04-12T21:04:27.206Z,1397336667.206 [science_to:J.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2014-04-12T21:04:27.244Z,1397336667.244 [science_to:K.YoYo](DEBUG): Construct YoYo.
2014-04-12T21:04:27.275Z,1397336667.275 [science_to:Wpt1:A.Waypoint](DEBUG): Construct Waypoint.
2014-04-12T21:04:27.293Z,1397336667.293 [science_to:Wpt2:A.Waypoint](DEBUG): Construct Waypoint.
2014-04-12T21:04:27.333Z,1397336667.333 [science_to:Wpt3:A.Waypoint](DEBUG): Construct Waypoint.
2014-04-12T21:04:27.341Z,1397336667.341 [science_to:Wpt4:A.Waypoint](DEBUG): Construct Waypoint.
2014-04-12T21:04:27.375Z,1397336667.375 [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="science_to">

    <Description>
        Vehicle yo-yo's to the specified waypoint, with science turned on.
    </Description>

    <DefineArg Name="MinAltitude"><Description>
        Minimum height above the sea floor for the entire mission.
    </Description><Units:meter/><Value>10.0</Value></DefineArg>

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

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

    <DefineArg Name="YoYoMinDepth"><Description>
        Minimum depth while performing the YoYo behavior.
    </Description><Units:meter/><Value>3.0</Value></DefineArg>

    <DefineArg Name="YoYoMaxDepth"><Description>
        Maximum depth while performing the YoYo behavior.
    </Description><Units:meter/><Value>100.0</Value></DefineArg>

    <DefineArg Name="YoYoUpPitch"><Description>
        Vehicle up pitch while performing the YoYo behavior.
    </Description><Units:degree/><Value>20.0</Value></DefineArg>

    <DefineArg Name="YoYoDownPitch"><Description>
        Vehicle down pitch while performing the YoYo behavior.
    </Description><Units:degree/><Value>-20.0</Value></DefineArg>

    <DefineArg Name="Wpt1Lat"><Description>
        Latitude of waypoint 1. If zero, waypoint is skipped.
    </Description><Units:degree/><Value>0</Value></DefineArg>

    <DefineArg Name="Wpt1Lon"><Description>
        Longitude of waypoint 1. If zero, waypoint is skipped.
    </Description><Units:degree/><Value>0</Value></DefineArg>

    <DefineArg Name="Wpt2Lat"><Description>
        Latitude of waypoint 2. If zero, waypoint is skipped.
    </Description><Units:degree/><Value>0</Value></DefineArg>

    <DefineArg Name="Wpt2Lon"><Description>
        Longitude of waypoint 2. If zero, waypoint is skipped.
    </Description><Units:degree/><Value>0</Value></DefineArg>

    <DefineArg Name="Wpt3Lat"><Description>
        Latitude of waypoint 3. If zero, waypoint is skipped.
    </Description><Units:degree/><Value>0</Value></DefineArg>

    <DefineArg Name="Wpt3Lon"><Description>
        Longitude of waypoint 3. If zero, waypoint is skipped.
    </Description><Units:degree/><Value>0</Value></DefineArg>

    <DefineArg Name="Wpt4Lat"><Description>
        Latitude of waypoint 4. If zero, waypoint is skipped.
    </Description><Units:degree/><Value>0</Value></DefineArg>

    <DefineArg Name="Wpt4Lon"><Description>
        Longitude of waypoint 4. If zero, waypoint is skipped.
    </Description><Units:degree/><Value>0</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 10 minutes more to begin ascent.
    </Description><Units:minute/><Value>120</Value></DefineArg>

    <DefineArg Name="Speed"><Description>
        Speed while performing the YoYo behavior.
    </Description><Units:meter_per_second/><Value>1</Value></DefineArg>

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

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

    <DefineArg Name="MassDefault"><Description>
        Static setting for mass during the mission. Set to NaN mm for active
        mass position
    </Description><Control:VerticalControl.massDefault/></DefineArg>

    <DefineArg Name="ZeroDeg"><Description>
        Constant value. Do not change.
    </Description><Units:degree/><Value>0</Value></DefineArg>

    <Timeout Duration="Timeout"/>

    <Aggregate Id="AltEnvWrapper">

        <When>
            <Arg Name="MinAltitude"/>
            <Gt><Units:meter/><Value>0</Value></Gt>
        </When>

        <Guidance:AltitudeEnvelope>
            <Description>
                Safety behavior that keeps vehicle off sea floor
            </Description>
            <Parallel/>
            <Setting><Guidance:AltitudeEnvelope.minAltitude/><Arg Name="MinAltitude"/></Setting>
        </Guidance:AltitudeEnvelope>

    </Aggregate>

    <Guidance:DepthEnvelope>
        <Description>
            Safety behavior that keeps vehicle from going too deep
        </Description>
        <Parallel/>
        <Setting><Guidance:DepthEnvelope.maxDepth/><Arg Name="MaxDepth"/></Setting>
    </Guidance:DepthEnvelope>

    <Aggregate Id="OffshoreWrapper">

        <When>
            <Arg Name="MinOffshore"/>
            <Gt><Units:meter/><Value>0</Value></Gt>
        </When>

        <Guidance:OffshoreEnvelope>
            <Description>
                Safety behavior that keeps vehicle away from the shore
            </Description>
            <Parallel/>
            <Setting><Guidance:OffshoreEnvelope.minOffshore/><Arg Name="MinOffshore"/></Setting>
        </Guidance:OffshoreEnvelope>

    </Aggregate>

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

    <Insert Filename="Insert/Surface.xml" Id="SURFACECOMMS">
        <RedefineArg Name="SurfacePitch"><Units:degree/><Value>20</Value></RedefineArg>
        <RedefineArg Name="SurfaceSpeed"><Arg Name="Speed"/></RedefineArg>
    </Insert>

<!-- 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:latitude_fix/></Elapsed><Gt><Arg Name="NeedCommsTime"/></Gt></And>
            <Or><Elapsed><Universal:latitude_fix/></Elapsed><Gt><Arg Name="NeedCommsTime"/><Add><Units:minute/><Value>10</Value></Add></Gt></Or>
        </When>

        <Call RefId="SURFACECOMMS"/>

    </Aggregate>

    <Insert Filename="Insert/Science.xml">
        <Description>
            If we're executing here or below, lets' get science data!
        </Description>
        <RedefineArg Name="SampleISUS"><True/></RedefineArg>
        <RedefineArg Name="PeakDetectChlActive"><True/></RedefineArg>
    </Insert>

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

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

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

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

    <Aggregate Id="Wpt1">

        <Sequence/>

        <Break><Arg Name="Wpt1Lat"/>
            <Eq><Arg Name="ZeroDeg"/></Eq>
            <Or><Arg Name="Wpt1Lon"/><Eq><Arg Name="ZeroDeg"/></Eq></Or>
        </Break>

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

    </Aggregate>

    <Aggregate Id="Wpt2">

        <Sequence/>

        <Break><Arg Name="Wpt2Lat"/>
            <Eq><Arg Name="ZeroDeg"/></Eq>
            <Or><Arg Name="Wpt2Lon"/><Eq><Arg Name="ZeroDeg"/></Eq></Or>
        </Break>

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

    </Aggregate>

    <Aggregate Id="Wpt3">

        <Sequence/>

        <Break><Arg Name="Wpt3Lat"/>
            <Eq><Arg Name="ZeroDeg"/></Eq>
            <Or><Arg Name="Wpt3Lon"/><Eq><Arg Name="ZeroDeg"/></Eq></Or>
        </Break>

        <Guidance:Waypoint>
            <Sequence/>
            <Setting><Guidance:Waypoint.latitude/><Arg Name="Wpt3Lat"/></Setting>
            <Setting><Guidance:Waypoint.longitude/><Arg Name="Wpt3Lon"/></Setting>
        </Guidance:Waypoint>

    </Aggregate>

    <Aggregate Id="Wpt4">

        <Sequence/>

        <Break><Arg Name="Wpt4Lat"/>
            <Eq><Arg Name="ZeroDeg"/></Eq>
            <Or><Arg Name="Wpt4Lon"/><Eq><Arg Name="ZeroDeg"/></Eq></Or>
        </Break>

        <Guidance:Waypoint>
            <Sequence/>
            <Setting><Guidance:Waypoint.latitude/><Arg Name="Wpt4Lat"/></Setting>
            <Setting><Guidance:Waypoint.longitude/><Arg Name="Wpt4Lon"/></Setting>
        </Guidance:Waypoint>

    </Aggregate>

</Mission>


2014-04-12T21:04:27.375Z,1397336667.375 [CommandLine](IMPORTANT): Loaded ./Missions/Science/science_to.xml
2014-04-12T21:04:34.049Z,1397336674.049 [CommandLine](IMPORTANT): got command set science_to.MaxDepth 50.000000 meter
2014-04-12T21:04:34.050Z,1397336674.050 [CommandLine](IMPORTANT): got command set science_to.YoYoMaxDepth 40.000000 meter
2014-04-12T21:04:34.050Z,1397336674.050 [CommandLine](IMPORTANT): got command set science_to.Wpt1Lat 33.606499 degree
2014-04-12T21:04:34.051Z,1397336674.051 [CommandLine](IMPORTANT): got command set science_to.Wpt1Lon -118.076401 degree
2014-04-12T21:04:34.052Z,1397336674.052 [CommandLine](IMPORTANT): got command set science_to.Wpt2Lat 33.627300 degree
2014-04-12T21:04:38.331Z,1397336678.331 [CommandLine](IMPORTANT): got command set science_to.Wpt2Lon -118.060303 degree
2014-04-12T21:04:38.333Z,1397336678.333 [CommandLine](IMPORTANT): got command set science_to.NeedCommsTime 45.000000 minute
2014-04-12T21:04:38.334Z,1397336678.334 [CommandLine](IMPORTANT): got command set science_to.Timeout 3.000000 hour
2014-04-12T21:04:38.335Z,1397336678.335 [CommandLine](IMPORTANT): got command run 
2014-04-12T21:04:38.371Z,1397336678.371 [CommandLine](IMPORTANT): Running 
2014-04-12T21:04:38.371Z,1397336678.371 [CommandLine](IMPORTANT): got command retransmit sbd
2014-04-12T21:04:38.371Z,1397336678.371 [CommandLine](FAULT): Incomplete syntax. Try: help retransmit
2014-04-12T21:04:38.713Z,1397336678.713 [Default] Stopped
2014-04-12T21:04:38.725Z,1397336678.725 [Default](INFO): Aggregate::uninitialize Default
2014-04-12T21:04:38.725Z,1397336678.725 [Default:GPS] Stopped
2014-04-12T21:04:38.725Z,1397336678.725 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2014-04-12T21:04:38.726Z,1397336678.726 [Default:GPS:A.SetSpeed] Stopped
2014-04-12T21:04:38.726Z,1397336678.726 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2014-04-12T21:04:38.726Z,1397336678.726 [Default:GPS:Read_GPS] Stopped
2014-04-12T21:04:38.726Z,1397336678.726 [Default:Iridium] Stopped
2014-04-12T21:04:38.726Z,1397336678.726 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2014-04-12T21:04:38.726Z,1397336678.726 [Default:Iridium:A.SetSpeed] Stopped
2014-04-12T21:04:38.726Z,1397336678.726 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2014-04-12T21:04:38.726Z,1397336678.726 [Default:Iridium:Read_Iridium] Stopped
2014-04-12T21:04:38.726Z,1397336678.726 [Default:CallIridium] Stopped
2014-04-12T21:04:38.726Z,1397336678.726 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2014-04-12T21:04:38.726Z,1397336678.726 [Default:CallIridium:B] Stopped
2014-04-12T21:04:38.727Z,1397336678.727 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2014-04-12T21:04:38.727Z,1397336678.727 [Default:WaitAtTheSurface] Stopped
2014-04-12T21:04:38.727Z,1397336678.727 [Default:WaitAtTheSurface](INFO): Aggregate::uninitialize Default:WaitAtTheSurface
2014-04-12T21:04:38.727Z,1397336678.727 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed] Stopped
2014-04-12T21:04:38.727Z,1397336678.727 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed](DEBUG): Uninitialize.
2014-04-12T21:04:38.727Z,1397336678.727 [Default:WaitAtTheSurface:B.GoToSurface] Stopped
2014-04-12T21:04:38.727Z,1397336678.727 [Default:WaitAtTheSurface:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-04-12T21:04:38.727Z,1397336678.727 [MissionManager](IMPORTANT): Started mission science_to
2014-04-12T21:04:38.727Z,1397336678.727 [science_to] Running Loop=1
2014-04-12T21:04:38.727Z,1397336678.727 [science_to](INFO): Aggregate::initialize science_to
2014-04-12T21:04:38.728Z,1397336678.728 [science_to:B.DepthEnvelope] Running Loop=1
2014-04-12T21:04:38.728Z,1397336678.728 [science_to:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2014-04-12T21:04:38.728Z,1397336678.728 [science_to:D.Pitch] Running Loop=1
2014-04-12T21:04:38.728Z,1397336678.728 [science_to:D.Pitch](DEBUG): Initialize.
2014-04-12T21:04:38.728Z,1397336678.728 [science_to:Science] Running Loop=1
2014-04-12T21:04:38.728Z,1397336678.728 [science_to:Science](INFO): Aggregate::initialize science_to:Science
2014-04-12T21:04:38.729Z,1397336678.729 [science_to:Science:A] Running Loop=1
2014-04-12T21:04:38.729Z,1397336678.729 [science_to:Science:B] Running Loop=1
2014-04-12T21:04:38.729Z,1397336678.729 [science_to:Science:C] Running Loop=1
2014-04-12T21:04:38.729Z,1397336678.729 [science_to:Science:D] Running Loop=1
2014-04-12T21:04:38.729Z,1397336678.729 [science_to:Science:E] Running Loop=1
2014-04-12T21:04:38.729Z,1397336678.729 [science_to:Science:F] Running Loop=1
2014-04-12T21:04:38.729Z,1397336678.729 [science_to:Science:G] Running Loop=1
2014-04-12T21:04:38.737Z,1397336678.737 [science_to:Science:PeakDetectChl] Running Loop=1
2014-04-12T21:04:38.737Z,1397336678.737 [science_to:Science:PeakDetectChl](INFO): Aggregate::initialize science_to:Science:PeakDetectChl
2014-04-12T21:04:38.737Z,1397336678.737 [science_to:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2014-04-12T21:04:38.738Z,1397336678.738 [science_to:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2014-04-12T21:04:38.739Z,1397336678.739 [science_to:Science:PeakDetectNO3] Running Loop=1
2014-04-12T21:04:38.739Z,1397336678.739 [science_to:Science:PeakDetectNO3](INFO): Aggregate::initialize science_to:Science:PeakDetectNO3
2014-04-12T21:04:38.739Z,1397336678.739 [science_to:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2014-04-12T21:04:38.739Z,1397336678.739 [science_to:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2014-04-12T21:04:38.747Z,1397336678.747 [science_to:H.Buoyancy] Running Loop=1
2014-04-12T21:04:38.747Z,1397336678.747 [science_to:H.Buoyancy](DEBUG): Initialize Buoyancy Component.
2014-04-12T21:04:38.747Z,1397336678.747 [science_to:I.SetSpeed] Running Loop=1
2014-04-12T21:04:38.747Z,1397336678.747 [science_to:I.SetSpeed](DEBUG): Initialize.
2014-04-12T21:04:38.747Z,1397336678.747 [science_to:J.DepthEnvelope] Running Loop=1
2014-04-12T21:04:38.747Z,1397336678.747 [science_to:J.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2014-04-12T21:04:38.748Z,1397336678.748 [science_to:K.YoYo] Running Loop=1
2014-04-12T21:04:38.748Z,1397336678.748 [science_to:K.YoYo](DEBUG): Initialize YoYoComponent.
2014-04-12T21:04:38.755Z,1397336678.755 [science_to:SURFACECOMMS] Running Loop=1
2014-04-12T21:04:38.755Z,1397336678.755 [science_to:SURFACECOMMS](INFO): Aggregate::initialize science_to:SURFACECOMMS
2014-04-12T21:04:38.757Z,1397336678.757 [science_to:SURFACECOMMS:A.GoToSurface] Running Loop=1
2014-04-12T21:04:38.757Z,1397336678.757 [science_to:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-04-12T21:04:38.763Z,1397336678.763 [science_to:K.YoYo] Running Loop=1
2014-04-12T21:04:38.777Z,1397336678.777 [science_to:J.DepthEnvelope] Running Loop=1
2014-04-12T21:04:38.784Z,1397336678.784 [science_to:I.SetSpeed] Running Loop=1
2014-04-12T21:04:38.788Z,1397336678.788 [science_to:H.Buoyancy] Running Loop=1
2014-04-12T21:04:38.793Z,1397336678.793 [science_to:Science] Running Loop=1
2014-04-12T21:04:38.796Z,1397336678.796 [science_to:Science:PeakDetectNO3] Stopped
2014-04-12T21:04:38.797Z,1397336678.797 [science_to:Science:PeakDetectNO3](INFO): Aggregate::uninitialize science_to:Science:PeakDetectNO3
2014-04-12T21:04:38.797Z,1397336678.797 [science_to:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2014-04-12T21:04:38.797Z,1397336678.797 [science_to:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2014-04-12T21:04:38.797Z,1397336678.797 [science_to:Science:PeakDetectChl] Running Loop=1
2014-04-12T21:04:38.804Z,1397336678.804 [science_to:Science:G] Running Loop=1
2014-04-12T21:04:38.805Z,1397336678.805 [science_to:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2014-04-12T21:04:38.806Z,1397336678.806 [science_to:Science:F] Stopped
2014-04-12T21:04:38.806Z,1397336678.806 [science_to:Science:E] Stopped
2014-04-12T21:04:38.806Z,1397336678.806 [science_to:Science:D] Running Loop=1
2014-04-12T21:04:38.807Z,1397336678.807 [science_to:Science:D](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2014-04-12T21:04:38.808Z,1397336678.808 [science_to:Science:D](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2014-04-12T21:04:38.809Z,1397336678.809 [science_to:Science:C] Stopped
2014-04-12T21:04:38.809Z,1397336678.809 [science_to:Science:B] Stopped
2014-04-12T21:04:38.809Z,1397336678.809 [science_to:Science:A] Stopped
2014-04-12T21:04:38.831Z,1397336678.831 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-04-12T21:04:38.849Z,1397336678.849 [science_to:SURFACECOMMS:B] Running Loop=1
2014-04-12T21:04:38.849Z,1397336678.849 [science_to:SURFACECOMMS:B](INFO): Aggregate::initialize science_to:SURFACECOMMS:B
2014-04-12T21:04:38.849Z,1397336678.849 [science_to:SURFACECOMMS:B:A] Running Loop=1
2014-04-12T21:04:38.849Z,1397336678.849 [science_to:D.Pitch] Running Loop=1
2014-04-12T21:04:38.870Z,1397336678.870 [science_to:OffshoreWrapper] Running Loop=1
2014-04-12T21:04:38.870Z,1397336678.870 [science_to:OffshoreWrapper](INFO): Aggregate::initialize science_to:OffshoreWrapper
2014-04-12T21:04:38.870Z,1397336678.870 [science_to:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1
2014-04-12T21:04:38.871Z,1397336678.871 [science_to:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2014-04-12T21:04:38.871Z,1397336678.871 [science_to:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1
2014-04-12T21:04:38.876Z,1397336678.876 [science_to:B.DepthEnvelope] Running Loop=1
2014-04-12T21:04:38.881Z,1397336678.881 [science_to:AltEnvWrapper] Running Loop=1
2014-04-12T21:04:38.881Z,1397336678.881 [science_to:AltEnvWrapper](INFO): Aggregate::initialize science_to:AltEnvWrapper
2014-04-12T21:04:38.881Z,1397336678.881 [science_to:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1
2014-04-12T21:04:38.881Z,1397336678.881 [science_to:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2014-04-12T21:04:38.882Z,1397336678.882 [science_to:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1
2014-04-12T21:04:39.184Z,1397336679.184 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4
2014-04-12T21:04:39.184Z,1397336679.184 [WetLabsBB2FL](INFO): Powering up
2014-04-12T21:04:39.354Z,1397336679.354 [science_to:SURFACECOMMS:B] Stopped
2014-04-12T21:04:39.355Z,1397336679.355 [science_to:SURFACECOMMS:B](INFO): Aggregate::uninitialize science_to:SURFACECOMMS:B
2014-04-12T21:04:39.355Z,1397336679.355 [science_to:SURFACECOMMS:B:A] Stopped
2014-04-12T21:04:39.355Z,1397336679.355 [science_to:SURFACECOMMS:C] Running Loop=1
2014-04-12T21:04:39.355Z,1397336679.355 [science_to:SURFACECOMMS:C](INFO): Aggregate::initialize science_to:SURFACECOMMS:C
2014-04-12T21:04:39.355Z,1397336679.355 [science_to:SURFACECOMMS:C:A] Running Loop=1
2014-04-12T21:04:39.766Z,1397336679.766 [science_to:K.YoYo] Preempted
2014-04-12T21:04:39.766Z,1397336679.766 [science_to:J.DepthEnvelope] Preempted
2014-04-12T21:04:39.766Z,1397336679.766 [science_to:I.SetSpeed] Preempted
2014-04-12T21:04:39.766Z,1397336679.766 [science_to:H.Buoyancy] Preempted
2014-04-12T21:04:39.766Z,1397336679.766 [science_to:Science] Preempted
2014-04-12T21:04:39.766Z,1397336679.766 [science_to:Science:D] Preempted
2014-04-12T21:04:39.766Z,1397336679.766 [science_to:Science:G] Preempted
2014-04-12T21:04:39.766Z,1397336679.766 [science_to:Science:PeakDetectChl] Preempted
2014-04-12T21:04:39.767Z,1397336679.767 [science_to:SURFACECOMMS:C:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2014-04-12T21:04:40.432Z,1397336680.432 [WetLabsBB2FL](INFO): Powering down
2014-04-12T21:04:43.227Z,1397336683.227 [NAL9602](INFO): SBD MO Status=0, MOMSN=24974, MT Status=0, MTMSN=0
2014-04-12T21:04:45.449Z,1397336685.449 [NAL9602](IMPORTANT): GPS fix at: 1397336653.00
2014-04-12T21:04:45.465Z,1397336685.465 [DeadReckonUsingMultipleVelocitySources](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07
2014-04-12T21:04:45.471Z,1397336685.471 [science_to:SURFACECOMMS:C:A] Stopped
2014-04-12T21:04:45.471Z,1397336685.471 [science_to:SURFACECOMMS:C:B] Running Loop=1
2014-04-12T21:04:46.012Z,1397336686.012 [science_to:SURFACECOMMS:C:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2014-04-12T21:04:58.031Z,1397336698.031 [NAL9602](INFO): SBD MO Status=1, MOMSN=24975, MT Status=0, MTMSN=0
2014-04-12T21:04:58.080Z,1397336698.080 [NAL9602](INFO): Sent 44 bytes from file Logs/20140412T205448/Courier0012.lzma
2014-04-12T21:04:58.080Z,1397336698.080 [NAL9602](INFO): Packets left to send: 0
2014-04-12T21:04:58.081Z,1397336698.081 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T205448/Courier0012.lzma.parts/0000.sbd
2014-04-12T21:04:58.082Z,1397336698.082 [NAL9602](INFO): Completed sending Logs/20140412T205448/Courier0012.lzma
2014-04-12T21:05:09.785Z,1397336709.785 [NAL9602](INFO): SBD MO Status=1, MOMSN=24976, MT Status=0, MTMSN=0
2014-04-12T21:05:09.838Z,1397336709.838 [NAL9602](INFO): Sent 25 bytes from file Logs/20140412T205448/Courier0016.lzma
2014-04-12T21:05:09.838Z,1397336709.838 [NAL9602](INFO): Packets left to send: 0
2014-04-12T21:05:09.840Z,1397336709.840 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T205448/Courier0016.lzma.parts/0000.sbd
2014-04-12T21:05:09.840Z,1397336709.840 [NAL9602](INFO): Completed sending Logs/20140412T205448/Courier0016.lzma
2014-04-12T21:05:18.693Z,1397336718.693 [NAL9602](INFO): SBD MO Status=1, MOMSN=24977, MT Status=0, MTMSN=0
2014-04-12T21:05:18.776Z,1397336718.776 [NAL9602](INFO): Sent 332 bytes from file Logs/20140412T205448/Express0013.lzma
2014-04-12T21:05:18.776Z,1397336718.776 [NAL9602](INFO): Packets left to send: 1
2014-04-12T21:05:18.796Z,1397336718.796 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T205448/Express0013.lzma.parts/0001.sbd
2014-04-12T21:05:28.053Z,1397336728.053 [NAL9602](INFO): SBD MO Status=1, MOMSN=24978, MT Status=0, MTMSN=0
2014-04-12T21:05:28.102Z,1397336728.102 [NAL9602](INFO): Sent 327 bytes from file Logs/20140412T205448/Express0013.lzma
2014-04-12T21:05:28.102Z,1397336728.102 [NAL9602](INFO): Packets left to send: 0
2014-04-12T21:05:28.103Z,1397336728.103 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T205448/Express0013.lzma.parts/0000.sbd
2014-04-12T21:05:28.104Z,1397336728.104 [NAL9602](INFO): Completed sending Logs/20140412T205448/Express0013.lzma
2014-04-12T21:05:47.931Z,1397336747.931 [NAL9602](INFO): SBD MO Status=1, MOMSN=24979, MT Status=0, MTMSN=0
2014-04-12T21:05:47.989Z,1397336747.989 [NAL9602](INFO): Sent 214 bytes from file Logs/20140412T205448/Express0017.lzma
2014-04-12T21:05:47.989Z,1397336747.989 [NAL9602](INFO): Packets left to send: 0
2014-04-12T21:05:47.991Z,1397336747.991 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T205448/Express0017.lzma.parts/0000.sbd
2014-04-12T21:05:47.991Z,1397336747.991 [NAL9602](INFO): Completed sending Logs/20140412T205448/Express0017.lzma
2014-04-12T21:05:58.399Z,1397336758.399 [NAL9602](INFO): SBD MO Status=0, MOMSN=24980, MT Status=0, MTMSN=0
2014-04-12T21:05:58.527Z,1397336758.527 [science_to:SURFACECOMMS:C:B] Stopped
2014-04-12T21:05:58.527Z,1397336758.527 [science_to:SURFACECOMMS:C:C] Running Loop=1
2014-04-12T21:05:58.917Z,1397336758.917 [science_to:SURFACECOMMS:C:C](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2014-04-12T21:06:01.117Z,1397336761.117 [NAL9602](IMPORTANT): GPS fix at: 1397336728.00
2014-04-12T21:06:01.128Z,1397336761.128 [DeadReckonUsingMultipleVelocitySources](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07
2014-04-12T21:06:01.132Z,1397336761.132 [science_to:SURFACECOMMS:C:C] Stopped
2014-04-12T21:06:01.132Z,1397336761.132 [science_to:SURFACECOMMS:C](INFO): Completed science_to:SURFACECOMMS:C
2014-04-12T21:06:01.132Z,1397336761.132 [science_to:SURFACECOMMS:C] Stopped
2014-04-12T21:06:01.132Z,1397336761.132 [science_to:SURFACECOMMS:C](INFO): Aggregate::uninitialize science_to:SURFACECOMMS:C
2014-04-12T21:06:01.136Z,1397336761.136 [science_to:SURFACECOMMS](INFO): Completed science_to:SURFACECOMMS
2014-04-12T21:06:01.136Z,1397336761.136 [science_to:SURFACECOMMS] Stopped
2014-04-12T21:06:01.136Z,1397336761.136 [science_to:SURFACECOMMS](INFO): Aggregate::uninitialize science_to:SURFACECOMMS
2014-04-12T21:06:01.136Z,1397336761.136 [science_to:SURFACECOMMS:A.GoToSurface] Stopped
2014-04-12T21:06:01.136Z,1397336761.136 [science_to:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-04-12T21:06:01.137Z,1397336761.137 [science_to:Wpt1] Running Loop=1
2014-04-12T21:06:01.137Z,1397336761.137 [science_to:Wpt1](INFO): Aggregate::initialize science_to:Wpt1
2014-04-12T21:06:01.137Z,1397336761.137 [science_to:Wpt1:A.Waypoint] Running Loop=1
2014-04-12T21:06:01.137Z,1397336761.137 [science_to:Wpt1:A.Waypoint](DEBUG): Initialize WaypointComponent.
2014-04-12T21:06:01.610Z,1397336761.610 [science_to:K.YoYo] Running Loop=1
2014-04-12T21:06:01.611Z,1397336761.611 [science_to:J.DepthEnvelope] Running Loop=1
2014-04-12T21:06:01.611Z,1397336761.611 [science_to:I.SetSpeed] Running Loop=1
2014-04-12T21:06:01.612Z,1397336761.612 [science_to:H.Buoyancy] Running Loop=1
2014-04-12T21:06:01.612Z,1397336761.612 [science_to:Science] Running Loop=1
2014-04-12T21:06:01.613Z,1397336761.613 [science_to:Science:PeakDetectChl] Running Loop=1
2014-04-12T21:06:01.613Z,1397336761.613 [science_to:Science:G] Running Loop=1
2014-04-12T21:06:01.613Z,1397336761.613 [science_to:Science:D] Running Loop=1
2014-04-12T21:06:01.744Z,1397336761.744 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-04-12T21:06:01.824Z,1397336761.824 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4
2014-04-12T21:06:01.824Z,1397336761.824 [WetLabsBB2FL](INFO): Powering up
2014-04-12T21:06:04.952Z,1397336764.952 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-12T21:06:04.953Z,1397336764.953 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 0 1 1 58.9 0 60.4 59.4 269.0 0 191.2 210.6 364 296 141 213 16 19 17 17 103.8 52.1 248.2 1 -5.3 -111.0 297 110 273 2 -90 -304 273 2 -2.37 -2.74 233.1 58.9 14.7 0.005 35.0 1489 111
2014-04-12T21:06:07.464Z,1397336767.464 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-12T21:06:07.464Z,1397336767.464 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 57.6 59.8 62.1 58.5 -103.2 68.4 34.5 -140.4 -56 37 -30 -194 16 17 19 17 -183.8 278.7 -37.9 1 327.5 -40.8 -62.9 1 -35 2 258 -172 -65 2 -2.12 -4.63 229.9 57.6 14.7 0.005 35.0 1489 73
2014-04-12T21:06:08.232Z,1397336768.232 [NAL9602](INFO): Powering down
2014-04-12T21:06:21.063Z,1397336781.063 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-12T21:06:21.064Z,1397336781.064 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 54.6 66.2 82.5 63.6 151.8 263.3 307.1 193.1 82 147 190 101 15 21 30 19 -207.3 93.7 246.8 1 183.8 259.5 107.2 1 -144 61 140 2 99 120 140 2 -2.8 253.6 54.6 14.7 0.005 35.0 1489 105
2014-04-12T21:07:24.347Z,1397336844.347 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:70 Calculated:88
2014-04-12T21:07:24.347Z,1397336844.347 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 4 52.7 44.5 -316.8 43.6 383.6 23.5 -336 -6 280 -50 10 14 16 12 -934.8 26.8 36.1 1 -166.5 889.4 -239.0 1 -822 58 -30 2 -109 816 -30 2 -5.25 -16.85 281.7 41.1 14.5 0.005 35.0 1489 70
2014-04-12T21:07:25.520Z,1397336845.520 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46
2014-04-12T21:07:25.521Z,1397336845.521 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 40.9 47.9 52.5 43.7 -370.5 51.8 362.1 -40.8 -346 13 185 -50 10 14 16 12 -977.8 123.6 0.7 1 -101.7 937.2 -287.8 1 -708 84 -53 2 -76 709 -53 2 -5.31 -16.46 282.9 40.9 14.5 0.00589 75
2014-04-12T21:07:59.261Z,1397336879.261 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46
2014-04-12T21:07:59.261Z,1397336879.261 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 32.4 38.5 42.0 34.6 -319.2 65.6 441.1 -13.8 -344 19 372 59 7 10 12 8 -1014.7 106.0 46.8 1 -241.2 951.2 -282.7 1 -955 -53 28 2 -369 882.48 -17.97 289.5 32.4 14.1 0.005 35.0 1489 116
2014-04-12T21:08:02.409Z,1397336882.409 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-12T21:08:02.417Z,1397336882.417 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 31.7 38.1 41.3 33.9 -279.6 135.2 534.1 142.6 -298 -22 389 93 7 10 12 8 -1086.0 -9.9 143.5 1 -319.0 1029.8  -916 -153 43 2 -407 835 43 2 -7.76 -17.75 286.5 31.7 14.0 0.005 35.0 1489 104
2014-04-12T21:08:05.904Z,1397336885.904 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:101 Calculated:127
2014-04-12T21:08:05.904Z,1397336885.904 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 31.0 36.9 41.3 33.4 -359.7 6.0 465.9 100.6 -398 -83 362 66 7 10 12 9 -126.3 57.4 1 -384.1 1008.9 -260.9 1 -1014 -198 -14 2 -428 940 -14 2 -7.45 -17.51 283.4 31.0 13.9 0.005 35.0 1489 101
2014-04-12T21:08:08.287Z,1397336888.287 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:104 Calculated:117
2014-04-12T21:08:08.287Z,1397336888.287 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 3 38.9 32.8 -393.0 15.6 432.4 77.3 -371 -53 327 21 7 9 11 8 -1101.6 -82.3 35.6 1 -305.8 1024.5 -280.5 1 -931 -98 -20 2 -281 893 -20 2 -6.86 -17.11 281.4 30.3 13.8 0.005 35.0 1489 104
2014-04-12T21:08:49.644Z,1397336929.644 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-12T21:08:49.644Z,1397336929.644 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 19.6 23.3 26.3 20.9 -359.8 102.9 428.6 -18.7 -377 64 378 18 4 5 6 4 -1052.2 162.3 41.2 1 -232.9 992.0 -311.5 1 -12 2 -310 960 22 2 -7.12 -18.29 291.4 19.6 13.0 0.005 35.0 1489 94
2014-04-12T21:08:57.910Z,1397336937.910 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-12T21:08:57.910Z,1397336937.910 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 19.9 20.3 18.5 18.5 -406.2 29.6 317.7 -98.1 -416 -51 231 56 4 4 3 3 -966.1 170.4 -42.3 1 -18.6 981.6 19.3 1 -863 -142 -48 2 -308 2 -7.07 4.88 281.0 18.5 12.8 0.005 35.0 1489 69
2014-04-12T21:09:14.278Z,1397336954.278 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 43 of 46
2014-04-12T21:09:14.278Z,1397336954.278 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 29.2 26.6 21.7 23.8 -419.3 -1.0 358.5 -97.9 -384 -50 326 -15 -1038.1 129.3 -43.1 1 -99.8 1001.7 287.7 1 -947 -48 -32 2 -275 907 -32 2 -7.38 19.39 284.0 21.7 12.5 0.005 35.0 1489 75
2014-04-12T21:09:24.009Z,1397336964.009 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:113 Calculated:115
2014-04-12T21:09:24.010Z,1397336964.010 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 33.9 30.3 24.5 26.7 -467.8 -7.5 362.3 -70.4 -469 -45 286 -33 9 8 5 6 -1107.9 83.9 -49.5 1 -15.5 319.2 1 -1007 -16 -70 2 -247 977 -70 2 -7.39 19.85 283.3 24.5 12.3 0.005 35.0 1489 113
2014-04-12T21:10:19.415Z,1397337019.415 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-12T21:10:19.415Z,1397337019.415 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 57.8 48.6 40.0 44.8 -515.9 -42.2 348.7 -83.7 -355 -51 308 -2 14 14 10 12 -1153.9 55.4 -79.1 1 33.2 1104.5 346.2 1 -884 -65 -27 2 -96 882 -27 2 -6.71 2.0 40.0 11.8 0.005 35.0 1489 108
2014-04-12T21:10:47.194Z,1397337047.194 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 2 of 46
2014-04-12T21:10:47.195Z,1397337047.195 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0001 64.3 58.5 48.7 52.5 -351.8 -39.2 304.6 -13.9 -311 -46 273 33 17 18 13 15 -876.1 -33.8 -27.1 1 -198.2 813.2 262.3 1 -779 -105 -13 2 -271 738 -13 2 -7.71 18.86 282.5 48.7 11.9 0.005 35.0 1489 72
2014-04-12T21:14:28.196Z,1397337268.196 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-12T21:14:28.197Z,1397337268.197 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 50.6 45.9 37.0 40.7 -377.1 68.3 427.5 -32.5 -327 20 399 16 10 13 9 11 -1073.8 134.5 23.2 1 47.6 1009.0 389.0 1 -968 5 29 2 -107 962 29 2 -8.16 21.11 276.7 37.0 11.6 0.005 9 116
2014-04-12T21:15:36.298Z,1397337336.298 [CTD_NeilBrown](ERROR): Bad response:   4.61
2014-04-12T21:16:21.502Z,1397337381.502 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:117 Calculated:110
2014-04-12T21:16:21.502Z,1397337381.502 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 36.2 43.0 48.2 40.4 -391.8 76.9 393.8 5.3 -417 -39 255 -40 9 12 15 11 95.6 22.7 1 -242.1 975.6 -314.2 1 -896 1 -65 2 -287 849 -65 2 -3.99 -18.29 288.8 36.2 13.2 0.005 35.0 1489 117
2014-04-12T21:16:42.334Z,1397337402.334 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-12T21:16:42.335Z,1397337402.335 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 30.6 37.7 42.1 33.4 -328.4 108.6 473.6 44.8 -299 -33 360 134 7 10 12 8 -1070.4 85.1 80.5 1 -282.6 991.7 -310.1 1 -879 -222 43 2 -506 752 43 2 -8.17 -20.37 289.7 30.6 13.2 .0 1489 127
2014-04-12T21:17:26.686Z,1397337446.686 [CTD_NeilBrown](ERROR): Bad response:  38.1929
2014-04-12T21:18:55.053Z,1397337535.053 [CTD_NeilBrown](ERROR): Bad response:  15.45
2014-04-12T21:19:31.529Z,1397337571.529 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:103 Calculated:99
2014-04-12T21:19:31.529Z,1397337571.529 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 1 1 1 0 62.6 50.2 56.7 0 65.3 455.8 110.7 -198 37 449 140 19 16 14 17 -981.8 -60.6 94.0 871.9 456.9 1 -863 -137 115 2 -201 850 115 2 -7.83 21.60 274.3 50.2 11.9 0.005 35.0 1489 103
2014-04-12T21:19:39.114Z,1397337579.114 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:90 Calculated:84
2014-04-12T21:19:39.114Z,1397337579.114 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 0 1 51.9 599 -216.1 4.6 0 70.6 -242 -76 128 26 14 18 47 15 -677.2 -88.1 40.6 1 -228.9 638.7 -88.3 1 -493 -136 -44 2 -235 454 -44 2 -7.14 -11.82 282.0 51.9 12.0 0.005 35.0 1489 90
2014-04-12T21:20:06.865Z,1397337606.865 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:88 Calculated:85
2014-04-12T21:20:06.866Z,1397337606.866 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 43.9 53.0 61.7 47.8 -387.0 -6.6 307.2 -6.0 -384 -87 90 -51 11 16 20 13 -926.5 -0.8 -211.7 833.8 -345.1 1 -632 -48 -116 2 -184 606 -116 2 -7.17 -20.34 282.6 43.9 12.4 0.005 35.0 1489 88
2014-04-12T21:23:05.330Z,1397337785.330 [CTD_NeilBrown](ERROR): Bad response:  16.74
2014-04-12T21:24:01.227Z,1397337841.227 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-12T21:24:01.227Z,1397337841.227 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 47.2 54.8 61.7 51.1 -398.8 -59.9 231.5 -115.0 -361 -127 49 -116 11 16 20 14 -841.2 73.5 -92.3 1 -142.8 770.11 -547 -14 -149 2 -148 526 -149 2 -6.14 -16.07 284.2 47.2 12.1 0.005 35.0 1489 127
2014-04-12T21:24:56.003Z,1397337896.003 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-12T21:24:56.003Z,1397337896.003 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 32.6 40.2 45.8 36.0 -328.5 65.4 512.7 105.6 -325 -39 419 88 7 11 14 9 -1122.7 -53.7 95.7 1 -396.6 1007.3 -317.4 1 -993 -169 38 2 -38 2 -8.91 -21.61 287.7 32.6 12.5 0.005 35.0 1489 88
2014-04-12T21:25:26.159Z,1397337926.159 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:65 Calculated:68
2014-04-12T21:25:26.159Z,1397337926.159 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 24.1 28.9 31.469.9 86.4 469.1 37.9 -341 -9 355 124 4 7 8 5 -1119.8 64.7 60.2 1 -113.3 1080.6 -285.1 1 -928 -177 34 2 -315 891 34 2 -6.64 -17.39 278.7 24.1 12.4 0.005 35.0 1489 65
2014-04-12T21:25:46.907Z,1397337946.907 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-12T21:25:46.908Z,1397337946.908 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 18.7 22.3 25.9 20.4 -335.0 125.8 468.5 -17.4 -358 58 482 18 3 072.4 191.1 65.2 1 -199.1 1025.2 -316.4 1 -1121 53 53 2 -348 1067 53 2 -5.87 -19.50 290.8 18.7 12.2 0.005 35.0 1489 95
2014-04-12T21:25:56.174Z,1397337956.174 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:82 Calculated:87
2014-04-12T21:25:56.174Z,1397337956.174 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 15.8 19.3 22.6 17.3 -336.5 114.2 471.276 95 443 -3 2 4 5 3 -1077.9 136.8 70.2 1 -240.7 1006.0 -340.2 1 -959 130 69 2 -205 946 69 2 -7.74 -21.02 290.0 15.8 12.1 0.005 35.0 1489 82
2014-04-12T21:27:01.291Z,1397338021.291 [CTD_NeilBrown](ERROR): Bad response: 1.46442
2014-04-12T21:28:08.139Z,1397338088.139 [CTD_NeilBrown](ERROR): Bad response:   2.34
2014-04-12T21:30:11.319Z,1397338211.319 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 1 of 46
2014-04-12T21:30:11.319Z,1397338211.319 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES16.7 20.4 22.4 18.2 -315.0 113.2 462.2 35.6 -299 58 426 43 3 037.3 103.6 79.8 1 -264.6 963.7 -307.4 1 -967 20 61 2 -318 913 61 2 -7.63 -20.75 .7 12.3 0.005 35.0 1489 71
2014-04-12T21:30:59.477Z,1397338259.477 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-12T21:30:59.477Z,1397338259.477 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 31.7 28.4 23.8 26.0 -427.0 33.8 408.3 -64.0 -445 -13 394 27 8 7 4 6 -1114.8 130.5 -13.2 1 -144.1 1064.4 326.2 1 -1119 -53 -10 2 -356 1063 -10 2 -7.31 18.59 285.8.7 0.005 35.0 1489 68
2014-04-12T21:31:16.963Z,1397338276.963 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46
2014-04-12T21:31:16.967Z,1397338276.967 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 38.3 34.3 28.6 30.5 -557.3 -139.5 318.0 -91.0 -430 -66 308 -17 11 9 6 7 -1168.2 -64.7 -126.7 1 -189.7 1131.9 260.5 1 -985 -65181 970 -55 2 -8.61 18.45 276.8 28.6 11.6 0.005 35.0 1489 108
2014-04-12T21:32:33.762Z,1397338353.762 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:124 Calculated:101
2014-04-12T21:32:33.762Z,1397338353.762 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 48.5.6 55.2 -527.4 -162.2 124.8 -224.8 -473 -244 -124 -246 13 17 26 16 -870.5 83.5 -212.9 1 -123.0 691.4 -563.0 1 -465 2 -293 2 -102 454 -293 2 -6.57 -24.52 283.1 48.5 11.9 0.005 35.0 1489 124
2014-04-12T21:34:30.785Z,1397338470.785 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 30 of 46
2014-04-12T21:34:30.785Z,1397338470.785 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 0 1 13.8 17.1 0 15.3 -358.3 141.8 0 29.2 -413 36 465 129 1 3 9 2 -1184.6 150.3 92.2 1 -282.1 1109.9 -350.8 1 -1171 - -534 1050 58 2 -7.67 -20.57 290.9 13.8 12.1 0.005 35.0 1489 109
2014-04-12T21:34:47.721Z,1397338487.721 [DVL_micro](ERROR): No DVL communication! Re-initializing
2014-04-12T21:34:47.722Z,1397338487.722 [DVL_micro] Communications Fault, FailCount= 1
2014-04-12T21:34:47.722Z,1397338487.722 [DVL_micro](ERROR): Communications Fault
2014-04-12T21:34:47.837Z,1397338487.837 [CBIT](ERROR): Communications Fault in component: DVL_micro
2014-04-12T21:34:48.296Z,1397338488.296 [DVL_micro](INFO): uninitialize:Powering down
2014-04-12T21:34:48.819Z,1397338488.819 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-04-12T21:34:48.819Z,1397338488.819 [DVL_micro] Hardware Fault, FailCount= 1
2014-04-12T21:34:48.819Z,1397338488.819 [DVL_micro](ERROR): Hardware Fault
2014-04-12T21:34:49.362Z,1397338489.362 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-04-12T21:34:49.362Z,1397338489.362 [DVL_micro] No Fault, FailCount= 1
2014-04-12T21:34:49.740Z,1397338489.740 [DVL_micro](INFO): Initializing
2014-04-12T21:35:05.581Z,1397338505.581 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46
2014-04-12T21:35:05.587Z,1397338505.587 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 28.3 25.4 20.9 22.7 -473.2 -55.9 350.4 -68.7 -371 -35 324 -31 7 6 4 4 -1099.2 17.1 -66.7 1 -59.5 1059.2 296.2 1 -927 -5 -30 2 -86 923 -30 2 -7.68 19.17 275.0 20.9 11.6 0.005 35.4
2014-04-12T21:35:21.608Z,1397338521.608 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:86 Calculated:78
2014-04-12T21:35:21.609Z,1397338521.609 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 34.7 30.1 25.2 27.7 -503.0 -27.8 319.1 -62.5  320 -78 10 7 5 6 -1097.2 46.3 -74.0 1 -125.9 1050.8 302.4 1 -995 78 -54 2 -97 994 -54 2 -6.79 20.09 280.1 25.2 11.5 0.005 35.0 1489 86
2014-04-12T21:35:33.458Z,1397338533.458 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:105 Calculated:125
2014-04-12T21:35:33.459Z,1397338533.459 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 39.1 34.3 28.8 1.0 35.4 346.8 -64.1 -390 0 359 9 11 9 6 7 -1051.4 132.8 -33.2 1 -54.3 1015.9 298.8 1 -999 -12 -5 2 -212 976 -5 2 -7.63 19.30 281.6 28.8 11.4 0.005 35.0 1489 105
2014-04-12T21:35:47.653Z,1397338547.653 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-12T21:35:47.653Z,1397338547.653 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 43.5 39.4 32.6 36.0 -495.6 -45.5 410.6 -76.5 -458 0 345 -44 13 11 7 9 -1209.4 41.4 -55.8 1 -102.0 1159.9 334.2 1 -1071 58 -42 2 -92 1069 .39 18.94 278.1 32.6 11.3 0.005 35.0 1489 126
2014-04-12T21:36:40.173Z,1397338600.173 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:119 Calculated:125
2014-04-12T21:36:40.174Z,1397338600.174 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 0 1 48.7 54.4 0 49.9 -301.9 -26.1 0 -75.0 -349 -146 15 -82 13 16 43 13 -670.9 65 1 -161.2 633.3 -173.8 1 -485 -85 -151 2 -237 432 -151 2 -6.89 -9.67 288.8 48.7 11.6 0.005 35.0 1489 119
2014-04-12T21:36:42.677Z,1397338602.677 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:74 Calculated:82
2014-04-12T21:36:42.677Z,1397338602.677 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 0 1 47.3 59.9 0 54.7 -264.1 17.5 0 40.7 -329 -123 12 16 41 16 -782.6 -31.0 31.4 1 -309.5 655.3 -298.7 1 -605 -136 -93 2 -350 512 -93 2 -5.85 -24.95 291.7 47.3 11.7 0.005 35.0 1489 74
2014-04-12T21:36:45.130Z,1397338605.130 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:127 Calculated:110
2014-04-12T21:36:45.130Z,1397338605.130 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 46.9 59.7 72.6 53.7 -298.2 77.9 339.8 -3 -57 55 -28 12 17 26 16 -851.5 136.7 25.6 1 -128.5 755.2 -397.1 1 -504 -38 -95 2 -191 468 -95 2 -7.79 -28.09 287.9 46.9 11.7 0.005 35.0 1489 127
2014-04-12T21:38:36.034Z,1397338716.034 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:121 Calculated:99
2014-04-12T21:38:36.034Z,1397338716.034 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 15.8 19.0 21.3 17.5 -357.2 66.9 406.0 14.1 -418 59 383 44 2 4 4 3 -1018.6 70.5 35.0 1 -440 -304.0 1 -1069 20 18 2 -518 934 18 2 -6.12 -18.87 300.1 15.8 12.1 0.005 35.0 1489 121
2014-04-12T21:38:40.873Z,1397338720.873 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:102 Calculated:118
2014-04-12T21:38:40.873Z,1397338720.873 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 14.5 17.9 20.1 16.1 -330.7 -36 45.5 -280 64 341 121 2 3 4 3 -1040.1 -103.3 35.4 1 -595.4 791.7 -335.6 1 -828 -76 66 2 -477 681 66 2 -7.32 -21.51 299.8 14.5 12.0 0.005 35.0 1489 102
2014-04-12T21:38:48.488Z,1397338728.488 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:95 Calculated:80
2014-04-12T21:38:48.488Z,1397338728.488 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 14.7 14.7 13.4 13.3 -428.7 -62.8 299.6 -88.9 -462 -25 -45 -180 2 2 1 134.8 -75.7 1 -199.9 954.2 35.3 1 -556 206 -192 2 69 589 -192 2 -8.66 6.79 283.7 13.3 11.9 0.005 35.0 1489 95
2014-04-12T21:39:27.147Z,1397338767.147 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:65 Calculated:87
2014-04-12T21:39:27.163Z,1397338767.163 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 28.3 26.1 2 -378.8 65.1 396.6 -42.3 -370 26 277 -53 7 6 4 5 -1034.9 143.3 10.9 1 79.4 996.0 306.0 1 -863 105 -32 2 31 869 -32 2 -7.33 17.67 274.9 22.2 11.5 0.005 35.0 1489 65
2014-04-12T21:39:40.965Z,1397338780.965 [DVL_micro](ERROR): NQ1 string checksum mismatch. Rep