2014-04-12T15:24:10.375Z,1397316250.375 [Supervisor](DEBUG): Initializing supervisor.
2014-04-12T15:24:10.384Z,1397316250.384 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2014-04-12T15:24:10.385Z,1397316250.385 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2014-04-12T15:24:10.393Z,1397316250.393 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0
2014-04-12T15:24:10.401Z,1397316250.401 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2014-04-12T15:24:10.434Z,1397316250.434 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2014-04-12T15:24:10.437Z,1397316250.437 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0
2014-04-12T15:24:10.454Z,1397316250.454 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2014-04-12T15:24:10.455Z,1397316250.455 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403CB4E0
2014-04-12T15:24:10.469Z,1397316250.469 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2014-04-12T15:24:10.469Z,1397316250.469 [Supervisor](INFO): Looking for Config files in directory: Config/
2014-04-12T15:24:10.483Z,1397316250.483 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2014-04-12T15:24:11.419Z,1397316251.419 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2014-04-12T15:24:11.419Z,1397316251.419 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2014-04-12T15:24:12.103Z,1397316252.103 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2014-04-12T15:24:12.104Z,1397316252.104 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2014-04-12T15:24:12.286Z,1397316252.286 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2014-04-12T15:24:12.288Z,1397316252.288 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2014-04-12T15:24:12.499Z,1397316252.499 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2014-04-12T15:24:12.499Z,1397316252.499 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2014-04-12T15:24:14.905Z,1397316254.905 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2014-04-12T15:24:15.540Z,1397316255.540 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2014-04-12T15:24:15.865Z,1397316255.865 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2014-04-12T15:24:15.866Z,1397316255.866 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2014-04-12T15:24:16.056Z,1397316256.056 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2014-04-12T15:24:16.059Z,1397316256.059 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2014-04-12T15:24:16.418Z,1397316256.418 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2014-04-12T15:24:16.420Z,1397316256.420 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2014-04-12T15:24:16.537Z,1397316256.537 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2014-04-12T15:24:16.537Z,1397316256.537 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2014-04-12T15:24:17.057Z,1397316257.057 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2014-04-12T15:24:17.058Z,1397316257.058 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2014-04-12T15:24:17.171Z,1397316257.171 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2014-04-12T15:24:17.172Z,1397316257.172 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2014-04-12T15:24:17.260Z,1397316257.260 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2014-04-12T15:24:17.369Z,1397316257.369 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2014-04-12T15:24:17.371Z,1397316257.371 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2014-04-12T15:24:17.478Z,1397316257.478 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2014-04-12T15:24:17.479Z,1397316257.479 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2014-04-12T15:24:17.637Z,1397316257.637 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2014-04-12T15:24:17.639Z,1397316257.639 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2014-04-12T15:24:17.646Z,1397316257.646 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2014-04-12T15:24:17.773Z,1397316257.773 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2014-04-12T15:24:17.938Z,1397316257.938 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2014-04-12T15:24:18.038Z,1397316258.038 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2014-04-12T15:24:18.133Z,1397316258.133 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2014-04-12T15:24:18.293Z,1397316258.293 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2014-04-12T15:24:18.415Z,1397316258.415 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2014-04-12T15:24:18.504Z,1397316258.504 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2014-04-12T15:24:18.612Z,1397316258.612 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2014-04-12T15:24:18.716Z,1397316258.716 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2014-04-12T15:24:18.728Z,1397316258.728 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2014-04-12T15:24:19.419Z,1397316259.419 [AHRS_sp3003D] Loaded
2014-04-12T15:24:19.420Z,1397316259.420 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2014-04-12T15:24:19.709Z,1397316259.709 [Batt_Ocean_Server] Loaded
2014-04-12T15:24:19.710Z,1397316259.710 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread.
2014-04-12T15:24:19.723Z,1397316259.723 [Depth_Keller] Loaded
2014-04-12T15:24:19.724Z,1397316259.724 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2014-04-12T15:24:19.728Z,1397316259.728 [DropWeight] Loaded
2014-04-12T15:24:19.729Z,1397316259.729 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2014-04-12T15:24:19.867Z,1397316259.867 [DVL_micro] Loaded
2014-04-12T15:24:19.867Z,1397316259.867 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2014-04-12T15:24:19.965Z,1397316259.965 [NAL9602] Loaded
2014-04-12T15:24:19.965Z,1397316259.965 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2014-04-12T15:24:20.017Z,1397316260.017 [Onboard] Loaded
2014-04-12T15:24:20.017Z,1397316260.017 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2014-04-12T15:24:20.018Z,1397316260.018 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2014-04-12T15:24:20.019Z,1397316260.019 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2014-04-12T15:24:20.208Z,1397316260.208 [InternalSim] Loaded
2014-04-12T15:24:20.208Z,1397316260.208 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2014-04-12T15:24:20.209Z,1397316260.209 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2014-04-12T15:24:20.210Z,1397316260.210 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2014-04-12T15:24:20.483Z,1397316260.483 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2014-04-12T15:24:20.483Z,1397316260.483 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2014-04-12T15:24:20.492Z,1397316260.492 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2014-04-12T15:24:20.497Z,1397316260.497 [AsyncPiEstimator] Loaded
2014-04-12T15:24:20.497Z,1397316260.497 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2014-04-12T15:24:20.498Z,1397316260.498 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 4069E4E0
2014-04-12T15:24:20.499Z,1397316260.499 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2014-04-12T15:24:20.500Z,1397316260.500 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2014-04-12T15:24:20.592Z,1397316260.592 [DeadReckonUsingMultipleVelocitySources] Loaded
2014-04-12T15:24:20.592Z,1397316260.592 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2014-04-12T15:24:20.609Z,1397316260.609 [NavChart] Loaded
2014-04-12T15:24:20.609Z,1397316260.609 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2014-04-12T15:24:20.610Z,1397316260.610 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2014-04-12T15:24:20.610Z,1397316260.610 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2014-04-12T15:24:20.677Z,1397316260.677 [VerticalControl](DEBUG): Construct VerticalControl.
2014-04-12T15:24:20.787Z,1397316260.787 [VerticalControl] Loaded
2014-04-12T15:24:20.787Z,1397316260.787 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2014-04-12T15:24:20.788Z,1397316260.788 [HorizontalControl](DEBUG): Construct HorizontalControl.
2014-04-12T15:24:20.854Z,1397316260.854 [HorizontalControl] Loaded
2014-04-12T15:24:20.854Z,1397316260.854 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2014-04-12T15:24:20.855Z,1397316260.855 [SpeedControl](DEBUG): Construct SpeedControl.
2014-04-12T15:24:20.857Z,1397316260.857 [SpeedControl] Loaded
2014-04-12T15:24:20.857Z,1397316260.857 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2014-04-12T15:24:20.857Z,1397316260.857 [LoopControl](DEBUG): Construct LoopControl.
2014-04-12T15:24:20.858Z,1397316260.858 [LoopControl] Loaded
2014-04-12T15:24:20.858Z,1397316260.858 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2014-04-12T15:24:20.859Z,1397316260.859 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2014-04-12T15:24:20.859Z,1397316260.859 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2014-04-12T15:24:20.888Z,1397316260.888 [DepthRateCalculator] Loaded
2014-04-12T15:24:20.888Z,1397316260.888 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2014-04-12T15:24:20.894Z,1397316260.894 [PitchRateCalculator] Loaded
2014-04-12T15:24:20.894Z,1397316260.894 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2014-04-12T15:24:20.902Z,1397316260.902 [SpeedCalculator] Loaded
2014-04-12T15:24:20.902Z,1397316260.902 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2014-04-12T15:24:20.918Z,1397316260.918 [TempGradientCalculator] Loaded
2014-04-12T15:24:20.918Z,1397316260.918 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2014-04-12T15:24:20.924Z,1397316260.924 [YawRateCalculator] Loaded
2014-04-12T15:24:20.924Z,1397316260.924 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2014-04-12T15:24:20.924Z,1397316260.924 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2014-04-12T15:24:20.925Z,1397316260.925 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2014-04-12T15:24:21.050Z,1397316261.050 [CTD_NeilBrown] Loaded
2014-04-12T15:24:21.051Z,1397316261.051 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2014-04-12T15:24:21.052Z,1397316261.052 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 407404E0
2014-04-12T15:24:21.087Z,1397316261.087 [WetLabsBB2FL] Loaded
2014-04-12T15:24:21.087Z,1397316261.087 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2014-04-12T15:24:21.088Z,1397316261.088 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407704E0
2014-04-12T15:24:21.089Z,1397316261.089 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2014-04-12T15:24:21.091Z,1397316261.091 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2014-04-12T15:24:21.206Z,1397316261.206 [SBIT](DEBUG): Construct Startup Built In Test.
2014-04-12T15:24:21.217Z,1397316261.217 [SBIT] Loaded
2014-04-12T15:24:21.217Z,1397316261.217 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2014-04-12T15:24:21.218Z,1397316261.218 [IBIT](DEBUG): Construct Initiated Built In Test.
2014-04-12T15:24:21.231Z,1397316261.231 [IBIT] Loaded
2014-04-12T15:24:21.231Z,1397316261.231 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2014-04-12T15:24:21.234Z,1397316261.234 [CBIT](DEBUG): Construct CBIT Built In Test.
2014-04-12T15:24:21.302Z,1397316261.302 [CBIT] Loaded
2014-04-12T15:24:21.302Z,1397316261.302 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2014-04-12T15:24:21.303Z,1397316261.303 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2014-04-12T15:24:21.305Z,1397316261.305 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2014-04-12T15:24:21.374Z,1397316261.374 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2014-04-12T15:24:21.374Z,1397316261.374 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2014-04-12T15:24:21.492Z,1397316261.492 [BuoyancyServo] Loaded
2014-04-12T15:24:21.492Z,1397316261.492 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2014-04-12T15:24:21.504Z,1397316261.504 [ElevatorServo] Loaded
2014-04-12T15:24:21.505Z,1397316261.505 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2014-04-12T15:24:21.516Z,1397316261.516 [MassServo] Loaded
2014-04-12T15:24:21.517Z,1397316261.517 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2014-04-12T15:24:21.528Z,1397316261.528 [RudderServo] Loaded
2014-04-12T15:24:21.529Z,1397316261.529 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2014-04-12T15:24:21.540Z,1397316261.540 [ThrusterServo] Loaded
2014-04-12T15:24:21.540Z,1397316261.540 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2014-04-12T15:24:21.541Z,1397316261.541 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2014-04-12T15:24:21.541Z,1397316261.541 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2014-04-12T15:24:21.579Z,1397316261.579 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2014-04-12T15:24:21.581Z,1397316261.581 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2014-04-12T15:24:21.582Z,1397316261.582 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2014-04-12T15:24:21.589Z,1397316261.589 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2014-04-12T15:24:21.590Z,1397316261.590 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 4083E4E0
2014-04-12T15:24:21.595Z,1397316261.595 [Supervisor](DEBUG): Running supervisor.
2014-04-12T15:24:21.596Z,1397316261.596 [CommandLine](INFO): Thread ID is 771
2014-04-12T15:24:21.599Z,1397316261.599 [controlThread](INFO): Thread ID is 770
2014-04-12T15:24:21.599Z,1397316261.599 [controlThread](DEBUG): Initializing ControlThread
2014-04-12T15:24:21.600Z,1397316261.600 [CycleStarter](INFO): Thread ID is 769
2014-04-12T15:24:21.603Z,1397316261.603 [logger](INFO): Thread ID is 772
2014-04-12T15:24:21.629Z,1397316261.629 [AsyncPiEstimator](INFO): Thread ID is 848
2014-04-12T15:24:21.629Z,1397316261.629 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2014-04-12T15:24:21.653Z,1397316261.653 [CTD_NeilBrown](INFO): Thread ID is 849
2014-04-12T15:24:21.653Z,1397316261.653 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2014-04-12T15:24:21.656Z,1397316261.656 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-04-12T15:24:21.658Z,1397316261.658 [WetLabsBB2FL](INFO): Thread ID is 850
2014-04-12T15:24:21.659Z,1397316261.659 [WetLabsBB2FL](INFO): Powering down
2014-04-12T15:24:21.686Z,1397316261.686 [NavChartDb](INFO): Thread ID is 851
2014-04-12T15:24:21.695Z,1397316261.695 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2014-04-12T15:24:21.698Z,1397316261.698 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2014-04-12T15:24:21.698Z,1397316261.698 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2014-04-12T15:24:21.698Z,1397316261.698 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2014-04-12T15:24:21.699Z,1397316261.699 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2014-04-12T15:24:21.699Z,1397316261.699 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2014-04-12T15:24:21.699Z,1397316261.699 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2014-04-12T15:24:21.699Z,1397316261.699 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2014-04-12T15:24:21.700Z,1397316261.700 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2014-04-12T15:24:23.377Z,1397316263.377 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2014-04-12T15:24:23.413Z,1397316263.413 [InternalSim](DEBUG):  InternalSim initializing...
2014-04-12T15:24:23.693Z,1397316263.693 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-04-12T15:24:23.693Z,1397316263.693 [NavChart](DEBUG): Initialize NavChart Navigation.
2014-04-12T15:24:23.694Z,1397316263.694 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2014-04-12T15:24:23.699Z,1397316263.699 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2014-04-12T15:24:23.700Z,1397316263.700 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2014-04-12T15:24:23.700Z,1397316263.700 [LoopControl](DEBUG): Initialize LoopControlComponent.
2014-04-12T15:24:23.701Z,1397316263.701 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-04-12T15:24:23.701Z,1397316263.701 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2014-04-12T15:24:23.702Z,1397316263.702 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2014-04-12T15:24:23.702Z,1397316263.702 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2014-04-12T15:24:23.703Z,1397316263.703 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2014-04-12T15:24:23.732Z,1397316263.732 [SBIT](INFO): Initialize SBIT Component.
2014-04-12T15:24:23.732Z,1397316263.732 [SBIT](IMPORTANT): Tethys CM Info: 
 $Rev:11196
2014-04-12T15:24:23.733Z,1397316263.733 [SBIT](IMPORTANT): Kernel Release:2.6.27.8
2014-04-12T15:24:23.733Z,1397316263.733 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2014-04-12T15:24:23.734Z,1397316263.734 [IBIT](INFO): Initialize IBIT Component.
2014-04-12T15:24:23.735Z,1397316263.735 [CBIT](DEBUG): Initialize CBIT Component.
2014-04-12T15:24:23.735Z,1397316263.735 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2014-04-12T15:24:23.761Z,1397316263.761 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2014-04-12T15:24:23.790Z,1397316263.790 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-04-12T15:24:23.831Z,1397316263.831 [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-12T15:24:23.851Z,1397316263.851 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2014-04-12T15:24:23.898Z,1397316263.898 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2014-04-12T15:24:23.925Z,1397316263.925 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2014-04-12T15:24:23.946Z,1397316263.946 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-04-12T15:24:23.954Z,1397316263.954 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2014-04-12T15:24:23.957Z,1397316263.957 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-04-12T15:24:23.981Z,1397316263.981 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2014-04-12T15:24:24.003Z,1397316264.003 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed](DEBUG): Construct.
2014-04-12T15:24:24.006Z,1397316264.006 [Default:WaitAtTheSurface:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-04-12T15:24:24.042Z,1397316264.042 [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-12T15:24:24.051Z,1397316264.051 [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-12T15:24:24.132Z,1397316264.132 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-04-12T15:24:24.295Z,1397316264.295 [DVL_micro](INFO): Initializing
2014-04-12T15:24:24.435Z,1397316264.435 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:24.530Z,1397316264.530 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-04-12T15:24:24.537Z,1397316264.537 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-04-12T15:24:24.557Z,1397316264.557 [ElevatorServo](DEBUG): Initializing EZServoServo.
2014-04-12T15:24:24.561Z,1397316264.561 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2014-04-12T15:24:24.567Z,1397316264.567 [MassServo](DEBUG): Initializing EZServoServo.
2014-04-12T15:24:24.573Z,1397316264.573 [MassServo](DEBUG): Initializing MassServo.
2014-04-12T15:24:24.578Z,1397316264.578 [RudderServo](DEBUG): Initializing EZServoServo.
2014-04-12T15:24:24.585Z,1397316264.585 [RudderServo](DEBUG): Initializing RudderServo.
2014-04-12T15:24:24.590Z,1397316264.590 [ThrusterServo](DEBUG): Initializing EZServoServo.
2014-04-12T15:24:24.597Z,1397316264.597 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2014-04-12T15:24:24.849Z,1397316264.849 [AHRS_sp3003D](ERROR): readHeadingMagBin got 0x4236A40900
2014-04-12T15:24:24.849Z,1397316264.849 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2014-04-12T15:24:24.849Z,1397316264.849 [AHRS_sp3003D] Hardware Fault, FailCount= 1
2014-04-12T15:24:24.849Z,1397316264.849 [AHRS_sp3003D](ERROR): Hardware Fault
2014-04-12T15:24:24.929Z,1397316264.929 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:24.948Z,1397316264.948 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2014-04-12T15:24:25.085Z,1397316265.085 [AHRS_sp3003D](INFO): Powering down
2014-04-12T15:24:25.181Z,1397316265.181 [NAL9602](INFO): Powering up NAL9602
2014-04-12T15:24:25.240Z,1397316265.240 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:25.683Z,1397316265.683 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:26.084Z,1397316266.084 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:26.471Z,1397316266.471 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:26.496Z,1397316266.496 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2014-04-12T15:24:26.496Z,1397316266.496 [AHRS_sp3003D] No Fault, FailCount= 1
2014-04-12T15:24:26.832Z,1397316266.832 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-04-12T15:24:26.929Z,1397316266.929 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:27.260Z,1397316267.260 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:27.825Z,1397316267.825 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:28.403Z,1397316268.403 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:29.453Z,1397316269.453 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:30.147Z,1397316270.147 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:30.641Z,1397316270.641 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:31.098Z,1397316271.098 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:31.576Z,1397316271.576 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:32.036Z,1397316272.036 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:32.548Z,1397316272.548 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:33.067Z,1397316273.067 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:33.541Z,1397316273.541 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:34.163Z,1397316274.163 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:34.659Z,1397316274.659 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:35.137Z,1397316275.137 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:35.654Z,1397316275.654 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:36.107Z,1397316276.107 [NAL9602](INFO): NAL9602 initialized
2014-04-12T15:24:36.162Z,1397316276.162 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:36.739Z,1397316276.739 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:37.196Z,1397316277.196 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:37.681Z,1397316277.681 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:38.164Z,1397316278.164 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:38.636Z,1397316278.636 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:39.183Z,1397316279.183 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:39.748Z,1397316279.748 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:39.922Z,1397316279.922 [SBIT](IMPORTANT): Beginning Startup BIT
2014-04-12T15:24:39.925Z,1397316279.925 [CBIT](IMPORTANT): Beginning GF scan
2014-04-12T15:24:40.432Z,1397316280.432 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:40.935Z,1397316280.935 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:41.417Z,1397316281.417 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:41.881Z,1397316281.881 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:42.404Z,1397316282.404 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:43.434Z,1397316283.434 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:44.098Z,1397316284.098 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:44.773Z,1397316284.773 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:45.250Z,1397316285.250 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:45.879Z,1397316285.879 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:46.446Z,1397316286.446 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:46.940Z,1397316286.940 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:47.404Z,1397316287.404 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:47.889Z,1397316287.889 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:48.368Z,1397316288.368 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:48.862Z,1397316288.862 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:49.529Z,1397316289.529 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:49.983Z,1397316289.983 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:50.424Z,1397316290.424 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:50.845Z,1397316290.845 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:51.271Z,1397316291.271 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:51.746Z,1397316291.746 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:52.171Z,1397316292.171 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:52.642Z,1397316292.642 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=24814, MT Status=1, MTMSN=1689
2014-04-12T15:24:52.643Z,1397316292.643 [NAL9602](INFO): Data available in MT queue
2014-04-12T15:24:52.669Z,1397316292.669 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:53.262Z,1397316293.262 [NAL9602](INFO): Received command:! killall fake_cron
2014-04-12T15:24:53.344Z,1397316293.344 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:53.361Z,1397316293.361 [CommandLine](IMPORTANT): got command ! killall fake_cron
2014-04-12T15:24:53.873Z,1397316293.873 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:54.475Z,1397316294.475 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:54.975Z,1397316294.975 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:55.472Z,1397316295.472 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:56.367Z,1397316296.367 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:56.959Z,1397316296.959 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:57.495Z,1397316297.495 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:58.024Z,1397316298.024 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:58.537Z,1397316298.537 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:59.103Z,1397316299.103 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:24:59.772Z,1397316299.772 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:25:00.272Z,1397316300.272 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:25:00.777Z,1397316300.777 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:25:01.285Z,1397316301.285 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:25:01.794Z,1397316301.794 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:25:02.443Z,1397316302.443 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:25:03.173Z,1397316303.173 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:25:03.629Z,1397316303.629 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:25:04.097Z,1397316304.097 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:25:04.686Z,1397316304.686 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:25:05.183Z,1397316305.183 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-12T15:25:05.668Z,1397316305.668 [NAL9602](IMPORTANT): GPS fix at: 1397316274.00
2014-04-12T15:25:05.785Z,1397316305.785 [DeadReckonUsingMultipleVelocitySources](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07
2014-04-12T15:25:07.907Z,1397316307.907 [CBIT](IMPORTANT): No ground fault detected
2014-04-12T15:25:12.962Z,1397316312.962 [NAL9602](INFO): Powering down
2014-04-12T15:25:34.383Z,1397316334.383 [SBIT](IMPORTANT): SBIT PASSED
2014-04-12T15:25:34.780Z,1397316334.780 [MissionManager](IMPORTANT): Started mission Startup
2014-04-12T15:25:34.780Z,1397316334.780 [Startup] Running Loop=1
2014-04-12T15:25:34.780Z,1397316334.780 [Startup](INFO): Aggregate::initialize Startup
2014-04-12T15:25:34.780Z,1397316334.780 [Startup:A.GoToSurface] Running Loop=1
2014-04-12T15:25:34.780Z,1397316334.780 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-04-12T15:25:34.789Z,1397316334.789 [Startup:StartupSatComms] Running Loop=1
2014-04-12T15:25:34.789Z,1397316334.789 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2014-04-12T15:25:34.789Z,1397316334.789 [Startup:StartupSatComms:A] Running Loop=1
2014-04-12T15:25:35.281Z,1397316335.281 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2014-04-12T15:25:35.766Z,1397316335.766 [NAL9602](INFO): Powering up
2014-04-12T15:25:46.604Z,1397316346.604 [NAL9602](INFO): NAL9602 initialized
2014-04-12T15:26:01.476Z,1397316361.476 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=24815, MT Status=1, MTMSN=1690
2014-04-12T15:26:01.476Z,1397316361.476 [NAL9602](INFO): Data available in MT queue
2014-04-12T15:26:02.116Z,1397316362.116 [NAL9602](INFO): Received command:configset list
2014-04-12T15:26:02.216Z,1397316362.216 [CommandLine](IMPORTANT): got command configSet list
2014-04-12T15:26:02.216Z,1397316362.216 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2014-04-12T15:26:02.217Z,1397316362.217 [CommandLine](IMPORTANT): DeadReckonUsingCompactModelForecast.loadAtStartup=0 bool;

2014-04-12T15:26:02.217Z,1397316362.217 [CommandLine](IMPORTANT): DeadReckonUsingDVLWaterTrack.loadAtStartup=0 bool;

2014-04-12T15:26:02.217Z,1397316362.217 [CommandLine](IMPORTANT): DeadReckonWithRespectToSeafloor.loadAtStartup=0 bool;

2014-04-12T15:26:02.218Z,1397316362.218 [CommandLine](IMPORTANT): DeadReckonWithRespectToWater.loadAtStartup=0 bool;

2014-04-12T15:26:02.218Z,1397316362.218 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup=0 bool;

2014-04-12T15:26:02.218Z,1397316362.218 [CommandLine](IMPORTANT): Radio_Freewave.loadAtStartup=0 bool;

2014-04-12T15:26:02.218Z,1397316362.218 [CommandLine](IMPORTANT): SCPI.loadAtStartup=0 bool;

2014-04-12T15:26:02.218Z,1397316362.218 [CommandLine](IMPORTANT): TempGradientCalculator.loadAtStartup=1 bool;

2014-04-12T15:26:02.218Z,1397316362.218 [CommandLine](IMPORTANT): UniversalFixResidualReporter.loadAtStartup=0 bool;

2014-04-12T15:26:03.386Z,1397316363.386 [NAL9602](IMPORTANT): GPS fix at: 1397316332.00
2014-04-12T15:26:03.398Z,1397316363.398 [DeadReckonUsingMultipleVelocitySources](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07
2014-04-12T15:26:03.400Z,1397316363.400 [Startup:StartupSatComms:A] Stopped
2014-04-12T15:26:03.400Z,1397316363.400 [Startup:StartupSatComms:B] Running Loop=1
2014-04-12T15:26:03.867Z,1397316363.867 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2014-04-12T15:26:28.212Z,1397316388.212 [NAL9602](INFO): SBD MO Status=1, MOMSN=24816, MT Status=0, MTMSN=0
2014-04-12T15:26:28.265Z,1397316388.265 [NAL9602](INFO): Sent 73 bytes from file Logs/20140412T073136/Courier0180.lzma
2014-04-12T15:26:28.265Z,1397316388.265 [NAL9602](INFO): Packets left to send: 0
2014-04-12T15:26:28.267Z,1397316388.267 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T073136/Courier0180.lzma.parts/0000.sbd
2014-04-12T15:26:28.267Z,1397316388.267 [NAL9602](INFO): Completed sending Logs/20140412T073136/Courier0180.lzma
2014-04-12T15:26:38.248Z,1397316398.248 [NAL9602](INFO): SBD MO Status=1, MOMSN=24817, MT Status=0, MTMSN=0
2014-04-12T15:26:38.296Z,1397316398.296 [NAL9602](INFO): Sent 296 bytes from file Logs/20140412T152410/Courier0000.lzma
2014-04-12T15:26:38.296Z,1397316398.296 [NAL9602](INFO): Packets left to send: 0
2014-04-12T15:26:38.299Z,1397316398.299 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T152410/Courier0000.lzma.parts/0000.sbd
2014-04-12T15:26:38.299Z,1397316398.299 [NAL9602](INFO): Completed sending Logs/20140412T152410/Courier0000.lzma
2014-04-12T15:26:50.469Z,1397316410.469 [NAL9602](INFO): SBD MO Status=1, MOMSN=24818, MT Status=0, MTMSN=0
2014-04-12T15:26:50.523Z,1397316410.523 [NAL9602](INFO): Sent 238 bytes from file Logs/20140412T073136/Express0177.lzma
2014-04-12T15:26:50.523Z,1397316410.523 [NAL9602](INFO): Packets left to send: 0
2014-04-12T15:26:50.525Z,1397316410.525 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T073136/Express0177.lzma.parts/0000.sbd
2014-04-12T15:26:50.525Z,1397316410.525 [NAL9602](INFO): Completed sending Logs/20140412T073136/Express0177.lzma
2014-04-12T15:27:03.587Z,1397316423.587 [Startup:StartupSatComms:B](INFO): Timed out from 2014-04-12T15:26:03.4Z
2014-04-12T15:27:03.588Z,1397316423.588 [Startup:StartupSatComms:B] Stopped
2014-04-12T15:27:03.588Z,1397316423.588 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2014-04-12T15:27:03.588Z,1397316423.588 [Startup:StartupSatComms] Stopped
2014-04-12T15:27:03.588Z,1397316423.588 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2014-04-12T15:27:03.589Z,1397316423.589 [Startup](INFO): Completed Startup
2014-04-12T15:27:03.589Z,1397316423.589 [Startup] Stopped
2014-04-12T15:27:03.589Z,1397316423.589 [Startup](INFO): Aggregate::uninitialize Startup
2014-04-12T15:27:03.589Z,1397316423.589 [Startup:A.GoToSurface] Stopped
2014-04-12T15:27:03.589Z,1397316423.589 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-04-12T15:27:03.984Z,1397316423.984 [NAL9602](INFO): SBD MO Status=1, MOMSN=24819, MT Status=0, MTMSN=0
2014-04-12T15:27:04.036Z,1397316424.036 [NAL9602](INFO): Sent 332 bytes from file Logs/20140412T073136/Express0181.lzma
2014-04-12T15:27:04.036Z,1397316424.036 [NAL9602](INFO): Packets left to send: 1
2014-04-12T15:27:04.038Z,1397316424.038 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T073136/Express0181.lzma.parts/0001.sbd
2014-04-12T15:27:04.557Z,1397316424.557 [MissionManager](IMPORTANT): Started mission Default
2014-04-12T15:27:04.557Z,1397316424.557 [Default] Running Loop=1
2014-04-12T15:27:04.558Z,1397316424.558 [Default](INFO): Aggregate::initialize Default
2014-04-12T15:27:04.558Z,1397316424.558 [Default:Iridium] Running Loop=1
2014-04-12T15:27:04.558Z,1397316424.558 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2014-04-12T15:27:04.558Z,1397316424.558 [Default:Iridium:A.SetSpeed] Running Loop=1
2014-04-12T15:27:04.558Z,1397316424.558 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2014-04-12T15:27:04.558Z,1397316424.558 [Default:Iridium:B.GoToSurface] Running Loop=1
2014-04-12T15:27:04.558Z,1397316424.558 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-04-12T15:27:04.559Z,1397316424.559 [Default:CallIridium] Running Loop=1
2014-04-12T15:27:04.559Z,1397316424.559 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2014-04-12T15:27:04.559Z,1397316424.559 [Default:CallIridium:A] Running Loop=1
2014-04-12T15:27:04.561Z,1397316424.561 [Default:CallIridium:A] Stopped
2014-04-12T15:27:04.561Z,1397316424.561 [Default:CallIridium:B] Running Loop=1
2014-04-12T15:27:04.561Z,1397316424.561 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2014-04-12T15:27:04.567Z,1397316424.567 [Default:Iridium:B.GoToSurface] Stopped
2014-04-12T15:27:04.567Z,1397316424.567 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-04-12T15:27:04.567Z,1397316424.567 [Default:Iridium:Read_Iridium] Running Loop=1
2014-04-12T15:27:04.567Z,1397316424.567 [Default:Iridium:A.SetSpeed] Running Loop=1
2014-04-12T15:27:04.572Z,1397316424.572 [Default:GPS] Running Loop=1
2014-04-12T15:27:04.572Z,1397316424.572 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2014-04-12T15:27:04.573Z,1397316424.573 [Default:GPS:A.SetSpeed] Running Loop=1
2014-04-12T15:27:04.573Z,1397316424.573 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2014-04-12T15:27:04.573Z,1397316424.573 [Default:GPS:B.GoToSurface] Running Loop=1
2014-04-12T15:27:04.573Z,1397316424.573 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-04-12T15:27:04.578Z,1397316424.578 [Default:GPS:B.GoToSurface] Stopped
2014-04-12T15:27:04.578Z,1397316424.578 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-04-12T15:27:04.578Z,1397316424.578 [Default:GPS:Read_GPS] Running Loop=1
2014-04-12T15:27:04.578Z,1397316424.578 [Default:GPS:A.SetSpeed] Running Loop=1
2014-04-12T15:27:05.060Z,1397316425.060 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2014-04-12T15:27:05.063Z,1397316425.063 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2014-04-12T15:27:06.886Z,1397316426.886 [NAL9602](IMPORTANT): GPS fix at: 1397316395.00
2014-04-12T15:27:06.897Z,1397316426.897 [DeadReckonUsingMultipleVelocitySources](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07
2014-04-12T15:27:06.902Z,1397316426.902 [Default:GPS:Read_GPS] Stopped
2014-04-12T15:27:06.902Z,1397316426.902 [Default:GPS:D] Running Loop=1
2014-04-12T15:27:07.382Z,1397316427.382 [Default:GPS:D] Stopped
2014-04-12T15:27:07.382Z,1397316427.382 [Default:GPS](INFO): Completed Default:GPS
2014-04-12T15:27:07.382Z,1397316427.382 [Default:GPS] Stopped
2014-04-12T15:27:07.382Z,1397316427.382 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2014-04-12T15:27:07.383Z,1397316427.383 [Default:GPS:A.SetSpeed] Stopped
2014-04-12T15:27:07.383Z,1397316427.383 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2014-04-12T15:27:25.862Z,1397316445.862 [NAL9602](INFO): SBD MO Status=1, MOMSN=24820, MT Status=0, MTMSN=0
2014-04-12T15:27:25.922Z,1397316445.922 [NAL9602](INFO): Sent 74 bytes from file Logs/20140412T152410/Courier0004.lzma
2014-04-12T15:27:25.922Z,1397316445.922 [NAL9602](INFO): Packets left to send: 0
2014-04-12T15:27:25.924Z,1397316445.924 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T152410/Courier0004.lzma.parts/0000.sbd
2014-04-12T15:27:25.924Z,1397316445.924 [NAL9602](INFO): Completed sending Logs/20140412T152410/Courier0004.lzma
2014-04-12T15:27:38.253Z,1397316458.253 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=24821, MT Status=1, MTMSN=1691
2014-04-12T15:27:38.301Z,1397316458.301 [NAL9602](INFO): Sent 25 bytes from file Logs/20140412T152410/Courier0008.lzma
2014-04-12T15:27:38.301Z,1397316458.301 [NAL9602](INFO): Packets left to send: 0
2014-04-12T15:27:38.303Z,1397316458.303 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T152410/Courier0008.lzma.parts/0000.sbd
2014-04-12T15:27:38.303Z,1397316458.303 [NAL9602](INFO): Completed sending Logs/20140412T152410/Courier0008.lzma
2014-04-12T15:27:39.191Z,1397316459.191 [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.585 degree;set science_to.Wpt1Lon -117.9825 degree;set science_to.Wpt2Lat 33.5644 degree" 3qiwk 1 3
2014-04-12T15:27:39.199Z,1397316459.199 [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.585 degree;set science_to.Wpt1Lon -117.9825 degree;set science_to.Wpt2Lat 33.5644 degree" 3qiwk 1 3.000000
2014-04-12T15:27:39.200Z,1397316459.200 [CommandLine](IMPORTANT): Scheduling command #1 of 3 with id=3qiwk
2014-04-12T15:27:39.201Z,1397316459.201 [CommandLine](IMPORTANT): Scheduled #1 (#1 of 3 with id='3qiwk'): "load Science/science_to.xml;set science_to.MaxDepth 50 meter;set science_to.YoYoMaxDepth 40 meter;set science_to.Wpt1Lat 33.585 degree;set science_to.Wpt1Lon -117.9825 degree;set science_to.Wpt2Lat 33.5644 degree" ASAP
2014-04-12T15:27:58.646Z,1397316478.646 [NAL9602](INFO): SBD MO Status=1, MOMSN=24822, MT Status=0, MTMSN=0
2014-04-12T15:27:58.697Z,1397316478.697 [NAL9602](INFO): Sent 4 bytes from file Logs/20140412T073136/Express0181.lzma
2014-04-12T15:27:58.697Z,1397316478.697 [NAL9602](INFO): Packets left to send: 0
2014-04-12T15:27:58.698Z,1397316478.698 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T073136/Express0181.lzma.parts/0000.sbd
2014-04-12T15:27:58.698Z,1397316478.698 [NAL9602](INFO): Completed sending Logs/20140412T073136/Express0181.lzma
2014-04-12T15:28:13.443Z,1397316493.443 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=24823, MT Status=1, MTMSN=1692
2014-04-12T15:28:13.492Z,1397316493.492 [NAL9602](INFO): Sent 332 bytes from file Logs/20140412T152410/Express0001.lzma
2014-04-12T15:28:13.492Z,1397316493.492 [NAL9602](INFO): Packets left to send: 1
2014-04-12T15:28:13.493Z,1397316493.493 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T152410/Express0001.lzma.parts/0001.sbd
2014-04-12T15:28:14.317Z,1397316494.317 [NAL9602](INFO): Received command:sched asap "set science_to.Wpt2Lon -117.9985 degree;set science_to.Wpt3Lat 33.6065 degree;set science_to.Wpt3Lon -118.0764 degree;set science_to.Wpt4Lat 33.6273 degree;set science_to.Wpt4Lon -118.0603 degree" 3qiwk 2 3
2014-04-12T15:28:14.367Z,1397316494.367 [CommandLine](IMPORTANT): got command schedule asap "set science_to.Wpt2Lon -117.9985 degree;set science_to.Wpt3Lat 33.6065 degree;set science_to.Wpt3Lon -118.0764 degree;set science_to.Wpt4Lat 33.6273 degree;set science_to.Wpt4Lon -118.0603 degree" 3qiwk 2 3.000000
2014-04-12T15:28:14.367Z,1397316494.367 [CommandLine](IMPORTANT): Scheduling command #2 of 3 with id=3qiwk
2014-04-12T15:28:14.368Z,1397316494.368 [CommandLine](IMPORTANT): Scheduled #2 (#2 of 3 with id='3qiwk'): "set science_to.Wpt2Lon -117.9985 degree;set science_to.Wpt3Lat 33.6065 degree;set science_to.Wpt3Lon -118.0764 degree;set science_to.Wpt4Lat 33.6273 degree;set science_to.Wpt4Lon -118.0603 degree" ASAP
2014-04-12T15:28:39.415Z,1397316519.415 [NAL9602](INFO): SBD MO Status=1, MOMSN=24824, MT Status=0, MTMSN=0
2014-04-12T15:28:39.464Z,1397316519.464 [NAL9602](INFO): Sent 325 bytes from file Logs/20140412T152410/Express0001.lzma
2014-04-12T15:28:39.464Z,1397316519.464 [NAL9602](INFO): Packets left to send: 0
2014-04-12T15:28:39.465Z,1397316519.465 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T152410/Express0001.lzma.parts/0000.sbd
2014-04-12T15:28:39.466Z,1397316519.466 [NAL9602](INFO): Completed sending Logs/20140412T152410/Express0001.lzma
2014-04-12T15:28:52.560Z,1397316532.560 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=24825, MT Status=1, MTMSN=1693
2014-04-12T15:28:52.616Z,1397316532.616 [NAL9602](INFO): Sent 332 bytes from file Logs/20140412T152410/Express0005.lzma
2014-04-12T15:28:52.616Z,1397316532.616 [NAL9602](INFO): Packets left to send: 1
2014-04-12T15:28:52.618Z,1397316532.618 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T152410/Express0005.lzma.parts/0001.sbd
2014-04-12T15:28:53.213Z,1397316533.213 [NAL9602](INFO): Received command:sched asap "set science_to.NeedCommsTime 45 minute;set science_to.Timeout 3 hour;run " 3qiwk 3 3
2014-04-12T15:28:53.243Z,1397316533.243 [CommandLine](IMPORTANT): got command schedule asap "set science_to.NeedCommsTime 45 minute;set science_to.Timeout 3 hour;run " 3qiwk 3 3.000000
2014-04-12T15:28:53.243Z,1397316533.243 [CommandLine](IMPORTANT): Scheduling command #3 of 3 with id=3qiwk
2014-04-12T15:28:53.244Z,1397316533.244 [CommandLine](IMPORTANT): Scheduled #3 (#3 of 3 with id='3qiwk'): "set science_to.NeedCommsTime 45 minute;set science_to.Timeout 3 hour;run " ASAP
2014-04-12T15:28:53.335Z,1397316533.335 [CommandLine](IMPORTANT): got command load ./Missions/Science/science_to.xml
2014-04-12T15:28:53.336Z,1397316533.336 [MissionManager](INFO): Loading Mission: ./Missions/Science/science_to.xml
2014-04-12T15:28:53.490Z,1397316533.490 [MissionManager](INFO): DefineArg science_to.MinAltitude = 10.000000 m
2014-04-12T15:28:53.493Z,1397316533.493 [MissionManager](INFO): DefineArg science_to.MaxDepth = 105.000000 m
2014-04-12T15:28:53.496Z,1397316533.496 [MissionManager](INFO): DefineArg science_to.MinOffshore = 2000.000000 m
2014-04-12T15:28:53.500Z,1397316533.500 [MissionManager](INFO): DefineArg science_to.YoYoMinDepth = 3.000000 m
2014-04-12T15:28:53.504Z,1397316533.504 [MissionManager](INFO): DefineArg science_to.YoYoMaxDepth = 100.000000 m
2014-04-12T15:28:53.535Z,1397316533.535 [MissionManager](INFO): DefineArg science_to.YoYoUpPitch = 20.000000 arcdeg
2014-04-12T15:28:53.538Z,1397316533.538 [MissionManager](INFO): DefineArg science_to.YoYoDownPitch = -20.000000 arcdeg
2014-04-12T15:28:53.541Z,1397316533.541 [MissionManager](INFO): DefineArg science_to.Wpt1Lat = 0.000000 arcdeg
2014-04-12T15:28:53.544Z,1397316533.544 [MissionManager](INFO): DefineArg science_to.Wpt1Lon = 0.000000 arcdeg
2014-04-12T15:28:53.558Z,1397316533.558 [MissionManager](INFO): DefineArg science_to.Wpt2Lat = 0.000000 arcdeg
2014-04-12T15:28:53.561Z,1397316533.561 [MissionManager](INFO): DefineArg science_to.Wpt2Lon = 0.000000 arcdeg
2014-04-12T15:28:53.564Z,1397316533.564 [MissionManager](INFO): DefineArg science_to.Wpt3Lat = 0.000000 arcdeg
2014-04-12T15:28:53.567Z,1397316533.567 [MissionManager](INFO): DefineArg science_to.Wpt3Lon = 0.000000 arcdeg
2014-04-12T15:28:53.573Z,1397316533.573 [MissionManager](INFO): DefineArg science_to.Wpt4Lat = 0.000000 arcdeg
2014-04-12T15:28:53.576Z,1397316533.576 [MissionManager](INFO): DefineArg science_to.Wpt4Lon = 0.000000 arcdeg
2014-04-12T15:28:53.579Z,1397316533.579 [MissionManager](INFO): DefineArg science_to.NeedCommsTime = 120.000000 min
2014-04-12T15:28:53.581Z,1397316533.581 [MissionManager](INFO): DefineArg science_to.Speed = 1.000000 m/s
2014-04-12T15:28:53.584Z,1397316533.584 [MissionManager](INFO): DefineArg science_to.Timeout = 36.000000 h
2014-04-12T15:28:53.588Z,1397316533.588 [MissionManager](INFO): DefineArg science_to.BuoyancyNeutral = 0.000300 n/a
2014-04-12T15:28:53.592Z,1397316533.592 [MissionManager](INFO): DefineArg science_to.MassDefault = 0.000000 n/a
2014-04-12T15:28:53.595Z,1397316533.595 [MissionManager](INFO): DefineArg science_to.ZeroDeg = 0.000000 arcdeg
2014-04-12T15:28:53.598Z,1397316533.598 [science_to:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2014-04-12T15:28:53.605Z,1397316533.605 [science_to:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2014-04-12T15:28:53.671Z,1397316533.671 [science_to:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2014-04-12T15:28:53.678Z,1397316533.678 [science_to:D.Pitch](DEBUG): Construct.
2014-04-12T15:28:53.687Z,1397316533.687 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml
2014-04-12T15:28:53.757Z,1397316533.757 [MissionManager](INFO): DefineArg science_to:SURFACECOMMS.SurfaceDepthRate = nan m/s
2014-04-12T15:28:53.760Z,1397316533.760 [MissionManager](INFO): DefineArg science_to:SURFACECOMMS.SurfacePitch = 20.000000 arcdeg
2014-04-12T15:28:53.780Z,1397316533.780 [MissionManager](INFO): DefineArg science_to:SURFACECOMMS.SurfaceSpeed = 1.000000 m/s
2014-04-12T15:28:53.783Z,1397316533.783 [MissionManager](INFO): DefineArg science_to:SURFACECOMMS.IridiumTimeout = 30.000000 min
2014-04-12T15:28:53.786Z,1397316533.786 [MissionManager](INFO): DefineArg science_to:SURFACECOMMS.UnpackSplitAtSurface = 0 bool
2014-04-12T15:28:53.787Z,1397316533.787 [science_to:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-04-12T15:28:53.823Z,1397316533.823 [MissionManager](INFO): RedefineArg science_to:SURFACECOMMS.SurfacePitch = value:20.000000 arcdeg
2014-04-12T15:28:53.825Z,1397316533.825 [MissionManager](INFO): RedefineArg science_to:SURFACECOMMS.SurfaceSpeed = reader:science_to.Speed
2014-04-12T15:28:53.839Z,1397316533.839 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2014-04-12T15:28:53.952Z,1397316533.952 [MissionManager](INFO): DefineArg science_to:Science.SampleISUS = 0 bool
2014-04-12T15:28:53.955Z,1397316533.955 [MissionManager](INFO): DefineArg science_to:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2014-04-12T15:28:53.959Z,1397316533.959 [MissionManager](INFO): DefineArg science_to:Science.SampleISUSminPitch = -60.000000 arcdeg
2014-04-12T15:28:53.963Z,1397316533.963 [MissionManager](INFO): DefineArg science_to:Science.PeakDetectChlActive = 0 bool
2014-04-12T15:28:53.965Z,1397316533.965 [MissionManager](INFO): DefineArg science_to:Science.PeakDetectNO3Active = 0 bool
2014-04-12T15:28:53.973Z,1397316533.973 [MissionManager](INFO): DefineArg science_to:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2014-04-12T15:28:53.976Z,1397316533.976 [MissionManager](INFO): DefineArg science_to:Science.ComputeWaterCurrent = 0 bool
2014-04-12T15:28:53.980Z,1397316533.980 [MissionManager](INFO): DefineArg science_to:Science.ComputeWaterCurrentMinDepth = 2.000000 m
2014-04-12T15:28:53.983Z,1397316533.983 [MissionManager](INFO): DefineArg science_to:Science.ComputeWaterCurrentMaxDepth = 5.000000 m
2014-04-12T15:28:53.986Z,1397316533.986 [MissionManager](INFO): DefineArg science_to:Science.EnabledAanderaaO2 = 0.000000 n/a
2014-04-12T15:28:53.989Z,1397316533.989 [MissionManager](INFO): DefineArg science_to:Science.EnabledNeilBrown = 1.000000 n/a
2014-04-12T15:28:53.995Z,1397316533.995 [MissionManager](INFO): DefineArg science_to:Science.EnabledTurbulence_NPS = 0.000000 n/a
2014-04-12T15:28:53.998Z,1397316533.998 [MissionManager](INFO): DefineArg science_to:Science.EnabledWetLabsBB2FL = 1.000000 n/a
2014-04-12T15:28:54.002Z,1397316534.002 [MissionManager](INFO): DefineArg science_to:Science.EnabledDVL = 1.000000 n/a
2014-04-12T15:28:54.005Z,1397316534.005 [MissionManager](INFO): DefineArg science_to:Science.EnabledISUS = 0.000000 n/a
2014-04-12T15:28:54.052Z,1397316534.052 [MissionManager](INFO): DefineOutput science_to:Science.PeakChl = 0.000000 ug/l
2014-04-12T15:28:54.055Z,1397316534.055 [MissionManager](INFO): DefineOutput science_to:Science.PeakChlDepth = 0.000000 m
2014-04-12T15:28:54.058Z,1397316534.058 [MissionManager](INFO): DefineOutput science_to:Science.PeakChlLatitude = nan arcdeg
2014-04-12T15:28:54.061Z,1397316534.061 [MissionManager](INFO): DefineOutput science_to:Science.PeakChlLongitude = nan arcdeg
2014-04-12T15:28:54.067Z,1397316534.067 [MissionManager](INFO): DefineOutput science_to:Science.PeakNO3 = 0.000000 umol/l
2014-04-12T15:28:54.070Z,1397316534.070 [MissionManager](INFO): DefineOutput science_to:Science.PeakNO3Depth = 0.000000 m
2014-04-12T15:28:54.073Z,1397316534.073 [MissionManager](INFO): DefineOutput science_to:Science.PeakNO3Latitude = nan arcdeg
2014-04-12T15:28:54.076Z,1397316534.076 [MissionManager](INFO): DefineOutput science_to:Science.PeakNO3Longitude = nan arcdeg
2014-04-12T15:28:54.091Z,1397316534.091 [science_to:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2014-04-12T15:28:54.100Z,1397316534.100 [science_to:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2014-04-12T15:28:54.154Z,1397316534.154 [MissionManager](INFO): RedefineArg science_to:Science.SampleISUS = value:1 bool
2014-04-12T15:28:54.156Z,1397316534.156 [MissionManager](INFO): RedefineArg science_to:Science.PeakDetectChlActive = value:1 bool
2014-04-12T15:28:54.157Z,1397316534.157 [science_to:H.Buoyancy](DEBUG): Construct Buoyancy.
2014-04-12T15:28:54.165Z,1397316534.165 [science_to:I.SetSpeed](DEBUG): Construct.
2014-04-12T15:28:54.178Z,1397316534.178 [science_to:J.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2014-04-12T15:28:54.204Z,1397316534.204 [science_to:K.YoYo](DEBUG): Construct YoYo.
2014-04-12T15:28:54.238Z,1397316534.238 [science_to:Wpt1:A.Waypoint](DEBUG): Construct Waypoint.
2014-04-12T15:28:54.262Z,1397316534.262 [science_to:Wpt2:A.Waypoint](DEBUG): Construct Waypoint.
2014-04-12T15:28:54.269Z,1397316534.269 [science_to:Wpt3:A.Waypoint](DEBUG): Construct Waypoint.
2014-04-12T15:28:54.277Z,1397316534.277 [science_to:Wpt4:A.Waypoint](DEBUG): Construct Waypoint.
2014-04-12T15:28:54.302Z,1397316534.302 [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-12T15:28:54.303Z,1397316534.303 [CommandLine](IMPORTANT): Loaded ./Missions/Science/science_to.xml
2014-04-12T15:29:02.053Z,1397316542.053 [CommandLine](IMPORTANT): got command set science_to.MaxDepth 50.000000 meter
2014-04-12T15:29:02.061Z,1397316542.061 [CommandLine](IMPORTANT): got command set science_to.YoYoMaxDepth 40.000000 meter
2014-04-12T15:29:02.062Z,1397316542.062 [CommandLine](IMPORTANT): got command set science_to.Wpt1Lat 33.584999 degree
2014-04-12T15:29:02.062Z,1397316542.062 [CommandLine](IMPORTANT): got command set science_to.Wpt1Lon -117.982498 degree
2014-04-12T15:29:02.063Z,1397316542.063 [CommandLine](IMPORTANT): got command set science_to.Wpt2Lat 33.564400 degree
2014-04-12T15:29:09.467Z,1397316549.467 [CommandLine](IMPORTANT): got command set science_to.Wpt2Lon -117.998497 degree
2014-04-12T15:29:09.468Z,1397316549.468 [CommandLine](IMPORTANT): got command set science_to.Wpt3Lat 33.606499 degree
2014-04-12T15:29:09.469Z,1397316549.469 [CommandLine](IMPORTANT): got command set science_to.Wpt3Lon -118.076401 degree
2014-04-12T15:29:09.470Z,1397316549.470 [CommandLine](IMPORTANT): got command set science_to.Wpt4Lat 33.627300 degree
2014-04-12T15:29:09.471Z,1397316549.471 [CommandLine](IMPORTANT): got command set science_to.Wpt4Lon -118.060303 degree
2014-04-12T15:29:13.081Z,1397316553.081 [NAL9602](INFO): SBD MO Status=1, MOMSN=24826, MT Status=0, MTMSN=0
2014-04-12T15:29:13.089Z,1397316553.089 [CommandLine](IMPORTANT): got command set science_to.NeedCommsTime 45.000000 minute
2014-04-12T15:29:13.090Z,1397316553.090 [CommandLine](IMPORTANT): got command set science_to.Timeout 3.000000 hour
2014-04-12T15:29:13.091Z,1397316553.091 [CommandLine](IMPORTANT): got command run 
2014-04-12T15:29:13.102Z,1397316553.102 [CommandLine](IMPORTANT): Running 
2014-04-12T15:29:13.128Z,1397316553.128 [NAL9602](INFO): Sent 66 bytes from file Logs/20140412T152410/Express0005.lzma
2014-04-12T15:29:13.128Z,1397316553.128 [NAL9602](INFO): Packets left to send: 0
2014-04-12T15:29:13.129Z,1397316553.129 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T152410/Express0005.lzma.parts/0000.sbd
2014-04-12T15:29:13.130Z,1397316553.130 [NAL9602](INFO): Completed sending Logs/20140412T152410/Express0005.lzma
2014-04-12T15:29:13.163Z,1397316553.163 [Default] Stopped
2014-04-12T15:29:13.163Z,1397316553.163 [Default](INFO): Aggregate::uninitialize Default
2014-04-12T15:29:13.163Z,1397316553.163 [Default:Iridium] Stopped
2014-04-12T15:29:13.163Z,1397316553.163 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2014-04-12T15:29:13.163Z,1397316553.163 [Default:Iridium:A.SetSpeed] Stopped
2014-04-12T15:29:13.163Z,1397316553.163 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2014-04-12T15:29:13.163Z,1397316553.163 [Default:Iridium:Read_Iridium] Stopped
2014-04-12T15:29:13.163Z,1397316553.163 [Default:CallIridium] Stopped
2014-04-12T15:29:13.164Z,1397316553.164 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2014-04-12T15:29:13.164Z,1397316553.164 [Default:CallIridium:B] Stopped
2014-04-12T15:29:13.164Z,1397316553.164 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2014-04-12T15:29:13.164Z,1397316553.164 [MissionManager](IMPORTANT): Started mission science_to
2014-04-12T15:29:13.164Z,1397316553.164 [science_to] Running Loop=1
2014-04-12T15:29:13.164Z,1397316553.164 [science_to](INFO): Aggregate::initialize science_to
2014-04-12T15:29:13.165Z,1397316553.165 [science_to:B.DepthEnvelope] Running Loop=1
2014-04-12T15:29:13.165Z,1397316553.165 [science_to:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2014-04-12T15:29:13.165Z,1397316553.165 [science_to:D.Pitch] Running Loop=1
2014-04-12T15:29:13.165Z,1397316553.165 [science_to:D.Pitch](DEBUG): Initialize.
2014-04-12T15:29:13.165Z,1397316553.165 [science_to:Science] Running Loop=1
2014-04-12T15:29:13.165Z,1397316553.165 [science_to:Science](INFO): Aggregate::initialize science_to:Science
2014-04-12T15:29:13.165Z,1397316553.165 [science_to:Science:A] Running Loop=1
2014-04-12T15:29:13.166Z,1397316553.166 [science_to:Science:B] Running Loop=1
2014-04-12T15:29:13.166Z,1397316553.166 [science_to:Science:C] Running Loop=1
2014-04-12T15:29:13.166Z,1397316553.166 [science_to:Science:D] Running Loop=1
2014-04-12T15:29:13.166Z,1397316553.166 [science_to:Science:E] Running Loop=1
2014-04-12T15:29:13.166Z,1397316553.166 [science_to:Science:F] Running Loop=1
2014-04-12T15:29:13.166Z,1397316553.166 [science_to:Science:G] Running Loop=1
2014-04-12T15:29:13.166Z,1397316553.166 [science_to:Science:PeakDetectChl] Running Loop=1
2014-04-12T15:29:13.166Z,1397316553.166 [science_to:Science:PeakDetectChl](INFO): Aggregate::initialize science_to:Science:PeakDetectChl
2014-04-12T15:29:13.167Z,1397316553.167 [science_to:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2014-04-12T15:29:13.167Z,1397316553.167 [science_to:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2014-04-12T15:29:13.169Z,1397316553.169 [science_to:Science:PeakDetectNO3] Running Loop=1
2014-04-12T15:29:13.169Z,1397316553.169 [science_to:Science:PeakDetectNO3](INFO): Aggregate::initialize science_to:Science:PeakDetectNO3
2014-04-12T15:29:13.169Z,1397316553.169 [science_to:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2014-04-12T15:29:13.169Z,1397316553.169 [science_to:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2014-04-12T15:29:13.171Z,1397316553.171 [science_to:H.Buoyancy] Running Loop=1
2014-04-12T15:29:13.171Z,1397316553.171 [science_to:H.Buoyancy](DEBUG): Initialize Buoyancy Component.
2014-04-12T15:29:13.171Z,1397316553.171 [science_to:I.SetSpeed] Running Loop=1
2014-04-12T15:29:13.171Z,1397316553.171 [science_to:I.SetSpeed](DEBUG): Initialize.
2014-04-12T15:29:13.174Z,1397316553.174 [science_to:J.DepthEnvelope] Running Loop=1
2014-04-12T15:29:13.174Z,1397316553.174 [science_to:J.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2014-04-12T15:29:13.174Z,1397316553.174 [science_to:K.YoYo] Running Loop=1
2014-04-12T15:29:13.174Z,1397316553.174 [science_to:K.YoYo](DEBUG): Initialize YoYoComponent.
2014-04-12T15:29:13.175Z,1397316553.175 [science_to:SURFACECOMMS] Running Loop=1
2014-04-12T15:29:13.175Z,1397316553.175 [science_to:SURFACECOMMS](INFO): Aggregate::initialize science_to:SURFACECOMMS
2014-04-12T15:29:13.175Z,1397316553.175 [science_to:SURFACECOMMS:A.GoToSurface] Running Loop=1
2014-04-12T15:29:13.175Z,1397316553.175 [science_to:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-04-12T15:29:13.176Z,1397316553.176 [science_to:K.YoYo] Running Loop=1
2014-04-12T15:29:13.182Z,1397316553.182 [science_to:J.DepthEnvelope] Running Loop=1
2014-04-12T15:29:13.188Z,1397316553.188 [science_to:I.SetSpeed] Running Loop=1
2014-04-12T15:29:13.192Z,1397316553.192 [science_to:H.Buoyancy] Running Loop=1
2014-04-12T15:29:13.197Z,1397316553.197 [science_to:Science] Running Loop=1
2014-04-12T15:29:13.201Z,1397316553.201 [science_to:Science:PeakDetectNO3] Stopped
2014-04-12T15:29:13.201Z,1397316553.201 [science_to:Science:PeakDetectNO3](INFO): Aggregate::uninitialize science_to:Science:PeakDetectNO3
2014-04-12T15:29:13.201Z,1397316553.201 [science_to:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2014-04-12T15:29:13.201Z,1397316553.201 [science_to:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2014-04-12T15:29:13.201Z,1397316553.201 [science_to:Science:PeakDetectChl] Running Loop=1
2014-04-12T15:29:13.206Z,1397316553.206 [science_to:Science:G] Running Loop=1
2014-04-12T15:29:13.207Z,1397316553.207 [science_to:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2014-04-12T15:29:13.233Z,1397316553.233 [science_to:Science:F] Stopped
2014-04-12T15:29:13.233Z,1397316553.233 [science_to:Science:E] Stopped
2014-04-12T15:29:13.233Z,1397316553.233 [science_to:Science:D] Running Loop=1
2014-04-12T15:29:13.234Z,1397316553.234 [science_to:Science:D](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2014-04-12T15:29:13.235Z,1397316553.235 [science_to:Science:D](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2014-04-12T15:29:13.236Z,1397316553.236 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4
2014-04-12T15:29:13.236Z,1397316553.237 [WetLabsBB2FL](INFO): Powering up
2014-04-12T15:29:13.244Z,1397316553.244 [science_to:Science:C] Stopped
2014-04-12T15:29:13.245Z,1397316553.245 [science_to:Science:B] Stopped
2014-04-12T15:29:13.245Z,1397316553.245 [science_to:Science:A] Stopped
2014-04-12T15:29:13.256Z,1397316553.256 [science_to:SURFACECOMMS:B] Running Loop=1
2014-04-12T15:29:13.256Z,1397316553.256 [science_to:SURFACECOMMS:B](INFO): Aggregate::initialize science_to:SURFACECOMMS:B
2014-04-12T15:29:13.256Z,1397316553.256 [science_to:SURFACECOMMS:B:A] Running Loop=1
2014-04-12T15:29:13.256Z,1397316553.256 [science_to:D.Pitch] Running Loop=1
2014-04-12T15:29:13.262Z,1397316553.262 [science_to:OffshoreWrapper] Running Loop=1
2014-04-12T15:29:13.263Z,1397316553.263 [science_to:OffshoreWrapper](INFO): Aggregate::initialize science_to:OffshoreWrapper
2014-04-12T15:29:13.263Z,1397316553.263 [science_to:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1
2014-04-12T15:29:13.263Z,1397316553.263 [science_to:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2014-04-12T15:29:13.263Z,1397316553.263 [science_to:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1
2014-04-12T15:29:13.268Z,1397316553.268 [science_to:B.DepthEnvelope] Running Loop=1
2014-04-12T15:29:13.273Z,1397316553.273 [science_to:AltEnvWrapper] Running Loop=1
2014-04-12T15:29:13.273Z,1397316553.273 [science_to:AltEnvWrapper](INFO): Aggregate::initialize science_to:AltEnvWrapper
2014-04-12T15:29:13.273Z,1397316553.273 [science_to:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1
2014-04-12T15:29:13.273Z,1397316553.273 [science_to:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2014-04-12T15:29:13.274Z,1397316553.274 [science_to:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1
2014-04-12T15:29:13.300Z,1397316553.300 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-04-12T15:29:14.780Z,1397316554.780 [science_to:SURFACECOMMS:B] Stopped
2014-04-12T15:29:14.780Z,1397316554.780 [science_to:SURFACECOMMS:B](INFO): Aggregate::uninitialize science_to:SURFACECOMMS:B
2014-04-12T15:29:14.780Z,1397316554.780 [science_to:SURFACECOMMS:B:A] Stopped
2014-04-12T15:29:14.780Z,1397316554.780 [science_to:SURFACECOMMS:C] Running Loop=1
2014-04-12T15:29:14.780Z,1397316554.780 [science_to:SURFACECOMMS:C](INFO): Aggregate::initialize science_to:SURFACECOMMS:C
2014-04-12T15:29:14.781Z,1397316554.781 [science_to:SURFACECOMMS:C:A] Running Loop=1
2014-04-12T15:29:17.364Z,1397316557.364 [science_to:K.YoYo] Preempted
2014-04-12T15:29:17.364Z,1397316557.364 [science_to:J.DepthEnvelope] Preempted
2014-04-12T15:29:17.364Z,1397316557.364 [science_to:I.SetSpeed] Preempted
2014-04-12T15:29:17.364Z,1397316557.364 [science_to:H.Buoyancy] Preempted
2014-04-12T15:29:17.364Z,1397316557.364 [science_to:Science] Preempted
2014-04-12T15:29:17.364Z,1397316557.364 [science_to:Science:D] Preempted
2014-04-12T15:29:17.364Z,1397316557.364 [science_to:Science:G] Preempted
2014-04-12T15:29:17.364Z,1397316557.364 [science_to:Science:PeakDetectChl] Preempted
2014-04-12T15:29:17.365Z,1397316557.365 [science_to:SURFACECOMMS:C:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2014-04-12T15:29:17.463Z,1397316557.463 [WetLabsBB2FL](INFO): Powering down
2014-04-12T15:29:24.384Z,1397316564.384 [NAL9602](INFO): SBD MO Status=1, MOMSN=24827, MT Status=0, MTMSN=0
2014-04-12T15:29:24.434Z,1397316564.434 [NAL9602](INFO): Sent 94 bytes from file Logs/20140412T152410/Express0009.lzma
2014-04-12T15:29:24.434Z,1397316564.434 [NAL9602](INFO): Packets left to send: 0
2014-04-12T15:29:24.435Z,1397316564.435 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T152410/Express0009.lzma.parts/0000.sbd
2014-04-12T15:29:24.436Z,1397316564.436 [NAL9602](INFO): Completed sending Logs/20140412T152410/Express0009.lzma
2014-04-12T15:29:26.426Z,1397316566.426 [NAL9602](IMPORTANT): GPS fix at: 1397316535.00
2014-04-12T15:29:26.445Z,1397316566.445 [DeadReckonUsingMultipleVelocitySources](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07
2014-04-12T15:29:26.451Z,1397316566.451 [science_to:SURFACECOMMS:C:A] Stopped
2014-04-12T15:29:26.451Z,1397316566.451 [science_to:SURFACECOMMS:C:B] Running Loop=1
2014-04-12T15:29:26.865Z,1397316566.865 [science_to:SURFACECOMMS:C:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2014-04-12T15:29:41.577Z,1397316581.577 [NAL9602](INFO): SBD MO Status=1, MOMSN=24828, MT Status=0, MTMSN=0
2014-04-12T15:29:41.628Z,1397316581.628 [NAL9602](INFO): Sent 75 bytes from file Logs/20140412T152410/Courier0012.lzma
2014-04-12T15:29:41.629Z,1397316581.629 [NAL9602](INFO): Packets left to send: 0
2014-04-12T15:29:41.630Z,1397316581.630 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T152410/Courier0012.lzma.parts/0000.sbd
2014-04-12T15:29:41.630Z,1397316581.630 [NAL9602](INFO): Completed sending Logs/20140412T152410/Courier0012.lzma
2014-04-12T15:29:52.830Z,1397316592.830 [NAL9602](INFO): SBD MO Status=1, MOMSN=24829, MT Status=0, MTMSN=0
2014-04-12T15:29:52.882Z,1397316592.882 [NAL9602](INFO): Sent 57 bytes from file Logs/20140412T152410/Courier0016.lzma
2014-04-12T15:29:52.882Z,1397316592.882 [NAL9602](INFO): Packets left to send: 0
2014-04-12T15:29:52.883Z,1397316592.883 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T152410/Courier0016.lzma.parts/0000.sbd
2014-04-12T15:29:52.884Z,1397316592.884 [NAL9602](INFO): Completed sending Logs/20140412T152410/Courier0016.lzma
2014-04-12T15:30:03.058Z,1397316603.058 [NAL9602](INFO): SBD MO Status=1, MOMSN=24830, MT Status=0, MTMSN=0
2014-04-12T15:30:03.118Z,1397316603.118 [NAL9602](INFO): Sent 332 bytes from file Logs/20140412T152410/Express0013.lzma
2014-04-12T15:30:03.118Z,1397316603.118 [NAL9602](INFO): Packets left to send: 2
2014-04-12T15:30:03.119Z,1397316603.119 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T152410/Express0013.lzma.parts/0002.sbd
2014-04-12T15:30:12.778Z,1397316612.778 [NAL9602](INFO): SBD MO Status=1, MOMSN=24831, MT Status=0, MTMSN=0
2014-04-12T15:30:12.829Z,1397316612.829 [NAL9602](INFO): Sent 332 bytes from file Logs/20140412T152410/Express0013.lzma
2014-04-12T15:30:12.829Z,1397316612.829 [NAL9602](INFO): Packets left to send: 1
2014-04-12T15:30:12.830Z,1397316612.830 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T152410/Express0013.lzma.parts/0001.sbd
2014-04-12T15:30:25.080Z,1397316625.080 [NAL9602](INFO): SBD MO Status=1, MOMSN=24832, MT Status=0, MTMSN=0
2014-04-12T15:30:25.132Z,1397316625.132 [NAL9602](INFO): Sent 263 bytes from file Logs/20140412T152410/Express0013.lzma
2014-04-12T15:30:25.132Z,1397316625.132 [NAL9602](INFO): Packets left to send: 0
2014-04-12T15:30:25.133Z,1397316625.133 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T152410/Express0013.lzma.parts/0000.sbd
2014-04-12T15:30:25.133Z,1397316625.133 [NAL9602](INFO): Completed sending Logs/20140412T152410/Express0013.lzma
2014-04-12T15:30:35.801Z,1397316635.801 [NAL9602](INFO): SBD MO Status=1, MOMSN=24833, MT Status=0, MTMSN=0
2014-04-12T15:30:35.860Z,1397316635.860 [NAL9602](INFO): Sent 93 bytes from file Logs/20140412T152410/Express0017.lzma
2014-04-12T15:30:35.861Z,1397316635.861 [NAL9602](INFO): Packets left to send: 0
2014-04-12T15:30:35.862Z,1397316635.862 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T152410/Express0017.lzma.parts/0000.sbd
2014-04-12T15:30:35.863Z,1397316635.863 [NAL9602](INFO): Completed sending Logs/20140412T152410/Express0017.lzma
2014-04-12T15:30:42.731Z,1397316642.731 [NAL9602](INFO): SBD MO Status=0, MOMSN=24834, MT Status=0, MTMSN=0
2014-04-12T15:30:42.826Z,1397316642.826 [science_to:SURFACECOMMS:C:B] Stopped
2014-04-12T15:30:42.826Z,1397316642.826 [science_to:SURFACECOMMS:C:C] Running Loop=1
2014-04-12T15:30:43.270Z,1397316643.270 [science_to:SURFACECOMMS:C:C](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2014-04-12T15:30:45.433Z,1397316645.433 [NAL9602](IMPORTANT): GPS fix at: 1397316614.00
2014-04-12T15:30:45.444Z,1397316645.444 [DeadReckonUsingMultipleVelocitySources](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07
2014-04-12T15:30:45.448Z,1397316645.448 [science_to:SURFACECOMMS:C:C] Stopped
2014-04-12T15:30:45.448Z,1397316645.448 [science_to:SURFACECOMMS:C](INFO): Completed science_to:SURFACECOMMS:C
2014-04-12T15:30:45.448Z,1397316645.448 [science_to:SURFACECOMMS:C] Stopped
2014-04-12T15:30:45.448Z,1397316645.448 [science_to:SURFACECOMMS:C](INFO): Aggregate::uninitialize science_to:SURFACECOMMS:C
2014-04-12T15:30:45.449Z,1397316645.449 [science_to:SURFACECOMMS](INFO): Completed science_to:SURFACECOMMS
2014-04-12T15:30:45.449Z,1397316645.449 [science_to:SURFACECOMMS] Stopped
2014-04-12T15:30:45.449Z,1397316645.449 [science_to:SURFACECOMMS](INFO): Aggregate::uninitialize science_to:SURFACECOMMS
2014-04-12T15:30:45.449Z,1397316645.449 [science_to:SURFACECOMMS:A.GoToSurface] Stopped
2014-04-12T15:30:45.449Z,1397316645.449 [science_to:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-04-12T15:30:45.450Z,1397316645.450 [science_to:Wpt1] Running Loop=1
2014-04-12T15:30:45.450Z,1397316645.450 [science_to:Wpt1](INFO): Aggregate::initialize science_to:Wpt1
2014-04-12T15:30:45.450Z,1397316645.450 [science_to:Wpt1:A.Waypoint] Running Loop=1
2014-04-12T15:30:45.450Z,1397316645.450 [science_to:Wpt1:A.Waypoint](DEBUG): Initialize WaypointComponent.
2014-04-12T15:30:45.939Z,1397316645.939 [science_to:K.YoYo] Running Loop=1
2014-04-12T15:30:45.939Z,1397316645.939 [science_to:J.DepthEnvelope] Running Loop=1
2014-04-12T15:30:45.940Z,1397316645.940 [science_to:I.SetSpeed] Running Loop=1
2014-04-12T15:30:45.940Z,1397316645.940 [science_to:H.Buoyancy] Running Loop=1
2014-04-12T15:30:45.941Z,1397316645.941 [science_to:Science] Running Loop=1
2014-04-12T15:30:45.942Z,1397316645.942 [science_to:Science:PeakDetectChl] Running Loop=1
2014-04-12T15:30:45.942Z,1397316645.942 [science_to:Science:G] Running Loop=1
2014-04-12T15:30:45.942Z,1397316645.942 [science_to:Science:D] Running Loop=1
2014-04-12T15:30:46.024Z,1397316646.024 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-04-12T15:30:46.124Z,1397316646.124 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4
2014-04-12T15:30:46.124Z,1397316646.124 [WetLabsBB2FL](INFO): Powering up
2014-04-12T15:30:51.798Z,1397316651.798 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-12T15:30:51.798Z,1397316651.798 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 37.2 38.4 36.9 35.3 -191.9 -25.8 263.7 72.3 -224 -80 145 4 9 108.1 -130.9 31.9 1 135.5 605.1 58.3 1 -492 -112 -41 2 101 494 -41 2 -5.48 1.33 245.6 35.3 14.3 0.005 35.0 1489 127
2014-04-12T15:30:52.434Z,1397316652.434 [NAL9602](INFO): Powering down
2014-04-12T15:31:34.161Z,1397316694.161 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-12T15:31:34.161Z,1397316694.161 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 30.3 39.4 41.3 32.1 -145.0 226.3 508.6 146.9 -209 230 576 132 7 11 12 8 -872.3 106.0 198.7 1 -587.1 -674.1 -112.0 1 -1047 2 -690 -799 196 2 -10.68 -18.53 56.3 30.3 14.3 0.005 35.0 1489 127
2014-04-12T15:31:39.936Z,1397316699.936 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46
2014-04-12T15:31:39.937Z,1397316699.937 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 28.6 34.4 37.2 30.5 -226.5 196.6 474.1 128.4 -243 190 536 128 6 9 10 7 -935.0 91.0 154.4 1 -547.3 -765.1 -146.6 1 -1039 82 164 2 -591 -859 164 2 -5.51 -17.70 60.0 28.6 14.3 0.00489 124
2014-04-12T15:32:04.351Z,1397316724.351 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:65 Calculated:86
2014-04-12T15:32:04.352Z,1397316724.352 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1.3 31.9 26.1 -167.7 96.1 426.6 170.7 -193 38 502 136 5 7 8 5 -793.2 -99.6 141.7 1 82.7 -802.0 -95.7 1 -927 -130 130 2 114 -929 130 2 -7.03 -17.75 89.0 24.4 14.3 0.005 35.0 1489 65
2014-04-12T15:32:04.470Z,1397316724.470 [CTD_NeilBrown](ERROR): Bad response: 39.31253
2014-04-12T15:32:29.399Z,1397316749.399 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:94 Calculated:79
2014-04-12T15:32:29.399Z,1397316749.399 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 19.8 23.6 0 -361.8 -128.9 271.1 29.7 -318 -17 460 73 4 5 6 4 -844.7 -211.7 -51.2 1 260.2 -782.6 -284.4 1 -1038 -120 53 2 156 -1033 53 2 -6.98 -18.06 92.0 19.8 14.0 0.005 35.0 1489 94
2014-04-12T15:32:37.721Z,1397316757.721 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:99 Calculated:125
2014-04-12T15:32:37.721Z,1397316757.721 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 18.4.7 19.5 -282.7 34.6 366.6 68.0 -264 47 391 172 3 4 6 4 -866.6 -44.6 50.2 1 -28.0 -831.0 -253.5 1 -874 -166 93 2 74 -886 93 2 -7.84 -20.68 84.0 18.1 13.9 0.005 35.0 1489 99
2014-04-12T15:33:16.223Z,1397316796.223 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:98 Calculated:100
2014-04-12T15:33:16.223Z,1397316796.223 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 8.9 10.6 10.5 9.2 -325.2 0 328.1 28.4 -106 21 450 107 1 2 2 1 -871.9.4 1 130.4 -835.2 -217.0 1 -742 -114 127 2 184 -728 127 2 -7.17 -15.28 95.4 8.9 13.3 0.005 35.0 1489 98
2014-04-12T15:33:27.606Z,1397316807.606 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:105 Calculated:103
2014-04-12T15:33:27.622Z,1397316807.622 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 1 1 1 0 14.9 11.4 13.7 0 38.3 335.0 126.4 -308 -121 299 -23 6 3 1 2 -674.4 -117.6 88.8 1 279.3444.2 1 -810 -130 -41 2 417 -706 -41 2 -4.20 32.36 111.4 11.4 13.1 0.005 35.0 1489 105
2014-04-12T15:33:31.764Z,1397316811.764 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-12T15:33:31.764Z,1397316811.764 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 18.4 15.4 12.8 14.8 -226.8 69.4 378.7 86.1 -449 62 377 -3 4 3 1 2 -808.1 -22.3 82.9 1 310.9 -644.7 384.8 1  -3 2 396 -1032 -3 2 -4.51 22.30 115.5 12.8 13.0 0.005 35.0 1489 73
2014-04-12T15:33:54.535Z,1397316834.535 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-12T15:33:54.540Z,1397316834.540 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 28.0 24.5 19.9 21.6 -276.6 0.5 369.8 105.9 -375 -9 326 -4 7 5 4 4 -862.7 -140.7 53.8 1 354.3 -701.5 3935 -6 -16 2 309 -883 -16 2 -8.20 21.56 108.9 19.9 12.6 0.005 35.0 1489 115
2014-04-12T15:36:14.367Z,1397316974.367 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46
2014-04-12T15:36:14.367Z,1397316974.367 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 10.4 10.2 8.9 9.2 -283.4 -44.9 239.5 9.7 -357 -158 430 -100 2 1 1 1 -697.9 -72.9 -21.4 1 148.1 -678.0 105.7 1 -1050 -77 -49 2 199 -1034 -49 2 -6.52 9.77 96.7 8.9 12.8 0.005 35.0 
2014-04-12T15:36:15.669Z,1397316975.669 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:82 Calculated:76
2014-04-12T15:36:15.670Z,1397316975.670 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 12.2 10.9 8.9 9.7 -243.9 -19.60.5 -399 -135 233 -62 2 1 1 1 -692.0 -120.3 22.0 1 193.9 -622.5 262.1 1 -843 -97 -97 2 215 -821 -97 2 -6.27 19.27 98.1 8.9 12.8 0.005 35.0 1489 82
2014-04-12T15:36:31.274Z,1397316991.274 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 43 of 46
2014-04-12T15:36:31.275Z,1397316991.275 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 18.7 17.6 14.8 15.3 -253.4 2.9 347.4 106.1 -309 -29 323  2 -801.8 -137.7 54.7 1 373.7 -670.3 275.5 1 -843 -64 1 2 355 -767 1 2 -7.62 14.81 110.5 14.8 12.7 0.005 35.0 1489 105
2014-04-12T15:36:41.295Z,1397317001.295 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46
2014-04-12T15:36:41.296Z,1397317001.296 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 23.3 21.5 17.7 18.7 -294.1 -11.8 410.6 110.4 -395 70 377 5 5 4 3 4 -940.5 -163.1 58.0 1 362.1 -797.2 384.6 1 -1030 200 -1014 15 2 -8.75 18.97 106.0 17.7 12.6 0.005 35.0 1489 78
2014-04-12T15:37:02.436Z,1397317022.436 [DVL_micro](ERROR): No DVL communication! Re-initializing
2014-04-12T15:37:02.436Z,1397317022.436 [DVL_micro] Communications Fault, FailCount= 1
2014-04-12T15:37:02.436Z,1397317022.436 [DVL_micro](ERROR): Communications Fault
2014-04-12T15:37:02.523Z,1397317022.523 [CBIT](ERROR): Communications Fault in component: DVL_micro
2014-04-12T15:37:02.993Z,1397317022.993 [DVL_micro](INFO): uninitialize:Powering down
2014-04-12T15:37:03.597Z,1397317023.597 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-04-12T15:37:03.597Z,1397317023.597 [DVL_micro] Hardware Fault, FailCount= 1
2014-04-12T15:37:03.597Z,1397317023.597 [DVL_micro](ERROR): Hardware Fault
2014-04-12T15:37:04.198Z,1397317024.198 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-04-12T15:37:04.198Z,1397317024.198 [DVL_micro] No Fault, FailCount= 1
2014-04-12T15:37:04.662Z,1397317024.662 [DVL_micro](INFO): Initializing
2014-04-12T15:37:22.540Z,1397317042.540 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:109 Calculated:121
2014-04-12T15:37:22.540Z,1397317042.540 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 46.3 38.6 32.1 36.1 -502.3 -14.7 283.1 -29 -60 308 -274 14 11 7 9 -1048.2 254.2 -118.4 1 132.7 -1051.9 231.1 1 -1157 285 -157 2 156 -1181 -157 2 -5.92 20.53 111.4 32.1 12.3 0.005 35.0 1489 109
2014-04-12T15:37:44.430Z,1397317064.430 [DVL_micro](ERROR): No DVL communication! Re-initializing
2014-04-12T15:37:44.431Z,1397317064.431 [DVL_micro] Communications Fault, FailCount= 1
2014-04-12T15:37:44.431Z,1397317064.431 [DVL_micro](ERROR): Communications Fault
2014-04-12T15:37:44.591Z,1397317064.591 [CBIT](ERROR): Communications Fault in component: DVL_micro
2014-04-12T15:37:45.139Z,1397317065.139 [DVL_micro](INFO): uninitialize:Powering down
2014-04-12T15:37:45.825Z,1397317065.825 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-04-12T15:37:45.825Z,1397317065.825 [DVL_micro] Hardware Fault, FailCount= 1
2014-04-12T15:37:45.826Z,1397317065.826 [DVL_micro](ERROR): Hardware Fault
2014-04-12T15:37:46.546Z,1397317066.546 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-04-12T15:37:46.547Z,1397317066.547 [DVL_micro] No Fault, FailCount= 1
2014-04-12T15:37:46.997Z,1397317066.997 [DVL_micro](INFO): Initializing
2014-04-12T15:39:05.706Z,1397317145.706 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:104 Calculated:124
2014-04-12T15:39:05.707Z,1397317145.707 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 8.0 9.5 10.7 8.7 -314.1 -51.7 281.5 30.9 0 197 312 70 0 1 1 1 -794.9 -110.2 -14.4 1 2.6 -230.5 1 -356 169 144 2 -181 -350 144 2 -6.50 -16.77 88.0 8.0 12.5 0.005 35.0 1489 104
2014-04-12T15:39:09.766Z,1397317149.766 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46
2014-04-12T15:39:09.766Z,1397317149.766 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 10.0 9.4 8.0 8.5 -233.9 34.4 296.9 35.1 -440 114 289 -158 2 1 1 1 -708.4 -0.9 35.7 1 72.9 -667.5 228.8 1 -973 363 -52 2 -234 -1011 -52 2 -6.17 15.95 97.4 8.0 12.4 0.00589 100
2014-04-12T15:39:13.898Z,1397317153.898 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:72 Calculated:88
2014-04-12T15:39:13.898Z,1397317153.898 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 1 1 1 0 11.9 9.3 11.9 0 -9.0 270.5 -12.5 -134 -69 251 -125 4 1 1 1 -750.7 4.7 -11.6 1 15.6 405.8 1 -513 74 -20 2 60 -515 -20 2 -1.29 33.62 105.0 9.3 12.4 0.005 35.0 1489 72
2014-04-12T15:39:15.246Z,1397317155.246 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:124 Calculated:102
2014-04-12T15:39:15.247Z,1397317155.247 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 1 1 1 0 13.1 9.9 12.6 0 -21.9 279.9 26.7 -66 29 -713 -53 5 2 1 2 -740.7 -64.9 2.1 -563.4 418.7 1 863 109 -216 2 -377 784 -216 2 -2.79 33.93 108.5 9.9 12.4 0.005 35.0 1489 124
2014-04-12T15:39:26.907Z,1397317166.907 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 4 of 46
2014-04-12T15:39:26.907Z,1397317166.907 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES1 1 1 1 17.5 17.2 14.3 15.2 189.9 46.1 339.1 43.3 -404 132 351 54 3 3 2 2 -199.1 3.7 166.7 1 18.4 -141.5 217.0 1 -1007 104 35 2 292 -969 35 2 -9.85 18.08 112.7 14.3 12.3 0.005 35.0 1489 86
2014-04-12T15:41:57.310Z,1397317317.310 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:103 Calculated:96
2014-04-12T15:41:57.310Z,1397317317.310 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 12.5 15.3 16.8 13.1 -317.9 -10.2 358.9 29.3 -461 176 4 3 3 1 -903.3 -52.7 16.2 1 68.8 -862.1 -266.3 1 -1206 28 84 2 -32 -1206 84 2 -8.54 -18.68 89.8 12.5 12.5 0.005 35.0 1489 103
2014-04-12T15:42:09.455Z,1397317329.455 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 43 of 46
2014-04-12T15:42:09.456Z,1397317329.456 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 9.1 11.4 12.5 9.9 -296.6 -68.2 310.8 26.4 -224 44 630  1 -810.7 -126.3 -7.4 1 125.7 -765.2 -268.2 1 -1139 -46 142 2 18 -1140 142 2 -7.31 -20.01 88.6 9.1 12.5 0.005 35.0 1489 78
2014-04-12T15:42:16.065Z,1397317336.065 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:95 Calculated:89
2014-04-12T15:42:16.065Z,1397317336.065 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 11.9 10.3 8.7 9.4 -265.1 -26.9 289.3 21.5 -339 1 1 2 1 1 -739.9 -64.6 5.1 1 185.0 -670.6 260.2 1 -585 -16 -24 2 130 -571 -24 2 -6.10 19.64 101.3 8.7 12.4 0.005 35.0 1489 95
2014-04-12T15:42:20.029Z,1397317340.029 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-12T15:42:20.029Z,1397317340.029 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 1 1 1 0 13.0 10.1 12.8 0 54.2 302.6 81.2 -99 -93 212 -42 5 27.0 -36.0 73.0 1 147.3 -444.6 424.7 1 -415 -68 -6 2 173 -383 -6 2 -1.83 35.52 105.0 10.1 12.4 0.005 35.0 1489 80
2014-04-12T15:42:32.250Z,1397317352.250 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:83 Calculated:85
2014-04-12T15:42:32.250Z,1397317352.250 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 18.0 17.3 14.8 15.4 -266.29.7 36.7 -387 41 323 -69 4 3 2 2 -795.8 -59.3 24.8 1 334.5 -688.8 225.9 1 -947 146 -24 2 231 -930 -24 2 -8.00 14.10 112.8 14.8 12.3 0.005 35.0 1489 83
2014-04-12T15:42:34.609Z,1397317354.609 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 34 of 46
2014-04-12T15:42:34.609Z,1397317354.609 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 19.5 18.4 15.4 15.8 -263.9 25.4 385.0 99.6 -435.6 1 -1062 -94 20 2 479 -952 20 2 -9.40 14.46 111.6 15.4 12.3 0.005 35.0 1489 119
2014-04-12T15:42:48.044Z,1397317368.044 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:107 Calculated:123
2014-04-12T15:42:48.044Z,1397317368.044 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 26.8 23.8 19.1 20.7 -269.3 91.7 366.0 64.3 -304 0 314 -62 7 5 3 4 -847.9 36.6 68.9 -754.5 351.8 1 -824 82 -14 2 179 -809 -14 2 -7.68 20.25 108.2 19.1 12.2 0.005 35.0 1489 107
2014-04-12T15:43:19.831Z,1397317399.831 [DVL_micro](ERROR): No DVL communication! Re-initializing
2014-04-12T15:43:19.831Z,1397317399.831 [DVL_micro] Communications Fault, FailCount= 1
2014-04-12T15:43:19.831Z,1397317399.831 [DVL_micro](ERROR): Communications Fault
2014-04-12T15:43:19.939Z,1397317399.939 [CBIT](ERROR): Communications Fault in component: DVL_micro
2014-04-12T15:43:20.450Z,1397317400.450 [DVL_micro](INFO): uninitialize:Powering down
2014-04-12T15:43:20.997Z,1397317400.997 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-04-12T15:43:20.997Z,1397317400.997 [DVL_micro] Hardware Fault, FailCount= 1
2014-04-12T15:43:20.997Z,1397317400.997 [DVL_micro](ERROR): Hardware Fault
2014-04-12T15:43:21.750Z,1397317401.750 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-04-12T15:43:21.750Z,1397317401.750 [DVL_micro] No Fault, FailCount= 1
2014-04-12T15:43:22.239Z,1397317402.239 [DVL_micro](INFO): Initializing
2014-04-12T15:45:19.220Z,1397317519.220 [CTD_NeilBrown](ERROR): Bad response: ,33.8768
2014-04-12T15:45:30.249Z,1397317530.249 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:119 Calculated:104
2014-04-12T15:45:30.250Z,1397317530.250 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 8 14.7 15.8 -153.4 105.1 379.2 81.1 -203 62 338 54 4 3 2 3 -710.8 32.0 111.1 1 182.5 -618.4 320.7 1 -722 10 67 2 261 -673 67 2 -8.16 18.11 112.1 14.7 12.0 0.005 35.0 1489 119
2014-04-12T15:46:31.543Z,1397317591.543 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-12T15:46:31.543Z,1397317591.543 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 39.4.5 33.7 -55.3 168.8 386.2 150.0 -140 17 11 10 8 8 -589.2 25.1 175.1 1 72.6 -530.3 303.2 1 -744 104 146 2 72 -748 146 2 -6.62 13.45 103.5 32.5 11.9 0.005 35.0 1489 120
2014-04-12T15:46:32.838Z,1397317592.838 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46
2014-04-12T15:46:32.838Z,1397317592.838 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 36.1 37.0 34.3 33.5 38.9 349.4 652.7 314.9 -98 309 671 299 9 10 8 8 -819.2 46.0 365.6 1 59.6 -780.6 440.5 1 -1026 1389 -1008 318 2 -7.07 5.98 101.4 33.5 11.9 0.005 35.0 1489 82
2014-04-12T15:47:11.466Z,1397317631.466 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46
2014-04-12T15:47:11.466Z,1397317631.466 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 23.7 28.3 32.1 25.8 -507.2 -215.0 181.7 -110.6 -415 -65 398 -63 4 7 8 5 -919.4 -139.3 -175.6 1 210.0 -797.8 -463.6 1 -1085 -2 -39 2 48 -1084 -39 2 -6.44 -20.07 92.4 23.7 12.2 0.01489 109
2014-04-12T15:47:25.166Z,1397317645.166 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2014-04-12T15:47:27.776Z,1397317647.776 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2014-04-12T15:47:30.058Z,1397317650.058 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2014-04-12T15:47:32.311Z,1397317652.311 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2014-04-12T15:47:35.166Z,1397317655.166 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2014-04-12T15:47:37.270Z,1397317657.270 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2014-04-12T15:47:39.842Z,1397317659.842 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2014-04-12T15:47:42.279Z,1397317662.279 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2014-04-12T15:47:44.878Z,1397317664.878 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2014-04-12T15:48:10.147Z,1397317690.147 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:74 Calculated:92
2014-04-12T15:48:10.148Z,1397317690.148 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 8.6 10.3 10.0 9.1 -340.7 -46.5 -372.6 -15.1 -186 96 435 134 1 2 1 1 .9 -209.0 1 69.8 102.8 -178.3 1 -828 -50 129 2 40 -829 129 2 -7.67 -17.84 89.3 8.6 12.3 0.005 35.0 1489 74
2014-04-12T15:48:18.413Z,1397317698.413 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:85 Calculated:61
2014-04-12T15:48:18.413Z,1397317698.413 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 01 1 1 0 13.2 10.0 13.1 0 32.1 325.2 -7.1 -253 -74 464 -13 5 2 1 2 -834.7 52.3 13.5 1 101.7 -655.0 510.2 1 -956 -81 33 2 310 -908 33 2 -1.47 36.87 104.0 10.0 12.3 0.005 35.0 1489 85
2014-04-12T15:48:23.690Z,1397317703.690 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:66 Calculated:84
2014-04-12T15:48:23.691Z,1397317703.691 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 1 0 1 0 16. 0 10.5 0 71.9 -152 2 118 13 7 3 7 2 -360.4 -14.7 -5.1 2 161.7 -322.4 -5.1 2 -360 -14 -5 2 161 -322 -5 2 -5.60 33.46 114.3 -2.0 12.2 0.005 35.0 1489 66
2014-04-12T15:49:43.485Z,1397317783.485 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:75 Calculated:74
2014-04-12T15:49:43.486Z,1397317783.486 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 32.5 40.4 43.6 34.3 -278.93.1 -53.6 -291 36 363 -22 7 11 13 8 -683.3 68.7 -27.4 1 10.4 -636.3 -259.7 1 -872 77 23 2 0 -876 23 2 -8.75 -19.11 95.1 32.5 12.0 0.005 35.0 1489 75
2014-04-12T15:49:53.645Z,1397317793.645 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-12T15:49:53.645Z,1397317793.645 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 30.0 34.6 37.0 31.4 -343.8 -126.6 224.3 -26.4 -390 -13 369 24 6 9 10 7 -758.2 -133.7 -73.5 1 237.7 -690.2 -255.5 1 -1013 -49 -2 2 169 -1000 -2 -15.43 96.8 30.0 12.1 0.005 35.0 1489 125
2014-04-12T15:51:27.461Z,1397317887.461 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-12T15:51:27.461Z,1397317887.461 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 18.9 15.9 12.7 14.0 -207.4 37.7 325.3 100.6 -333 37 285 60 4 3 1 2-83.9 69.1 1 280.9 -554.1 362.5 1 -824 -30 13 2 333 -755 13 2 -7.05 24.05 111.7 12.7 05 35.0 1489 79
2014-04-12T15:52:27.548Z,1397317947.548 [AHRS_sp3003D](ERROR): readPitchRollBin UART error: serial timeout
2014-04-12T15:52:27.694Z,1397317947.694 [AHRS_sp3003D](FAULT): Read Heading/Pitch/Roll Failure.
2014-04-12T15:52:27.694Z,1397317947.694 [AHRS_sp3003D] Communications Fault, FailCount= 1
2014-04-12T15:52:27.694Z,1397317947.694 [AHRS_sp3003D](ERROR): Communications Fault
2014-04-12T15:52:27.937Z,1397317947.937 [CBIT](ERROR): Communications Fault in component: AHRS_sp3003D
2014-04-12T15:52:27.976Z,1397317947.976 [AHRS_sp3003D](INFO): Powering down
2014-04-12T15:52:29.360Z,1397317949.360 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2014-04-12T15:52:29.360Z,1397317949.360 [AHRS_sp3003D] No Fault, FailCount= 1
2014-04-12T15:52:29.554Z,1397317949.554 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-04-12T15:52:39.090Z,1397317959.090 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:120 Calculated:125
2014-04-12T15:52:39.091Z,1397317959.091 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 32.9 39.4 46.0 36.8 -247.4 86.8 430.5 90.2 -189 163 542 84 7 11 14 9 -95 97.1 1 37.3 -880.1 -228.5 1 -975 105 161 2 -74 -978 161 2 -4.38 -20.67 91.8 32.9 11.9 0.005 35.0 1489 120
2014-04-12T15:54:09.033Z,1397318049.033 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-12T15:54:09.034Z,1397318049.034 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 10.5 13.0 14.7 11.3 -330.5 -45.2 291.3 -14.2 -351 149 314 247 1 2 3 2 -829.9 -41.4 -26.6 1 99.6 -763.5 -313.7 1 -887 -130 96 2 169 -8-8.06 -20.82 92.5 10.5 12.2 0.005 35.0 1489 104
2014-04-12T15:54:28.247Z,1397318068.247 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:74 Calculated:85
2014-04-12T15:54:28.248Z,1397318068.247 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 1 1 1 0 15.4.3 0 69.1 315.4 59.1 -98 91 569 -48 6 3 1 2 -670.8 13.3 69.1 1 178.5 -489.0 429.0 1 -890 185 138 2 194 -888 138 2 -4.93 33.79 114.1 11.5 12.1 0.005 35.0 1489 74
2014-04-12T15:55:25.398Z,1397318125.398 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46
2014-04-12T15:55:25.399Z,1397318125.399 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 40.0 34.3 27.9 31.0 -419.8 -142.6 246.0 -79.8 -532 -131 257 -8 12 9 6 7 -888.6 -83.8 -106.8 1 368.7 -785.4 234.9 1 -1053 -164 -111 2 517 -931 -111 2 -7.65 21.27 110.2 27.9 11.8 0.005 35.0 
2014-04-12T15:57:32.373Z,1397318252.373 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 43 of 46
2014-04-12T15:57:32.374Z,1397318252.374 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 1 1 1 0 11.9 9.4 11.2 0 99.6 316.2 -9.3 -110 -108 344 15 4723.5 145.3 48.7 1 -21.2 -636.2 376.4 1 -605 -164 38 2 300 -551 38 2 -5.76 28.81 103.4 9.4 12.1 0.005 35.0 1489 86
2014-04-12T15:57:36.352Z,1397318256.352 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 34 of 46
2014-04-12T15:57:36.357Z,1397318256.357 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 1 1 1 0 14.5 11.2 14.7 0 24.6 301.9 -31.0 -111 -56 250 179 6 2 2 3 -814.4 74.2 -3.5 1 123.3 -659.8 467.2 1 -481 -313 70 2 444 - -1.71 35.45 107.6 11.2 12.1 0.005 35.0 1489 87
2014-04-12T15:57:45.680Z,1397318265.680 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-12T15:57:45.680Z,1397318265.680 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 17.9 16.6 14.3 14.9 -212.9 90.7 349.9 48.6 -235 134 33 2 2 -751.1 56.2 74.5 1 222.5 -676.7 255.7 1 -770 110 78 2 219 -746 78 2 -7.91 14.83 114.6 14.3 12.0 0.005 35.0 1489 121
2014-04-12T15:58:26.500Z,1397318306.500 [CTD_NeilBrown](ERROR): Bad response: 1.93863
2014-04-12T15:58:49.447Z,1397318329.447 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:118 Calculated:98
2014-04-12T15:58:49.447Z,1397318329.447 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 1 1 1 0 41.7 33.3 35.3 0 13.4 347.8 98.6 -251 -33 352 6 26 12 .9 -113.7 60.4 1 239.6 -665.3 351.0 1 -804 -52 19 2 243 -768 19 2 -11.00 20.60 103.9 33.3 11.7 0.005 35.0 1489 118
2014-04-12T15:59:12.479Z,1397318352.479 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:81 Calculated:66
2014-04-12T15:59:12.479Z,1397318352.479 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 31.4 36.3 39.1 32.8 -399.1 -110 -93.3 -383 -43 276 -19 7 9 11 8 -765.8 -23.1 -115.5 1 76.8 -709.6 -301.5 1 -879 -32 -45 2 70 -877 -45 2 -6.14 -14.59 92.5 31.4 11.8 0.005 35.0 1489 81
2014-04-12T16:00:02.113Z,1397318402.113 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:99 Calculated:100
2014-04-12T16:00:02.114Z,1397318402.114 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 17.8 22.4 24.9 19.3 -339.1 -46.9 278.2 -5.3 -295 4 413 74 3 5 6 4 -823.9 -55.5 -30.5-764.1 -308.8 1 -944 -93 52 2 70 -946 52 2 -7.40 -20.43 88.6 17.8 12.3 0.005 35.0 1489 99
2014-04-12T16:00:42.160Z,1397318442.160 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 3 of 46
2014-04-12T16:00:42.161Z,1397318442.161 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X00 1 12.1 10.7 8.8 9.4 -269.1 -2.1 265.7 17.5 -540 96 291 -28 2 1 1 1 -713.8 -26.2 3.2 1 182.9 -643.7 249.9 1 -1109 165 -48 2 129 -1114 -48 2 -7.57 19.95 105.1 8.8 12.1 0.005 35.0 1489 85
2014-04-12T16:02:34.774Z,1397318554.774 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 43 of 46
2014-04-12T16:02:34.775Z,1397318554.775 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 28.0 35.3 38.6 29.7 -367.7 -38.3 359.7 42.9 -369 0 520 80 6 9 11 7 -970.8 -108.4 -0.9 1 74.8 -922.4 -312.7 1 -1186 -106 62 2 262 2 -9.71 -19.88 86.2 28.0 12.0 0.00589 119
2014-04-12T16:02:50.276Z,1397318570.276 [CTD_NeilBrown](ERROR): Bad response:  12.73
2014-04-12T16:03:52.145Z,1397318632.145 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46
2014-04-12T16:03:52.145Z,1397318632.145 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 11.4 10.4 8.6 9.3 -273.0 -25.8 295.2 -13.9 -343 96 214 10 1 2 1 1 -758.3 -15.9 -4.7 1 188.5 -695.4 237.1 1 -743 114 -6 2 82 -747 -6 2 -7.19 18.42 1012.1 0.005 35.0 1489 112
2014-04-12T16:04:00.149Z,1397318640.149 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:89 Calculated:33
2014-04-12T16:04:00.149Z,1397318640.149 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES0 1 1 1 0 15.0 11.7 14.2 0 73.6 365.3 75.0 -315 35 397 69 6 3 1 2 -776.8 -1.9 80.1 1 240.5 -594.1 446.1 1 -950 -45 50 2 42