2014-04-13T01:08:50.916Z,1397351330.916 [Supervisor](DEBUG): Initializing supervisor.
2014-04-13T01:08:50.919Z,1397351330.919 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2014-04-13T01:08:50.919Z,1397351330.919 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2014-04-13T01:08:50.924Z,1397351330.924 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0
2014-04-13T01:08:50.928Z,1397351330.928 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2014-04-13T01:08:50.949Z,1397351330.949 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2014-04-13T01:08:50.955Z,1397351330.955 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0
2014-04-13T01:08:50.956Z,1397351330.956 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2014-04-13T01:08:50.959Z,1397351330.959 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403CB4E0
2014-04-13T01:08:50.976Z,1397351330.976 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2014-04-13T01:08:50.978Z,1397351330.978 [Supervisor](INFO): Looking for Config files in directory: Config/
2014-04-13T01:08:50.994Z,1397351330.994 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2014-04-13T01:08:51.578Z,1397351331.578 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2014-04-13T01:08:51.579Z,1397351331.579 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2014-04-13T01:08:52.012Z,1397351332.012 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2014-04-13T01:08:52.013Z,1397351332.013 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2014-04-13T01:08:52.153Z,1397351332.153 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2014-04-13T01:08:52.162Z,1397351332.162 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2014-04-13T01:08:52.472Z,1397351332.472 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2014-04-13T01:08:52.472Z,1397351332.472 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2014-04-13T01:08:52.756Z,1397351332.756 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2014-04-13T01:08:52.772Z,1397351332.772 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2014-04-13T01:08:53.240Z,1397351333.240 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2014-04-13T01:08:53.241Z,1397351333.241 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2014-04-13T01:08:53.510Z,1397351333.510 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2014-04-13T01:08:53.518Z,1397351333.518 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2014-04-13T01:08:54.080Z,1397351334.080 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2014-04-13T01:08:54.086Z,1397351334.086 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2014-04-13T01:08:54.238Z,1397351334.238 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2014-04-13T01:08:54.239Z,1397351334.239 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2014-04-13T01:08:55.049Z,1397351335.049 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2014-04-13T01:08:55.050Z,1397351335.050 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2014-04-13T01:08:55.237Z,1397351335.237 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2014-04-13T01:08:55.237Z,1397351335.237 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2014-04-13T01:08:55.405Z,1397351335.405 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2014-04-13T01:08:55.569Z,1397351335.569 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2014-04-13T01:08:55.590Z,1397351335.590 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2014-04-13T01:08:55.741Z,1397351335.741 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2014-04-13T01:08:55.741Z,1397351335.741 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2014-04-13T01:08:55.926Z,1397351335.926 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2014-04-13T01:08:55.928Z,1397351335.928 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2014-04-13T01:08:55.951Z,1397351335.951 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2014-04-13T01:08:56.122Z,1397351336.122 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2014-04-13T01:08:56.323Z,1397351336.323 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2014-04-13T01:08:56.431Z,1397351336.431 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2014-04-13T01:08:56.535Z,1397351336.535 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2014-04-13T01:08:56.708Z,1397351336.708 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2014-04-13T01:08:56.840Z,1397351336.840 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2014-04-13T01:08:56.939Z,1397351336.939 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2014-04-13T01:08:57.052Z,1397351337.052 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2014-04-13T01:08:57.164Z,1397351337.164 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2014-04-13T01:08:57.177Z,1397351337.177 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2014-04-13T01:08:57.926Z,1397351337.926 [AHRS_sp3003D] Loaded
2014-04-13T01:08:57.926Z,1397351337.926 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2014-04-13T01:08:58.254Z,1397351338.254 [Batt_Ocean_Server] Loaded
2014-04-13T01:08:58.254Z,1397351338.254 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread.
2014-04-13T01:08:58.268Z,1397351338.268 [Depth_Keller] Loaded
2014-04-13T01:08:58.268Z,1397351338.268 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2014-04-13T01:08:58.274Z,1397351338.274 [DropWeight] Loaded
2014-04-13T01:08:58.274Z,1397351338.274 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2014-04-13T01:08:58.430Z,1397351338.430 [DVL_micro] Loaded
2014-04-13T01:08:58.430Z,1397351338.430 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2014-04-13T01:08:58.543Z,1397351338.543 [NAL9602] Loaded
2014-04-13T01:08:58.543Z,1397351338.543 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2014-04-13T01:08:58.606Z,1397351338.606 [Onboard] Loaded
2014-04-13T01:08:58.607Z,1397351338.607 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2014-04-13T01:08:58.608Z,1397351338.608 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2014-04-13T01:08:58.608Z,1397351338.608 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2014-04-13T01:08:59.380Z,1397351339.380 [InternalSim] Loaded
2014-04-13T01:08:59.380Z,1397351339.380 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2014-04-13T01:08:59.380Z,1397351339.380 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2014-04-13T01:08:59.381Z,1397351339.381 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2014-04-13T01:08:59.661Z,1397351339.661 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2014-04-13T01:08:59.662Z,1397351339.662 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2014-04-13T01:08:59.671Z,1397351339.671 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2014-04-13T01:08:59.676Z,1397351339.676 [AsyncPiEstimator] Loaded
2014-04-13T01:08:59.676Z,1397351339.676 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2014-04-13T01:08:59.678Z,1397351339.678 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 4069E4E0
2014-04-13T01:08:59.678Z,1397351339.678 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2014-04-13T01:08:59.679Z,1397351339.679 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2014-04-13T01:08:59.771Z,1397351339.771 [DeadReckonUsingMultipleVelocitySources] Loaded
2014-04-13T01:08:59.772Z,1397351339.772 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2014-04-13T01:08:59.788Z,1397351339.788 [NavChart] Loaded
2014-04-13T01:08:59.789Z,1397351339.789 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2014-04-13T01:08:59.789Z,1397351339.789 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2014-04-13T01:08:59.790Z,1397351339.790 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2014-04-13T01:08:59.857Z,1397351339.857 [VerticalControl](DEBUG): Construct VerticalControl.
2014-04-13T01:08:59.967Z,1397351339.967 [VerticalControl] Loaded
2014-04-13T01:08:59.968Z,1397351339.968 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2014-04-13T01:08:59.968Z,1397351339.968 [HorizontalControl](DEBUG): Construct HorizontalControl.
2014-04-13T01:09:00.034Z,1397351340.034 [HorizontalControl] Loaded
2014-04-13T01:09:00.035Z,1397351340.035 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2014-04-13T01:09:00.036Z,1397351340.036 [SpeedControl](DEBUG): Construct SpeedControl.
2014-04-13T01:09:00.037Z,1397351340.037 [SpeedControl] Loaded
2014-04-13T01:09:00.038Z,1397351340.038 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2014-04-13T01:09:00.038Z,1397351340.038 [LoopControl](DEBUG): Construct LoopControl.
2014-04-13T01:09:00.039Z,1397351340.039 [LoopControl] Loaded
2014-04-13T01:09:00.039Z,1397351340.039 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2014-04-13T01:09:00.040Z,1397351340.040 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2014-04-13T01:09:00.040Z,1397351340.040 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2014-04-13T01:09:00.069Z,1397351340.069 [DepthRateCalculator] Loaded
2014-04-13T01:09:00.069Z,1397351340.069 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2014-04-13T01:09:00.075Z,1397351340.075 [PitchRateCalculator] Loaded
2014-04-13T01:09:00.075Z,1397351340.075 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2014-04-13T01:09:00.082Z,1397351340.082 [SpeedCalculator] Loaded
2014-04-13T01:09:00.083Z,1397351340.083 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2014-04-13T01:09:00.099Z,1397351340.099 [TempGradientCalculator] Loaded
2014-04-13T01:09:00.099Z,1397351340.099 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2014-04-13T01:09:00.105Z,1397351340.105 [YawRateCalculator] Loaded
2014-04-13T01:09:00.105Z,1397351340.105 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2014-04-13T01:09:00.105Z,1397351340.105 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2014-04-13T01:09:00.106Z,1397351340.106 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2014-04-13T01:09:00.233Z,1397351340.233 [CTD_NeilBrown] Loaded
2014-04-13T01:09:00.233Z,1397351340.233 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2014-04-13T01:09:00.234Z,1397351340.234 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 407404E0
2014-04-13T01:09:00.270Z,1397351340.270 [WetLabsBB2FL] Loaded
2014-04-13T01:09:00.270Z,1397351340.270 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2014-04-13T01:09:00.271Z,1397351340.271 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407704E0
2014-04-13T01:09:00.272Z,1397351340.272 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2014-04-13T01:09:00.274Z,1397351340.274 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2014-04-13T01:09:00.390Z,1397351340.390 [SBIT](DEBUG): Construct Startup Built In Test.
2014-04-13T01:09:00.401Z,1397351340.401 [SBIT] Loaded
2014-04-13T01:09:00.402Z,1397351340.402 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2014-04-13T01:09:00.402Z,1397351340.402 [IBIT](DEBUG): Construct Initiated Built In Test.
2014-04-13T01:09:00.415Z,1397351340.415 [IBIT] Loaded
2014-04-13T01:09:00.416Z,1397351340.416 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2014-04-13T01:09:00.418Z,1397351340.418 [CBIT](DEBUG): Construct CBIT Built In Test.
2014-04-13T01:09:00.486Z,1397351340.486 [CBIT] Loaded
2014-04-13T01:09:00.487Z,1397351340.487 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2014-04-13T01:09:00.487Z,1397351340.487 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2014-04-13T01:09:00.489Z,1397351340.489 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2014-04-13T01:09:00.558Z,1397351340.558 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2014-04-13T01:09:00.559Z,1397351340.559 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2014-04-13T01:09:00.677Z,1397351340.677 [BuoyancyServo] Loaded
2014-04-13T01:09:00.678Z,1397351340.678 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2014-04-13T01:09:00.690Z,1397351340.690 [ElevatorServo] Loaded
2014-04-13T01:09:00.690Z,1397351340.690 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2014-04-13T01:09:00.702Z,1397351340.702 [MassServo] Loaded
2014-04-13T01:09:00.702Z,1397351340.702 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2014-04-13T01:09:00.714Z,1397351340.714 [RudderServo] Loaded
2014-04-13T01:09:00.714Z,1397351340.714 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2014-04-13T01:09:00.725Z,1397351340.725 [ThrusterServo] Loaded
2014-04-13T01:09:00.726Z,1397351340.726 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2014-04-13T01:09:00.726Z,1397351340.726 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2014-04-13T01:09:00.727Z,1397351340.727 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2014-04-13T01:09:00.765Z,1397351340.765 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2014-04-13T01:09:00.767Z,1397351340.767 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2014-04-13T01:09:00.768Z,1397351340.768 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2014-04-13T01:09:00.775Z,1397351340.775 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2014-04-13T01:09:00.776Z,1397351340.776 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 4083E4E0
2014-04-13T01:09:00.781Z,1397351340.781 [Supervisor](DEBUG): Running supervisor.
2014-04-13T01:09:00.782Z,1397351340.782 [CommandLine](INFO): Thread ID is 771
2014-04-13T01:09:00.785Z,1397351340.785 [controlThread](INFO): Thread ID is 770
2014-04-13T01:09:00.785Z,1397351340.785 [controlThread](DEBUG): Initializing ControlThread
2014-04-13T01:09:00.786Z,1397351340.786 [CycleStarter](INFO): Thread ID is 769
2014-04-13T01:09:00.789Z,1397351340.789 [logger](INFO): Thread ID is 772
2014-04-13T01:09:00.809Z,1397351340.809 [AsyncPiEstimator](INFO): Thread ID is 847
2014-04-13T01:09:00.809Z,1397351340.809 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2014-04-13T01:09:00.837Z,1397351340.837 [CTD_NeilBrown](INFO): Thread ID is 848
2014-04-13T01:09:00.838Z,1397351340.838 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2014-04-13T01:09:00.841Z,1397351340.841 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-04-13T01:09:00.847Z,1397351340.847 [WetLabsBB2FL](INFO): Thread ID is 849
2014-04-13T01:09:00.848Z,1397351340.848 [WetLabsBB2FL](INFO): Powering down
2014-04-13T01:09:00.873Z,1397351340.873 [NavChartDb](INFO): Thread ID is 850
2014-04-13T01:09:00.880Z,1397351340.880 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2014-04-13T01:09:00.883Z,1397351340.883 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2014-04-13T01:09:00.883Z,1397351340.883 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2014-04-13T01:09:00.884Z,1397351340.884 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2014-04-13T01:09:00.884Z,1397351340.884 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2014-04-13T01:09:00.884Z,1397351340.884 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2014-04-13T01:09:00.885Z,1397351340.885 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2014-04-13T01:09:00.885Z,1397351340.885 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2014-04-13T01:09:00.885Z,1397351340.885 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2014-04-13T01:09:02.468Z,1397351342.468 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized
2014-04-13T01:09:02.515Z,1397351342.515 [InternalSim](DEBUG):  InternalSim initializing...
2014-04-13T01:09:02.816Z,1397351342.816 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2014-04-13T01:09:02.817Z,1397351342.817 [NavChart](DEBUG): Initialize NavChart Navigation.
2014-04-13T01:09:02.817Z,1397351342.817 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2014-04-13T01:09:02.819Z,1397351342.819 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2014-04-13T01:09:02.835Z,1397351342.835 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2014-04-13T01:09:02.836Z,1397351342.836 [LoopControl](DEBUG): Initialize LoopControlComponent.
2014-04-13T01:09:02.836Z,1397351342.836 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2014-04-13T01:09:02.837Z,1397351342.837 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2014-04-13T01:09:02.837Z,1397351342.837 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2014-04-13T01:09:02.837Z,1397351342.837 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2014-04-13T01:09:02.838Z,1397351342.838 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2014-04-13T01:09:02.838Z,1397351342.838 [SBIT](INFO): Initialize SBIT Component.
2014-04-13T01:09:02.839Z,1397351342.839 [SBIT](IMPORTANT): Tethys CM Info: 
 $Rev:11196
2014-04-13T01:09:02.839Z,1397351342.839 [SBIT](IMPORTANT): Kernel Release:2.6.27.8
2014-04-13T01:09:02.840Z,1397351342.840 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2014-04-13T01:09:02.840Z,1397351342.840 [IBIT](INFO): Initialize IBIT Component.
2014-04-13T01:09:02.841Z,1397351342.841 [CBIT](DEBUG): Initialize CBIT Component.
2014-04-13T01:09:02.841Z,1397351342.841 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2014-04-13T01:09:02.870Z,1397351342.870 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2014-04-13T01:09:02.911Z,1397351342.911 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-04-13T01:09:02.940Z,1397351342.940 [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-13T01:09:02.940Z,1397351342.940 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2014-04-13T01:09:03.025Z,1397351343.025 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2014-04-13T01:09:03.027Z,1397351343.027 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2014-04-13T01:09:03.045Z,1397351343.045 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-04-13T01:09:03.054Z,1397351343.054 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2014-04-13T01:09:03.057Z,1397351343.057 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-04-13T01:09:03.076Z,1397351343.076 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2014-04-13T01:09:03.134Z,1397351343.134 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed](DEBUG): Construct.
2014-04-13T01:09:03.137Z,1397351343.137 [Default:WaitAtTheSurface:B.GoToSurface](DEBUG): Construct GoToSurface.
2014-04-13T01:09:03.152Z,1397351343.152 [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-13T01:09:03.172Z,1397351343.172 [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-13T01:09:03.253Z,1397351343.253 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-04-13T01:09:03.440Z,1397351343.440 [DVL_micro](INFO): Initializing
2014-04-13T01:09:03.716Z,1397351343.716 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:03.816Z,1397351343.816 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2014-04-13T01:09:03.821Z,1397351343.821 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2014-04-13T01:09:03.828Z,1397351343.828 [ElevatorServo](DEBUG): Initializing EZServoServo.
2014-04-13T01:09:03.833Z,1397351343.833 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2014-04-13T01:09:03.851Z,1397351343.851 [MassServo](DEBUG): Initializing EZServoServo.
2014-04-13T01:09:03.853Z,1397351343.853 [MassServo](DEBUG): Initializing MassServo.
2014-04-13T01:09:03.859Z,1397351343.859 [RudderServo](DEBUG): Initializing EZServoServo.
2014-04-13T01:09:03.865Z,1397351343.865 [RudderServo](DEBUG): Initializing RudderServo.
2014-04-13T01:09:03.871Z,1397351343.871 [ThrusterServo](DEBUG): Initializing EZServoServo.
2014-04-13T01:09:03.877Z,1397351343.877 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2014-04-13T01:09:04.140Z,1397351344.140 [NAL9602](INFO): Powering up NAL9602
2014-04-13T01:09:04.169Z,1397351344.169 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:04.735Z,1397351344.735 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:05.478Z,1397351345.478 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:06.020Z,1397351346.020 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:06.469Z,1397351346.469 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:07.086Z,1397351347.086 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:07.539Z,1397351347.539 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:08.316Z,1397351348.316 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:09.271Z,1397351349.271 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:09.738Z,1397351349.738 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:10.219Z,1397351350.219 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:10.670Z,1397351350.670 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:11.108Z,1397351351.108 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:11.557Z,1397351351.557 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:12.049Z,1397351352.049 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:12.475Z,1397351352.475 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:12.968Z,1397351352.968 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:13.373Z,1397351353.373 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:13.758Z,1397351353.758 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:14.244Z,1397351354.244 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:14.711Z,1397351354.711 [NAL9602](INFO): NAL9602 initialized
2014-04-13T01:09:14.734Z,1397351354.734 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:15.216Z,1397351355.216 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:15.700Z,1397351355.700 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:16.204Z,1397351356.204 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:16.796Z,1397351356.796 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:17.226Z,1397351357.226 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:17.683Z,1397351357.683 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:18.328Z,1397351358.328 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:18.810Z,1397351358.810 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:18.862Z,1397351358.862 [SBIT](IMPORTANT): Beginning Startup BIT
2014-04-13T01:09:18.865Z,1397351358.865 [CBIT](IMPORTANT): Beginning GF scan
2014-04-13T01:09:19.334Z,1397351359.334 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:19.813Z,1397351359.813 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:20.312Z,1397351360.312 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:20.796Z,1397351360.796 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:21.373Z,1397351361.373 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:21.942Z,1397351361.942 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:22.492Z,1397351362.492 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:23.231Z,1397351363.231 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:23.847Z,1397351363.847 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:24.413Z,1397351364.413 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:25.031Z,1397351365.031 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:25.549Z,1397351365.549 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:26.022Z,1397351366.022 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:26.507Z,1397351366.507 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:27.006Z,1397351367.006 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:27.470Z,1397351367.470 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:27.927Z,1397351367.927 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:28.624Z,1397351368.624 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:29.093Z,1397351369.093 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:29.725Z,1397351369.725 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:30.185Z,1397351370.185 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:30.606Z,1397351370.606 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:31.078Z,1397351371.078 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:31.504Z,1397351371.504 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:31.984Z,1397351371.984 [NAL9602](INFO): SBD MO Status=0, MOMSN=25088, MT Status=0, MTMSN=0
2014-04-13T01:09:31.984Z,1397351371.984 [NAL9602](INFO): No messages in MT queue
2014-04-13T01:09:32.023Z,1397351372.023 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:32.469Z,1397351372.469 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:32.926Z,1397351372.926 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:33.542Z,1397351373.542 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:34.099Z,1397351374.099 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:34.970Z,1397351374.970 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:35.608Z,1397351375.608 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:36.103Z,1397351376.103 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:36.632Z,1397351376.632 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2014-04-13T01:09:37.096Z,1397351377.096 [NAL9602](IMPORTANT): GPS fix at: 1397351342.00
2014-04-13T01:09:37.167Z,1397351377.167 [DeadReckonUsingMultipleVelocitySources](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07
2014-04-13T01:09:45.264Z,1397351385.264 [NAL9602](INFO): Powering down
2014-04-13T01:09:45.371Z,1397351385.371 [CBIT](IMPORTANT): No ground fault detected
2014-04-13T01:10:13.038Z,1397351413.038 [SBIT](IMPORTANT): SBIT PASSED
2014-04-13T01:10:13.447Z,1397351413.447 [MissionManager](IMPORTANT): Started mission Startup
2014-04-13T01:10:13.448Z,1397351413.448 [Startup] Running Loop=1
2014-04-13T01:10:13.448Z,1397351413.448 [Startup](INFO): Aggregate::initialize Startup
2014-04-13T01:10:13.448Z,1397351413.448 [Startup:A.GoToSurface] Running Loop=1
2014-04-13T01:10:13.448Z,1397351413.448 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-04-13T01:10:13.474Z,1397351413.474 [Startup:StartupSatComms] Running Loop=1
2014-04-13T01:10:13.474Z,1397351413.474 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2014-04-13T01:10:13.474Z,1397351413.474 [Startup:StartupSatComms:A] Running Loop=1
2014-04-13T01:10:13.889Z,1397351413.889 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2014-04-13T01:10:14.342Z,1397351414.342 [NAL9602](INFO): Powering up
2014-04-13T01:10:25.272Z,1397351425.272 [NAL9602](INFO): NAL9602 initialized
2014-04-13T01:10:42.641Z,1397351442.641 [NAL9602](INFO): SBD MO Status=0, MOMSN=25089, MT Status=0, MTMSN=0
2014-04-13T01:10:42.641Z,1397351442.641 [NAL9602](INFO): No messages in MT queue
2014-04-13T01:11:10.584Z,1397351470.584 [NAL9602](IMPORTANT): GPS fix at: 1397351436.00
2014-04-13T01:11:10.600Z,1397351470.600 [DeadReckonUsingMultipleVelocitySources](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07
2014-04-13T01:11:10.604Z,1397351470.604 [Startup:StartupSatComms:A] Stopped
2014-04-13T01:11:10.604Z,1397351470.604 [Startup:StartupSatComms:B] Running Loop=1
2014-04-13T01:11:11.141Z,1397351471.141 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2014-04-13T01:11:34.394Z,1397351494.394 [NAL9602](INFO): SBD MO Status=1, MOMSN=25090, MT Status=0, MTMSN=0
2014-04-13T01:11:34.443Z,1397351494.443 [NAL9602](INFO): Sent 45 bytes from file Logs/20140413T005134/Courier0012.lzma
2014-04-13T01:11:34.443Z,1397351494.443 [NAL9602](INFO): Packets left to send: 0
2014-04-13T01:11:34.445Z,1397351494.445 [NAL9602](INFO): Stored copy of sent data in Logs/20140413T005134/Courier0012.lzma.parts/0000.sbd
2014-04-13T01:11:34.445Z,1397351494.445 [NAL9602](INFO): Completed sending Logs/20140413T005134/Courier0012.lzma
2014-04-13T01:11:44.895Z,1397351504.895 [NAL9602](INFO): SBD MO Status=1, MOMSN=25091, MT Status=0, MTMSN=0
2014-04-13T01:11:44.943Z,1397351504.943 [NAL9602](INFO): Sent 294 bytes from file Logs/20140413T010850/Courier0000.lzma
2014-04-13T01:11:44.943Z,1397351504.943 [NAL9602](INFO): Packets left to send: 0
2014-04-13T01:11:44.945Z,1397351504.945 [NAL9602](INFO): Stored copy of sent data in Logs/20140413T010850/Courier0000.lzma.parts/0000.sbd
2014-04-13T01:11:44.945Z,1397351504.945 [NAL9602](INFO): Completed sending Logs/20140413T010850/Courier0000.lzma
2014-04-13T01:11:56.569Z,1397351516.569 [NAL9602](INFO): SBD MO Status=1, MOMSN=25092, MT Status=0, MTMSN=0
2014-04-13T01:11:56.617Z,1397351516.617 [NAL9602](INFO): Sent 143 bytes from file Logs/20140413T005134/Express0013.lzma
2014-04-13T01:11:56.618Z,1397351516.618 [NAL9602](INFO): Packets left to send: 0
2014-04-13T01:11:56.619Z,1397351516.619 [NAL9602](INFO): Stored copy of sent data in Logs/20140413T005134/Express0013.lzma.parts/0000.sbd
2014-04-13T01:11:56.620Z,1397351516.620 [NAL9602](INFO): Completed sending Logs/20140413T005134/Express0013.lzma
2014-04-13T01:12:09.433Z,1397351529.433 [NAL9602](INFO): SBD MO Status=1, MOMSN=25093, MT Status=0, MTMSN=0
2014-04-13T01:12:09.485Z,1397351529.485 [NAL9602](INFO): Sent 332 bytes from file Logs/20140413T010850/Express0001.lzma
2014-04-13T01:12:09.486Z,1397351529.486 [NAL9602](INFO): Packets left to send: 1
2014-04-13T01:12:09.487Z,1397351529.487 [NAL9602](INFO): Stored copy of sent data in Logs/20140413T010850/Express0001.lzma.parts/0001.sbd
2014-04-13T01:12:13.025Z,1397351533.025 [Startup:StartupSatComms:B](INFO): Timed out from 2014-04-13T01:11:10.6Z
2014-04-13T01:12:13.025Z,1397351533.025 [Startup:StartupSatComms:B] Stopped
2014-04-13T01:12:13.026Z,1397351533.026 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2014-04-13T01:12:13.026Z,1397351533.026 [Startup:StartupSatComms] Stopped
2014-04-13T01:12:13.026Z,1397351533.026 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2014-04-13T01:12:13.027Z,1397351533.027 [Startup](INFO): Completed Startup
2014-04-13T01:12:13.027Z,1397351533.027 [Startup] Stopped
2014-04-13T01:12:13.027Z,1397351533.027 [Startup](INFO): Aggregate::uninitialize Startup
2014-04-13T01:12:13.027Z,1397351533.027 [Startup:A.GoToSurface] Stopped
2014-04-13T01:12:13.027Z,1397351533.027 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-04-13T01:12:13.616Z,1397351533.616 [MissionManager](IMPORTANT): Started mission Default
2014-04-13T01:12:13.616Z,1397351533.616 [Default] Running Loop=1
2014-04-13T01:12:13.616Z,1397351533.616 [Default](INFO): Aggregate::initialize Default
2014-04-13T01:12:13.616Z,1397351533.616 [Default:Iridium] Running Loop=1
2014-04-13T01:12:13.616Z,1397351533.616 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2014-04-13T01:12:13.616Z,1397351533.616 [Default:Iridium:A.SetSpeed] Running Loop=1
2014-04-13T01:12:13.616Z,1397351533.616 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2014-04-13T01:12:13.616Z,1397351533.616 [Default:Iridium:B.GoToSurface] Running Loop=1
2014-04-13T01:12:13.617Z,1397351533.617 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-04-13T01:12:13.617Z,1397351533.617 [Default:CallIridium] Running Loop=1
2014-04-13T01:12:13.617Z,1397351533.617 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2014-04-13T01:12:13.617Z,1397351533.617 [Default:CallIridium:A] Running Loop=1
2014-04-13T01:12:13.620Z,1397351533.620 [Default:CallIridium:A] Stopped
2014-04-13T01:12:13.620Z,1397351533.620 [Default:CallIridium:B] Running Loop=1
2014-04-13T01:12:13.620Z,1397351533.620 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2014-04-13T01:12:13.626Z,1397351533.626 [Default:Iridium:B.GoToSurface] Stopped
2014-04-13T01:12:13.626Z,1397351533.626 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-04-13T01:12:13.626Z,1397351533.626 [Default:Iridium:Read_Iridium] Running Loop=1
2014-04-13T01:12:13.626Z,1397351533.626 [Default:Iridium:A.SetSpeed] Running Loop=1
2014-04-13T01:12:13.632Z,1397351533.632 [Default:GPS] Running Loop=1
2014-04-13T01:12:13.632Z,1397351533.632 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2014-04-13T01:12:13.632Z,1397351533.632 [Default:GPS:A.SetSpeed] Running Loop=1
2014-04-13T01:12:13.632Z,1397351533.632 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2014-04-13T01:12:13.632Z,1397351533.632 [Default:GPS:B.GoToSurface] Running Loop=1
2014-04-13T01:12:13.632Z,1397351533.632 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-04-13T01:12:13.638Z,1397351533.638 [Default:GPS:B.GoToSurface] Stopped
2014-04-13T01:12:13.638Z,1397351533.638 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-04-13T01:12:13.638Z,1397351533.638 [Default:GPS:Read_GPS] Running Loop=1
2014-04-13T01:12:13.638Z,1397351533.638 [Default:GPS:A.SetSpeed] Running Loop=1
2014-04-13T01:12:14.113Z,1397351534.113 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2014-04-13T01:12:14.116Z,1397351534.116 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2014-04-13T01:12:19.836Z,1397351539.836 [NAL9602](INFO): SBD MO Status=1, MOMSN=25094, MT Status=0, MTMSN=0
2014-04-13T01:12:19.898Z,1397351539.898 [NAL9602](INFO): Sent 243 bytes from file Logs/20140413T010850/Express0001.lzma
2014-04-13T01:12:19.898Z,1397351539.898 [NAL9602](INFO): Packets left to send: 0
2014-04-13T01:12:19.899Z,1397351539.899 [NAL9602](INFO): Stored copy of sent data in Logs/20140413T010850/Express0001.lzma.parts/0000.sbd
2014-04-13T01:12:19.899Z,1397351539.899 [NAL9602](INFO): Completed sending Logs/20140413T010850/Express0001.lzma
2014-04-13T01:12:29.307Z,1397351549.307 [NAL9602](INFO): SBD MO Status=1, MOMSN=25095, MT Status=0, MTMSN=0
2014-04-13T01:12:29.360Z,1397351549.360 [NAL9602](INFO): Sent 77 bytes from file Logs/20140413T010850/Courier0004.lzma
2014-04-13T01:12:29.361Z,1397351549.361 [NAL9602](INFO): Packets left to send: 0
2014-04-13T01:12:29.362Z,1397351549.362 [NAL9602](INFO): Stored copy of sent data in Logs/20140413T010850/Courier0004.lzma.parts/0000.sbd
2014-04-13T01:12:29.362Z,1397351549.362 [NAL9602](INFO): Completed sending Logs/20140413T010850/Courier0004.lzma
2014-04-13T01:12:40.825Z,1397351560.825 [NAL9602](INFO): SBD MO Status=1, MOMSN=25096, MT Status=0, MTMSN=0
2014-04-13T01:12:40.870Z,1397351560.870 [NAL9602](INFO): Sent 126 bytes from file Logs/20140413T010850/Express0005.lzma
2014-04-13T01:12:40.870Z,1397351560.870 [NAL9602](INFO): Packets left to send: 0
2014-04-13T01:12:40.872Z,1397351560.872 [NAL9602](INFO): Stored copy of sent data in Logs/20140413T010850/Express0005.lzma.parts/0000.sbd
2014-04-13T01:12:40.872Z,1397351560.872 [NAL9602](INFO): Completed sending Logs/20140413T010850/Express0005.lzma
2014-04-13T01:12:51.546Z,1397351571.546 [NAL9602](INFO): SBD MO Status=0, MOMSN=25097, MT Status=0, MTMSN=0
2014-04-13T01:12:51.625Z,1397351571.625 [Default:Iridium:Read_Iridium] Stopped
2014-04-13T01:12:51.625Z,1397351571.625 [Default:Iridium](INFO): Completed Default:Iridium
2014-04-13T01:12:51.625Z,1397351571.625 [Default:Iridium] Stopped
2014-04-13T01:12:51.626Z,1397351571.626 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2014-04-13T01:12:51.626Z,1397351571.626 [Default:Iridium:A.SetSpeed] Stopped
2014-04-13T01:12:51.626Z,1397351571.626 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2014-04-13T01:12:51.626Z,1397351571.626 [Default:WaitAtTheSurface] Running Loop=1
2014-04-13T01:12:51.626Z,1397351571.626 [Default:WaitAtTheSurface](INFO): Aggregate::initialize Default:WaitAtTheSurface
2014-04-13T01:12:51.626Z,1397351571.626 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed] Running Loop=1
2014-04-13T01:12:51.626Z,1397351571.626 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed](DEBUG): Initialize.
2014-04-13T01:12:51.627Z,1397351571.627 [Default:WaitAtTheSurface:B.GoToSurface] Running Loop=1
2014-04-13T01:12:51.627Z,1397351571.627 [Default:WaitAtTheSurface:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-04-13T01:12:52.029Z,1397351572.029 [Default:WaitAtTheSurface:B.GoToSurface] Running Loop=1
2014-04-13T01:12:52.034Z,1397351572.034 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed] Running Loop=1
2014-04-13T01:12:52.040Z,1397351572.040 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2014-04-13T01:12:52.040Z,1397351572.040 [Default:CallIridium:B] Stopped
2014-04-13T01:12:52.040Z,1397351572.040 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2014-04-13T01:12:52.040Z,1397351572.040 [Default:CallIridium](INFO): Completed Default:CallIridium
2014-04-13T01:12:52.040Z,1397351572.040 [Default:CallIridium] Stopped
2014-04-13T01:12:52.040Z,1397351572.040 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2014-04-13T01:12:52.893Z,1397351572.893 [NAL9602](IMPORTANT): GPS fix at: 1397351539.00
2014-04-13T01:12:52.904Z,1397351572.904 [DeadReckonUsingMultipleVelocitySources](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07
2014-04-13T01:12:52.909Z,1397351572.909 [Default:GPS:Read_GPS] Stopped
2014-04-13T01:12:52.909Z,1397351572.909 [Default:GPS:D] Running Loop=1
2014-04-13T01:12:53.402Z,1397351573.402 [Default:GPS:D] Stopped
2014-04-13T01:12:53.402Z,1397351573.402 [Default:GPS](INFO): Completed Default:GPS
2014-04-13T01:12:53.402Z,1397351573.402 [Default:GPS] Stopped
2014-04-13T01:12:53.402Z,1397351573.402 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2014-04-13T01:12:53.402Z,1397351573.402 [Default:GPS:A.SetSpeed] Stopped
2014-04-13T01:12:53.402Z,1397351573.402 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2014-04-13T01:13:14.004Z,1397351594.004 [NAL9602](INFO): Powering down
2014-04-13T01:17:54.000Z,1397351874.000 [Default:CallIridium] Running Loop=1
2014-04-13T01:17:54.000Z,1397351874.000 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2014-04-13T01:17:54.000Z,1397351874.000 [Default:CallIridium:A] Running Loop=1
2014-04-13T01:17:54.001Z,1397351874.001 [Default:CallIridium:A] Stopped
2014-04-13T01:17:54.001Z,1397351874.001 [Default:CallIridium:B] Running Loop=1
2014-04-13T01:17:54.001Z,1397351874.001 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2014-04-13T01:17:59.036Z,1397351879.036 [Default:Iridium] Running Loop=1
2014-04-13T01:17:59.036Z,1397351879.036 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2014-04-13T01:17:59.036Z,1397351879.036 [Default:Iridium:A.SetSpeed] Running Loop=1
2014-04-13T01:17:59.037Z,1397351879.037 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2014-04-13T01:17:59.037Z,1397351879.037 [Default:Iridium:B.GoToSurface] Running Loop=1
2014-04-13T01:17:59.037Z,1397351879.037 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-04-13T01:17:59.037Z,1397351879.037 [Default:Iridium:B.GoToSurface] Stopped
2014-04-13T01:17:59.038Z,1397351879.038 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-04-13T01:17:59.038Z,1397351879.038 [Default:Iridium:Read_Iridium] Running Loop=1
2014-04-13T01:17:59.038Z,1397351879.038 [Default:GPS] Running Loop=1
2014-04-13T01:17:59.038Z,1397351879.038 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2014-04-13T01:17:59.038Z,1397351879.038 [Default:GPS:A.SetSpeed] Running Loop=1
2014-04-13T01:17:59.038Z,1397351879.038 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2014-04-13T01:17:59.038Z,1397351879.038 [Default:GPS:B.GoToSurface] Running Loop=1
2014-04-13T01:17:59.039Z,1397351879.039 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-04-13T01:17:59.040Z,1397351879.040 [Default:GPS:B.GoToSurface] Stopped
2014-04-13T01:17:59.040Z,1397351879.040 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-04-13T01:17:59.040Z,1397351879.040 [Default:GPS:Read_GPS] Running Loop=1
2014-04-13T01:18:00.447Z,1397351880.447 [NAL9602](INFO): Powering up
2014-04-13T01:18:11.322Z,1397351891.322 [NAL9602](INFO): NAL9602 initialized
2014-04-13T01:18:28.727Z,1397351908.727 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=25098, MT Status=1, MTMSN=1716
2014-04-13T01:18:28.777Z,1397351908.777 [NAL9602](INFO): Sent 74 bytes from file Logs/20140413T010850/Courier0008.lzma
2014-04-13T01:18:28.778Z,1397351908.778 [NAL9602](INFO): Packets left to send: 0
2014-04-13T01:18:28.779Z,1397351908.779 [NAL9602](INFO): Stored copy of sent data in Logs/20140413T010850/Courier0008.lzma.parts/0000.sbd
2014-04-13T01:18:28.779Z,1397351908.779 [NAL9602](INFO): Completed sending Logs/20140413T010850/Courier0008.lzma
2014-04-13T01:18:29.607Z,1397351909.607 [NAL9602](INFO): Received command:sched asap "load Science/science_to.xml;set science_to.MaxDepth 50 meter;set science_to.YoYoMaxDepth 35 meter;set science_to.Wpt1Lat 33.627258 degree;set science_to.Wpt1Lon -118.060314 degree;set science_to.Wpt2Lat 33.58515 degree" 3ra38 1 2
2014-04-13T01:18:29.620Z,1397351909.620 [CommandLine](IMPORTANT): got command schedule asap "load Science/science_to.xml;set science_to.MaxDepth 50 meter;set science_to.YoYoMaxDepth 35 meter;set science_to.Wpt1Lat 33.627258 degree;set science_to.Wpt1Lon -118.060314 degree;set science_to.Wpt2Lat 33.58515 degree" 3ra38 1 2.000000
2014-04-13T01:18:29.621Z,1397351909.621 [CommandLine](IMPORTANT): Scheduling command #1 of 2 with id=3ra38
2014-04-13T01:18:29.628Z,1397351909.628 [CommandLine](IMPORTANT): Scheduled #1 (#1 of 2 with id='3ra38'): "load Science/science_to.xml;set science_to.MaxDepth 50 meter;set science_to.YoYoMaxDepth 35 meter;set science_to.Wpt1Lat 33.627258 degree;set science_to.Wpt1Lon -118.060314 degree;set science_to.Wpt2Lat 33.58515 degree" ASAP
2014-04-13T01:18:49.377Z,1397351929.377 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=25099, MT Status=1, MTMSN=1717
2014-04-13T01:18:49.425Z,1397351929.425 [NAL9602](INFO): Sent 95 bytes from file Logs/20140413T010850/Express0009.lzma
2014-04-13T01:18:49.426Z,1397351929.426 [NAL9602](INFO): Packets left to send: 0
2014-04-13T01:18:49.427Z,1397351929.427 [NAL9602](INFO): Stored copy of sent data in Logs/20140413T010850/Express0009.lzma.parts/0000.sbd
2014-04-13T01:18:49.428Z,1397351929.428 [NAL9602](INFO): Completed sending Logs/20140413T010850/Express0009.lzma
2014-04-13T01:18:50.131Z,1397351930.131 [NAL9602](INFO): Received command:sched asap "set science_to.Wpt2Lon -117.982505 degree;set science_to.NeedCommsTime 45 minute;set science_to.Timeout 3 hour;run " 3ra38 2 2
2014-04-13T01:18:50.168Z,1397351930.168 [CommandLine](IMPORTANT): got command schedule asap "set science_to.Wpt2Lon -117.982505 degree;set science_to.NeedCommsTime 45 minute;set science_to.Timeout 3 hour;run " 3ra38 2 2.000000
2014-04-13T01:18:50.168Z,1397351930.168 [CommandLine](IMPORTANT): Scheduling command #2 of 2 with id=3ra38
2014-04-13T01:18:50.169Z,1397351930.169 [CommandLine](IMPORTANT): Scheduled #2 (#2 of 2 with id='3ra38'): "set science_to.Wpt2Lon -117.982505 degree;set science_to.NeedCommsTime 45 minute;set science_to.Timeout 3 hour;run " ASAP
2014-04-13T01:18:50.288Z,1397351930.288 [CommandLine](IMPORTANT): got command load ./Missions/Science/science_to.xml
2014-04-13T01:18:50.289Z,1397351930.289 [MissionManager](INFO): Loading Mission: ./Missions/Science/science_to.xml
2014-04-13T01:18:50.427Z,1397351930.427 [MissionManager](INFO): DefineArg science_to.MinAltitude = 10.000000 m
2014-04-13T01:18:50.430Z,1397351930.430 [MissionManager](INFO): DefineArg science_to.MaxDepth = 105.000000 m
2014-04-13T01:18:50.433Z,1397351930.433 [MissionManager](INFO): DefineArg science_to.MinOffshore = 2000.000000 m
2014-04-13T01:18:50.436Z,1397351930.436 [MissionManager](INFO): DefineArg science_to.YoYoMinDepth = 3.000000 m
2014-04-13T01:18:50.439Z,1397351930.439 [MissionManager](INFO): DefineArg science_to.YoYoMaxDepth = 100.000000 m
2014-04-13T01:18:50.442Z,1397351930.442 [MissionManager](INFO): DefineArg science_to.YoYoUpPitch = 20.000000 arcdeg
2014-04-13T01:18:50.445Z,1397351930.445 [MissionManager](INFO): DefineArg science_to.YoYoDownPitch = -20.000000 arcdeg
2014-04-13T01:18:50.448Z,1397351930.448 [MissionManager](INFO): DefineArg science_to.Wpt1Lat = 0.000000 arcdeg
2014-04-13T01:18:50.482Z,1397351930.482 [MissionManager](INFO): DefineArg science_to.Wpt1Lon = 0.000000 arcdeg
2014-04-13T01:18:50.485Z,1397351930.485 [MissionManager](INFO): DefineArg science_to.Wpt2Lat = 0.000000 arcdeg
2014-04-13T01:18:50.488Z,1397351930.488 [MissionManager](INFO): DefineArg science_to.Wpt2Lon = 0.000000 arcdeg
2014-04-13T01:18:50.502Z,1397351930.502 [MissionManager](INFO): DefineArg science_to.Wpt3Lat = 0.000000 arcdeg
2014-04-13T01:18:50.505Z,1397351930.505 [MissionManager](INFO): DefineArg science_to.Wpt3Lon = 0.000000 arcdeg
2014-04-13T01:18:50.508Z,1397351930.508 [MissionManager](INFO): DefineArg science_to.Wpt4Lat = 0.000000 arcdeg
2014-04-13T01:18:50.511Z,1397351930.511 [MissionManager](INFO): DefineArg science_to.Wpt4Lon = 0.000000 arcdeg
2014-04-13T01:18:50.514Z,1397351930.514 [MissionManager](INFO): DefineArg science_to.NeedCommsTime = 120.000000 min
2014-04-13T01:18:50.519Z,1397351930.519 [MissionManager](INFO): DefineArg science_to.Speed = 1.000000 m/s
2014-04-13T01:18:50.523Z,1397351930.523 [MissionManager](INFO): DefineArg science_to.Timeout = 36.000000 h
2014-04-13T01:18:50.527Z,1397351930.527 [MissionManager](INFO): DefineArg science_to.BuoyancyNeutral = 0.000300 n/a
2014-04-13T01:18:50.530Z,1397351930.530 [MissionManager](INFO): DefineArg science_to.MassDefault = 0.000000 n/a
2014-04-13T01:18:50.533Z,1397351930.533 [MissionManager](INFO): DefineArg science_to.ZeroDeg = 0.000000 arcdeg
2014-04-13T01:18:50.536Z,1397351930.536 [science_to:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2014-04-13T01:18:50.545Z,1397351930.545 [science_to:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2014-04-13T01:18:50.595Z,1397351930.595 [science_to:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2014-04-13T01:18:50.607Z,1397351930.607 [science_to:D.Pitch](DEBUG): Construct.
2014-04-13T01:18:50.612Z,1397351930.612 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml
2014-04-13T01:18:50.668Z,1397351930.668 [MissionManager](INFO): DefineArg science_to:SURFACECOMMS.SurfaceDepthRate = nan m/s
2014-04-13T01:18:50.681Z,1397351930.681 [MissionManager](INFO): DefineArg science_to:SURFACECOMMS.SurfacePitch = 20.000000 arcdeg
2014-04-13T01:18:50.684Z,1397351930.684 [MissionManager](INFO): DefineArg science_to:SURFACECOMMS.SurfaceSpeed = 1.000000 m/s
2014-04-13T01:18:50.700Z,1397351930.700 [MissionManager](INFO): DefineArg science_to:SURFACECOMMS.IridiumTimeout = 30.000000 min
2014-04-13T01:18:50.702Z,1397351930.702 [MissionManager](INFO): DefineArg science_to:SURFACECOMMS.UnpackSplitAtSurface = 0 bool
2014-04-13T01:18:50.713Z,1397351930.713 [science_to:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface.
2014-04-13T01:18:50.743Z,1397351930.743 [MissionManager](INFO): RedefineArg science_to:SURFACECOMMS.SurfacePitch = value:20.000000 arcdeg
2014-04-13T01:18:50.745Z,1397351930.745 [MissionManager](INFO): RedefineArg science_to:SURFACECOMMS.SurfaceSpeed = reader:science_to.Speed
2014-04-13T01:18:50.750Z,1397351930.750 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2014-04-13T01:18:50.880Z,1397351930.880 [MissionManager](INFO): DefineArg science_to:Science.SampleISUS = 0 bool
2014-04-13T01:18:50.892Z,1397351930.892 [MissionManager](INFO): DefineArg science_to:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2014-04-13T01:18:50.895Z,1397351930.895 [MissionManager](INFO): DefineArg science_to:Science.SampleISUSminPitch = -60.000000 arcdeg
2014-04-13T01:18:50.902Z,1397351930.902 [MissionManager](INFO): DefineArg science_to:Science.PeakDetectChlActive = 0 bool
2014-04-13T01:18:50.905Z,1397351930.905 [MissionManager](INFO): DefineArg science_to:Science.PeakDetectNO3Active = 0 bool
2014-04-13T01:18:50.909Z,1397351930.909 [MissionManager](INFO): DefineArg science_to:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2014-04-13T01:18:50.912Z,1397351930.912 [MissionManager](INFO): DefineArg science_to:Science.ComputeWaterCurrent = 0 bool
2014-04-13T01:18:50.917Z,1397351930.917 [MissionManager](INFO): DefineArg science_to:Science.ComputeWaterCurrentMinDepth = 2.000000 m
2014-04-13T01:18:50.921Z,1397351930.921 [MissionManager](INFO): DefineArg science_to:Science.ComputeWaterCurrentMaxDepth = 5.000000 m
2014-04-13T01:18:50.925Z,1397351930.925 [MissionManager](INFO): DefineArg science_to:Science.EnabledAanderaaO2 = 0.000000 n/a
2014-04-13T01:18:50.928Z,1397351930.928 [MissionManager](INFO): DefineArg science_to:Science.EnabledNeilBrown = 1.000000 n/a
2014-04-13T01:18:50.932Z,1397351930.932 [MissionManager](INFO): DefineArg science_to:Science.EnabledTurbulence_NPS = 0.000000 n/a
2014-04-13T01:18:50.937Z,1397351930.937 [MissionManager](INFO): DefineArg science_to:Science.EnabledWetLabsBB2FL = 1.000000 n/a
2014-04-13T01:18:50.941Z,1397351930.941 [MissionManager](INFO): DefineArg science_to:Science.EnabledDVL = 1.000000 n/a
2014-04-13T01:18:50.945Z,1397351930.945 [MissionManager](INFO): DefineArg science_to:Science.EnabledISUS = 0.000000 n/a
2014-04-13T01:18:50.949Z,1397351930.949 [MissionManager](INFO): DefineOutput science_to:Science.PeakChl = 0.000000 ug/l
2014-04-13T01:18:50.981Z,1397351930.981 [MissionManager](INFO): DefineOutput science_to:Science.PeakChlDepth = 0.000000 m
2014-04-13T01:18:50.987Z,1397351930.987 [MissionManager](INFO): DefineOutput science_to:Science.PeakChlLatitude = nan arcdeg
2014-04-13T01:18:50.990Z,1397351930.990 [MissionManager](INFO): DefineOutput science_to:Science.PeakChlLongitude = nan arcdeg
2014-04-13T01:18:50.994Z,1397351930.994 [MissionManager](INFO): DefineOutput science_to:Science.PeakNO3 = 0.000000 umol/l
2014-04-13T01:18:51.005Z,1397351931.005 [MissionManager](INFO): DefineOutput science_to:Science.PeakNO3Depth = 0.000000 m
2014-04-13T01:18:51.009Z,1397351931.009 [MissionManager](INFO): DefineOutput science_to:Science.PeakNO3Latitude = nan arcdeg
2014-04-13T01:18:51.014Z,1397351931.014 [MissionManager](INFO): DefineOutput science_to:Science.PeakNO3Longitude = nan arcdeg
2014-04-13T01:18:51.031Z,1397351931.031 [science_to:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2014-04-13T01:18:51.041Z,1397351931.041 [science_to:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2014-04-13T01:18:51.092Z,1397351931.092 [MissionManager](INFO): RedefineArg science_to:Science.SampleISUS = value:1 bool
2014-04-13T01:18:51.101Z,1397351931.101 [MissionManager](INFO): RedefineArg science_to:Science.PeakDetectChlActive = value:1 bool
2014-04-13T01:18:51.102Z,1397351931.102 [science_to:H.Buoyancy](DEBUG): Construct Buoyancy.
2014-04-13T01:18:51.109Z,1397351931.109 [science_to:I.SetSpeed](DEBUG): Construct.
2014-04-13T01:18:51.115Z,1397351931.115 [science_to:J.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2014-04-13T01:18:51.126Z,1397351931.126 [science_to:K.YoYo](DEBUG): Construct YoYo.
2014-04-13T01:18:51.149Z,1397351931.149 [science_to:Wpt1:A.Waypoint](DEBUG): Construct Waypoint.
2014-04-13T01:18:51.181Z,1397351931.181 [science_to:Wpt2:A.Waypoint](DEBUG): Construct Waypoint.
2014-04-13T01:18:51.201Z,1397351931.201 [science_to:Wpt3:A.Waypoint](DEBUG): Construct Waypoint.
2014-04-13T01:18:51.217Z,1397351931.217 [science_to:Wpt4:A.Waypoint](DEBUG): Construct Waypoint.
2014-04-13T01:18:51.294Z,1397351931.294 [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-13T01:18:51.294Z,1397351931.294 [CommandLine](IMPORTANT): Loaded ./Missions/Science/science_to.xml
2014-04-13T01:18:58.007Z,1397351938.007 [CommandLine](IMPORTANT): got command set science_to.MaxDepth 50.000000 meter
2014-04-13T01:18:58.007Z,1397351938.007 [CommandLine](IMPORTANT): got command set science_to.YoYoMaxDepth 35.000000 meter
2014-04-13T01:18:58.008Z,1397351938.008 [CommandLine](IMPORTANT): got command set science_to.Wpt1Lat 33.627258 degree
2014-04-13T01:18:58.009Z,1397351938.009 [CommandLine](IMPORTANT): got command set science_to.Wpt1Lon -118.060310 degree
2014-04-13T01:18:58.010Z,1397351938.010 [CommandLine](IMPORTANT): got command set science_to.Wpt2Lat 33.585152 degree
2014-04-13T01:19:04.050Z,1397351944.050 [CommandLine](IMPORTANT): got command set science_to.Wpt2Lon -117.982506 degree
2014-04-13T01:19:04.051Z,1397351944.051 [CommandLine](IMPORTANT): got command set science_to.NeedCommsTime 45.000000 minute
2014-04-13T01:19:04.051Z,1397351944.051 [CommandLine](IMPORTANT): got command set science_to.Timeout 3.000000 hour
2014-04-13T01:19:04.076Z,1397351944.076 [CommandLine](IMPORTANT): got command run 
2014-04-13T01:19:04.079Z,1397351944.079 [CommandLine](IMPORTANT): Running 
2014-04-13T01:19:04.128Z,1397351944.128 [Default] Stopped
2014-04-13T01:19:04.128Z,1397351944.128 [Default](INFO): Aggregate::uninitialize Default
2014-04-13T01:19:04.128Z,1397351944.128 [Default:GPS] Stopped
2014-04-13T01:19:04.129Z,1397351944.129 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2014-04-13T01:19:04.129Z,1397351944.129 [Default:GPS:A.SetSpeed] Stopped
2014-04-13T01:19:04.129Z,1397351944.129 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2014-04-13T01:19:04.129Z,1397351944.129 [Default:GPS:Read_GPS] Stopped
2014-04-13T01:19:04.129Z,1397351944.129 [Default:Iridium] Stopped
2014-04-13T01:19:04.129Z,1397351944.129 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2014-04-13T01:19:04.129Z,1397351944.129 [Default:Iridium:A.SetSpeed] Stopped
2014-04-13T01:19:04.129Z,1397351944.129 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2014-04-13T01:19:04.129Z,1397351944.129 [Default:Iridium:Read_Iridium] Stopped
2014-04-13T01:19:04.129Z,1397351944.129 [Default:CallIridium] Stopped
2014-04-13T01:19:04.129Z,1397351944.129 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2014-04-13T01:19:04.130Z,1397351944.130 [Default:CallIridium:B] Stopped
2014-04-13T01:19:04.130Z,1397351944.130 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2014-04-13T01:19:04.130Z,1397351944.130 [Default:WaitAtTheSurface] Stopped
2014-04-13T01:19:04.130Z,1397351944.130 [Default:WaitAtTheSurface](INFO): Aggregate::uninitialize Default:WaitAtTheSurface
2014-04-13T01:19:04.130Z,1397351944.130 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed] Stopped
2014-04-13T01:19:04.130Z,1397351944.130 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed](DEBUG): Uninitialize.
2014-04-13T01:19:04.130Z,1397351944.130 [Default:WaitAtTheSurface:B.GoToSurface] Stopped
2014-04-13T01:19:04.130Z,1397351944.130 [Default:WaitAtTheSurface:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-04-13T01:19:04.130Z,1397351944.130 [MissionManager](IMPORTANT): Started mission science_to
2014-04-13T01:19:04.131Z,1397351944.131 [science_to] Running Loop=1
2014-04-13T01:19:04.131Z,1397351944.131 [science_to](INFO): Aggregate::initialize science_to
2014-04-13T01:19:04.131Z,1397351944.131 [science_to:B.DepthEnvelope] Running Loop=1
2014-04-13T01:19:04.131Z,1397351944.131 [science_to:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2014-04-13T01:19:04.131Z,1397351944.131 [science_to:D.Pitch] Running Loop=1
2014-04-13T01:19:04.131Z,1397351944.131 [science_to:D.Pitch](DEBUG): Initialize.
2014-04-13T01:19:04.132Z,1397351944.132 [science_to:Science] Running Loop=1
2014-04-13T01:19:04.132Z,1397351944.132 [science_to:Science](INFO): Aggregate::initialize science_to:Science
2014-04-13T01:19:04.132Z,1397351944.132 [science_to:Science:A] Running Loop=1
2014-04-13T01:19:04.132Z,1397351944.132 [science_to:Science:B] Running Loop=1
2014-04-13T01:19:04.132Z,1397351944.132 [science_to:Science:C] Running Loop=1
2014-04-13T01:19:04.132Z,1397351944.132 [science_to:Science:D] Running Loop=1
2014-04-13T01:19:04.132Z,1397351944.132 [science_to:Science:E] Running Loop=1
2014-04-13T01:19:04.132Z,1397351944.132 [science_to:Science:F] Running Loop=1
2014-04-13T01:19:04.132Z,1397351944.132 [science_to:Science:G] Running Loop=1
2014-04-13T01:19:04.132Z,1397351944.132 [science_to:Science:PeakDetectChl] Running Loop=1
2014-04-13T01:19:04.133Z,1397351944.133 [science_to:Science:PeakDetectChl](INFO): Aggregate::initialize science_to:Science:PeakDetectChl
2014-04-13T01:19:04.133Z,1397351944.133 [science_to:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2014-04-13T01:19:04.133Z,1397351944.133 [science_to:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2014-04-13T01:19:04.134Z,1397351944.134 [science_to:Science:PeakDetectNO3] Running Loop=1
2014-04-13T01:19:04.135Z,1397351944.135 [science_to:Science:PeakDetectNO3](INFO): Aggregate::initialize science_to:Science:PeakDetectNO3
2014-04-13T01:19:04.135Z,1397351944.135 [science_to:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2014-04-13T01:19:04.135Z,1397351944.135 [science_to:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2014-04-13T01:19:04.137Z,1397351944.137 [science_to:H.Buoyancy] Running Loop=1
2014-04-13T01:19:04.137Z,1397351944.137 [science_to:H.Buoyancy](DEBUG): Initialize Buoyancy Component.
2014-04-13T01:19:04.137Z,1397351944.137 [science_to:I.SetSpeed] Running Loop=1
2014-04-13T01:19:04.137Z,1397351944.137 [science_to:I.SetSpeed](DEBUG): Initialize.
2014-04-13T01:19:04.137Z,1397351944.137 [science_to:J.DepthEnvelope] Running Loop=1
2014-04-13T01:19:04.137Z,1397351944.137 [science_to:J.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2014-04-13T01:19:04.138Z,1397351944.138 [science_to:K.YoYo] Running Loop=1
2014-04-13T01:19:04.138Z,1397351944.138 [science_to:K.YoYo](DEBUG): Initialize YoYoComponent.
2014-04-13T01:19:04.138Z,1397351944.138 [science_to:SURFACECOMMS] Running Loop=1
2014-04-13T01:19:04.138Z,1397351944.138 [science_to:SURFACECOMMS](INFO): Aggregate::initialize science_to:SURFACECOMMS
2014-04-13T01:19:04.138Z,1397351944.138 [science_to:SURFACECOMMS:A.GoToSurface] Running Loop=1
2014-04-13T01:19:04.138Z,1397351944.138 [science_to:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2014-04-13T01:19:04.139Z,1397351944.139 [science_to:K.YoYo] Running Loop=1
2014-04-13T01:19:04.147Z,1397351944.147 [science_to:J.DepthEnvelope] Running Loop=1
2014-04-13T01:19:04.170Z,1397351944.170 [science_to:I.SetSpeed] Running Loop=1
2014-04-13T01:19:04.183Z,1397351944.183 [science_to:H.Buoyancy] Running Loop=1
2014-04-13T01:19:04.188Z,1397351944.188 [science_to:Science] Running Loop=1
2014-04-13T01:19:04.199Z,1397351944.199 [science_to:Science:PeakDetectNO3] Stopped
2014-04-13T01:19:04.199Z,1397351944.199 [science_to:Science:PeakDetectNO3](INFO): Aggregate::uninitialize science_to:Science:PeakDetectNO3
2014-04-13T01:19:04.199Z,1397351944.199 [science_to:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2014-04-13T01:19:04.199Z,1397351944.199 [science_to:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2014-04-13T01:19:04.199Z,1397351944.199 [science_to:Science:PeakDetectChl] Running Loop=1
2014-04-13T01:19:04.206Z,1397351944.206 [science_to:Science:G] Running Loop=1
2014-04-13T01:19:04.207Z,1397351944.207 [science_to:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2014-04-13T01:19:04.209Z,1397351944.209 [science_to:Science:F] Stopped
2014-04-13T01:19:04.209Z,1397351944.209 [science_to:Science:E] Stopped
2014-04-13T01:19:04.209Z,1397351944.209 [science_to:Science:D] Running Loop=1
2014-04-13T01:19:04.209Z,1397351944.209 [science_to:Science:D](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2014-04-13T01:19:04.210Z,1397351944.210 [science_to:Science:D](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2014-04-13T01:19:04.211Z,1397351944.211 [science_to:Science:C] Stopped
2014-04-13T01:19:04.211Z,1397351944.211 [science_to:Science:B] Stopped
2014-04-13T01:19:04.212Z,1397351944.212 [science_to:Science:A] Stopped
2014-04-13T01:19:04.221Z,1397351944.221 [science_to:SURFACECOMMS:B] Running Loop=1
2014-04-13T01:19:04.221Z,1397351944.221 [science_to:SURFACECOMMS:B](INFO): Aggregate::initialize science_to:SURFACECOMMS:B
2014-04-13T01:19:04.221Z,1397351944.221 [science_to:SURFACECOMMS:B:A] Running Loop=1
2014-04-13T01:19:04.221Z,1397351944.221 [science_to:D.Pitch] Running Loop=1
2014-04-13T01:19:04.226Z,1397351944.226 [science_to:OffshoreWrapper] Running Loop=1
2014-04-13T01:19:04.226Z,1397351944.226 [science_to:OffshoreWrapper](INFO): Aggregate::initialize science_to:OffshoreWrapper
2014-04-13T01:19:04.226Z,1397351944.226 [science_to:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1
2014-04-13T01:19:04.226Z,1397351944.226 [science_to:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2014-04-13T01:19:04.226Z,1397351944.226 [science_to:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1
2014-04-13T01:19:04.232Z,1397351944.232 [science_to:B.DepthEnvelope] Running Loop=1
2014-04-13T01:19:04.237Z,1397351944.237 [science_to:AltEnvWrapper] Running Loop=1
2014-04-13T01:19:04.237Z,1397351944.237 [science_to:AltEnvWrapper](INFO): Aggregate::initialize science_to:AltEnvWrapper
2014-04-13T01:19:04.237Z,1397351944.237 [science_to:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1
2014-04-13T01:19:04.237Z,1397351944.237 [science_to:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2014-04-13T01:19:04.238Z,1397351944.238 [science_to:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1
2014-04-13T01:19:04.304Z,1397351944.304 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4
2014-04-13T01:19:04.305Z,1397351944.305 [WetLabsBB2FL](INFO): Powering up
2014-04-13T01:19:04.470Z,1397351944.470 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-04-13T01:19:04.742Z,1397351944.742 [science_to:SURFACECOMMS:B] Stopped
2014-04-13T01:19:04.742Z,1397351944.742 [science_to:SURFACECOMMS:B](INFO): Aggregate::uninitialize science_to:SURFACECOMMS:B
2014-04-13T01:19:04.742Z,1397351944.742 [science_to:SURFACECOMMS:B:A] Stopped
2014-04-13T01:19:04.742Z,1397351944.742 [science_to:SURFACECOMMS:C] Running Loop=1
2014-04-13T01:19:04.742Z,1397351944.742 [science_to:SURFACECOMMS:C](INFO): Aggregate::initialize science_to:SURFACECOMMS:C
2014-04-13T01:19:04.743Z,1397351944.743 [science_to:SURFACECOMMS:C:A] Running Loop=1
2014-04-13T01:19:05.212Z,1397351945.212 [science_to:K.YoYo] Preempted
2014-04-13T01:19:05.212Z,1397351945.212 [science_to:J.DepthEnvelope] Preempted
2014-04-13T01:19:05.212Z,1397351945.212 [science_to:I.SetSpeed] Preempted
2014-04-13T01:19:05.212Z,1397351945.212 [science_to:H.Buoyancy] Preempted
2014-04-13T01:19:05.212Z,1397351945.212 [science_to:Science] Preempted
2014-04-13T01:19:05.212Z,1397351945.212 [science_to:Science:D] Preempted
2014-04-13T01:19:05.212Z,1397351945.212 [science_to:Science:G] Preempted
2014-04-13T01:19:05.212Z,1397351945.212 [science_to:Science:PeakDetectChl] Preempted
2014-04-13T01:19:05.214Z,1397351945.214 [science_to:SURFACECOMMS:C:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2014-04-13T01:19:05.554Z,1397351945.554 [WetLabsBB2FL](INFO): Powering down
2014-04-13T01:19:09.630Z,1397351949.630 [NAL9602](INFO): SBD MO Status=0, MOMSN=25100, MT Status=0, MTMSN=0
2014-04-13T01:20:26.469Z,1397352026.469 [NAL9602](IMPORTANT): GPS fix at: 1397351992.00
2014-04-13T01:20:26.485Z,1397352026.485 [DeadReckonUsingMultipleVelocitySources](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07
2014-04-13T01:20:26.493Z,1397352026.493 [science_to:SURFACECOMMS:C:A] Stopped
2014-04-13T01:20:26.493Z,1397352026.493 [science_to:SURFACECOMMS:C:B] Running Loop=1
2014-04-13T01:20:27.108Z,1397352027.108 [science_to:SURFACECOMMS:C:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2014-04-13T01:20:47.729Z,1397352047.729 [NAL9602](INFO): SBD MO Status=1, MOMSN=25101, MT Status=0, MTMSN=0
2014-04-13T01:20:47.781Z,1397352047.781 [NAL9602](INFO): Sent 47 bytes from file Logs/20140413T010850/Courier0012.lzma
2014-04-13T01:20:47.781Z,1397352047.781 [NAL9602](INFO): Packets left to send: 0
2014-04-13T01:20:47.783Z,1397352047.783 [NAL9602](INFO): Stored copy of sent data in Logs/20140413T010850/Courier0012.lzma.parts/0000.sbd
2014-04-13T01:20:47.783Z,1397352047.783 [NAL9602](INFO): Completed sending Logs/20140413T010850/Courier0012.lzma
2014-04-13T01:20:57.145Z,1397352057.145 [NAL9602](INFO): SBD MO Status=1, MOMSN=25102, MT Status=0, MTMSN=0
2014-04-13T01:20:57.199Z,1397352057.199 [NAL9602](INFO): Sent 74 bytes from file Logs/20140413T010850/Courier0016.lzma
2014-04-13T01:20:57.200Z,1397352057.200 [NAL9602](INFO): Packets left to send: 0
2014-04-13T01:20:57.201Z,1397352057.201 [NAL9602](INFO): Stored copy of sent data in Logs/20140413T010850/Courier0016.lzma.parts/0000.sbd
2014-04-13T01:20:57.202Z,1397352057.202 [NAL9602](INFO): Completed sending Logs/20140413T010850/Courier0016.lzma
2014-04-13T01:21:09.805Z,1397352069.805 [NAL9602](INFO): SBD MO Status=1, MOMSN=25103, MT Status=0, MTMSN=0
2014-04-13T01:21:09.859Z,1397352069.859 [NAL9602](INFO): Sent 332 bytes from file Logs/20140413T010850/Express0013.lzma
2014-04-13T01:21:09.859Z,1397352069.859 [NAL9602](INFO): Packets left to send: 2
2014-04-13T01:21:09.861Z,1397352069.861 [NAL9602](INFO): Stored copy of sent data in Logs/20140413T010850/Express0013.lzma.parts/0002.sbd
2014-04-13T01:21:21.283Z,1397352081.283 [NAL9602](INFO): SBD MO Status=1, MOMSN=25104, MT Status=0, MTMSN=0
2014-04-13T01:21:21.333Z,1397352081.333 [NAL9602](INFO): Sent 332 bytes from file Logs/20140413T010850/Express0013.lzma
2014-04-13T01:21:21.333Z,1397352081.333 [NAL9602](INFO): Packets left to send: 1
2014-04-13T01:21:21.334Z,1397352081.334 [NAL9602](INFO): Stored copy of sent data in Logs/20140413T010850/Express0013.lzma.parts/0001.sbd
2014-04-13T01:21:29.983Z,1397352089.983 [NAL9602](INFO): SBD MO Status=1, MOMSN=25105, MT Status=0, MTMSN=0
2014-04-13T01:21:30.030Z,1397352090.030 [NAL9602](INFO): Sent 51 bytes from file Logs/20140413T010850/Express0013.lzma
2014-04-13T01:21:30.031Z,1397352090.031 [NAL9602](INFO): Packets left to send: 0
2014-04-13T01:21:30.032Z,1397352090.032 [NAL9602](INFO): Stored copy of sent data in Logs/20140413T010850/Express0013.lzma.parts/0000.sbd
2014-04-13T01:21:30.032Z,1397352090.032 [NAL9602](INFO): Completed sending Logs/20140413T010850/Express0013.lzma
2014-04-13T01:21:39.346Z,1397352099.346 [NAL9602](INFO): SBD MO Status=1, MOMSN=25106, MT Status=0, MTMSN=0
2014-04-13T01:21:39.405Z,1397352099.405 [NAL9602](INFO): Sent 218 bytes from file Logs/20140413T010850/Express0017.lzma
2014-04-13T01:21:39.405Z,1397352099.405 [NAL9602](INFO): Packets left to send: 0
2014-04-13T01:21:39.407Z,1397352099.407 [NAL9602](INFO): Stored copy of sent data in Logs/20140413T010850/Express0017.lzma.parts/0000.sbd
2014-04-13T01:21:39.407Z,1397352099.407 [NAL9602](INFO): Completed sending Logs/20140413T010850/Express0017.lzma
2014-04-13T01:21:48.988Z,1397352108.988 [NAL9602](INFO): SBD MO Status=0, MOMSN=25107, MT Status=0, MTMSN=0
2014-04-13T01:21:49.113Z,1397352109.113 [science_to:SURFACECOMMS:C:B] Stopped
2014-04-13T01:21:49.113Z,1397352109.113 [science_to:SURFACECOMMS:C:C] Running Loop=1
2014-04-13T01:21:49.606Z,1397352109.606 [science_to:SURFACECOMMS:C:C](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2014-04-13T01:22:00.507Z,1397352120.507 [NAL9602](IMPORTANT): GPS fix at: 1397352086.00
2014-04-13T01:22:00.559Z,1397352120.559 [DeadReckonUsingMultipleVelocitySources](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07
2014-04-13T01:22:00.567Z,1397352120.567 [science_to:SURFACECOMMS:C:C] Stopped
2014-04-13T01:22:00.567Z,1397352120.567 [science_to:SURFACECOMMS:C](INFO): Completed science_to:SURFACECOMMS:C
2014-04-13T01:22:00.567Z,1397352120.567 [science_to:SURFACECOMMS:C] Stopped
2014-04-13T01:22:00.567Z,1397352120.567 [science_to:SURFACECOMMS:C](INFO): Aggregate::uninitialize science_to:SURFACECOMMS:C
2014-04-13T01:22:00.568Z,1397352120.568 [science_to:SURFACECOMMS](INFO): Completed science_to:SURFACECOMMS
2014-04-13T01:22:00.568Z,1397352120.568 [science_to:SURFACECOMMS] Stopped
2014-04-13T01:22:00.568Z,1397352120.568 [science_to:SURFACECOMMS](INFO): Aggregate::uninitialize science_to:SURFACECOMMS
2014-04-13T01:22:00.568Z,1397352120.568 [science_to:SURFACECOMMS:A.GoToSurface] Stopped
2014-04-13T01:22:00.568Z,1397352120.568 [science_to:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2014-04-13T01:22:00.569Z,1397352120.569 [science_to:Wpt1] Running Loop=1
2014-04-13T01:22:00.569Z,1397352120.569 [science_to:Wpt1](INFO): Aggregate::initialize science_to:Wpt1
2014-04-13T01:22:00.569Z,1397352120.569 [science_to:Wpt1:A.Waypoint] Running Loop=1
2014-04-13T01:22:00.569Z,1397352120.569 [science_to:Wpt1:A.Waypoint](DEBUG): Initialize WaypointComponent.
2014-04-13T01:22:01.050Z,1397352121.050 [science_to:K.YoYo] Running Loop=1
2014-04-13T01:22:01.090Z,1397352121.090 [science_to:J.DepthEnvelope] Running Loop=1
2014-04-13T01:22:01.091Z,1397352121.091 [science_to:I.SetSpeed] Running Loop=1
2014-04-13T01:22:01.092Z,1397352121.092 [science_to:H.Buoyancy] Running Loop=1
2014-04-13T01:22:01.092Z,1397352121.092 [science_to:Science] Running Loop=1
2014-04-13T01:22:01.093Z,1397352121.093 [science_to:Science:PeakDetectChl] Running Loop=1
2014-04-13T01:22:01.093Z,1397352121.093 [science_to:Science:G] Running Loop=1
2014-04-13T01:22:01.093Z,1397352121.093 [science_to:Science:D] Running Loop=1
2014-04-13T01:22:01.312Z,1397352121.312 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4
2014-04-13T01:22:01.312Z,1397352121.312 [WetLabsBB2FL](INFO): Powering up
2014-04-13T01:22:01.472Z,1397352121.472 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2014-04-13T01:22:08.520Z,1397352128.520 [NAL9602](INFO): Powering down
2014-04-13T01:22:30.175Z,1397352150.175 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:22:30.180Z,1397352150.180 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 26.6 31.1 36.7 30.5 -230.0 10.2 237.4 21.9 -110 -68 134 43 5 8 10 7 -623.8 -15.6 10.6 1 -385.5 436.3 -224.9 1 -325 -148 0 2 -322 156 0 2 -.14 309.7 26.6 14.4 0.005 35.0 1489 84
2014-04-13T01:22:32.804Z,1397352152.804 [CTD_NeilBrown](ERROR): Bad response:   1.72
2014-04-13T01:22:52.748Z,1397352172.748 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:122 Calculated:120
2014-04-13T01:22:52.748Z,1397352172.748 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 22.1 26.8 29.5 23.7 -28 300.2 13.1 -277 -152 195 33 4 6 7 5 -780.4 -26.6 5.9 1 -731.6 -120.4 -245.1 1 -629 -246 -54 2 -599 -313 -54 2 -6.57 -18.97 6.2 22.1 14.4 0.005 35.0 1489 122
2014-04-13T01:23:17.786Z,1397352197.786 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:23:17.786Z,1397352197.786 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 16.3 19.7.8 -250.1 130.3 362.6 -14.4 -177 -1 3 4 4 3 -817.7 193.1 61.6 1 -804.4 172.2 -182.0 1 -711 32 41 2 -711 18 41 2 -5.15 -15.88 1.1 16.3 14.4 0.005 35.0 1489 90
2014-04-13T01:23:34.360Z,1397352214.360 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:96 Calculated:107
2014-04-13T01:23:34.360Z,1397352214.360 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 12.6 15.2 16.7 13.5 -294.3 166.8 467.4 43.7 -297 127 446 -5 1 2 3 2 -1016.6 164.3 103.4 1 -991.5 1232 1 -991 176 73 2 -996 145 73 2 -6.69 -19.99 1.8 12.6 14.1 0.005 35.0 1489 96
2014-04-13T01:23:45.807Z,1397352225.807 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:23:45.808Z,1397352225.808 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 9.5 11.4 12.7 10.3 -287.8 131.7 468.8 37.282 108 1 1 2 1 -1009.8 126.1 94.3 1 -986.6 107.8 -244.4 1 -654 -50 175 2 -653 -57 175 2 -5.97 -18.49 0.6 9.5 13.9 0.005 35.0 1489 65
2014-04-13T01:23:54.408Z,1397352234.408 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:125 Calculated:107
2014-04-13T01:23:54.408Z,1397352234.408 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 11.7 10.4 8.9 9.4 -479.3 -90.4 298.7 -33.6 -411 -87 339 -159 1 2 1.3 -75.8 -82.2 1 -1013.9 39.6 247.0 1 -1001 96 -85 2 -985 198 -85 2 -7.38 17.66 354.1 8.9 13.8 0.005 35.0 1489 125
2014-04-13T01:24:02.784Z,1397352242.784 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46
2014-04-13T01:24:02.785Z,1397352242.784 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 1 1 1 0 16.8 12.5 15.0 0 -2.1 336.5 -172.6 -210 -18 358 -108 7 3 1 2 -1131.4 227.6 -94.2 1 -957.8 353.7 545.9 1 -758 120 5 2 -742 194 5 2 -6.71 35.00 354.3 120.005 35.0 1489 77
2014-04-13T01:24:09.046Z,1397352249.046 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:83 Calculated:69
2014-04-13T01:24:09.047Z,1397352249.047 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1.5 14.7 16.2 -468.7 86.3 416.0 -78.2 -409 90 393 -88 4 3 2 2 -1180.8 219.5 -12.0 1 -1108.9 270.1 373.9 1 -1070 237 -3 2 -1062 271 -3 2 -4.78 19.98 358.2 14.7 13.4 0.005 35.0 1489 83
2014-04-13T01:24:24.675Z,1397352264.675 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:24:24.675Z,1397352264.675 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 30.3 25.2 20.4 23.1 -370.9 100.9 422.9 13.9 -240 10 421 58 8 6 4 5 -1059.4 116.1 44.9 1 -943.5 77.1 491.6 1 -882 -64 67 2 -877 2 -5.39 25.88 4.0 20.4 13.1 0.005 35.0 1489 124
2014-04-13T01:24:55.583Z,1397352295.583 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:24:55.583Z,1397352295.583 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 24.1 28.3 31.1 25.4 -429.9 -37.2 271.7 -105.8 -427 -165 149 -21 4 7 8 5 -936.4 91.6 -81.2 1 -815.0 324.2 -349.8 1 -768 -192 -11 45 -125 2 -6.35 -16.27 342.7 24.1 13.1 0.005 35.0 1489 118
2014-04-13T01:24:58.170Z,1397352298.170 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:99 Calculated:121
2014-04-13T01:24:58.170Z,1397352298.170 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 23.6 26.9 28.9 24.9 -255.1 4.1 305.4 16.5 -325 41 4 6 7 5 -748.1 -16.5 19.1 1 -706.4 191.8 -156.5 1 -750 -173 -36 2 -768 52 -36 2 -4.49 -13.63 343.1 23.6 13.1 0.005 35.0 1489 99
2014-04-13T01:25:12.372Z,1397352312.372 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:121 Calculated:104
2014-04-13T01:25:12.373Z,1397352312.373 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 20.5 24.1 25.9 21.8 -335.5 41.8 325.0 -59.6 -325 -31 211 -55 4 5 6 4 -881.5 135.3 -7.7  260.3 -269.9 1 -715 32 -54 2 -699 152 -54 2 -6.12 -16.35 350.3 20.5 13.2 0.005 35.0 1489 121
2014-04-13T01:26:00.245Z,1397352360.245 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46
2014-04-13T01:26:00.245Z,1397352360.245 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 9.0 8.7 -441.6 49.9 501.4 -63.3 -322 33 317 65 1 1 1 1 -1258.6 151.1 12.5 1 -1242.3 250.1 -852 -42 25 2 -853 25 25 2 -7.04 -1.05 355.4 8.7 13.2 0.005 35.0 1489 69
2014-04-13T01:26:04.434Z,1397352364.434 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:92 Calculated:91
2014-04-13T01:26:04.435Z,1397352364.435 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 12.8 10.8 8.8 9.8 -527.8 -85.7 214.2 -206.5 -248 -190 204 -196 2 1 1 1 -990.3163.4 1 -946.2 299.0 220.7 1 -603 8 -116 2 -596 91 -116 2 -6.00 23.01 352.0 8.8 13.1 0.005 35.0 1489 92
2014-04-13T01:26:20.245Z,1397352380.245 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:26:20.245Z,1397352380.245 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 19.0 17.1 14.5 15.8 -407.3 55.5 376.2 -61.0 -271 100 343 58 4 3 2 3 -1045.7 155.5 -9.9 1 -996.7 180.1 302.8 1 -819 56 6 66 62 2 -4.71 18.11 359.3 14.5 12.9 0.005 35.0 1489 75
2014-04-13T01:26:24.243Z,1397352384.243 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46
2014-04-13T01:26:24.244Z,1397352384.244 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 20.7 19.0 15.8 16.7 -444.0 122.5 47.7 -340 -2 325 -17 4 4 2 3 -1132.2 333.9 -12.1 1 -1091.0 363.3 266.9 1 -887 20 -9 2 -887 29 -9 2 -7.84 16.63 359.4 15.8 12.9 0.00489 74
2014-04-13T01:26:54.177Z,1397352414.177 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:111 Calculated:120
2014-04-13T01:26:54.178Z,1397352414.178 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 31.2 29.2 24.7 26.3 -365.5 -93.9 188.0 -95.6 -281 -19 8 7 5 5 -738.7 2.3 -99.0 1 -733.7 64.9 113.5 1 -601 -185 -72 2 -615 -134 -72 2 -7.55 16.35 355.2 24.7 12.7 0.005 35.0 1489 111
2014-04-13T01:27:01.838Z,1397352421.838 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:111 Calculated:106
2014-04-13T01:27:01.838Z,1397352421.838 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 34.8 31.8 26.7 28.4 -253.8 60.9 488.4 129.5 -227 36 353 220 9 8 5 6 -990 114.6 1 -883.7 215.3 419.0 1 -774 -245 103 2 -812 -14 103 2 -9.49 17.40 343.4 26.7 12.7 0.005 35.0 1489 111
2014-04-13T01:27:07.667Z,1397352427.667 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:27:07.668Z,1397352427.668 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 29.2 32.1 30.5 27.7 -126.2 144.3 407.3 187.4 -112 18 280 141 6 8 7 6 -712.0 -57.5 165.2 1 -701.8 165.0 133.6-164 88 2 -548 -19 88 2 -11.54 -3.18 344.6 27.7 12.8 0.005 35.0 1489 125
2014-04-13T01:27:26.043Z,1397352446.043 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 43 of 46
2014-04-13T01:27:26.043Z,1397352446.043 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 23.0 26.1 28.8 24.1 -331.1 -48.7 293.4 -14.5 -377 -149 152 -14 4 6 7 5 -833.5 -45.6 -27.2 1 -773.8 205.8 -237. -180 -104 2 -726 60 -162 -15.02 340.9 23.0 13.0 0.005 35.0 1489 101
2014-04-13T01:27:29.572Z,1397352449.572 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 32 of 46
2014-04-13T01:27:29.573Z,1397352449.573 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 21.8 25.2 28.0 23.5 -266.7 64.2 394.9 39.4 -323 -71 147 45 4 5 7 4 -883.0 33.1 62.5 1 1.8 13.0 0.005 35.0 1489 122
2014-04-13T01:28:18.100Z,1397352498.100 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:87 Calculated:75
2014-04-13T01:28:18.100Z,1397352498.100 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 9.2 11.1 12.3 10.1 -330.1 121.6 476.4 29.3 -396 10 1 1 2 1 -1076.4 123.2 80.1 1 -1037.1 56.4 -318.9 1 -1065 -34 50 2 -1061 -90 50 2 -5.88 -20.72 3.0 9.2 13.2 0.005 35.0 1489 87
2014-04-13T01:28:23.834Z,1397352503.834 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 43 of 46
2014-04-13T01:28:23.835Z,1397352503.835 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 9.9 9.8 8.6 8.7 -510.7 3.6 328.7 -104.6 -570 -156 177 -194 -1120.3 144.4 -76.3 1 -1108.0 213.6 92.1 1 -997 50 -200 2 -992 111 -200 2 -7.92 9.59 356.5 8.6 13.1 0.005 35.0 1489 120
2014-04-13T01:28:39.441Z,1397352519.441 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:28:39.441Z,1397352519.441 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 19.9 16.8 13.5 15.3 -463.8 -10.0 327.9 -157.8 -434 46 352 -40 4 3 2 2 -1056.6 197.3 -81.9 1 -997.3 253.9 32049 114 -20 2 -1043 160 -20 2 -5.86 23.18 357.5 13.5 13.0 0.005 35.0 1489 122
2014-04-13T01:28:58.011Z,1397352538.011 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:91 Calculated:85
2014-04-13T01:28:58.012Z,1397352538.012 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 27.9 23.1 18.7 20.7 -449.7 49.7 400.7 -87.3 -342 -19 7 5 3 4 -1135.0 182.8 -23.4 1 -1037.2 302.2 394.2 1 -1017 -170 45 2 -1028 -81 45 2 -8.21 22.90 355.0 18.7 12.7 0.005 35.0 1489 91
2014-04-13T01:29:04.441Z,1397352544.441 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:29:04.442Z,1397352544.442 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 29.5 24.7 20.7 23.8 -303.4 69.6 323.4 -29.1 -154 24 322 105 8 5 4 5 -836.6 131.7 16.3 1 -770.7 115.1 332.1 1 -635 -108 8 -131 80 2 -3.64 22.90 2.1 20.7 12.6 0.005 35.0 1489 97
2014-04-13T01:30:43.925Z,1397352643.925 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:78 Calculated:76
2014-04-13T01:30:43.925Z,1397352643.925 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 8.9 9.1 8.7 8.3 -435.4 -116.6 359.9 -71.6 -264 142 643 -21 1 1 1 1 -1061.4 -60.1 -71.1 1 -1.6 -31.2 1 -1210 217 134 2 -1187 320 134 2 -7.23 1.72 355.1 8.3 13.2 0.005 35.0 1489 78
2014-04-13T01:30:46.804Z,1397352646.804 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46
2014-04-13T01:30:46.805Z,1397352646.805 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 11.2 9.9 8.4 9.1 -471.2 13.5 307.3 -142.3 -324 30 203 -192 1 1 1 1 -1039.0 207.9 -79.0 1 -980.2 343.6 223.5 1 -703 296 -76 2 -660 383 -76 2 -6.48 18.03 352.7 8.4 13.2 0.005 35.00
2014-04-13T01:30:50.901Z,1397352650.901 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:91 Calculated:82
2014-04-13T01:30:50.901Z,1397352650.901 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 1 1 1 0 12.2 9.6 11.260.0 -216.6 -86 -38 277 -216 5 2 1 1 -982.6 289.6 -116.6 1 -899.9 370.7 340.1 1 -484 237 -17 2 -464 274 -17 2 -5.27 28.49 355.6 9.6 13.2 0.005 35.0 1489 91
2014-04-13T01:31:28.227Z,1397352688.227 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:89 Calculated:74
2014-04-13T01:31:28.228Z,1397352688.228 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 28.3 25.4 21.6 23.1 -401.3 -37.6 298.6 -54.3 -316 -91 225 31 7 6 4 4 -934.1 22.3 -52.5 1 -906.7 -58 1 -722 -162 -40 2 -703 -228 -40 2 -6.01 17.32 5.3 21.6 12.8 0.005 35.0 1489 89
2014-04-13T01:31:29.532Z,1397352689.532 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:31:29.532Z,1397352689.532 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 28.7 25.9 21.9 23.6 -391.3 -26.2 339.3 -26.3 -337 -112 321 28 7 6 4 5 -97-28.2 1 -939.5 -50.2 257.7 1 -878 -186 -27 2 -863 -247 -27 2 -7.48 16.97 4.0 21.9 12 35.0 1489 85
2014-04-13T01:31:42.774Z,1397352702.774 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:121 Calculated:96
2014-04-13T01:31:42.774Z,1397352702.774 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 33.8 32.0 25.6 26.8 -380.5 -65.6 280.7 82.6 -244 135 9 8 5 6 -882.5 -197.8 -22.3 1 -855.1 79.2 284.4 1 -707 -301 0 2 -764 -84 0 2 -11.21 17.60 343.2 25.6 12.8 0.005 35.0 1489 121
2014-04-13T01:31:48.093Z,1397352708.093 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46
2014-04-13T01:31:48.094Z,1397352708.094 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 34.3 30.7 26.5 28.7 -306.5 41.1 313.7 -11.7 -325 -75 259 -14 9 7 5 6 -827.7 70.5 9.9 1 -746.6 257.5 257.6 1 -779 -81777 94 -41 2 -5.76 17.97 347.1 26.5 12.9 0.005 35.0 1489 113
2014-04-13T01:31:52.304Z,1397352712.304 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:31:52.304Z,1397352712.304 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 29.1 31.2 30.6 28.1 -360.1 -77.8 340.7 127.5 -315 -116 303 154 6 7 7 6 -935.3 -274.0 8.1 1 -964.1 -142.7 -5.9 1 -824 -360 7 2 -866 -243 7 2 -6.54 -2.77 352.2.9 0.005 35.0 1489 65
2014-04-13T01:32:16.028Z,1397352736.028 [science_to:Wpt1:A.Waypoint](INFO): Reached Waypoint: 33.627258,-118.060312
2014-04-13T01:32:16.028Z,1397352736.028 [science_to:Wpt1:A.Waypoint] Stopped
2014-04-13T01:32:16.028Z,1397352736.028 [science_to:Wpt1:A.Waypoint](DEBUG): Uninitialize WaypointComponent.
2014-04-13T01:32:16.029Z,1397352736.029 [science_to:Wpt1](INFO): Completed science_to:Wpt1
2014-04-13T01:32:16.029Z,1397352736.029 [science_to:Wpt1] Stopped
2014-04-13T01:32:16.029Z,1397352736.029 [science_to:Wpt1](INFO): Aggregate::uninitialize science_to:Wpt1
2014-04-13T01:32:16.029Z,1397352736.029 [science_to:Wpt2] Running Loop=1
2014-04-13T01:32:16.030Z,1397352736.030 [science_to:Wpt2](INFO): Aggregate::initialize science_to:Wpt2
2014-04-13T01:32:16.030Z,1397352736.030 [science_to:Wpt2:A.Waypoint] Running Loop=1
2014-04-13T01:32:16.030Z,1397352736.030 [science_to:Wpt2:A.Waypoint](DEBUG): Initialize WaypointComponent.
2014-04-13T01:32:27.004Z,1397352747.004 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:32:27.005Z,1397352747.005 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 18.7 19.5 23.1 21.7 -422.9 -127.9 104.4 -192.2 -343 -135 190 31 3 4 5 4 -703.8 85.8 -172.2 1 -604.4 -239.7 -331.0 1 -711 -221 -69 2 -490 -560 -69 2 5.72 -14.28 31.5 18.7 05 35.0 1489 117
2014-04-13T01:33:09.179Z,1397352789.179 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 43 of 46
2014-04-13T01:33:09.179Z,1397352789.179 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 8.5 10.9 12.1 9.0 -317.9 -50.7 252.0 -23.3 -363 -77 384 83 1 1 1 1 -760.6 -36.6 -37.7 1 598.1 -354.8 -312.4 1 -9-419 7 2 -10.28 -21.98 143.6 8.5 13.3 0.005 35.0 1489 73
2014-04-13T01:33:25.186Z,1397352805.186 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:95 Calculated:69
2014-04-13T01:33:25.186Z,1397352805.186 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 11.9 15.0 11.7 14.4 320.2 40.9 355.6 100. 162 -51 1 3 1 2 -47.2 -79.7 220.3 1 -23.5 85.6 221.9 1 -593 78 -44 2 446 -399 -44 2 -5.39 29.06 145.7 11.7 13.1 0.005 35.0 1489 95
2014-04-13T01:33:26.582Z,1397352806.582 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:68 Calculated:85
2014-04-13T01:33:26.582Z,1397352806.582 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 1 1 1 0 15.3 12.4 14.4 0 37.2 369.1 94.4 -206 -51 430 191 7 3 1 2 -84.0 98.1 2 883.5 -210.0 98.1 2 -848 -323 98 2 883 -210 98 2 -6.07 25.73 145.8 12.4 13.1 0.005 35.0 1489 68
2014-04-13T01:34:00.002Z,1397352840.002 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:78 Calculated:86
2014-04-13T01:34:00.002Z,1397352840.002 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 27.3 25.1 2 -294.6 11.1 335.8 25.8 -454 -150 311 -63 7 6 4 4 -841.4 -19.6 21.0 1 418.7 -670.7 289.1 1 -1021 -116 -96 2 642 -802 -96 2 -8.15 18.48 122.2 20.3 12.8 0.005 35.0 1489 78
2014-04-13T01:34:02.931Z,1397352842.931 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:95 Calculated:70
2014-04-13T01:34:02.931Z,1397352842.931 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 27.4 24.9 20.9 22.4 -369.6 -69.9 242.9 -68.2 -559 -139 219 -120 7 5 4 4 -817.5 -2.3 -71.4 1 410.5 -689 1 -1038 -25 -161 2 564 -872 -161 2 -7.74 18.88 121.5 20.9 12.8 0.005 35.0 1489 95
2014-04-13T01:34:37.614Z,1397352877.614 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 4 of 46
2014-04-13T01:34:37.614Z,1397352877.614 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 19 31.2 34.3 26.9 -270.6 8.1 418.1 124.9 -309 3 508 177 5 8 9 6 -919.2 -155.9 75.6 1 304.5 -851.3 -240.0 1 -1090 -232 102 2 398 -1041 102 2 -8.96 -21.29 98.9 24.9 13.0 0.005 35.0 1489 70
2014-04-13T01:34:42.354Z,1397352882.354 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:34:42.354Z,1397352882.354 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 23.5 27.7 29.2 25.0 -305.5 32.5 355.3 37.3 -365 36 540 151 4 6 7 5 -881.9 -6.4 32.2 1 214.5 -834.7 -189.8 1 -1207 -153 97 2 429 -1139 34 -14.55 103.4 23.5 13.1 0.005 35.0 1489 77
2014-04-13T01:35:15.401Z,1397352915.401 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:71 Calculated:66
2014-04-13T01:35:15.401Z,1397352915.401 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 13.8 16.5 18.2 15.0 -328.2 54.7 217.-265 -102 491 -1 1 3 4 2 -728.3 168.4 -34.4 1 -5.7 -695.2 -277.1 1 -1009 -134 33 2 346 -957 33 2 -5.30 -18.33 102.3 13.8 13.4 0.005 35.0 1489 71
2014-04-13T01:35:31.920Z,1397352931.920 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:77 Calculated:76
2014-04-13T01:35:31.921Z,1397352931.921 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 9.4 11.5 12.5 10.2 -334.5 6.4 214.3 -87.3 -526 83 147 -393 1 1 2 1 -732.4 125.1 207.8 -647.8 -303.6 1 -898 635 -185 2 -165 -1087 -185 2 -6.51 -19.01 116.6 9.4 13.4 0.005 35.0 1489 77
2014-04-13T01:35:42.850Z,1397352942.850 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:115 Calculated:106
2014-04-13T01:35:42.851Z,1397352942.851 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 1 1 1 0 11.0 8.9 10.6 0 42.0 268.1 35.9 -155 12 299 -60 4 1 1 1 -611.7 8.1 42.0 1 282.6 -436 1 -605 96 25 2 264 -553 25 2 -2.17 28.07 124.6 8.9 13.3 0.005 35.0 1489 115
2014-04-13T01:35:56.298Z,1397352956.298 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:110 Calculated:115
2014-04-13T01:35:56.298Z,1397352956.298 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 1 1 1 0 16.2 13.9 15.2 0 123.8 362.1 37.0 -243 -19 343 52 8 3 2 2 -758 86.7 1 337.6 -612.3 312.0 1 -782 -94 35 2 576 -537 35 2 -5.65 18.76 130.1 13.9 13.1 0.005 35.0 1489 110
2014-04-13T01:36:10.681Z,1397352970.681 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:112 Calculated:126
2014-04-13T01:36:10.681Z,1397352970.681 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 22.7 20.3 17.3 18.6 -245.6 9.1 293.3 -6.5 -443 -73 291 -35 5 4 3 320.8 13.5 1 423.9 -535.4 227.0 1 -979 -50 -70 2 683 -703 -70 2 -5.98 17.51 131.2 17.3 12.9 0.005 35.0 1489 112
2014-04-13T01:36:12.055Z,1397352972.055 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:36:12.055Z,1397352972.055 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 23.1 20.9 17.6 19.0 -263.1 13.6 324.3 59.0 -460 -117 360 11 5 4 3 4 -784.0 -60.6 36.1 1 514.7 -527.8 276.1 1 -1094 -170 -55 2 845 -715 -55 2 -6.13 17.48 130.9 17.6 12.95.0 1489 97
2014-04-13T01:36:13.398Z,1397352973.398 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:82 Calculated:79
2014-04-13T01:36:13.398Z,1397352973.398 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 23.6 21.9 18.0 19.3 -23 321.4 59.3 -372 -103 360 15 5 4 3 4 -740.3 -13.7 52.9 1 445.5 -526.6 274.4 1 -977 -157 -27 2 757 -636 -27 2 -6.99 17.51 130.8 18.0 12.9 0.005 35.0 1489 82
2014-04-13T01:36:46.394Z,1397353006.394 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:36:46.394Z,1397353006.394 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 35.2 31.4 26.1 28.7 -269.4 36.3 453.0 162.4 -504 -85 416 114 10 8 5 7 -964.1 -168.3 103.1 1 567.5 -656.5 464.0 1 -1227 -265 -16 2 871 -905 -16 21.37 121.7 26.1 12.8 0.005 35.0 1489 97
2014-04-13T01:36:54.533Z,1397353014.533 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:122 Calculated:117
2014-04-13T01:36:54.534Z,1397353014.534 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 26.4 31.5 35.6 28.9 -126.4 190.5 576.7 358.0 -637 326 5 8 10 6 -938.4 -223.6 269.3 1 565.8 -826.2 -19.5 1 -1094 -233 251 2 629 -925 251 2 -5.29 -18.30 112.2 26.4 12.9 0.005 35.0 1489 122
2014-04-13T01:37:41.578Z,1397353061.578 [CTD_NeilBrown](ERROR): Bad response:  15.81
2014-04-13T01:37:46.498Z,1397353066.498 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:94 Calculated:67
2014-04-13T01:37:46.498Z,1397353066.498 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 11.6 13.9 15.3 12.6 -348.5 -50.6 199.6 -67.8 -319 -21 195 -83 1 2 3 1 -731.5 23.0 -72.1 1 326.2 -00.6 1 -686 82 -61 2 264 -638 -61 2 -5.78 -18.35 119.4 11.6 13.3 0.005 35.0 1489 94
2014-04-13T01:38:26.961Z,1397353106.961 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:38:26.961Z,1397353106.961 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 20.3 18.7 15.9 17.1 -250.7 32.8 325.5 27.2 -454 -16 272 28 4 4 2 3 -769.0 7.5 36.3 1 466.1 -556.4 256.6 1 -965 2 691 -680 -45 2 -7.03 16.87 132.0 15.9 12.8 0.005 35.0 1489 68
2014-04-13T01:38:49.010Z,1397353129.010 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46
2014-04-13T01:38:49.010Z,1397353129.010 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 28.2 25.8 21.7 22.7 -350.7 -33.1 310.0 58.6 -521 -121 322 -21 7 6 4 4 -881.8 -122.4 -4.1 1 15.7 261.3 1 -1125 -133 -92 2 779 -822 -92 2 -8.35 16.30 12612.7 0.005 35.0 1489 77
2014-04-13T01:38:52.954Z,1397353132.954 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:104 Calculated:119
2014-04-13T01:38:52.954Z,1397353132.954 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 29.3 27.3 2 -291.5 7.0 383.2 27.5 -520 -91 308 36 7 6 4 5 -900.5 -27.4 34.0 1 476.8 -706.2 294.4 1 -1105 -169 -72 2 754 -825 -72 2 -7.67 16.69 123.7 22.9 12.7 0.005 35.0 1489 104
2014-04-13T01:38:59.587Z,1397353139.587 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:38:59.587Z,1397353139.587 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 33.2 29.5 24.1 25.9 -444.2 -152.5 266.7 45.8 -527 -155 279 -29 9 7 4 5 -948.8 -264.7 -76.6 1 720.5 -611.8 287.6 1 -1075 -168 -116 2 743 -795 -116 2 -6.64 20.25 124.2 24.1 12.7 0.005 9 65
2014-04-13T01:39:02.273Z,1397353142.273 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:39:02.273Z,1397353142.273 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 36.2 31.2 25.1 27.9 -318.8 28.3 415.2 92.9 -533 -100 397 -44 10 8 5 6 -979.6 -86.2 58.7 1 559.2 -690.6 425.3 1 -1241 -74 -75 2 776 -971 -75 2 -6.21 21.68 125.2 25.1 12.7 0.005 3 78
2014-04-13T01:39:36.145Z,1397353176.145 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:39:38.704Z,1397353178.704 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:39:41.119Z,1397353181.119 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:39:43.461Z,1397353183.461 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:39:46.023Z,1397353186.023 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:39:48.226Z,1397353188.226 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:39:50.736Z,1397353190.736 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:39:52.981Z,1397353192.981 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:39:55.722Z,1397353195.722 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:39:57.940Z,1397353197.940 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:40:00.821Z,1397353200.821 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:40:03.281Z,1397353203.281 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:40:05.553Z,1397353205.553 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:40:07.623Z,1397353207.623 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:40:10.416Z,1397353210.416 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:40:13.218Z,1397353213.218 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:40:15.279Z,1397353215.279 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2014-04-13T01:40:15.427Z,1397353215.427 [AHRS_sp3003D](FAULT): Read Heading/Pitch/Roll Failure.
2014-04-13T01:40:15.428Z,1397353215.428 [AHRS_sp3003D] Communications Fault, FailCount= 1
2014-04-13T01:40:15.428Z,1397353215.428 [AHRS_sp3003D](ERROR): Communications Fault
2014-04-13T01:40:15.484Z,1397353215.484 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:40:15.780Z,1397353215.780 [CBIT](ERROR): Communications Fault in component: AHRS_sp3003D
2014-04-13T01:40:15.832Z,1397353215.832 [AHRS_sp3003D](INFO): Powering down
2014-04-13T01:40:16.329Z,1397353216.329 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 25 of 46
2014-04-13T01:40:16.330Z,1397353216.330 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 .6 -43.9 1 61 137 -99 2 -150 -13 -99 2 -7.36 0.36 119.2 8.6 13.1 0.005 35.0 1489 109
2014-04-13T01:40:17.325Z,1397353217.325 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2014-04-13T01:40:17.325Z,1397353217.325 [AHRS_sp3003D] No Fault, FailCount= 1
2014-04-13T01:40:17.399Z,1397353217.399 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2014-04-13T01:40:17.448Z,1397353217.448 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:40:17.835Z,1397353217.835 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:116 Calculated:108
2014-04-13T01:40:17.835Z,1397353217.835 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 10.3 9.7 8294.6 33.8 221.8 -87.8 -185 -17 422 -111 2 1 1 1 -689.2 162.3 -34.2 1 202.9 -673.4 88.8 1 -810 125 29 2 301 -762 29 2 -7.10 11.90 120.4 8.5 13.2 0.005 35.0 1489 116
2014-04-13T01:40:20.287Z,1397353220.287 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:40:22.423Z,1397353222.423 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:40:24.909Z,1397353224.909 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:40:25.686Z,1397353225.686 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:101 Calculated:114
2014-04-13T01:40:25.686Z,1397353225.686 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 1 1 1 0 13.7 10.9 14.4 0 124.2 313.8 42.5 -83 55 310 -70 6 2 2 2 -615.1 109. 212.5 -422.6 417.8 1 -524 166 57 2 205 -510 57 2 0.71 33.75 129.6 10.9 13.0 0.005 35.0 1489 101
2014-04-13T01:40:27.119Z,1397353227.119 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:40:27.159Z,1397353227.159 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:40:27.160Z,1397353227.160 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 1 1 1 0 14.9 11.5 14.7 0 98.8 327.0 37.9 -262 60 469 -85 6 3 1 2 -690.4 81.3 73.7 1 306.6 -436.3 452.0 1 -975 193 49 2 534 -839 49 2 0.31 34.411.5 13.0 0.005 35.0 1489 81
2014-04-13T01:40:29.857Z,1397353229.857 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:40:32.226Z,1397353232.226 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:40:34.719Z,1397353234.719 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:40:37.119Z,1397353237.119 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:40:39.956Z,1397353239.956 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:40:41.822Z,1397353241.822 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:40:44.418Z,1397353244.418 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:40:47.099Z,1397353247.099 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:40:49.680Z,1397353249.680 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:40:51.620Z,1397353251.620 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:40:54.042Z,1397353254.042 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:40:56.720Z,1397353256.720 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:40:59.382Z,1397353259.382 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:41:01.949Z,1397353261.949 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:41:02.001Z,1397353262.001 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:81 Calculated:76
2014-04-13T01:41:02.001Z,1397353262.001 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 29.1 25.8 21.4 22.7 -460.6 -148.4 213.9 -126.1 -587 -174 280 -77 7 6 4 4 -900.2 -29.8 -140.5 -724.7 144.8 1 -1157 -129 -150 2 762 -880 -150 2 -9.22 17.60 124.5 21.4 12.7 0.005 35.0 1489 81
2014-04-13T01:41:04.029Z,1397353264.029 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:41:06.456Z,1397353266.456 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:41:08.920Z,1397353268.920 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:41:11.316Z,1397353271.316 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:41:13.281Z,1397353273.281 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46
2014-04-13T01:41:13.281Z,1397353273.281 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 33.8 29.5 24.1 26.5 -470.8 -176.6 192.5 -129.2 -589 -165 230 -115 9 7 4 6 -885.3 -63.3 -157.5 1 527.7 -709.9 173.5 1 -1093 -66 -172 2 645 -885 -172 2 -8.17 20.52 122.6 24.1005 35.0 1489 85
2014-04-13T01:41:13.999Z,1397353273.999 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:41:16.549Z,1397353276.549 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:41:19.127Z,1397353279.127 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2014-04-13T01:41:22.603Z,1397353282.603 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 39 of 46
2014-04-13T01:41:22.603Z,1397353282.603 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 34.4 30.7 26.8 28.7 -561.5 -197.6 156.9 -180.4 -618 -238 198 -160 9 7 5 6 -958.8 -23.0 -211.0 1 565.5 -800.8 56.3 1 -1089 -104 -220 2 686 -851 -220 2 489 82
2014-04-13T01:42:49.183Z,1397353369.183 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:87 Calculated:70
2014-04-13T01:42:49.184Z,1397353369.184 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 1 1 1 0 16.6 12.6 14.8 0 77.4 368.3 104.1 326 -13 7 3 1 2 -740.9 -35.6 97.9 1 418.5 -421.1 455.3 1 -688 70 43 2 432 -540 43 2 -6.22 29.71 134.5 12.6 13.0 0.005 35.0 1489 87
2014-04-13T01:43:30.246Z,1397353410.246 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:43:30.246Z,1397353410.246 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 30.2 27.9 22.7 24.2 -436.3 -109.0 263.1 -17.0 -580 -145 298 -84 8 7 4 5 -933.4 -122.8 -80.7 1 579.5 -708.5 234.1 1 -1171 -81 -137 2 694 -947 -137 2 -8.28 18.25 122.3 22.7 12.8 0.005 9 94
2014-04-13T01:43:35.587Z,1397353415.587 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:43:35.588Z,1397353415.588 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 33.1 29.6 24.0 26.0 -533.7 -160.1 207.2 -110.4 -608 -201 232 -157 9 7 4 6 -988.8 -66.3 -161.0 1 555.4 -811.5 21121 -58 -198 2 622 -934 -198 2 -8.08 20.28 120.7 24.0 12.8 0.005 35.0 1489 81
2014-04-13T01:43:39.458Z,1397353419.458 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:43:39.459Z,1397353419.459 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 33.8 30.6 25.1 27.7 -453.6 -17.0 442.0 -76.9 -572 -85 422 -108 9 8 5 6 -1195.3 79.9 -28.5 1 507.2 -1013.1 -1326 30 -92 2 670 -1144 -92 2 -6.47 20.78 121.7 25.1 12.8 0.005 35.0 1489 97
2014-04-13T01:43:49.212Z,1397353429.212 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:43:49.212Z,1397353429.212 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 26.7 30.3 32.1 28.4 -388.7 -12.9 290.7 -42.4 -380 47 470 52 5 7 8 6 -906.8 39.4 -41.3 1 400.6 -779.5 -239.6 1 -1134 -6 50 2 552 -990 50 2 -6.06 -12.44 .7 12.9 0.005 35.0 1489 119
2014-04-13T01:44:24.162Z,1397353464.162 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46
2014-04-13T01:44:24.162Z,1397353464.162 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 0 1 16.3 19.5 0 17.7 -340.1 -40.5 0 -36.3 -484 -102 329 -25 2 4 11 3 -805.3 -5.6 -41.4 1 179.4 -727.3 -298.7 1 -1085 -102 -76 2 326 -1040 -76 2 -6.13 -18.87 102.0 16.3 13.3 0.01489 99
2014-04-13T01:45:34.711Z,1397353534.711 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:102 Calculated:125
2014-04-13T01:45:34.711Z,1397353534.711 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 25.8 23.5 19.5 20.9 -287.4 -0.4 327.3 46.2 -405 -186 335 -30 6 5 4 4 -820.4 -62.2 23.1 -557.7 292.1 1 -987 -208 -77 2 800 -614 -77 2 -7.94 18.62 130.6 19.5 12.7 0.005 35.0 1489 102
2014-04-13T01:45:54.646Z,1397353554.646 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 4 of 46
2014-04-13T01:45:54.647Z,1397353554.647 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1  29.5 24.2 26.3 -384.7 -62.0 376.2 65.4 -487 -117 360 6 9 7 5 6 -1015.5 -170.0 -1.4 1 67.9 362.9 1 -1130 -164 -64 2 741 -869 -64 2 -7.25 19.73 122.2 24.2 12.7 0.005 35.0 1489 107
2014-04-13T01:46:07.170Z,1397353567.170 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:70 Calculated:77
2014-04-13T01:46:07.170Z,1397353567.170 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 28.4 30.7 30.5 27.9 -249.0 152.4 531.3 145.1 -348 154 595 190 6 7 7 6 -104156.3 1 526.2 -909.5 69.3 1 -1258 -48 159 2 692 -1051 159 2 -5.72 -4.67 121.2 27.9 12.9 0.005 35.0 1489 70
2014-04-13T01:46:39.219Z,1397353599.219 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:75 Calculated:72
2014-04-13T01:46:39.219Z,1397353599.219 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 18.2 22.3 24.5 20.1 -399.7 -80.6 275.1 -47.6 -446 -143 3 4 6 4 -900.6 -44.0 -68.2 1 278.5 -781.7 -359.3 1 -1127 -337 -21 2 614 -1004 -21 2 -6.66 -19.50 104.8 18.2 13.2 0.005 35.0 1489 75
2014-04-13T01:47:13.263Z,1397353633.263 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 43 of 46
2014-04-13T01:47:13.263Z,1397353633.263 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 8.7 10.5 12.2 9.5 -377.7 -180.4 254.1 -81.4 -310 143 437 21 -843.2 -132.1 -104.0 1 487.2 -595.1 -384.3 1 -997 160 79 2 314 -959 79 2 -6.33 -21.02 117.3 8.7 13.2 0.005 35.0 1489 114
2014-04-13T01:47:29.486Z,1397353649.486 [DVL_micro](ERROR): No DVL communication! Re-initializing
2014-04-13T01:47:29.487Z,1397353649.487 [DVL_micro] Communications Fault, FailCount= 1
2014-04-13T01:47:29.487Z,1397353649.487 [DVL_micro](ERROR): Communications Fault
2014-04-13T01:47:29.736Z,1397353649.736 [CBIT](ERROR): Communications Fault in component: DVL_micro
2014-04-13T01:47:30.185Z,1397353650.185 [DVL_micro](INFO): uninitialize:Powering down
2014-04-13T01:47:30.721Z,1397353650.721 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-04-13T01:47:30.721Z,1397353650.721 [DVL_micro] Hardware Fault, FailCount= 1
2014-04-13T01:47:30.721Z,1397353650.721 [DVL_micro](ERROR): Hardware Fault
2014-04-13T01:47:31.817Z,1397353651.817 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-04-13T01:47:31.818Z,1397353651.818 [DVL_micro] No Fault, FailCount= 1
2014-04-13T01:47:32.299Z,1397353652.299 [DVL_micro](INFO): Initializing
2014-04-13T01:47:35.653Z,1397353655.653 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:99 Calculated:114
2014-04-13T01:47:35.659Z,1397353655.659 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 25.1 20.1 15.2 17.5 -169.5 114.111.0 -330 2 236 44 6 4 2 3 -706.6 5.2 112.2 1 369.9 -415.7 449.7 1 -755 -56 -13 2 588 -477 -13 2 -8.26 30.15 136.7 15.2 13.0 0.005 35.0 1489 99
2014-04-13T01:47:40.762Z,1397353660.762 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 43 of 46
2014-04-13T01:47:40.762Z,1397353660.762 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 21.4 19.1 16.5 18.2 -233.7 64.9 408.9 93.8 -382 4 415 67 4 4 3 3 -857.6 -38.6 90.0 1 604.8 -503.4 354.6 1 -1063 -84 28 2 850 -8.16 138.3 16.5 12.8 0.005 35.0 1489 119
2014-04-13T01:47:48.876Z,1397353668.876 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46
2014-04-13T01:47:48.877Z,1397353668.877 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 24.1 21.9 18.7 19.6 -379.2 -27.8 278.6 19.3 -466 -76 313 -20 5 4 3 4 -877.9 -62.9 -29.5 1 598.6 -610.7 210.6 1 -1039 -7439 -734 -67 2 -8.23 15.18 131.1 18.7 12.8 0.005 35.0 1489 64
2014-04-13T01:48:05.778Z,1397353685.778 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46
2014-04-13T01:48:05.778Z,1397353685.778 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 29.8 26.9 22.3 24.1 -350.0 -140.5 245.4 17.8 -506 -174 313 -53 8 6 4 5 -794.6 -211.3 -61.3 1 603.2 -516.1 222.3 1 -1093 -161 -113 2 756 -805 -113 2 -7.22 18.60 124.8 22.3005 35.0 1489 72
2014-04-13T01:48:18.994Z,1397353698.994 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46
2014-04-13T01:48:18.995Z,1397353698.995 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 35.4 31.8 25.9 27.9 -381.3 -43.7 376.8 13.2 -523 -79 342 -76 10 8 5 6 -1011.8 -75.9 -9.4 1 559.2 -773.5 344.5 1 -1154 -4 -90 2 625 -970 -90 2 -7.14 19.87 122.6 25.9 12.8 0.005 35.2
2014-04-13T01:49:31.117Z,1397353771.117 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:127 Calculated:126
2014-04-13T01:49:31.118Z,1397353771.118 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 8.8 9.2 8.9 8.3 -224.8 75.5 205.2 -85.5 -585-14 1 1 1 1 -573.9 214.9 -8.0 1 138.0 -594.8 -53.5 1 -670 52 -177 2 324 -588 -177 2 -7.03 -1.93 123.3 8.3 13.1 0.005 35.0 1489 127
2014-04-13T01:49:51.265Z,1397353791.265 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:105 Calculated:104
2014-04-13T01:49:51.266Z,1397353791.266 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 18.9 17.5 14.5 15.4 -237.3 67.6 .1 -535 -22 336 -13 4 3 2 2 -702.2 71.4 35.9 1 412.7 -524.9 231.4 1 -1162 -12 -63 2 843 -800 -63 2 -7.65 17.14 135.9 14.5 12.9 0.005 35.0 1489 105
2014-04-13T01:51:10.048Z,1397353870.048 [CTD_NeilBrown](ERROR): Bad response:   8.031
2014-04-13T01:51:57.149Z,1397353917.149 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46
2014-04-13T01:51:57.150Z,1397353917.150 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 12.9 10.7 8.7 9.8 -240.9 85.4 301.9 -18.1 57 -48 2 1 1 1 -724.4 138.1 34.6 1 271.9 -612.8 308.9 1 -4 2 240 -442 81 2 -5.78 23.72 127.4 8.7 13.0 0.005 35.0 1489 67
2014-04-13T01:52:02.282Z,1397353922.282 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46
2014-04-13T01:52:02.283Z,1397353922.283 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 1 1 1 0 13.8 10.6 13.0 0 116.9 326.8 20.2 -307 -41 356 -132 5 2 2 2 -689.3 129.1 73.9 1 248.1 -508.7 4284 121 -33 2 492 -745 -33 2 -4.31 32.210.6 12.9 0.005 35.0 1489 97
2014-04-13T01:52:07.657Z,1397353927.657 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:52:07.657Z,1397353927.657 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 19.4 15.7 12.7 15.0 -270.6 49.4 357.1 8.8 -370 -43 400 -39 4 3 1 2 -837.8 54.2 39.0 1 494.7 -549.8 399.3 -5 -14 2 760 -691 -14 2 -3.86 26.06 137.4 12.7 12.9 0.005 35.0 1489 96
2014-04-13T01:52:15.550Z,1397353935.550 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 2 of 46
2014-04-13T01:52:15.550Z,1397353935.550 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 19.6 18.0 15.3 15.9 -250.3 122.8 342.9 59.0 -302 23 360 -60 4 3 2 2 -791.7 85.1 73.9 1 438.2 -614.3 264.8 1 -883 110 5 2 534 -712 5 2 -8.76 15.19 134.0 15.3 12.8 0.005 35.0 1489 83
2014-04-13T01:52:16.990Z,1397353936.990 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:73 Calculated:95
2014-04-13T01:52:16.991Z,1397353936.991 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 19.6 18.4 15.4 16. 44.8 279.6 -8.2 -404 -67 361 -13 4 4 2 3 -778.9 70.7 3.2 1 451.8 -607.1 197.3 1 -1021 -72 -33 2 751 -694 -33 2 -8.67 15.26 133.2 15.4 12.8 0.005 35.0 1489 73
2014-04-13T01:52:47.315Z,1397353967.315 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:52:47.315Z,1397353967.315 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 22.8 27.0 22.8 24.4 326.0 -132.8 313.1 19.6 -510 -244 374 -63 4 6 4 5 -1179.8 -241.6 -119.5 2 845.2 -857.9 -119.5 2 -1179 -241 -119 2 845 -857-7.29 18.38 123.0 22.8 12.6 0.005 35.0 1489 76
2014-04-13T01:52:59.252Z,1397353979.252 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:97 Calculated:100
2014-04-13T01:52:59.252Z,1397353979.252 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 34.3 31.0 25.8 27.9 -476.7 -230.5 139.4 -140.8 -599 -288 123 -212 9 8 5 6 119.7 -191.1 1 572.3 -622.9 107.4 1 -963 -101 -263 2 622 -742 -263 2 -8.51 19.04 124.0 25.8 12.6 0.005 35.0 1489 97
2014-04-13T01:53:03.169Z,1397353983.169 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:88 Calculated:78
2014-04-13T01:53:03.174Z,1397353983.174 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 28.7 29.7 28.3 26.9 -605.2 -321.5 49.6 -211.4 -6215 -206 6 7 6 5 -873.9 -146.9 -293.5 1 633.0 -627.4 -277.7 1 -1135 -104 -245 2 705 -895 -245 2 -8.48 -0.60 123.0 26.9 12.7 0.005 35.0 1489 88
2014-04-13T01:53:10.946Z,1397353990.946 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:53:10.947Z,1397353990.947 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 24.7 31.0 34.9 27.2 -460.4 -16.3 378.7 80.4 -393 4 567 138 5 8 10 6 -1119.9 -129.1 -4.8 1 492.1 -924.6 -416.9 1 -1281 -178 8-1150 85 2 -7.77 -22.58 109.3 24.7 12.7 0.005 35.0 1489 74
2014-04-13T01:53:14.391Z,1397353994.391 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:120 Calculated:99
2014-04-13T01:53:14.391Z,1397353994.391 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 24.1 30.0 34.2 25.4 -264.1 113 198.2 -314 84 631 230 4 7 9 5 -1122.0 -112.5 168.4 1 323.4 -1070.4 -223.2 1 -1261 -194 170 2 427 -1202 170 2 -9.61 -20.72 100.8 24.1 12.8 0.005 35.0 1489 120
2014-04-13T01:53:15.711Z,1397353995.711 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:53:15.711Z,1397353995.711 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 23.7 29.3 31.6 24.9 -391.8 -17.0 436.3 33.4 -457 -65 545 76 4 7 8 5 -1105.2 -67.3 16.4 1 309.4 -1006.5 -342.8 1 -1337 -188 26 2 457 -1 -10.15 -19.55 101.8 23.7 12.8 0.005 35.0 1489 126
2014-04-13T01:53:19.297Z,1397353999.297 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:53:19.297Z,1397353999.297 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 22.6 26.9 29.2 24.0 -364.2 -27.4 363.6 20.6 -361 -33 445 146 4 6 7 5 -971.3 -64.1 -2.0 1 282.4 -892.5 -21075 -238 53 2 466 -998 53 2 -7.70 -16.35 102.5 22.6 12.8 0.005 35.0 1489 95
2014-04-13T01:54:18.358Z,1397354058.358 [CTD_NeilBrown](ERROR): Bad response:  19.69
2014-04-13T01:54:25.145Z,1397354065.145 [MassServo](FAULT): Overload Error
2014-04-13T01:54:25.146Z,1397354065.146 [MassServo] Hardware Fault, FailCount= 1
2014-04-13T01:54:25.146Z,1397354065.146 [MassServo](ERROR): Hardware Fault
2014-04-13T01:54:25.215Z,1397354065.215 [CBIT](ERROR): Hardware Fault in component: MassServo
2014-04-13T01:54:25.883Z,1397354065.883 [MassServo](DEBUG): Uninitialize Mass Servo.
2014-04-13T01:54:25.885Z,1397354065.885 [MassServo](INFO): Powering down
2014-04-13T01:54:27.198Z,1397354067.198 [MassServo](DEBUG): Initializing EZServoServo.
2014-04-13T01:54:27.314Z,1397354067.314 [MassServo](DEBUG): Initializing MassServo.
2014-04-13T01:54:27.433Z,1397354067.433 [CBIT](INFO): Clearing failed state for component MassServo
2014-04-13T01:54:27.433Z,1397354067.433 [MassServo] No Fault, FailCount= 1
2014-04-13T01:54:45.103Z,1397354085.103 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 2 of 46
2014-04-13T01:54:45.104Z,1397354085.104 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0001 22.4 21.0 17.8 18.9 -306.0 -64.6 282.6 37.7 -426 -112 351 -24 5 4 3 3 -785.6 -136.5 -13.6 1 612.8 -464.1 212.0 1 -1037 -117 -56 2 800 -670 -56 2 -7.17 15.36 133.6 17.8 12.7 0.005 35.0 1489 127
2014-04-13T01:55:11.237Z,1397354111.237 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 43 of 46
2014-04-13T01:55:11.237Z,1397354111.237 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 31.5 28.3 23.6 25.0 -334.2 -24.8 345.5 56.8 -520 -71 314 -25 8 7 4 5 -907.2 -108.9 11.7 1 506.7 -696.1 306.0 1 -1113 -61 -81 2 614 -93098 120.3 23.6 12.7 0.005 35.0 1489 83
2014-04-13T01:55:24.892Z,1397354124.892 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:114 Calculated:108
2014-04-13T01:55:24.893Z,1397354124.893 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 26.8 30.7 31.7 27.5 -255.1 230.1 687.6 184.7 -311 214 762 229 5 7 8 6 -1258.2 60.6 228.4 1 175.0 6.3 1 -1432 -20 241 2 659 -1271 241 2 -5.34 -9.71 116.6 26.8 12.7 0.005 35.0 1489 114
2014-04-13T01:56:27.597Z,1397354187.597 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:56:27.597Z,1397354187.597 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 9.6 11.7 13.2 10.4 -301.4 12.7 285.3 16.7 -202 -39 307 42 1 1 2 1 -783.0 -5.3 3.6 1 311.1 -670.2 -259.2 1 -679 -108 29 2 368 -581 29 2 -664 113.3 9.6 13.1 0.005 35.0 1489 109
2014-04-13T01:57:31.771Z,1397354251.771 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:100 Calculated:102
2014-04-13T01:57:31.771Z,1397354251.771 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 31.1 27.8 23.3 24.9 -374.8 -160.6 276.898 -250 298 47 8 6 4 5 -869.6 -262.4 -60.0 1 658.8 -579.2 243.1 1 -1062 -396 -108 2 901 -687 -108 2 -8.30 17.53 122.2 23.3 12.6 0.005 35.0 1489 100
2014-04-13T01:57:43.343Z,1397354263.343 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:91 Calculated:81
2014-04-13T01:57:43.343Z,1397354263.343 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 31.8 31.0 26.1 28.3 -93.5 -134.4 221.5 -74.239 299 -124 8 8 5 6 -420.4 -79.4 -22.0 1 269.7 -311.2 117.9 1 -1161 -153 -171 2 721 -922 -171 2 -5.68 18.14 120.5 26.1 12.7 0.005 35.0 1489 91
2014-04-13T01:58:50.076Z,1397354330.076 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:64 Calculated:94
2014-04-13T01:58:50.076Z,1397354330.076 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 9.5 11.5 12.7 10.3 -363.0 -1.4 259.3 -93.0 -441 18 352 -49 1 1 2 1 -830.5 122.3 -53.9 -723.6 -332.7 1 -1058 89 -32 2 418 -976 -32 2 -6.29 -18.91 118.0 9.5 13.0 0.005 35.0 1489 64
2014-04-13T01:58:52.950Z,1397354332.950 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:111 Calculated:96
2014-04-13T01:58:52.951Z,1397354332.951 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 8.8 10.4 1-316.3 -19.6 220.7 -84.5 -324 -265 379 78 1 2 1 1 -716.7 86.6 -53.9 1 261.1 -615.2 -278.2 1 -938 -457 -35 2 854 -600 -35 2 -6.01 -17.72 118.9 8.8 13.0 0.005 35.0 1489 111
2014-04-13T01:58:56.857Z,1397354336.857 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:58:56.862Z,1397354336.862 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 9.9 9.7 8.6 8.7 -257.0 33.7 231.7 -65.9 -286 -191 443 2 1 1 1 1 -652.2 132.9 -15.5 1 253.8 -610.7 76.4 1 -973 -257 -8 2 763 -656 -8 2 52 124.5 8.6 13.0 0.005 35.0 1489 64
2014-04-13T01:59:11.533Z,1397354351.533 [CTD_NeilBrown](ERROR): Bad response:  15.3393
2014-04-13T01:59:12.996Z,1397354352.996 [DVL_micro](ERROR): No DVL communication! Re-initializing
2014-04-13T01:59:12.997Z,1397354352.997 [DVL_micro] Communications Fault, FailCount= 1
2014-04-13T01:59:12.997Z,1397354352.997 [DVL_micro](ERROR): Communications Fault
2014-04-13T01:59:13.178Z,1397354353.178 [CBIT](ERROR): Communications Fault in component: DVL_micro
2014-04-13T01:59:13.729Z,1397354353.729 [DVL_micro](INFO): uninitialize:Powering down
2014-04-13T01:59:14.316Z,1397354354.316 [DVL_micro](FAULT): LCB fault:  Software Overcurrent.
2014-04-13T01:59:14.316Z,1397354354.316 [DVL_micro] Hardware Fault, FailCount= 1
2014-04-13T01:59:14.316Z,1397354354.316 [DVL_micro](ERROR): Hardware Fault
2014-04-13T01:59:15.377Z,1397354355.377 [CBIT](INFO): Clearing failed state for component DVL_micro
2014-04-13T01:59:15.377Z,1397354355.377 [DVL_micro] No Fault, FailCount= 1
2014-04-13T01:59:15.805Z,1397354355.805 [DVL_micro](INFO): Initializing
2014-04-13T01:59:20.264Z,1397354360.264 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46
2014-04-13T01:59:20.265Z,1397354360.265 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 0 1 1 1 0 21.7 17.1 19.1 0 125.6 411.8 88.8 -307 27 323 -23 11 4 3 4 -813.1 49.1 115.6 1 437.7 -556.2 419.5 1 -840 66 5 2 554 -635 5 23.34 135.6 17.1 12.7 0.005 35.0 1489 126
2014-04-13T01:59:36.130Z,1397354376.130 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:84 Calculate