2014-04-12T04:01:57.021Z,1397275317.021 [Supervisor](DEBUG): Initializing supervisor. 2014-04-12T04:01:57.026Z,1397275317.026 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0 2014-04-12T04:01:57.033Z,1397275317.033 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2014-04-12T04:01:57.043Z,1397275317.043 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0 2014-04-12T04:01:57.061Z,1397275317.061 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2014-04-12T04:01:57.078Z,1397275317.078 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2014-04-12T04:01:57.087Z,1397275317.087 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0 2014-04-12T04:01:57.088Z,1397275317.088 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2014-04-12T04:01:57.095Z,1397275317.095 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403CB4E0 2014-04-12T04:01:57.101Z,1397275317.101 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2014-04-12T04:01:57.101Z,1397275317.101 [Supervisor](INFO): Looking for Config files in directory: Config/ 2014-04-12T04:01:57.111Z,1397275317.111 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2014-04-12T04:01:58.022Z,1397275318.022 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2014-04-12T04:01:58.022Z,1397275318.022 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2014-04-12T04:01:58.679Z,1397275318.679 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2014-04-12T04:01:58.680Z,1397275318.680 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2014-04-12T04:01:58.768Z,1397275318.768 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2014-04-12T04:01:58.771Z,1397275318.771 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2014-04-12T04:01:58.966Z,1397275318.966 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2014-04-12T04:01:58.967Z,1397275318.967 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2014-04-12T04:02:00.239Z,1397275320.239 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2014-04-12T04:02:00.897Z,1397275320.897 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2014-04-12T04:02:01.204Z,1397275321.204 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2014-04-12T04:02:01.205Z,1397275321.205 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2014-04-12T04:02:01.389Z,1397275321.389 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2014-04-12T04:02:01.391Z,1397275321.391 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2014-04-12T04:02:01.731Z,1397275321.731 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2014-04-12T04:02:01.733Z,1397275321.733 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2014-04-12T04:02:01.848Z,1397275321.848 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2014-04-12T04:02:01.849Z,1397275321.849 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2014-04-12T04:02:02.353Z,1397275322.353 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2014-04-12T04:02:02.354Z,1397275322.354 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2014-04-12T04:02:02.465Z,1397275322.465 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2014-04-12T04:02:02.466Z,1397275322.466 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2014-04-12T04:02:02.553Z,1397275322.553 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2014-04-12T04:02:02.660Z,1397275322.660 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2014-04-12T04:02:02.663Z,1397275322.663 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2014-04-12T04:02:02.767Z,1397275322.767 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2014-04-12T04:02:02.768Z,1397275322.768 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2014-04-12T04:02:02.892Z,1397275322.892 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2014-04-12T04:02:02.894Z,1397275322.894 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2014-04-12T04:02:02.898Z,1397275322.898 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2014-04-12T04:02:03.005Z,1397275323.005 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2014-04-12T04:02:03.134Z,1397275323.134 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2014-04-12T04:02:03.231Z,1397275323.231 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2014-04-12T04:02:03.324Z,1397275323.324 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2014-04-12T04:02:03.477Z,1397275323.477 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2014-04-12T04:02:03.595Z,1397275323.595 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2014-04-12T04:02:03.684Z,1397275323.684 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2014-04-12T04:02:03.788Z,1397275323.788 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2014-04-12T04:02:03.889Z,1397275323.889 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2014-04-12T04:02:03.892Z,1397275323.892 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2014-04-12T04:02:04.613Z,1397275324.613 [AHRS_sp3003D] Loaded 2014-04-12T04:02:04.613Z,1397275324.613 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread. 2014-04-12T04:02:04.953Z,1397275324.953 [Batt_Ocean_Server] Loaded 2014-04-12T04:02:04.953Z,1397275324.953 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread. 2014-04-12T04:02:04.966Z,1397275324.966 [Depth_Keller] Loaded 2014-04-12T04:02:04.967Z,1397275324.967 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2014-04-12T04:02:04.972Z,1397275324.972 [DropWeight] Loaded 2014-04-12T04:02:04.972Z,1397275324.972 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2014-04-12T04:02:05.110Z,1397275325.110 [DVL_micro] Loaded 2014-04-12T04:02:05.110Z,1397275325.110 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2014-04-12T04:02:05.209Z,1397275325.209 [NAL9602] Loaded 2014-04-12T04:02:05.209Z,1397275325.209 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2014-04-12T04:02:05.260Z,1397275325.260 [Onboard] Loaded 2014-04-12T04:02:05.260Z,1397275325.260 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2014-04-12T04:02:05.268Z,1397275325.268 [Radio_Freewave] Loaded 2014-04-12T04:02:05.268Z,1397275325.268 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread. 2014-04-12T04:02:05.274Z,1397275325.274 [SCPI] Loaded 2014-04-12T04:02:05.275Z,1397275325.275 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread. 2014-04-12T04:02:05.275Z,1397275325.275 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2014-04-12T04:02:05.276Z,1397275325.276 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2014-04-12T04:02:05.470Z,1397275325.470 [InternalSim] Loaded 2014-04-12T04:02:05.470Z,1397275325.470 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread. 2014-04-12T04:02:05.470Z,1397275325.470 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2014-04-12T04:02:05.471Z,1397275325.471 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2014-04-12T04:02:05.747Z,1397275325.747 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2014-04-12T04:02:05.748Z,1397275325.748 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2014-04-12T04:02:05.757Z,1397275325.757 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator. 2014-04-12T04:02:05.762Z,1397275325.762 [AsyncPiEstimator] Loaded 2014-04-12T04:02:05.762Z,1397275325.762 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread. 2014-04-12T04:02:05.763Z,1397275325.763 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 4069E4E0 2014-04-12T04:02:05.764Z,1397275325.764 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2014-04-12T04:02:05.765Z,1397275325.765 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2014-04-12T04:02:05.858Z,1397275325.858 [DeadReckonUsingMultipleVelocitySources] Loaded 2014-04-12T04:02:05.858Z,1397275325.858 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2014-04-12T04:02:05.902Z,1397275325.902 [DeadReckonWithRespectToWater] Loaded 2014-04-12T04:02:05.902Z,1397275325.902 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread. 2014-04-12T04:02:05.948Z,1397275325.948 [DeadReckonUsingDVLWaterTrack] Loaded 2014-04-12T04:02:05.948Z,1397275325.948 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread. 2014-04-12T04:02:05.964Z,1397275325.964 [NavChart] Loaded 2014-04-12T04:02:05.965Z,1397275325.965 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2014-04-12T04:02:05.968Z,1397275325.968 [UniversalFixResidualReporter] Loaded 2014-04-12T04:02:05.969Z,1397275325.969 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2014-04-12T04:02:05.969Z,1397275325.969 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2014-04-12T04:02:05.970Z,1397275325.970 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2014-04-12T04:02:06.037Z,1397275326.037 [VerticalControl](DEBUG): Construct VerticalControl. 2014-04-12T04:02:06.147Z,1397275326.147 [VerticalControl] Loaded 2014-04-12T04:02:06.147Z,1397275326.147 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2014-04-12T04:02:06.148Z,1397275326.148 [HorizontalControl](DEBUG): Construct HorizontalControl. 2014-04-12T04:02:06.214Z,1397275326.214 [HorizontalControl] Loaded 2014-04-12T04:02:06.214Z,1397275326.214 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2014-04-12T04:02:06.215Z,1397275326.215 [SpeedControl](DEBUG): Construct SpeedControl. 2014-04-12T04:02:06.216Z,1397275326.216 [SpeedControl] Loaded 2014-04-12T04:02:06.217Z,1397275326.217 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2014-04-12T04:02:06.217Z,1397275326.217 [LoopControl](DEBUG): Construct LoopControl. 2014-04-12T04:02:06.218Z,1397275326.218 [LoopControl] Loaded 2014-04-12T04:02:06.218Z,1397275326.218 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2014-04-12T04:02:06.219Z,1397275326.219 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2014-04-12T04:02:06.219Z,1397275326.219 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2014-04-12T04:02:06.248Z,1397275326.248 [DepthRateCalculator] Loaded 2014-04-12T04:02:06.248Z,1397275326.248 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2014-04-12T04:02:06.254Z,1397275326.254 [PitchRateCalculator] Loaded 2014-04-12T04:02:06.254Z,1397275326.254 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2014-04-12T04:02:06.261Z,1397275326.261 [SpeedCalculator] Loaded 2014-04-12T04:02:06.261Z,1397275326.261 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2014-04-12T04:02:06.277Z,1397275326.277 [TempGradientCalculator] Loaded 2014-04-12T04:02:06.278Z,1397275326.278 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2014-04-12T04:02:06.283Z,1397275326.283 [YawRateCalculator] Loaded 2014-04-12T04:02:06.283Z,1397275326.283 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2014-04-12T04:02:06.284Z,1397275326.284 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2014-04-12T04:02:06.284Z,1397275326.284 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2014-04-12T04:02:06.412Z,1397275326.412 [CTD_NeilBrown] Loaded 2014-04-12T04:02:06.412Z,1397275326.412 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2014-04-12T04:02:06.413Z,1397275326.413 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 407404E0 2014-04-12T04:02:06.428Z,1397275326.428 [PAR_Licor] Loaded 2014-04-12T04:02:06.428Z,1397275326.428 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2014-04-12T04:02:06.463Z,1397275326.463 [WetLabsBB2FL] Loaded 2014-04-12T04:02:06.463Z,1397275326.463 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2014-04-12T04:02:06.464Z,1397275326.464 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407704E0 2014-04-12T04:02:06.465Z,1397275326.465 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2014-04-12T04:02:06.467Z,1397275326.467 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2014-04-12T04:02:06.582Z,1397275326.582 [SBIT](DEBUG): Construct Startup Built In Test. 2014-04-12T04:02:06.594Z,1397275326.594 [SBIT] Loaded 2014-04-12T04:02:06.594Z,1397275326.594 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2014-04-12T04:02:06.595Z,1397275326.595 [IBIT](DEBUG): Construct Initiated Built In Test. 2014-04-12T04:02:06.607Z,1397275326.607 [IBIT] Loaded 2014-04-12T04:02:06.607Z,1397275326.607 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2014-04-12T04:02:06.609Z,1397275326.609 [CBIT](DEBUG): Construct CBIT Built In Test. 2014-04-12T04:02:06.678Z,1397275326.678 [CBIT] Loaded 2014-04-12T04:02:06.678Z,1397275326.678 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2014-04-12T04:02:06.678Z,1397275326.678 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2014-04-12T04:02:06.681Z,1397275326.681 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2014-04-12T04:02:06.750Z,1397275326.750 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2014-04-12T04:02:06.751Z,1397275326.751 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2014-04-12T04:02:06.870Z,1397275326.870 [BuoyancyServo] Loaded 2014-04-12T04:02:06.870Z,1397275326.870 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2014-04-12T04:02:06.882Z,1397275326.882 [ElevatorServo] Loaded 2014-04-12T04:02:06.883Z,1397275326.883 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2014-04-12T04:02:06.894Z,1397275326.894 [MassServo] Loaded 2014-04-12T04:02:06.895Z,1397275326.895 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2014-04-12T04:02:06.907Z,1397275326.907 [RudderServo] Loaded 2014-04-12T04:02:06.907Z,1397275326.907 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2014-04-12T04:02:06.918Z,1397275326.918 [ThrusterServo] Loaded 2014-04-12T04:02:06.918Z,1397275326.918 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2014-04-12T04:02:06.919Z,1397275326.919 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2014-04-12T04:02:06.920Z,1397275326.920 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2014-04-12T04:02:06.958Z,1397275326.958 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2014-04-12T04:02:06.960Z,1397275326.960 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2014-04-12T04:02:06.961Z,1397275326.961 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2014-04-12T04:02:06.967Z,1397275326.967 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2014-04-12T04:02:06.968Z,1397275326.968 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 4083E4E0 2014-04-12T04:02:06.973Z,1397275326.973 [Supervisor](DEBUG): Running supervisor. 2014-04-12T04:02:06.974Z,1397275326.974 [CommandLine](INFO): Thread ID is 771 2014-04-12T04:02:06.977Z,1397275326.977 [controlThread](INFO): Thread ID is 770 2014-04-12T04:02:06.977Z,1397275326.977 [controlThread](DEBUG): Initializing ControlThread 2014-04-12T04:02:06.978Z,1397275326.978 [CycleStarter](INFO): Thread ID is 769 2014-04-12T04:02:06.981Z,1397275326.981 [logger](INFO): Thread ID is 772 2014-04-12T04:02:07.003Z,1397275327.003 [AsyncPiEstimator](INFO): Thread ID is 848 2014-04-12T04:02:07.003Z,1397275327.003 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator. 2014-04-12T04:02:07.019Z,1397275327.019 [CTD_NeilBrown](INFO): Thread ID is 849 2014-04-12T04:02:07.019Z,1397275327.019 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown. 2014-04-12T04:02:07.022Z,1397275327.022 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4 2014-04-12T04:02:07.024Z,1397275327.024 [WetLabsBB2FL](INFO): Thread ID is 850 2014-04-12T04:02:07.026Z,1397275327.026 [WetLabsBB2FL](INFO): Powering down 2014-04-12T04:02:07.055Z,1397275327.055 [NavChartDb](INFO): Thread ID is 851 2014-04-12T04:02:07.061Z,1397275327.061 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory 2014-04-12T04:02:07.078Z,1397275327.078 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources 2014-04-12T04:02:07.091Z,1397275327.091 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000 2014-04-12T04:02:07.092Z,1397275327.092 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000 2014-04-12T04:02:07.094Z,1397275327.094 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000 2014-04-12T04:02:07.096Z,1397275327.096 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000 2014-04-12T04:02:07.097Z,1397275327.097 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000 2014-04-12T04:02:07.099Z,1397275327.099 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000 2014-04-12T04:02:07.101Z,1397275327.101 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000 2014-04-12T04:02:07.111Z,1397275327.111 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000 2014-04-12T04:02:07.278Z,1397275327.278 [NavChartDb](INFO): Setup scan of Resources/US5CA62M.000 2014-04-12T04:02:08.823Z,1397275328.823 [Batt_Ocean_Server](INFO): Ocean Server Batteries initialized 2014-04-12T04:02:08.852Z,1397275328.852 [InternalSim](DEBUG): InternalSim initializing... 2014-04-12T04:02:09.023Z,1397275329.023 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2014-04-12T04:02:09.023Z,1397275329.023 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component. 2014-04-12T04:02:09.024Z,1397275329.024 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component. 2014-04-12T04:02:09.024Z,1397275329.024 [NavChart](DEBUG): Initialize NavChart Navigation. 2014-04-12T04:02:09.025Z,1397275329.025 [UniversalFixResidualReporter](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2014-04-12T04:02:09.025Z,1397275329.025 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2014-04-12T04:02:09.027Z,1397275329.027 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2014-04-12T04:02:09.028Z,1397275329.028 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2014-04-12T04:02:09.028Z,1397275329.028 [LoopControl](DEBUG): Initialize LoopControlComponent. 2014-04-12T04:02:09.028Z,1397275329.028 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2014-04-12T04:02:09.029Z,1397275329.029 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2014-04-12T04:02:09.029Z,1397275329.029 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2014-04-12T04:02:09.030Z,1397275329.030 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2014-04-12T04:02:09.030Z,1397275329.030 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2014-04-12T04:02:09.031Z,1397275329.031 [SBIT](INFO): Initialize SBIT Component. 2014-04-12T04:02:09.032Z,1397275329.032 [SBIT](IMPORTANT): Tethys CM Info: $Rev:11196 2014-04-12T04:02:09.032Z,1397275329.032 [SBIT](IMPORTANT): Kernel Release:2.6.27.8 2014-04-12T04:02:09.032Z,1397275329.032 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 2014-04-12T04:02:09.036Z,1397275329.036 [IBIT](INFO): Initialize IBIT Component. 2014-04-12T04:02:09.037Z,1397275329.037 [CBIT](DEBUG): Initialize CBIT Component. 2014-04-12T04:02:09.037Z,1397275329.037 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2014-04-12T04:02:09.038Z,1397275329.038 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2014-04-12T04:02:09.063Z,1397275329.063 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2014-04-12T04:02:09.078Z,1397275329.078 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2014-04-12T04:02:09.113Z,1397275329.113 [MissionManager](DEBUG): 2014-04-12T04:02:09.114Z,1397275329.114 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2014-04-12T04:02:09.157Z,1397275329.157 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool 2014-04-12T04:02:09.159Z,1397275329.159 [Default:GPS:A.SetSpeed](DEBUG): Construct. 2014-04-12T04:02:09.162Z,1397275329.162 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface. 2014-04-12T04:02:09.171Z,1397275329.171 [Default:Iridium:A.SetSpeed](DEBUG): Construct. 2014-04-12T04:02:09.174Z,1397275329.174 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface. 2014-04-12T04:02:09.182Z,1397275329.182 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2014-04-12T04:02:09.228Z,1397275329.228 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed](DEBUG): Construct. 2014-04-12T04:02:09.232Z,1397275329.232 [Default:WaitAtTheSurface:B.GoToSurface](DEBUG): Construct GoToSurface. 2014-04-12T04:02:09.239Z,1397275329.239 [MissionManager](DEBUG): 400 400 Burn 300 Dropped drop weight due to communications timeout 5.0 1.0 5 2014-04-12T04:02:09.246Z,1397275329.246 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,Radio_Freewave,SCPI,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonWithRespectToWater,DeadReckonUsingDVLWaterTrack,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2014-04-12T04:02:09.280Z,1397275329.280 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D. 2014-04-12T04:02:09.334Z,1397275329.334 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar 2014-04-12T04:02:09.359Z,1397275329.359 [DVL_micro](INFO): Initializing 2014-04-12T04:02:09.442Z,1397275329.442 [Radio_Freewave](INFO): Powering up 2014-04-12T04:02:09.636Z,1397275329.636 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:09.643Z,1397275329.643 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:09.650Z,1397275329.650 [DeadReckonUsingDVLWaterTrack](DEBUG): DVL water track data is invalid. 2014-04-12T04:02:09.650Z,1397275329.650 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:09.738Z,1397275329.737 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2014-04-12T04:02:09.741Z,1397275329.741 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2014-04-12T04:02:09.748Z,1397275329.748 [ElevatorServo](DEBUG): Initializing EZServoServo. 2014-04-12T04:02:09.749Z,1397275329.749 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2014-04-12T04:02:09.756Z,1397275329.756 [MassServo](DEBUG): Initializing EZServoServo. 2014-04-12T04:02:09.757Z,1397275329.757 [MassServo](DEBUG): Initializing MassServo. 2014-04-12T04:02:09.763Z,1397275329.763 [RudderServo](DEBUG): Initializing EZServoServo. 2014-04-12T04:02:09.764Z,1397275329.764 [RudderServo](DEBUG): Initializing RudderServo. 2014-04-12T04:02:09.770Z,1397275329.770 [ThrusterServo](DEBUG): Initializing EZServoServo. 2014-04-12T04:02:09.771Z,1397275329.771 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2014-04-12T04:02:09.821Z,1397275329.821 [CommandLine](FAULT): Scheduling is paused 2014-04-12T04:02:09.967Z,1397275329.967 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:09.968Z,1397275329.968 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:09.969Z,1397275329.969 [DeadReckonUsingDVLWaterTrack](DEBUG): DVL water track data is invalid. 2014-04-12T04:02:09.970Z,1397275329.970 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:10.346Z,1397275330.346 [NAL9602](INFO): Powering up NAL9602 2014-04-12T04:02:10.365Z,1397275330.365 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:10.366Z,1397275330.366 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:10.367Z,1397275330.367 [DeadReckonUsingDVLWaterTrack](DEBUG): DVL water track data is invalid. 2014-04-12T04:02:10.367Z,1397275330.367 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:10.708Z,1397275330.708 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2014-04-12T04:02:10.708Z,1397275330.708 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2014-04-12T04:02:10.708Z,1397275330.708 [BuoyancyServo] Communications Fault, FailCount= 1 2014-04-12T04:02:10.708Z,1397275330.708 [BuoyancyServo](ERROR): Communications Fault 2014-04-12T04:02:10.906Z,1397275330.906 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2014-04-12T04:02:11.269Z,1397275331.269 [Radio_Freewave](IMPORTANT): Starting PPPD with command:/sbin/pppd nocrtscts /dev/ttyS1 noauth 115200 10.1.1.2:10.1.1.1 persist maxfail 0 2014-04-12T04:02:11.330Z,1397275331.330 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:11.331Z,1397275331.331 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:11.332Z,1397275331.332 [DeadReckonUsingDVLWaterTrack](DEBUG): DVL water track data is invalid. 2014-04-12T04:02:11.332Z,1397275331.332 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:11.363Z,1397275331.363 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2014-04-12T04:02:11.364Z,1397275331.364 [BuoyancyServo](INFO): Powering down 2014-04-12T04:02:12.036Z,1397275332.036 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:12.038Z,1397275332.038 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:12.039Z,1397275332.039 [DeadReckonUsingDVLWaterTrack](DEBUG): DVL water track data is invalid. 2014-04-12T04:02:12.039Z,1397275332.039 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:12.419Z,1397275332.419 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:12.420Z,1397275332.420 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:12.421Z,1397275332.421 [DeadReckonUsingDVLWaterTrack](DEBUG): DVL water track data is invalid. 2014-04-12T04:02:12.421Z,1397275332.421 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:13.070Z,1397275333.070 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:13.071Z,1397275333.071 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:13.073Z,1397275333.073 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:13.476Z,1397275333.476 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:13.478Z,1397275333.478 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:13.479Z,1397275333.479 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:13.619Z,1397275333.619 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2014-04-12T04:02:13.619Z,1397275333.619 [BuoyancyServo] No Fault, FailCount= 1 2014-04-12T04:02:14.149Z,1397275334.149 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:14.151Z,1397275334.151 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:14.152Z,1397275334.152 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:14.158Z,1397275334.158 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2014-04-12T04:02:14.159Z,1397275334.159 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2014-04-12T04:02:14.704Z,1397275334.704 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:14.706Z,1397275334.706 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:14.708Z,1397275334.708 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:15.046Z,1397275335.046 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2014-04-12T04:02:15.046Z,1397275335.046 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2014-04-12T04:02:15.047Z,1397275335.047 [BuoyancyServo] Communications Fault, FailCount= 2 2014-04-12T04:02:15.047Z,1397275335.047 [BuoyancyServo](ERROR): Communications Fault 2014-04-12T04:02:15.077Z,1397275335.077 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2014-04-12T04:02:15.585Z,1397275335.585 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:15.586Z,1397275335.586 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:15.588Z,1397275335.588 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:15.594Z,1397275335.594 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2014-04-12T04:02:15.595Z,1397275335.595 [BuoyancyServo](INFO): Powering down 2014-04-12T04:02:16.016Z,1397275336.016 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:16.017Z,1397275336.017 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:16.018Z,1397275336.018 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:16.409Z,1397275336.409 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:16.410Z,1397275336.410 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:16.412Z,1397275336.412 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:16.818Z,1397275336.818 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:16.819Z,1397275336.819 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:16.821Z,1397275336.821 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:17.207Z,1397275337.207 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:17.208Z,1397275337.208 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:17.210Z,1397275337.210 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:17.611Z,1397275337.611 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:17.612Z,1397275337.612 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:17.613Z,1397275337.613 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:17.658Z,1397275337.658 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2014-04-12T04:02:17.658Z,1397275337.658 [BuoyancyServo] No Fault, FailCount= 2 2014-04-12T04:02:18.076Z,1397275338.076 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:18.077Z,1397275338.077 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:18.082Z,1397275338.082 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:18.088Z,1397275338.088 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2014-04-12T04:02:18.089Z,1397275338.089 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2014-04-12T04:02:18.571Z,1397275338.571 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:18.576Z,1397275338.576 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:18.578Z,1397275338.578 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:19.014Z,1397275339.014 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:19.015Z,1397275339.015 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:19.016Z,1397275339.016 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:19.615Z,1397275339.615 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:19.616Z,1397275339.616 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:19.618Z,1397275339.618 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:20.091Z,1397275340.091 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:20.092Z,1397275340.092 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:20.093Z,1397275340.093 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:20.513Z,1397275340.513 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:20.514Z,1397275340.514 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:20.516Z,1397275340.516 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:20.948Z,1397275340.948 [NAL9602](INFO): NAL9602 initialized 2014-04-12T04:02:20.979Z,1397275340.979 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:20.982Z,1397275340.982 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:20.985Z,1397275340.985 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:21.475Z,1397275341.475 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:21.478Z,1397275341.478 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:21.479Z,1397275341.479 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:21.972Z,1397275341.972 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:21.976Z,1397275341.976 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:21.978Z,1397275341.978 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:22.522Z,1397275342.522 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:22.523Z,1397275342.523 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:22.524Z,1397275342.524 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:23.074Z,1397275343.074 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:23.075Z,1397275343.075 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:23.077Z,1397275343.077 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:23.575Z,1397275343.575 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:23.576Z,1397275343.576 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:23.578Z,1397275343.578 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:24.021Z,1397275344.021 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:24.022Z,1397275344.022 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:24.040Z,1397275344.040 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:24.586Z,1397275344.586 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:24.587Z,1397275344.587 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:24.589Z,1397275344.589 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:24.625Z,1397275344.625 [SBIT](IMPORTANT): Beginning Startup BIT 2014-04-12T04:02:24.640Z,1397275344.640 [CBIT](IMPORTANT): Beginning GF scan 2014-04-12T04:02:25.117Z,1397275345.117 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:25.118Z,1397275345.118 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:25.120Z,1397275345.120 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:25.615Z,1397275345.615 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:25.616Z,1397275345.616 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:25.617Z,1397275345.617 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:26.067Z,1397275346.067 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:26.068Z,1397275346.068 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:26.069Z,1397275346.069 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:26.558Z,1397275346.558 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:26.560Z,1397275346.560 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:26.565Z,1397275346.565 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:27.142Z,1397275347.142 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:27.143Z,1397275347.143 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:27.149Z,1397275347.149 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:28.102Z,1397275348.102 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:28.103Z,1397275348.103 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:28.104Z,1397275348.104 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:28.670Z,1397275348.670 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:28.671Z,1397275348.671 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:28.676Z,1397275348.676 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:29.209Z,1397275349.209 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:29.210Z,1397275349.210 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:29.211Z,1397275349.211 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:29.796Z,1397275349.796 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:29.797Z,1397275349.797 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:29.799Z,1397275349.799 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:30.387Z,1397275350.387 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:30.388Z,1397275350.388 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:30.390Z,1397275350.390 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:30.846Z,1397275350.846 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:30.852Z,1397275350.852 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:30.853Z,1397275350.853 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:31.391Z,1397275351.391 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:31.392Z,1397275351.392 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:31.394Z,1397275351.394 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:31.875Z,1397275351.875 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:31.877Z,1397275351.877 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:31.878Z,1397275351.878 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:32.373Z,1397275352.373 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:32.378Z,1397275352.378 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:32.380Z,1397275352.380 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:32.833Z,1397275352.833 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:32.834Z,1397275352.834 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:32.836Z,1397275352.836 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:33.310Z,1397275353.310 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:33.311Z,1397275353.311 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:33.313Z,1397275353.313 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:33.786Z,1397275353.786 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:33.787Z,1397275353.787 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:33.789Z,1397275353.789 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:34.272Z,1397275354.272 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:34.277Z,1397275354.277 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:34.279Z,1397275354.279 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:34.859Z,1397275354.859 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:34.860Z,1397275354.860 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:34.862Z,1397275354.862 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:35.297Z,1397275355.297 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:35.299Z,1397275355.299 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:35.300Z,1397275355.300 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:35.769Z,1397275355.769 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:35.770Z,1397275355.770 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:35.775Z,1397275355.775 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:36.199Z,1397275356.199 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:36.200Z,1397275356.200 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:36.202Z,1397275356.202 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:36.602Z,1397275356.602 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:36.604Z,1397275356.604 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:36.605Z,1397275356.605 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:37.067Z,1397275357.067 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:37.068Z,1397275357.068 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:37.073Z,1397275357.073 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:37.512Z,1397275357.512 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:37.513Z,1397275357.513 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:37.514Z,1397275357.514 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:37.997Z,1397275357.997 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:37.998Z,1397275357.998 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:37.000Z,1397275358.000 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:38.443Z,1397275358.443 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:38.444Z,1397275358.444 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:38.446Z,1397275358.446 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:38.892Z,1397275358.892 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:38.893Z,1397275358.893 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:38.895Z,1397275358.895 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:39.311Z,1397275359.311 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:39.313Z,1397275359.313 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:39.314Z,1397275359.314 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:39.717Z,1397275359.717 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=24509, MT Status=1, MTMSN=1657 2014-04-12T04:02:39.717Z,1397275359.717 [NAL9602](INFO): Data available in MT queue 2014-04-12T04:02:39.759Z,1397275359.759 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:39.760Z,1397275359.760 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:39.762Z,1397275359.762 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:40.483Z,1397275360.483 [NAL9602](INFO): Received command:configSet SCPI.loadAtStartup 0 bool 2014-04-12T04:02:40.663Z,1397275360.663 [CommandLine](IMPORTANT): got command configSet SCPI.loadAtStartup 0.000000 bool 2014-04-12T04:02:40.663Z,1397275360.663 [CommandLine](FAULT): configSet SCPI.loadAtStartup without persist will have no effect. 2014-04-12T04:02:40.693Z,1397275360.693 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:40.694Z,1397275360.694 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:40.695Z,1397275360.695 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:41.717Z,1397275361.717 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:41.718Z,1397275361.718 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:41.720Z,1397275361.720 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:42.280Z,1397275362.280 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:42.282Z,1397275362.282 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:42.283Z,1397275362.283 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:42.820Z,1397275362.820 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:42.821Z,1397275362.821 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:42.823Z,1397275362.823 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:43.390Z,1397275363.390 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:43.392Z,1397275363.392 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:43.393Z,1397275363.393 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:43.902Z,1397275363.902 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:43.904Z,1397275363.904 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:43.905Z,1397275363.905 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:44.465Z,1397275364.465 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:44.467Z,1397275364.467 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:44.472Z,1397275364.472 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:45.024Z,1397275365.024 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:45.041Z,1397275365.041 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:45.043Z,1397275365.043 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:45.686Z,1397275365.686 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:45.687Z,1397275365.687 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:45.689Z,1397275365.689 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:46.221Z,1397275366.221 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:46.223Z,1397275366.223 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:46.224Z,1397275366.224 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:46.784Z,1397275366.784 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:46.786Z,1397275366.786 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:46.787Z,1397275366.787 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:47.244Z,1397275367.244 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:47.245Z,1397275367.245 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:47.247Z,1397275367.247 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:47.722Z,1397275367.722 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:47.724Z,1397275367.724 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:47.741Z,1397275367.741 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:48.269Z,1397275368.269 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:48.275Z,1397275368.275 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:48.276Z,1397275368.276 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:48.782Z,1397275368.782 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:48.783Z,1397275368.783 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:48.784Z,1397275368.784 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:49.281Z,1397275369.281 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:49.282Z,1397275369.282 [DeadReckonWithRespectToWater](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:49.284Z,1397275369.284 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:02:49.738Z,1397275369.738 [NAL9602](IMPORTANT): GPS fix at: 1397275342.00 2014-04-12T04:02:49.769Z,1397275369.769 [DeadReckonUsingMultipleVelocitySources](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07 2014-04-12T04:02:49.777Z,1397275369.777 [DeadReckonWithRespectToWater](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07 2014-04-12T04:02:49.780Z,1397275369.780 [DeadReckonUsingDVLWaterTrack](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07 2014-04-12T04:02:52.065Z,1397275372.065 [CBIT](IMPORTANT): No ground fault detected 2014-04-12T04:02:57.248Z,1397275377.248 [NAL9602](INFO): Powering down 2014-04-12T04:03:18.852Z,1397275398.852 [SBIT](IMPORTANT): SBIT PASSED 2014-04-12T04:03:19.247Z,1397275399.247 [MissionManager](IMPORTANT): Started mission Startup 2014-04-12T04:03:19.247Z,1397275399.247 [Startup] Running Loop=1 2014-04-12T04:03:19.247Z,1397275399.247 [Startup](INFO): Aggregate::initialize Startup 2014-04-12T04:03:19.247Z,1397275399.247 [Startup:A.GoToSurface] Running Loop=1 2014-04-12T04:03:19.247Z,1397275399.247 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2014-04-12T04:03:19.254Z,1397275399.254 [Startup:StartupSatComms] Running Loop=1 2014-04-12T04:03:19.255Z,1397275399.255 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms 2014-04-12T04:03:19.255Z,1397275399.255 [Startup:StartupSatComms:A] Running Loop=1 2014-04-12T04:03:19.702Z,1397275399.702 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2014-04-12T04:03:20.155Z,1397275400.155 [NAL9602](INFO): Powering up 2014-04-12T04:03:30.816Z,1397275410.816 [NAL9602](INFO): NAL9602 initialized 2014-04-12T04:03:34.262Z,1397275414.262 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46 2014-04-12T04:03:34.263Z,1397275414.263 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 46.4 51.6 56.0 50.2 351.4 504.2 515.0 346.4 244 462 369 236 12 15 13 14 -218.3 210.6 462.9 1 370.6 183.4 367.9301 353 2 344 -10 353 2 -1.94 -17.60 239.3 46.4 14.5 0.005 35.0 1489 114 2014-04-12T04:03:47.714Z,1397275427.714 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=24510, MT Status=1, MTMSN=1658 2014-04-12T04:03:47.714Z,1397275427.714 [NAL9602](INFO): Data available in MT queue 2014-04-12T04:03:48.536Z,1397275428.536 [NAL9602](INFO): Received command:! tail /mnt/mmc/LRAUV/Logs/previous/topLog 2014-04-12T04:03:48.568Z,1397275428.568 [CommandLine](IMPORTANT): got command ! tail /mnt/mmc/LRAUV/Logs/previous/topLog 2014-04-12T04:03:53.104Z,1397275433.104 [NAL9602](IMPORTANT): GPS fix at: 1397275405.00 2014-04-12T04:03:53.117Z,1397275433.117 [DeadReckonUsingMultipleVelocitySources](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07 2014-04-12T04:03:53.120Z,1397275433.120 [DeadReckonWithRespectToWater](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07 2014-04-12T04:03:53.123Z,1397275433.123 [DeadReckonUsingDVLWaterTrack](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07 2014-04-12T04:03:53.126Z,1397275433.126 [Startup:StartupSatComms:A] Stopped 2014-04-12T04:03:53.126Z,1397275433.126 [Startup:StartupSatComms:B] Running Loop=1 2014-04-12T04:03:53.664Z,1397275433.664 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2014-04-12T04:04:17.216Z,1397275457.216 [NAL9602](INFO): SBD MO Status=1, MOMSN=24511, MT Status=0, MTMSN=0 2014-04-12T04:04:17.268Z,1397275457.268 [NAL9602](INFO): Sent 18 bytes from file Logs/20140412T025444/Courier0024.lzma 2014-04-12T04:04:17.269Z,1397275457.269 [NAL9602](INFO): Packets left to send: 0 2014-04-12T04:04:17.270Z,1397275457.270 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T025444/Courier0024.lzma.parts/0000.sbd 2014-04-12T04:04:17.271Z,1397275457.271 [NAL9602](INFO): Completed sending Logs/20140412T025444/Courier0024.lzma 2014-04-12T04:04:22.809Z,1397275462.809 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46 2014-04-12T04:04:22.809Z,1397275462.809 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 0 1 1 50.6 0 50.0 48.8 222.0 0 142.6 98.9 112 127 14 14 10 13 106.0 222.9 196.6 1 190.9 -169.6 185.5 1 177 189 203 2 119 -230 203 2 -4.12 -3.09 250.4 48.8 14.5 0.01489 84 2014-04-12T04:04:28.145Z,1397275468.145 [NAL9602](INFO): SBD MO Status=1, MOMSN=24512, MT Status=0, MTMSN=0 2014-04-12T04:04:28.193Z,1397275468.193 [NAL9602](INFO): Sent 332 bytes from file Logs/20140412T040156/Courier0000.lzma 2014-04-12T04:04:28.193Z,1397275468.193 [NAL9602](INFO): Packets left to send: 1 2014-04-12T04:04:28.195Z,1397275468.195 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Courier0000.lzma.parts/0001.sbd 2014-04-12T04:04:39.687Z,1397275479.687 [NAL9602](INFO): SBD MO Status=1, MOMSN=24513, MT Status=0, MTMSN=0 2014-04-12T04:04:39.735Z,1397275479.735 [NAL9602](INFO): Sent 25 bytes from file Logs/20140412T040156/Courier0000.lzma 2014-04-12T04:04:39.735Z,1397275479.735 [NAL9602](INFO): Packets left to send: 0 2014-04-12T04:04:39.737Z,1397275479.737 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Courier0000.lzma.parts/0000.sbd 2014-04-12T04:04:39.737Z,1397275479.737 [NAL9602](INFO): Completed sending Logs/20140412T040156/Courier0000.lzma 2014-04-12T04:04:49.807Z,1397275489.807 [NAL9602](INFO): SBD MO Status=1, MOMSN=24514, MT Status=0, MTMSN=0 2014-04-12T04:04:49.859Z,1397275489.859 [NAL9602](INFO): Sent 18 bytes from file Logs/20140412T025444/Express0025.lzma 2014-04-12T04:04:49.859Z,1397275489.859 [NAL9602](INFO): Packets left to send: 0 2014-04-12T04:04:49.861Z,1397275489.861 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T025444/Express0025.lzma.parts/0000.sbd 2014-04-12T04:04:49.861Z,1397275489.861 [NAL9602](INFO): Completed sending Logs/20140412T025444/Express0025.lzma 2014-04-12T04:04:53.254Z,1397275493.254 [Startup:StartupSatComms:B](INFO): Timed out from 2014-04-12T04:03:53.1Z 2014-04-12T04:04:53.254Z,1397275493.254 [Startup:StartupSatComms:B] Stopped 2014-04-12T04:04:53.254Z,1397275493.254 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2014-04-12T04:04:53.254Z,1397275493.254 [Startup:StartupSatComms] Stopped 2014-04-12T04:04:53.254Z,1397275493.254 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms 2014-04-12T04:04:53.255Z,1397275493.255 [Startup](INFO): Completed Startup 2014-04-12T04:04:53.255Z,1397275493.255 [Startup] Stopped 2014-04-12T04:04:53.255Z,1397275493.255 [Startup](INFO): Aggregate::uninitialize Startup 2014-04-12T04:04:53.255Z,1397275493.255 [Startup:A.GoToSurface] Stopped 2014-04-12T04:04:53.256Z,1397275493.256 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2014-04-12T04:04:53.711Z,1397275493.711 [MissionManager](IMPORTANT): Started mission Default 2014-04-12T04:04:53.711Z,1397275493.711 [Default] Running Loop=1 2014-04-12T04:04:53.711Z,1397275493.711 [Default](INFO): Aggregate::initialize Default 2014-04-12T04:04:53.711Z,1397275493.711 [Default:Iridium] Running Loop=1 2014-04-12T04:04:53.711Z,1397275493.711 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2014-04-12T04:04:53.711Z,1397275493.711 [Default:Iridium:A.SetSpeed] Running Loop=1 2014-04-12T04:04:53.711Z,1397275493.711 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2014-04-12T04:04:53.712Z,1397275493.712 [Default:Iridium:B.GoToSurface] Running Loop=1 2014-04-12T04:04:53.712Z,1397275493.712 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2014-04-12T04:04:53.712Z,1397275493.712 [Default:CallIridium] Running Loop=1 2014-04-12T04:04:53.712Z,1397275493.712 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium 2014-04-12T04:04:53.713Z,1397275493.713 [Default:CallIridium:A] Running Loop=1 2014-04-12T04:04:53.715Z,1397275493.715 [Default:CallIridium:A] Stopped 2014-04-12T04:04:53.715Z,1397275493.715 [Default:CallIridium:B] Running Loop=1 2014-04-12T04:04:53.715Z,1397275493.715 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B 2014-04-12T04:04:53.721Z,1397275493.721 [Default:Iridium:B.GoToSurface] Stopped 2014-04-12T04:04:53.721Z,1397275493.721 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2014-04-12T04:04:53.721Z,1397275493.721 [Default:Iridium:Read_Iridium] Running Loop=1 2014-04-12T04:04:53.721Z,1397275493.721 [Default:Iridium:A.SetSpeed] Running Loop=1 2014-04-12T04:04:53.747Z,1397275493.747 [Default:GPS] Running Loop=1 2014-04-12T04:04:53.747Z,1397275493.747 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2014-04-12T04:04:53.747Z,1397275493.747 [Default:GPS:A.SetSpeed] Running Loop=1 2014-04-12T04:04:53.747Z,1397275493.747 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2014-04-12T04:04:53.747Z,1397275493.747 [Default:GPS:B.GoToSurface] Running Loop=1 2014-04-12T04:04:53.748Z,1397275493.748 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2014-04-12T04:04:53.758Z,1397275493.758 [Default:GPS:B.GoToSurface] Stopped 2014-04-12T04:04:53.758Z,1397275493.758 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2014-04-12T04:04:53.758Z,1397275493.758 [Default:GPS:Read_GPS] Running Loop=1 2014-04-12T04:04:53.758Z,1397275493.758 [Default:GPS:A.SetSpeed] Running Loop=1 2014-04-12T04:04:54.188Z,1397275494.188 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2014-04-12T04:04:54.191Z,1397275494.191 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2014-04-12T04:05:01.977Z,1397275501.977 [NAL9602](INFO): SBD MO Status=1, MOMSN=24515, MT Status=0, MTMSN=0 2014-04-12T04:05:02.030Z,1397275502.030 [NAL9602](INFO): Sent 332 bytes from file Logs/20140412T040156/Express0001.lzma 2014-04-12T04:05:02.030Z,1397275502.030 [NAL9602](INFO): Packets left to send: 3 2014-04-12T04:05:02.031Z,1397275502.031 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Express0001.lzma.parts/0003.sbd 2014-04-12T04:05:14.215Z,1397275514.215 [NAL9602](INFO): SBD MO Status=1, MOMSN=24516, MT Status=0, MTMSN=0 2014-04-12T04:05:14.269Z,1397275514.269 [NAL9602](INFO): Sent 76 bytes from file Logs/20140412T040156/Courier0004.lzma 2014-04-12T04:05:14.269Z,1397275514.269 [NAL9602](INFO): Packets left to send: 0 2014-04-12T04:05:14.271Z,1397275514.271 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Courier0004.lzma.parts/0000.sbd 2014-04-12T04:05:14.271Z,1397275514.271 [NAL9602](INFO): Completed sending Logs/20140412T040156/Courier0004.lzma 2014-04-12T04:05:23.322Z,1397275523.322 [NAL9602](INFO): SBD MO Status=1, MOMSN=24517, MT Status=0, MTMSN=0 2014-04-12T04:05:23.380Z,1397275523.380 [NAL9602](INFO): Sent 332 bytes from file Logs/20140412T040156/Express0001.lzma 2014-04-12T04:05:23.380Z,1397275523.380 [NAL9602](INFO): Packets left to send: 2 2014-04-12T04:05:23.381Z,1397275523.381 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Express0001.lzma.parts/0002.sbd 2014-04-12T04:05:36.571Z,1397275536.571 [NAL9602](INFO): SBD MO Status=1, MOMSN=24518, MT Status=0, MTMSN=0 2014-04-12T04:05:36.619Z,1397275536.619 [NAL9602](INFO): Sent 332 bytes from file Logs/20140412T040156/Express0001.lzma 2014-04-12T04:05:36.619Z,1397275536.619 [NAL9602](INFO): Packets left to send: 1 2014-04-12T04:05:36.621Z,1397275536.621 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Express0001.lzma.parts/0001.sbd 2014-04-12T04:05:47.059Z,1397275547.059 [NAL9602](INFO): SBD MO Status=1, MOMSN=24519, MT Status=0, MTMSN=0 2014-04-12T04:05:47.107Z,1397275547.107 [NAL9602](INFO): Sent 41 bytes from file Logs/20140412T040156/Express0001.lzma 2014-04-12T04:05:47.108Z,1397275547.108 [NAL9602](INFO): Packets left to send: 0 2014-04-12T04:05:47.109Z,1397275547.109 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Express0001.lzma.parts/0000.sbd 2014-04-12T04:05:47.109Z,1397275547.109 [NAL9602](INFO): Completed sending Logs/20140412T040156/Express0001.lzma 2014-04-12T04:05:58.222Z,1397275558.222 [NAL9602](INFO): SBD MO Status=1, MOMSN=24520, MT Status=0, MTMSN=0 2014-04-12T04:05:58.277Z,1397275558.277 [NAL9602](INFO): Sent 224 bytes from file Logs/20140412T040156/Express0005.lzma 2014-04-12T04:05:58.277Z,1397275558.277 [NAL9602](INFO): Packets left to send: 0 2014-04-12T04:05:58.280Z,1397275558.280 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Express0005.lzma.parts/0000.sbd 2014-04-12T04:05:58.283Z,1397275558.283 [NAL9602](INFO): Completed sending Logs/20140412T040156/Express0005.lzma 2014-04-12T04:06:05.797Z,1397275565.797 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 44 of 46 2014-04-12T04:06:05.797Z,1397275565.797 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 50.9 49.6 49.6 50.6 117.0 279.6 56.8 -58.7 257 308 75 -27 14 14 13 14 80.3 451.5 106.4 1 388.4 -242.6 109.1 1 242 447 165 2 320 -395 165 2 -0.03 -2.12 247.6 49.6 14.6 0.005 35.04 2014-04-12T04:06:08.957Z,1397275568.957 [NAL9602](INFO): SBD MO Status=0, MOMSN=24521, MT Status=0, MTMSN=0 2014-04-12T04:06:10.765Z,1397275570.765 [NAL9602](IMPORTANT): GPS fix at: 1397275544.00 2014-04-12T04:06:10.794Z,1397275570.794 [DeadReckonUsingMultipleVelocitySources](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07 2014-04-12T04:06:10.797Z,1397275570.797 [DeadReckonWithRespectToWater](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07 2014-04-12T04:06:10.799Z,1397275570.799 [DeadReckonUsingDVLWaterTrack](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07 2014-04-12T04:06:10.803Z,1397275570.803 [Default:GPS:Read_GPS] Stopped 2014-04-12T04:06:10.803Z,1397275570.803 [Default:GPS:D] Running Loop=1 2014-04-12T04:06:11.315Z,1397275571.315 [Default:GPS:D] Stopped 2014-04-12T04:06:11.315Z,1397275571.315 [Default:GPS](INFO): Completed Default:GPS 2014-04-12T04:06:11.315Z,1397275571.315 [Default:GPS] Stopped 2014-04-12T04:06:11.316Z,1397275571.316 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2014-04-12T04:06:11.316Z,1397275571.316 [Default:GPS:A.SetSpeed] Stopped 2014-04-12T04:06:11.316Z,1397275571.316 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2014-04-12T04:06:14.499Z,1397275574.499 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:94 Calculated:74 2014-04-12T04:06:14.500Z,1397275574.500 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 49.5 51.8 52.2 49.9 241.0 232.696.2 292 324 201 185 13 14 12 14 71.4 48.6 231.1 1 43.1 -64.2 234.3 1 121 185 270 2 135 -175 270 2 -3.21 -6.06 251.0 49.5 14.5 0.005 35.0 1489 94 2014-04-12T04:06:26.744Z,1397275586.744 [NAL9602](INFO): SBD MO Status=1, MOMSN=24522, MT Status=0, MTMSN=0 2014-04-12T04:06:26.789Z,1397275586.789 [NAL9602](INFO): Sent 45 bytes from file Logs/20140412T040156/Courier0008.lzma 2014-04-12T04:06:26.789Z,1397275586.789 [NAL9602](INFO): Packets left to send: 0 2014-04-12T04:06:26.794Z,1397275586.794 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Courier0008.lzma.parts/0000.sbd 2014-04-12T04:06:26.795Z,1397275586.795 [NAL9602](INFO): Completed sending Logs/20140412T040156/Courier0008.lzma 2014-04-12T04:06:39.871Z,1397275599.871 [NAL9602](INFO): SBD MO Status=1, MOMSN=24523, MT Status=0, MTMSN=0 2014-04-12T04:06:39.921Z,1397275599.921 [NAL9602](INFO): Sent 63 bytes from file Logs/20140412T040156/Express0009.lzma 2014-04-12T04:06:39.922Z,1397275599.922 [NAL9602](INFO): Packets left to send: 0 2014-04-12T04:06:39.923Z,1397275599.923 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Express0009.lzma.parts/0000.sbd 2014-04-12T04:06:39.923Z,1397275599.923 [NAL9602](INFO): Completed sending Logs/20140412T040156/Express0009.lzma 2014-04-12T04:06:47.856Z,1397275607.856 [NAL9602](INFO): SBD MO Status=0, MOMSN=24524, MT Status=0, MTMSN=0 2014-04-12T04:06:47.980Z,1397275607.980 [Default:Iridium:Read_Iridium] Stopped 2014-04-12T04:06:47.980Z,1397275607.980 [Default:Iridium](INFO): Completed Default:Iridium 2014-04-12T04:06:47.980Z,1397275607.980 [Default:Iridium] Stopped 2014-04-12T04:06:47.981Z,1397275607.981 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2014-04-12T04:06:47.981Z,1397275607.981 [Default:Iridium:A.SetSpeed] Stopped 2014-04-12T04:06:47.981Z,1397275607.981 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2014-04-12T04:06:47.981Z,1397275607.981 [Default:WaitAtTheSurface] Running Loop=1 2014-04-12T04:06:47.981Z,1397275607.981 [Default:WaitAtTheSurface](INFO): Aggregate::initialize Default:WaitAtTheSurface 2014-04-12T04:06:47.981Z,1397275607.981 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed] Running Loop=1 2014-04-12T04:06:47.983Z,1397275607.983 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed](DEBUG): Initialize. 2014-04-12T04:06:47.983Z,1397275607.983 [Default:WaitAtTheSurface:B.GoToSurface] Running Loop=1 2014-04-12T04:06:47.983Z,1397275607.983 [Default:WaitAtTheSurface:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2014-04-12T04:06:48.397Z,1397275608.397 [Default:WaitAtTheSurface:B.GoToSurface] Running Loop=1 2014-04-12T04:06:48.403Z,1397275608.403 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed] Running Loop=1 2014-04-12T04:06:48.409Z,1397275608.409 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B 2014-04-12T04:06:48.409Z,1397275608.409 [Default:CallIridium:B] Stopped 2014-04-12T04:06:48.409Z,1397275608.409 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B 2014-04-12T04:06:48.409Z,1397275608.409 [Default:CallIridium](INFO): Completed Default:CallIridium 2014-04-12T04:06:48.409Z,1397275608.409 [Default:CallIridium] Stopped 2014-04-12T04:06:48.409Z,1397275608.409 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium 2014-04-12T04:06:58.545Z,1397275618.545 [NAL9602](INFO): Powering down 2014-04-12T04:11:48.644Z,1397275908.644 [Default:CallIridium] Running Loop=1 2014-04-12T04:11:48.644Z,1397275908.644 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium 2014-04-12T04:11:48.644Z,1397275908.644 [Default:CallIridium:A] Running Loop=1 2014-04-12T04:11:48.645Z,1397275908.645 [Default:CallIridium:A] Stopped 2014-04-12T04:11:48.645Z,1397275908.645 [Default:CallIridium:B] Running Loop=1 2014-04-12T04:11:48.645Z,1397275908.645 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B 2014-04-12T04:11:53.593Z,1397275913.593 [Default:Iridium] Running Loop=1 2014-04-12T04:11:53.593Z,1397275913.593 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2014-04-12T04:11:53.593Z,1397275913.593 [Default:Iridium:A.SetSpeed] Running Loop=1 2014-04-12T04:11:53.594Z,1397275913.594 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2014-04-12T04:11:53.594Z,1397275913.594 [Default:Iridium:B.GoToSurface] Running Loop=1 2014-04-12T04:11:53.594Z,1397275913.594 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2014-04-12T04:11:53.594Z,1397275913.594 [Default:Iridium:B.GoToSurface] Stopped 2014-04-12T04:11:53.595Z,1397275913.595 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2014-04-12T04:11:53.595Z,1397275913.595 [Default:Iridium:Read_Iridium] Running Loop=1 2014-04-12T04:11:53.595Z,1397275913.595 [Default:GPS] Running Loop=1 2014-04-12T04:11:53.595Z,1397275913.595 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2014-04-12T04:11:53.595Z,1397275913.595 [Default:GPS:A.SetSpeed] Running Loop=1 2014-04-12T04:11:53.596Z,1397275913.596 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2014-04-12T04:11:53.596Z,1397275913.596 [Default:GPS:B.GoToSurface] Running Loop=1 2014-04-12T04:11:53.596Z,1397275913.596 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2014-04-12T04:11:53.597Z,1397275913.597 [Default:GPS:B.GoToSurface] Stopped 2014-04-12T04:11:53.597Z,1397275913.597 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2014-04-12T04:11:53.597Z,1397275913.597 [Default:GPS:Read_GPS] Running Loop=1 2014-04-12T04:11:55.051Z,1397275915.051 [NAL9602](INFO): Powering up 2014-04-12T04:12:05.547Z,1397275925.547 [NAL9602](INFO): NAL9602 initialized 2014-04-12T04:12:23.061Z,1397275943.061 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=24525, MT Status=1, MTMSN=1659 2014-04-12T04:12:23.109Z,1397275943.109 [NAL9602](INFO): Sent 75 bytes from file Logs/20140412T040156/Courier0012.lzma 2014-04-12T04:12:23.110Z,1397275943.110 [NAL9602](INFO): Packets left to send: 0 2014-04-12T04:12:23.111Z,1397275943.111 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Courier0012.lzma.parts/0000.sbd 2014-04-12T04:12:23.111Z,1397275943.111 [NAL9602](INFO): Completed sending Logs/20140412T040156/Courier0012.lzma 2014-04-12T04:12:23.995Z,1397275943.995 [NAL9602](INFO): Received command:sched asap "configSet Radio_Freewave.loadAtStartup 0 bool;configSet TempGradientCalculator.loadAtStartup 1 bool;configSet PAR_Licor.loadAtStartup 0 bool;configSet DeadReckonWithRespectToWater.loadAtStartup 0 bool" 3pnkt 1 3 2014-04-12T04:12:24.051Z,1397275944.051 [CommandLine](IMPORTANT): got command schedule asap "configSet Radio_Freewave.loadAtStartup 0 bool;configSet TempGradientCalculator.loadAtStartup 1 bool;configSet PAR_Licor.loadAtStartup 0 bool;configSet DeadReckonWithRespectToWater.loadAtStartup 0 bool" 3pnkt 1 3.000000 2014-04-12T04:12:24.052Z,1397275944.052 [CommandLine](IMPORTANT): Scheduling command #1 of 3 with id=3pnkt 2014-04-12T04:12:24.053Z,1397275944.053 [CommandLine](IMPORTANT): Scheduled #1 (#1 of 3 with id='3pnkt'): "configSet Radio_Freewave.loadAtStartup 0 bool;configSet TempGradientCalculator.loadAtStartup 1 bool;configSet PAR_Licor.loadAtStartup 0 bool;configSet DeadReckonWithRespectToWater.loadAtStartup 0 bool" ASAP 2014-04-12T04:12:49.884Z,1397275969.884 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=24526, MT Status=1, MTMSN=1660 2014-04-12T04:12:49.935Z,1397275969.935 [NAL9602](INFO): Sent 109 bytes from file Logs/20140412T040156/Express0013.lzma 2014-04-12T04:12:49.935Z,1397275969.935 [NAL9602](INFO): Packets left to send: 0 2014-04-12T04:12:49.937Z,1397275969.937 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Express0013.lzma.parts/0000.sbd 2014-04-12T04:12:49.937Z,1397275969.937 [NAL9602](INFO): Completed sending Logs/20140412T040156/Express0013.lzma 2014-04-12T04:12:50.753Z,1397275970.753 [NAL9602](INFO): Received command:sched asap "configSet DeadReckonWithRespectToSeafloor.loadAtStartup 0 bool;configSet DeadReckonUsingDVLWaterTrack.loadAtStartup 0 bool;configSet DeadReckonUsingCompactModelForecast.loadAtStartup 0 bool" 3pnkt 2 3 2014-04-12T04:12:50.798Z,1397275970.798 [CommandLine](IMPORTANT): got command schedule asap "configSet DeadReckonWithRespectToSeafloor.loadAtStartup 0 bool;configSet DeadReckonUsingDVLWaterTrack.loadAtStartup 0 bool;configSet DeadReckonUsingCompactModelForecast.loadAtStartup 0 bool" 3pnkt 2 3.000000 2014-04-12T04:12:50.798Z,1397275970.798 [CommandLine](IMPORTANT): Scheduling command #2 of 3 with id=3pnkt 2014-04-12T04:12:50.799Z,1397275970.799 [CommandLine](IMPORTANT): Scheduled #2 (#2 of 3 with id='3pnkt'): "configSet DeadReckonWithRespectToSeafloor.loadAtStartup 0 bool;configSet DeadReckonUsingDVLWaterTrack.loadAtStartup 0 bool;configSet DeadReckonUsingCompactModelForecast.loadAtStartup 0 bool" ASAP 2014-04-12T04:12:52.125Z,1397275972.125 [NAL9602](IMPORTANT): GPS fix at: 1397275944.00 2014-04-12T04:12:52.191Z,1397275972.191 [DeadReckonUsingMultipleVelocitySources](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07 2014-04-12T04:12:52.193Z,1397275972.193 [DeadReckonWithRespectToWater](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07 2014-04-12T04:12:52.196Z,1397275972.196 [DeadReckonUsingDVLWaterTrack](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07 2014-04-12T04:12:52.200Z,1397275972.200 [Default:GPS:Read_GPS] Stopped 2014-04-12T04:12:52.201Z,1397275972.201 [Default:GPS:D] Running Loop=1 2014-04-12T04:12:52.670Z,1397275972.670 [Default:GPS:D] Stopped 2014-04-12T04:12:52.670Z,1397275972.670 [Default:GPS](INFO): Completed Default:GPS 2014-04-12T04:12:52.670Z,1397275972.670 [Default:GPS] Stopped 2014-04-12T04:12:52.670Z,1397275972.670 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2014-04-12T04:12:52.670Z,1397275972.670 [Default:GPS:A.SetSpeed] Stopped 2014-04-12T04:12:52.670Z,1397275972.670 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2014-04-12T04:13:10.167Z,1397275990.167 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:106 Calculated:115 2014-04-12T04:13:10.167Z,1397275990.167 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 49.6 52.0 54.3 50.9 -355.1 -438.3 -516.0 -437.9 -297 -417 -485 14 10 14 214.7 -0.5 -471.2 1 -18.7 -243.1 -456.8 1 250 -5 -434 2 -7 -250 -434 2 -1.93 -3.53 269.4 49.6 14.5 0.005 35.0 1489 106 2014-04-12T04:13:11.877Z,1397275991.877 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=24527, MT Status=1, MTMSN=1661 2014-04-12T04:13:12.723Z,1397275992.723 [NAL9602](INFO): Received command:sched asap "configSet UniversalFixResidualReporter.loadAtStartup 0 bool;configSet SCPI.loadAtStartup 0 bool;" 3pnkt 3 3 2014-04-12T04:13:12.736Z,1397275992.736 [CommandLine](IMPORTANT): got command schedule asap "configSet UniversalFixResidualReporter.loadAtStartup 0 bool;configSet SCPI.loadAtStartup 0 bool;" 3pnkt 3 3.000000 2014-04-12T04:13:12.737Z,1397275992.737 [CommandLine](IMPORTANT): Scheduling command #3 of 3 with id=3pnkt 2014-04-12T04:13:12.738Z,1397275992.738 [CommandLine](IMPORTANT): Scheduled #3 (#3 of 3 with id='3pnkt'): "configSet UniversalFixResidualReporter.loadAtStartup 0 bool;configSet SCPI.loadAtStartup 0 bool;" ASAP 2014-04-12T04:13:13.896Z,1397275993.896 [CommandLine](IMPORTANT): got command configSet Radio_Freewave.loadAtStartup 0.000000 bool 2014-04-12T04:13:13.897Z,1397275993.897 [CommandLine](FAULT): configSet Radio_Freewave.loadAtStartup without persist will have no effect. 2014-04-12T04:13:13.897Z,1397275993.897 [CommandLine](IMPORTANT): got command configSet TempGradientCalculator.loadAtStartup 1.000000 bool 2014-04-12T04:13:13.898Z,1397275993.898 [CommandLine](FAULT): configSet TempGradientCalculator.loadAtStartup without persist will have no effect. 2014-04-12T04:13:13.899Z,1397275993.899 [CommandLine](IMPORTANT): got command configSet PAR_Licor.loadAtStartup 0.000000 bool 2014-04-12T04:13:13.899Z,1397275993.899 [CommandLine](FAULT): configSet PAR_Licor.loadAtStartup without persist will have no effect. 2014-04-12T04:13:13.900Z,1397275993.900 [CommandLine](IMPORTANT): got command configSet DeadReckonWithRespectToWater.loadAtStartup 0.000000 bool 2014-04-12T04:13:13.900Z,1397275993.900 [CommandLine](FAULT): configSet DeadReckonWithRespectToWater.loadAtStartup without persist will have no effect. 2014-04-12T04:13:15.103Z,1397275995.103 [CommandLine](IMPORTANT): got command configSet DeadReckonWithRespectToSeafloor.loadAtStartup 0.000000 bool 2014-04-12T04:13:15.103Z,1397275995.103 [CommandLine](FAULT): configSet DeadReckonWithRespectToSeafloor.loadAtStartup without persist will have no effect. 2014-04-12T04:13:15.104Z,1397275995.104 [CommandLine](IMPORTANT): got command configSet DeadReckonUsingDVLWaterTrack.loadAtStartup 0.000000 bool 2014-04-12T04:13:15.104Z,1397275995.104 [CommandLine](FAULT): configSet DeadReckonUsingDVLWaterTrack.loadAtStartup without persist will have no effect. 2014-04-12T04:13:15.105Z,1397275995.105 [CommandLine](IMPORTANT): got command configSet DeadReckonUsingCompactModelForecast.loadAtStartup 0.000000 bool 2014-04-12T04:13:15.110Z,1397275995.110 [CommandLine](FAULT): configSet DeadReckonUsingCompactModelForecast.loadAtStartup without persist will have no effect. 2014-04-12T04:13:15.801Z,1397275995.801 [CommandLine](IMPORTANT): got command configSet UniversalFixResidualReporter.loadAtStartup 0.000000 bool 2014-04-12T04:13:15.802Z,1397275995.802 [CommandLine](FAULT): configSet UniversalFixResidualReporter.loadAtStartup without persist will have no effect. 2014-04-12T04:13:15.802Z,1397275995.802 [CommandLine](IMPORTANT): got command configSet SCPI.loadAtStartup 0.000000 bool 2014-04-12T04:13:15.803Z,1397275995.803 [CommandLine](FAULT): configSet SCPI.loadAtStartup without persist will have no effect. 2014-04-12T04:13:21.390Z,1397276001.390 [NAL9602](INFO): SBD MO Status=0, MOMSN=24528, MT Status=0, MTMSN=0 2014-04-12T04:13:30.823Z,1397276010.823 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 45 of 46 2014-04-12T04:13:30.823Z,1397276010.823 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 52.7 50.2 50.7 50.9 603.2 7.0 541.4 608 677 539 488 13 14 14 14 -35.8 220.9 669.1 1 243.0 68.5 658.8 1 23 2 251 -93 623 2 -1.87 -2.93 269.7 50.2 14.5 0.005 35.0 1489 89 2014-04-12T04:13:38.851Z,1397276018.851 [NAL9602](INFO): SBD MO Status=1, MOMSN=24529, MT Status=0, MTMSN=0 2014-04-12T04:13:38.899Z,1397276018.899 [NAL9602](INFO): Sent 74 bytes from file Logs/20140412T040156/Courier0016.lzma 2014-04-12T04:13:38.899Z,1397276018.899 [NAL9602](INFO): Packets left to send: 0 2014-04-12T04:13:38.901Z,1397276018.901 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Courier0016.lzma.parts/0000.sbd 2014-04-12T04:13:38.901Z,1397276018.901 [NAL9602](INFO): Completed sending Logs/20140412T040156/Courier0016.lzma 2014-04-12T04:13:51.811Z,1397276031.811 [NAL9602](INFO): SBD MO Status=1, MOMSN=24530, MT Status=0, MTMSN=0 2014-04-12T04:13:51.859Z,1397276031.859 [NAL9602](INFO): Sent 332 bytes from file Logs/20140412T040156/Express0017.lzma 2014-04-12T04:13:51.859Z,1397276031.859 [NAL9602](INFO): Packets left to send: 2 2014-04-12T04:13:51.861Z,1397276031.861 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Express0017.lzma.parts/0002.sbd 2014-04-12T04:14:04.242Z,1397276044.242 [NAL9602](INFO): SBD MO Status=1, MOMSN=24531, MT Status=0, MTMSN=0 2014-04-12T04:14:04.294Z,1397276044.294 [NAL9602](INFO): Sent 332 bytes from file Logs/20140412T040156/Express0017.lzma 2014-04-12T04:14:04.295Z,1397276044.295 [NAL9602](INFO): Packets left to send: 1 2014-04-12T04:14:04.296Z,1397276044.296 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Express0017.lzma.parts/0001.sbd 2014-04-12T04:14:10.978Z,1397276050.978 [NAL9602](INFO): SBD MO Status=1, MOMSN=24532, MT Status=0, MTMSN=0 2014-04-12T04:14:11.033Z,1397276051.033 [NAL9602](INFO): Sent 38 bytes from file Logs/20140412T040156/Express0017.lzma 2014-04-12T04:14:11.033Z,1397276051.033 [NAL9602](INFO): Packets left to send: 0 2014-04-12T04:14:11.034Z,1397276051.034 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Express0017.lzma.parts/0000.sbd 2014-04-12T04:14:11.035Z,1397276051.035 [NAL9602](INFO): Completed sending Logs/20140412T040156/Express0017.lzma 2014-04-12T04:14:21.810Z,1397276061.810 [NAL9602](INFO): SBD MO Status=0, MOMSN=24533, MT Status=0, MTMSN=0 2014-04-12T04:14:21.897Z,1397276061.897 [Default:Iridium:Read_Iridium] Stopped 2014-04-12T04:14:21.898Z,1397276061.898 [Default:Iridium](INFO): Completed Default:Iridium 2014-04-12T04:14:21.898Z,1397276061.898 [Default:Iridium] Stopped 2014-04-12T04:14:21.898Z,1397276061.898 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2014-04-12T04:14:21.898Z,1397276061.898 [Default:Iridium:A.SetSpeed] Stopped 2014-04-12T04:14:21.898Z,1397276061.898 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2014-04-12T04:14:22.344Z,1397276062.344 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B 2014-04-12T04:14:22.344Z,1397276062.344 [Default:CallIridium:B] Stopped 2014-04-12T04:14:22.345Z,1397276062.345 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B 2014-04-12T04:14:22.345Z,1397276062.345 [Default:CallIridium](INFO): Completed Default:CallIridium 2014-04-12T04:14:22.345Z,1397276062.345 [Default:CallIridium] Stopped 2014-04-12T04:14:22.345Z,1397276062.345 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium 2014-04-12T04:14:32.486Z,1397276072.486 [NAL9602](INFO): Powering down 2014-04-12T04:19:22.520Z,1397276362.520 [Default:CallIridium] Running Loop=1 2014-04-12T04:19:22.520Z,1397276362.520 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium 2014-04-12T04:19:22.520Z,1397276362.520 [Default:CallIridium:A] Running Loop=1 2014-04-12T04:19:22.520Z,1397276362.520 [Default:CallIridium:A] Stopped 2014-04-12T04:19:22.520Z,1397276362.520 [Default:CallIridium:B] Running Loop=1 2014-04-12T04:19:22.520Z,1397276362.520 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B 2014-04-12T04:19:27.496Z,1397276367.496 [Default:Iridium] Running Loop=1 2014-04-12T04:19:27.496Z,1397276367.496 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2014-04-12T04:19:27.496Z,1397276367.496 [Default:Iridium:A.SetSpeed] Running Loop=1 2014-04-12T04:19:27.496Z,1397276367.496 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2014-04-12T04:19:27.496Z,1397276367.496 [Default:Iridium:B.GoToSurface] Running Loop=1 2014-04-12T04:19:27.496Z,1397276367.496 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2014-04-12T04:19:27.497Z,1397276367.497 [Default:Iridium:B.GoToSurface] Stopped 2014-04-12T04:19:27.497Z,1397276367.497 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2014-04-12T04:19:27.497Z,1397276367.497 [Default:Iridium:Read_Iridium] Running Loop=1 2014-04-12T04:19:27.498Z,1397276367.498 [Default:GPS] Running Loop=1 2014-04-12T04:19:27.498Z,1397276367.498 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2014-04-12T04:19:27.498Z,1397276367.498 [Default:GPS:A.SetSpeed] Running Loop=1 2014-04-12T04:19:27.498Z,1397276367.498 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2014-04-12T04:19:27.498Z,1397276367.498 [Default:GPS:B.GoToSurface] Running Loop=1 2014-04-12T04:19:27.498Z,1397276367.498 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2014-04-12T04:19:27.499Z,1397276367.499 [Default:GPS:B.GoToSurface] Stopped 2014-04-12T04:19:27.499Z,1397276367.499 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2014-04-12T04:19:27.499Z,1397276367.499 [Default:GPS:Read_GPS] Running Loop=1 2014-04-12T04:19:28.981Z,1397276368.981 [NAL9602](INFO): Powering up 2014-04-12T04:19:39.734Z,1397276379.734 [NAL9602](INFO): NAL9602 initialized 2014-04-12T04:20:04.605Z,1397276404.605 [NAL9602](INFO): SBD MO Status=1, MOMSN=24534, MT Status=0, MTMSN=0 2014-04-12T04:20:04.657Z,1397276404.657 [NAL9602](INFO): Sent 45 bytes from file Logs/20140412T040156/Courier0020.lzma 2014-04-12T04:20:04.657Z,1397276404.657 [NAL9602](INFO): Packets left to send: 0 2014-04-12T04:20:04.659Z,1397276404.659 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Courier0020.lzma.parts/0000.sbd 2014-04-12T04:20:04.659Z,1397276404.659 [NAL9602](INFO): Completed sending Logs/20140412T040156/Courier0020.lzma 2014-04-12T04:20:20.400Z,1397276420.400 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 4 of 46 2014-04-12T04:20:20.400Z,1397276420.400 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 1 1 1 49.0 53.4 55.5 51.4 -481.7 -338.5 -480.6 -488.5 -469 -406 -402 -539 13 13 16 14 -1.5 200.2 -482.5 1 150.8 -75.7 -494.4 1 -89 177 -489 2 194 41 -489 2 -4.11 -4.26 255.3 49.0 14.5 0.005 35.0 1489 66 2014-04-12T04:20:32.341Z,1397276432.341 [NAL9602](INFO): SBD MO Status=1, MOMSN=24535, MT Status=0, MTMSN=0 2014-04-12T04:20:32.388Z,1397276432.388 [NAL9602](INFO): Sent 189 bytes from file Logs/20140412T040156/Express0021.lzma 2014-04-12T04:20:32.388Z,1397276432.388 [NAL9602](INFO): Packets left to send: 0 2014-04-12T04:20:32.390Z,1397276432.390 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Express0021.lzma.parts/0000.sbd 2014-04-12T04:20:32.390Z,1397276432.390 [NAL9602](INFO): Completed sending Logs/20140412T040156/Express0021.lzma 2014-04-12T04:20:38.762Z,1397276438.762 [DVL_micro](ERROR): No DVL communication! Re-initializing 2014-04-12T04:20:38.762Z,1397276438.762 [DVL_micro] Communications Fault, FailCount= 1 2014-04-12T04:20:38.762Z,1397276438.762 [DVL_micro](ERROR): Communications Fault 2014-04-12T04:20:38.783Z,1397276438.783 [DeadReckonUsingDVLWaterTrack](DEBUG): DVL water track data is invalid. 2014-04-12T04:20:38.783Z,1397276438.783 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:38.797Z,1397276438.797 [CBIT](ERROR): Communications Fault in component: DVL_micro 2014-04-12T04:20:39.162Z,1397276439.162 [DVL_micro](INFO): uninitialize:Powering down 2014-04-12T04:20:39.191Z,1397276439.191 [DeadReckonUsingDVLWaterTrack](DEBUG): DVL water track data is invalid. 2014-04-12T04:20:39.191Z,1397276439.191 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:39.548Z,1397276439.548 [DVL_micro](FAULT): LCB fault: Software Overcurrent. 2014-04-12T04:20:39.548Z,1397276439.548 [DVL_micro] Hardware Fault, FailCount= 1 2014-04-12T04:20:39.549Z,1397276439.549 [DVL_micro](ERROR): Hardware Fault 2014-04-12T04:20:39.591Z,1397276439.591 [DeadReckonUsingDVLWaterTrack](DEBUG): DVL water track data is invalid. 2014-04-12T04:20:39.592Z,1397276439.592 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:39.976Z,1397276439.976 [DVL_micro](INFO): Initializing 2014-04-12T04:20:39.996Z,1397276439.996 [DeadReckonUsingDVLWaterTrack](DEBUG): DVL water track data is invalid. 2014-04-12T04:20:39.996Z,1397276439.996 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:40.011Z,1397276440.011 [CBIT](INFO): Clearing failed state for component DVL_micro 2014-04-12T04:20:40.011Z,1397276440.011 [DVL_micro] No Fault, FailCount= 1 2014-04-12T04:20:40.447Z,1397276440.447 [DeadReckonUsingDVLWaterTrack](DEBUG): DVL water track data is invalid. 2014-04-12T04:20:40.448Z,1397276440.448 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:40.893Z,1397276440.893 [DeadReckonUsingDVLWaterTrack](DEBUG): DVL water track data is invalid. 2014-04-12T04:20:40.894Z,1397276440.894 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:41.308Z,1397276441.308 [DeadReckonUsingDVLWaterTrack](DEBUG): DVL water track data is invalid. 2014-04-12T04:20:41.308Z,1397276441.308 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:41.744Z,1397276441.744 [DeadReckonUsingDVLWaterTrack](DEBUG): DVL water track data is invalid. 2014-04-12T04:20:41.745Z,1397276441.745 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:42.224Z,1397276442.224 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:42.696Z,1397276442.696 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:43.093Z,1397276443.093 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:43.604Z,1397276443.604 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:44.023Z,1397276444.023 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:44.458Z,1397276444.458 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:44.901Z,1397276444.901 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:45.322Z,1397276445.322 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:45.782Z,1397276445.782 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:46.187Z,1397276446.187 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:46.618Z,1397276446.618 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:47.058Z,1397276447.058 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:47.596Z,1397276447.596 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:48.010Z,1397276448.010 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:48.416Z,1397276448.416 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:48.894Z,1397276448.894 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:49.304Z,1397276449.304 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:49.710Z,1397276449.710 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:50.205Z,1397276450.205 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:50.678Z,1397276450.678 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:51.072Z,1397276451.072 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:51.593Z,1397276451.593 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:51.987Z,1397276451.987 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:52.510Z,1397276452.510 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:52.926Z,1397276452.926 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:53.443Z,1397276453.443 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:53.883Z,1397276453.883 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=24536, MT Status=1, MTMSN=1662 2014-04-12T04:20:53.988Z,1397276453.988 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:54.759Z,1397276454.759 [NAL9602](INFO): Received command:sched asap "configSet Radio_Freewave.loadAtStartup 0 bool persist;configSet TempGradientCalculator.loadAtStartup 1 bool persist;configSet PAR_Licor.loadAtStartup 0 bool persist" 3po0t 1 3 2014-04-12T04:20:54.786Z,1397276454.786 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:54.845Z,1397276454.845 [CommandLine](IMPORTANT): got command schedule asap "configSet Radio_Freewave.loadAtStartup 0 bool persist;configSet TempGradientCalculator.loadAtStartup 1 bool persist;configSet PAR_Licor.loadAtStartup 0 bool persist" 3po0t 1 3.000000 2014-04-12T04:20:54.845Z,1397276454.845 [CommandLine](IMPORTANT): Scheduling command #1 of 3 with id=3po0t 2014-04-12T04:20:54.846Z,1397276454.846 [CommandLine](IMPORTANT): Scheduled #4 (#1 of 3 with id='3po0t'): "configSet Radio_Freewave.loadAtStartup 0 bool persist;configSet TempGradientCalculator.loadAtStartup 1 bool persist;configSet PAR_Licor.loadAtStartup 0 bool persist" ASAP 2014-04-12T04:20:55.209Z,1397276455.209 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:55.651Z,1397276455.651 [DeadReckonUsingDVLWaterTrack](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2014-04-12T04:20:56.046Z,1397276456.046 [NAL9602](IMPORTANT): GPS fix at: 1397276428.00 2014-04-12T04:20:56.060Z,1397276456.060 [DeadReckonUsingMultipleVelocitySources](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07 2014-04-12T04:20:56.063Z,1397276456.063 [DeadReckonWithRespectToWater](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07 2014-04-12T04:20:56.066Z,1397276456.066 [DeadReckonUsingDVLWaterTrack](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07 2014-04-12T04:20:56.070Z,1397276456.070 [Default:GPS:Read_GPS] Stopped 2014-04-12T04:20:56.070Z,1397276456.070 [Default:GPS:D] Running Loop=1 2014-04-12T04:20:56.587Z,1397276456.587 [Default:GPS:D] Stopped 2014-04-12T04:20:56.588Z,1397276456.588 [Default:GPS](INFO): Completed Default:GPS 2014-04-12T04:20:56.588Z,1397276456.588 [Default:GPS] Stopped 2014-04-12T04:20:56.588Z,1397276456.588 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2014-04-12T04:20:56.588Z,1397276456.588 [Default:GPS:A.SetSpeed] Stopped 2014-04-12T04:20:56.588Z,1397276456.588 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2014-04-12T04:21:12.506Z,1397276472.506 [NAL9602](INFO): SBD MO Status=0, MOMSN=24537, MT Status=0, MTMSN=0 2014-04-12T04:21:31.702Z,1397276491.702 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=24538, MT Status=1, MTMSN=1663 2014-04-12T04:21:31.748Z,1397276491.748 [NAL9602](INFO): Sent 76 bytes from file Logs/20140412T040156/Courier0024.lzma 2014-04-12T04:21:31.748Z,1397276491.748 [NAL9602](INFO): Packets left to send: 0 2014-04-12T04:21:31.750Z,1397276491.750 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Courier0024.lzma.parts/0000.sbd 2014-04-12T04:21:31.750Z,1397276491.750 [NAL9602](INFO): Completed sending Logs/20140412T040156/Courier0024.lzma 2014-04-12T04:21:32.610Z,1397276492.610 [NAL9602](INFO): Received command:sched asap "configSet DeadReckonWithRespectToWater.loadAtStartup 0 bool persist;configSet DeadReckonWithRespectToSeafloor.loadAtStartup 0 bool persist;configSet DeadReckonUsingDVLWaterTrack.loadAtStartup 0 bool persist" 3po0t 2 3 2014-04-12T04:21:32.708Z,1397276492.708 [CommandLine](IMPORTANT): got command schedule asap "configSet DeadReckonWithRespectToWater.loadAtStartup 0 bool persist;configSet DeadReckonWithRespectToSeafloor.loadAtStartup 0 bool persist;configSet DeadReckonUsingDVLWaterTrack.loadAtStartup 0 bool persist" 3po0t 2 3.000000 2014-04-12T04:21:32.709Z,1397276492.709 [CommandLine](IMPORTANT): Scheduling command #2 of 3 with id=3po0t 2014-04-12T04:21:32.710Z,1397276492.710 [CommandLine](IMPORTANT): Scheduled #5 (#2 of 3 with id='3po0t'): "configSet DeadReckonWithRespectToWater.loadAtStartup 0 bool persist;configSet DeadReckonWithRespectToSeafloor.loadAtStartup 0 bool persist;configSet DeadReckonUsingDVLWaterTrack.loadAtStartup 0 bool persist" ASAP 2014-04-12T04:21:54.687Z,1397276514.687 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=24539, MT Status=1, MTMSN=1664 2014-04-12T04:21:54.736Z,1397276514.736 [NAL9602](INFO): Sent 332 bytes from file Logs/20140412T040156/Express0025.lzma 2014-04-12T04:21:54.736Z,1397276514.736 [NAL9602](INFO): Packets left to send: 1 2014-04-12T04:21:54.738Z,1397276514.738 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Express0025.lzma.parts/0001.sbd 2014-04-12T04:21:55.681Z,1397276515.681 [NAL9602](INFO): Received command:sched asap "configSet DeadReckonUsingCompactModelForecast.loadAtStartup 0 bool persist;configSet UniversalFixResidualReporter.loadAtStartup 0 bool persist;configSet SCPI.loadAtStartup 0 bool persist;" 3po0t 3 3 2014-04-12T04:21:55.750Z,1397276515.750 [CommandLine](IMPORTANT): got command schedule asap "configSet DeadReckonUsingCompactModelForecast.loadAtStartup 0 bool persist;configSet UniversalFixResidualReporter.loadAtStartup 0 bool persist;configSet SCPI.loadAtStartup 0 bool persist;" 3po0t 3 3.000000 2014-04-12T04:21:55.751Z,1397276515.751 [CommandLine](IMPORTANT): Scheduling command #3 of 3 with id=3po0t 2014-04-12T04:21:55.752Z,1397276515.752 [CommandLine](IMPORTANT): Scheduled #6 (#3 of 3 with id='3po0t'): "configSet DeadReckonUsingCompactModelForecast.loadAtStartup 0 bool persist;configSet UniversalFixResidualReporter.loadAtStartup 0 bool persist;configSet SCPI.loadAtStartup 0 bool persist;" ASAP 2014-04-12T04:21:56.479Z,1397276516.479 [CommandLine](IMPORTANT): got command configSet Radio_Freewave.loadAtStartup 0.000000 bool persist 2014-04-12T04:21:56.479Z,1397276516.479 [CommandLine](IMPORTANT): configSet Radio_Freewave.loadAtStartup requires a restart to take effect. 2014-04-12T04:21:56.499Z,1397276516.499 [CommandLine](IMPORTANT): got command configSet TempGradientCalculator.loadAtStartup 1.000000 bool persist 2014-04-12T04:21:56.499Z,1397276516.499 [CommandLine](IMPORTANT): configSet TempGradientCalculator.loadAtStartup requires a restart to take effect. 2014-04-12T04:21:56.502Z,1397276516.502 [CommandLine](IMPORTANT): got command configSet PAR_Licor.loadAtStartup 0.000000 bool persist 2014-04-12T04:21:56.502Z,1397276516.502 [CommandLine](IMPORTANT): configSet PAR_Licor.loadAtStartup requires a restart to take effect. 2014-04-12T04:21:57.591Z,1397276517.591 [CommandLine](IMPORTANT): got command configSet DeadReckonWithRespectToWater.loadAtStartup 0.000000 bool persist 2014-04-12T04:21:57.592Z,1397276517.592 [CommandLine](IMPORTANT): configSet DeadReckonWithRespectToWater.loadAtStartup requires a restart to take effect. 2014-04-12T04:21:57.594Z,1397276517.594 [CommandLine](IMPORTANT): got command configSet DeadReckonWithRespectToSeafloor.loadAtStartup 0.000000 bool persist 2014-04-12T04:21:57.595Z,1397276517.595 [CommandLine](IMPORTANT): configSet DeadReckonWithRespectToSeafloor.loadAtStartup requires a restart to take effect. 2014-04-12T04:21:57.597Z,1397276517.597 [CommandLine](IMPORTANT): got command configSet DeadReckonUsingDVLWaterTrack.loadAtStartup 0.000000 bool persist 2014-04-12T04:21:57.597Z,1397276517.597 [CommandLine](IMPORTANT): configSet DeadReckonUsingDVLWaterTrack.loadAtStartup requires a restart to take effect. 2014-04-12T04:21:58.568Z,1397276518.568 [CommandLine](IMPORTANT): got command configSet DeadReckonUsingCompactModelForecast.loadAtStartup 0.000000 bool persist 2014-04-12T04:21:58.570Z,1397276518.570 [CommandLine](IMPORTANT): configSet DeadReckonUsingCompactModelForecast.loadAtStartup requires a restart to take effect. 2014-04-12T04:21:58.572Z,1397276518.572 [CommandLine](IMPORTANT): got command configSet UniversalFixResidualReporter.loadAtStartup 0.000000 bool persist 2014-04-12T04:21:58.573Z,1397276518.573 [CommandLine](IMPORTANT): configSet UniversalFixResidualReporter.loadAtStartup requires a restart to take effect. 2014-04-12T04:21:58.575Z,1397276518.575 [CommandLine](IMPORTANT): got command configSet SCPI.loadAtStartup 0.000000 bool persist 2014-04-12T04:21:58.575Z,1397276518.575 [CommandLine](IMPORTANT): configSet SCPI.loadAtStartup requires a restart to take effect. 2014-04-12T04:22:12.651Z,1397276532.651 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:68 Calculated:95 2014-04-12T04:22:12.652Z,1397276532.652 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 50.9 53.7 52.7 50.2 69.0 224 -18.9 114 199 154 2 14 15 13 13 -92.6 321.9 110.7 1 340.2 56.3 74.7 1 -53 262 126 2 267 21 126 2 -5.47 -2.89 263.0 50.2 14.6 0.005 35.0 1489 68 2014-04-12T04:22:12.656Z,1397276532.656 [NAL9602](INFO): SBD MO Status=1, MOMSN=24540, MT Status=0, MTMSN=0 2014-04-12T04:22:12.704Z,1397276532.704 [NAL9602](INFO): Sent 57 bytes from file Logs/20140412T040156/Express0025.lzma 2014-04-12T04:22:12.704Z,1397276532.704 [NAL9602](INFO): Packets left to send: 0 2014-04-12T04:22:12.705Z,1397276532.705 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Express0025.lzma.parts/0000.sbd 2014-04-12T04:22:12.705Z,1397276532.705 [NAL9602](INFO): Completed sending Logs/20140412T040156/Express0025.lzma 2014-04-12T04:22:23.411Z,1397276543.411 [NAL9602](INFO): SBD MO Status=0, MOMSN=24541, MT Status=0, MTMSN=0 2014-04-12T04:22:34.933Z,1397276554.933 [NAL9602](INFO): SBD MO Status=1, MOMSN=24542, MT Status=0, MTMSN=0 2014-04-12T04:22:34.982Z,1397276554.982 [NAL9602](INFO): Sent 47 bytes from file Logs/20140412T040156/Courier0028.lzma 2014-04-12T04:22:34.982Z,1397276554.982 [NAL9602](INFO): Packets left to send: 0 2014-04-12T04:22:34.984Z,1397276554.984 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Courier0028.lzma.parts/0000.sbd 2014-04-12T04:22:34.984Z,1397276554.984 [NAL9602](INFO): Completed sending Logs/20140412T040156/Courier0028.lzma 2014-04-12T04:22:47.549Z,1397276567.549 [NAL9602](INFO): SBD MO Status=1, MOMSN=24543, MT Status=0, MTMSN=0 2014-04-12T04:22:47.598Z,1397276567.598 [NAL9602](INFO): Sent 332 bytes from file Logs/20140412T040156/Express0029.lzma 2014-04-12T04:22:47.598Z,1397276567.598 [NAL9602](INFO): Packets left to send: 1 2014-04-12T04:22:47.600Z,1397276567.600 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Express0029.lzma.parts/0001.sbd 2014-04-12T04:22:59.859Z,1397276579.859 [NAL9602](INFO): SBD MO Status=1, MOMSN=24544, MT Status=0, MTMSN=0 2014-04-12T04:22:59.909Z,1397276579.909 [NAL9602](INFO): Sent 299 bytes from file Logs/20140412T040156/Express0029.lzma 2014-04-12T04:22:59.909Z,1397276579.909 [NAL9602](INFO): Packets left to send: 0 2014-04-12T04:22:59.910Z,1397276579.910 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Express0029.lzma.parts/0000.sbd 2014-04-12T04:22:59.910Z,1397276579.910 [NAL9602](INFO): Completed sending Logs/20140412T040156/Express0029.lzma 2014-04-12T04:23:09.307Z,1397276589.307 [NAL9602](INFO): SBD MO Status=0, MOMSN=24545, MT Status=0, MTMSN=0 2014-04-12T04:23:09.398Z,1397276589.398 [Default:Iridium:Read_Iridium] Stopped 2014-04-12T04:23:09.399Z,1397276589.399 [Default:Iridium](INFO): Completed Default:Iridium 2014-04-12T04:23:09.399Z,1397276589.399 [Default:Iridium] Stopped 2014-04-12T04:23:09.399Z,1397276589.399 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2014-04-12T04:23:09.399Z,1397276589.399 [Default:Iridium:A.SetSpeed] Stopped 2014-04-12T04:23:09.399Z,1397276589.399 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2014-04-12T04:23:09.821Z,1397276589.821 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B 2014-04-12T04:23:09.821Z,1397276589.821 [Default:CallIridium:B] Stopped 2014-04-12T04:23:09.821Z,1397276589.821 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B 2014-04-12T04:23:09.821Z,1397276589.821 [Default:CallIridium](INFO): Completed Default:CallIridium 2014-04-12T04:23:09.821Z,1397276589.821 [Default:CallIridium] Stopped 2014-04-12T04:23:09.822Z,1397276589.822 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium 2014-04-12T04:23:19.999Z,1397276599.999 [NAL9602](INFO): Powering down 2014-04-12T04:28:10.020Z,1397276890.020 [Default:CallIridium] Running Loop=1 2014-04-12T04:28:10.020Z,1397276890.020 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium 2014-04-12T04:28:10.020Z,1397276890.020 [Default:CallIridium:A] Running Loop=1 2014-04-12T04:28:10.020Z,1397276890.020 [Default:CallIridium:A] Stopped 2014-04-12T04:28:10.021Z,1397276890.021 [Default:CallIridium:B] Running Loop=1 2014-04-12T04:28:10.021Z,1397276890.021 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B 2014-04-12T04:28:14.988Z,1397276894.988 [Default:Iridium] Running Loop=1 2014-04-12T04:28:14.988Z,1397276894.988 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2014-04-12T04:28:14.988Z,1397276894.988 [Default:Iridium:A.SetSpeed] Running Loop=1 2014-04-12T04:28:14.988Z,1397276894.988 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2014-04-12T04:28:14.988Z,1397276894.988 [Default:Iridium:B.GoToSurface] Running Loop=1 2014-04-12T04:28:14.988Z,1397276894.988 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2014-04-12T04:28:14.989Z,1397276894.989 [Default:Iridium:B.GoToSurface] Stopped 2014-04-12T04:28:14.989Z,1397276894.989 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2014-04-12T04:28:14.990Z,1397276894.990 [Default:Iridium:Read_Iridium] Running Loop=1 2014-04-12T04:28:14.990Z,1397276894.990 [Default:GPS] Running Loop=1 2014-04-12T04:28:14.990Z,1397276894.990 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2014-04-12T04:28:14.990Z,1397276894.990 [Default:GPS:A.SetSpeed] Running Loop=1 2014-04-12T04:28:14.990Z,1397276894.990 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2014-04-12T04:28:14.990Z,1397276894.990 [Default:GPS:B.GoToSurface] Running Loop=1 2014-04-12T04:28:14.990Z,1397276894.990 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2014-04-12T04:28:14.991Z,1397276894.991 [Default:GPS:B.GoToSurface] Stopped 2014-04-12T04:28:14.991Z,1397276894.991 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2014-04-12T04:28:14.991Z,1397276894.991 [Default:GPS:Read_GPS] Running Loop=1 2014-04-12T04:28:16.471Z,1397276896.471 [NAL9602](INFO): Powering up 2014-04-12T04:28:27.076Z,1397276907.076 [NAL9602](INFO): NAL9602 initialized 2014-04-12T04:28:54.287Z,1397276934.287 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=24546, MT Status=1, MTMSN=1665 2014-04-12T04:28:54.341Z,1397276934.341 [NAL9602](INFO): Sent 45 bytes from file Logs/20140412T040156/Courier0032.lzma 2014-04-12T04:28:54.341Z,1397276934.341 [NAL9602](INFO): Packets left to send: 0 2014-04-12T04:28:54.343Z,1397276934.343 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Courier0032.lzma.parts/0000.sbd 2014-04-12T04:28:54.343Z,1397276934.343 [NAL9602](INFO): Completed sending Logs/20140412T040156/Courier0032.lzma 2014-04-12T04:28:54.983Z,1397276934.983 [NAL9602](INFO): Received command:! tail /mnt/mmc/LRAUV/Logs/previous/topLog;! echo gotIt 2014-04-12T04:28:55.040Z,1397276935.040 [CommandLine](IMPORTANT): got command ! tail /mnt/mmc/LRAUV/Logs/previous/topLog;! echo gotIt 2014-04-12T04:28:55.177Z,1397276935.177 [CommandLine](IMPORTANT): gotIt 2014-04-12T04:28:58.219Z,1397276938.219 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:94 Calculated:69 2014-04-12T04:28:58.220Z,1397276938.220 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 52.5 52.7 53.0 52.3 -50.2 209.2 1.2 -9 187 131 -114 13 14 13 14 -273.6 410.3 58.1 1 491.6 42.7 56.4 1 -186 401 52 2 441 -30 52 2 0.66 -1.34 241.1 52.3 14.5 0.005 35.0 1489 94 2014-04-12T04:29:10.263Z,1397276950.263 [NavChartDb](INFO): Done scanning features of Resources/US5CA62M.000 2014-04-12T04:29:21.505Z,1397276961.505 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=24547, MT Status=1, MTMSN=1666 2014-04-12T04:29:21.552Z,1397276961.552 [NAL9602](INFO): Sent 62 bytes from file Logs/20140412T040156/Express0033.lzma 2014-04-12T04:29:21.553Z,1397276961.553 [NAL9602](INFO): Packets left to send: 0 2014-04-12T04:29:21.554Z,1397276961.554 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Express0033.lzma.parts/0000.sbd 2014-04-12T04:29:21.555Z,1397276961.555 [NAL9602](INFO): Completed sending Logs/20140412T040156/Express0033.lzma 2014-04-12T04:29:22.154Z,1397276962.154 [NAL9602](INFO): Received command:! ls -l -h /mnt/mmc/LRAUV/Logs/previous/topLog;! echo gotIt2 2014-04-12T04:29:22.218Z,1397276962.218 [CommandLine](IMPORTANT): got command ! ls -l -h /mnt/mmc/LRAUV/Logs/previous/topLog;! echo gotIt2 2014-04-12T04:29:22.687Z,1397276962.687 [CommandLine](IMPORTANT): gotIt2 2014-04-12T04:29:43.455Z,1397276983.455 [DVL_micro](ERROR): DVL Failure: Failed to read in all data items. Got 43 of 46 2014-04-12T04:29:43.455Z,1397276983.455 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 53.7 55.0 54.7 51.6 234.5 247.1 35.2 37.0 311 267 29 48 13 16 14 14 266.0 280.4 149.3 1 139.0 -355.6 161.1 1 376 292 176 2 94 -467 176 2 -0.80 -3.44 243.6 51.6 14.5 101 2014-04-12T04:29:48.740Z,1397276988.740 [DVL_micro](ERROR): NQ1 string checksum mismatch. Reported:107 Calculated:104 2014-04-12T04:29:48.740Z,1397276988.740 [DVL_micro](ERROR): Failed to parse DVL response:$#NQ.RES 0X0000 1 1 1 1 52.8 53.6 53.4 53.0 165.3 402.4 430.7 204.9 241 241 13 15 12 15 -354.2 263.6 324.4 1 417.5 208.9 286.7 1 -220 295 366 2 363 61 366 2 -2.28 -4.22 242.8 52.8 14.5 0.005 35.0 1489 107 2014-04-12T04:29:53.085Z,1397276993.085 [NAL9602](INFO): SBD MO Status=0, MOMSN=24548, MT Status=0, MTMSN=0 2014-04-12T04:29:55.150Z,1397276995.150 [NAL9602](IMPORTANT): GPS fix at: 1397276968.00 2014-04-12T04:29:55.205Z,1397276995.205 [DeadReckonUsingMultipleVelocitySources](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07 2014-04-12T04:29:55.211Z,1397276995.211 [DeadReckonWithRespectToWater](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07 2014-04-12T04:29:55.214Z,1397276995.214 [DeadReckonUsingDVLWaterTrack](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07 2014-04-12T04:29:55.218Z,1397276995.218 [Default:GPS:Read_GPS] Stopped 2014-04-12T04:29:55.218Z,1397276995.218 [Default:GPS:D] Running Loop=1 2014-04-12T04:29:55.651Z,1397276995.651 [Default:GPS:D] Stopped 2014-04-12T04:29:55.651Z,1397276995.651 [Default:GPS](INFO): Completed Default:GPS 2014-04-12T04:29:55.651Z,1397276995.651 [Default:GPS] Stopped 2014-04-12T04:29:55.651Z,1397276995.651 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2014-04-12T04:29:55.651Z,1397276995.651 [Default:GPS:A.SetSpeed] Stopped 2014-04-12T04:29:55.651Z,1397276995.651 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2014-04-12T04:30:11.678Z,1397277011.678 [NAL9602](INFO): SBD MO Status=1, MOMSN=24549, MT Status=0, MTMSN=0 2014-04-12T04:30:11.726Z,1397277011.726 [NAL9602](INFO): Sent 45 bytes from file Logs/20140412T040156/Courier0036.lzma 2014-04-12T04:30:11.726Z,1397277011.726 [NAL9602](INFO): Packets left to send: 0 2014-04-12T04:30:11.729Z,1397277011.729 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Courier0036.lzma.parts/0000.sbd 2014-04-12T04:30:11.730Z,1397277011.730 [NAL9602](INFO): Completed sending Logs/20140412T040156/Courier0036.lzma 2014-04-12T04:30:22.082Z,1397277022.082 [NAL9602](INFO): SBD MO Status=1, MOMSN=24550, MT Status=0, MTMSN=0 2014-04-12T04:30:22.133Z,1397277022.133 [NAL9602](INFO): Sent 236 bytes from file Logs/20140412T040156/Express0037.lzma 2014-04-12T04:30:22.134Z,1397277022.134 [NAL9602](INFO): Packets left to send: 0 2014-04-12T04:30:22.140Z,1397277022.140 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Express0037.lzma.parts/0000.sbd 2014-04-12T04:30:22.141Z,1397277022.141 [NAL9602](INFO): Completed sending Logs/20140412T040156/Express0037.lzma 2014-04-12T04:30:37.903Z,1397277037.903 [NAL9602](INFO): SBD MO Status=0, MOMSN=24551, MT Status=0, MTMSN=0 2014-04-12T04:30:38.025Z,1397277038.025 [Default:Iridium:Read_Iridium] Stopped 2014-04-12T04:30:38.026Z,1397277038.026 [Default:Iridium](INFO): Completed Default:Iridium 2014-04-12T04:30:38.026Z,1397277038.026 [Default:Iridium] Stopped 2014-04-12T04:30:38.026Z,1397277038.026 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2014-04-12T04:30:38.026Z,1397277038.026 [Default:Iridium:A.SetSpeed] Stopped 2014-04-12T04:30:38.026Z,1397277038.026 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2014-04-12T04:30:38.535Z,1397277038.535 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B 2014-04-12T04:30:38.535Z,1397277038.535 [Default:CallIridium:B] Stopped 2014-04-12T04:30:38.536Z,1397277038.536 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B 2014-04-12T04:30:38.536Z,1397277038.536 [Default:CallIridium](INFO): Completed Default:CallIridium 2014-04-12T04:30:38.536Z,1397277038.536 [Default:CallIridium] Stopped 2014-04-12T04:30:38.536Z,1397277038.536 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium 2014-04-12T04:30:48.652Z,1397277048.652 [NAL9602](INFO): Powering down 2014-04-12T04:35:38.687Z,1397277338.687 [Default:CallIridium] Running Loop=1 2014-04-12T04:35:38.687Z,1397277338.687 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium 2014-04-12T04:35:38.687Z,1397277338.687 [Default:CallIridium:A] Running Loop=1 2014-04-12T04:35:38.687Z,1397277338.687 [Default:CallIridium:A] Stopped 2014-04-12T04:35:38.687Z,1397277338.687 [Default:CallIridium:B] Running Loop=1 2014-04-12T04:35:38.688Z,1397277338.688 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B 2014-04-12T04:35:43.613Z,1397277343.613 [Default:Iridium] Running Loop=1 2014-04-12T04:35:43.613Z,1397277343.613 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2014-04-12T04:35:43.613Z,1397277343.613 [Default:Iridium:A.SetSpeed] Running Loop=1 2014-04-12T04:35:43.613Z,1397277343.613 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2014-04-12T04:35:43.613Z,1397277343.613 [Default:Iridium:B.GoToSurface] Running Loop=1 2014-04-12T04:35:43.613Z,1397277343.613 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2014-04-12T04:35:43.614Z,1397277343.614 [Default:Iridium:B.GoToSurface] Stopped 2014-04-12T04:35:43.614Z,1397277343.614 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2014-04-12T04:35:43.614Z,1397277343.614 [Default:Iridium:Read_Iridium] Running Loop=1 2014-04-12T04:35:43.615Z,1397277343.615 [Default:GPS] Running Loop=1 2014-04-12T04:35:43.615Z,1397277343.615 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2014-04-12T04:35:43.615Z,1397277343.615 [Default:GPS:A.SetSpeed] Running Loop=1 2014-04-12T04:35:43.615Z,1397277343.615 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2014-04-12T04:35:43.615Z,1397277343.615 [Default:GPS:B.GoToSurface] Running Loop=1 2014-04-12T04:35:43.615Z,1397277343.615 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2014-04-12T04:35:43.616Z,1397277343.616 [Default:GPS:B.GoToSurface] Stopped 2014-04-12T04:35:43.617Z,1397277343.617 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2014-04-12T04:35:43.617Z,1397277343.617 [Default:GPS:Read_GPS] Running Loop=1 2014-04-12T04:35:45.130Z,1397277345.130 [NAL9602](INFO): Powering up 2014-04-12T04:35:55.756Z,1397277355.756 [NAL9602](INFO): NAL9602 initialized 2014-04-12T04:36:41.122Z,1397277401.122 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=24552, MT Status=1, MTMSN=1667 2014-04-12T04:36:41.174Z,1397277401.174 [NAL9602](INFO): Sent 75 bytes from file Logs/20140412T040156/Courier0040.lzma 2014-04-12T04:36:41.174Z,1397277401.174 [NAL9602](INFO): Packets left to send: 0 2014-04-12T04:36:41.176Z,1397277401.176 [NAL9602](INFO): Stored copy of sent data in Logs/20140412T040156/Courier0040.lzma.parts/0000.sbd 2014-04-12T04:36:41.176Z,1397277401.176 [NAL9602](INFO): Completed sending Logs/20140412T040156/Courier0040.lzma 2014-04-12T04:36:41.783Z,1397277401.783 [NAL9602](INFO): Received command:restart app 2014-04-12T04:36:41.883Z,1397277401.883 [CommandLine](IMPORTANT): got command restart application 2014-04-12T04:36:43.012Z,1397277403.012 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2014-04-12T04:36:43.017Z,1397277403.017 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2014-04-12T04:36:43.082Z,1397277403.082 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2014-04-12T04:36:43.140Z,1397277403.140 [NAL9602](IMPORTANT): GPS fix at: 1397277375.00 2014-04-12T04:36:43.154Z,1397277403.154 [DeadReckonUsingMultipleVelocitySources](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07 2014-04-12T04:36:43.156Z,1397277403.156 [DeadReckonWithRespectToWater](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07 2014-04-12T04:36:43.159Z,1397277403.159 [DeadReckonUsingDVLWaterTrack](DEBUG): Got GPS fix: latitudeAccuracy_ = 1.74533e-07, longitudeAccuracy = 1.74533e-07 2014-04-12T04:36:43.175Z,1397277403.175 [Default:GPS:Read_GPS] Stopped 2014-04-12T04:36:43.175Z,1397277403.175 [Default:GPS:D] Running Loop=1 2014-04-12T04:36:43.422Z,1397277403.422 [WetLabsBB2FL](INFO): Powering down 2014-04-12T04:36:43.437Z,1397277403.437 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2014-04-12T04:36:43.568Z,1397277403.568 [CTD_NeilBrown](INFO): Powering down 2014-04-12T04:36:43.569Z,1397277403.569 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler 2014-04-12T04:36:43.622Z,1397277403.622 [Default:GPS:D] Stopped 2014-04-12T04:36:43.623Z,1397277403.623 [Default:GPS](INFO): Completed Default:GPS 2014-04-12T04:36:43.623Z,1397277403.623 [Default:GPS] Stopped 2014-04-12T04:36:43.623Z,1397277403.623 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2014-04-12T04:36:43.623Z,1397277403.623 [Default:GPS:A.SetSpeed] Stopped 2014-04-12T04:36:43.623Z,1397277403.623 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2014-04-12T04:36:43.670Z,1397277403.670 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator. 2014-04-12T04:36:43.683Z,1397277403.683 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2014-04-12T04:36:43.715Z,1397277403.715 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2014-04-12T04:36:43.715Z,1397277403.715 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2014-04-12T04:36:44.016Z,1397277404.016 [controlThread](DEBUG): Uninitializing ControlThread 2014-04-12T04:36:44.017Z,1397277404.017 [AHRS_sp3003D](INFO): Powering down 2014-04-12T04:36:44.018Z,1397277404.018 [DVL_micro](INFO): uninitialize:Powering down 2014-04-12T04:36:44.019Z,1397277404.019 [NAL9602](INFO): Powering down 2014-04-12T04:36:44.031Z,1397277404.031 [Radio_Freewave](INFO): Powering down 2014-04-12T04:36:44.035Z,1397277404.035 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2014-04-12T04:36:44.036Z,1397277404.036 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2014-04-12T04:36:44.037Z,1397277404.037 [Default] Stopped 2014-04-12T04:36:44.038Z,1397277404.038 [Default](INFO): Aggregate::uninitialize Default 2014-04-12T04:36:44.038Z,1397277404.038 [Default:Iridium] Stopped 2014-04-12T04:36:44.039Z,1397277404.039 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2014-04-12T04:36:44.039Z,1397277404.039 [Default:Iridium:A.SetSpeed] Stopped 2014-04-12T04:36:44.039Z,1397277404.039 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2014-04-12T04:36:44.039Z,1397277404.039 [Default:Iridium:Read_Iridium] Stopped 2014-04-12T04:36:44.040Z,1397277404.040 [Default:CallIridium] Stopped 2014-04-12T04:36:44.040Z,1397277404.040 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium 2014-04-12T04:36:44.040Z,1397277404.040 [Default:CallIridium:B] Stopped 2014-04-12T04:36:44.041Z,1397277404.041 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B 2014-04-12T04:36:44.041Z,1397277404.041 [Default:WaitAtTheSurface] Stopped 2014-04-12T04:36:44.041Z,1397277404.041 [Default:WaitAtTheSurface](INFO): Aggregate::uninitialize Default:WaitAtTheSurface 2014-04-12T04:36:44.042Z,1397277404.042 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed] Stopped 2014-04-12T04:36:44.042Z,1397277404.042 [Default:WaitAtTheSurface:RunAtLowSpeed.SetSpeed](DEBUG): Uninitialize. 2014-04-12T04:36:44.046Z,1397277404.046 [Default:WaitAtTheSurface:B.GoToSurface] Stopped 2014-04-12T04:36:44.046Z,1397277404.046 [Default:WaitAtTheSurface:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2014-04-12T04:36:44.073Z,1397277404.073 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2014-04-12T04:36:44.073Z,1397277404.073 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2014-04-12T04:36:44.074Z,1397277404.074 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2014-04-12T04:36:44.079Z,1397277404.079 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2014-04-12T04:36:44.079Z,1397277404.079 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2014-04-12T04:36:44.079Z,1397277404.079 [BuoyancyServo](INFO): Powering down 2014-04-12T04:36:44.091Z,1397277404.091 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2014-04-12T04:36:44.091Z,1397277404.091 [ElevatorServo](INFO): Powering down 2014-04-12T04:36:44.092Z,1397277404.092 [MassServo](DEBUG): Uninitialize Mass Servo. 2014-04-12T04:36:44.092Z,1397277404.092 [MassServo](INFO): Powering down 2014-04-12T04:36:44.093Z,1397277404.093 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2014-04-12T04:36:44.093Z,1397277404.093 [RudderServo](INFO): Powering down 2014-04-12T04:36:44.094Z,1397277404.094 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2014-04-12T04:36:44.094Z,1397277404.094 [ThrusterServo](INFO): Powering down 2014-04-12T04:36:44.095Z,1397277404.095 [SBIT](DEBUG): Uninitialize SBIT Component. 2014-04-12T04:36:44.096Z,1397277404.096 [IBIT](DEBUG): Uninitialize IBIT Component. 2014-04-12T04:36:44.096Z,1397277404.096 [CBIT](DEBUG): Uninitialize CBIT Component.