2018-07-30T20:36:57.503Z,1532983017.503 [Supervisor](DEBUG): Initializing supervisor.
2018-07-30T20:36:57.506Z,1532983017.506 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-07-30T20:36:57.507Z,1532983017.507 [SyncHandler](INFO): Protected caller Thread ID is 2134
2018-07-30T20:36:57.507Z,1532983017.507 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-07-30T20:36:57.508Z,1532983017.508 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-07-30T20:36:57.508Z,1532983017.508 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2135
2018-07-30T20:36:57.511Z,1532983017.511 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-07-30T20:36:57.523Z,1532983017.523 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-07-30T20:36:57.524Z,1532983017.524 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-07-30T20:36:57.524Z,1532983017.524 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2136
2018-07-30T20:36:57.525Z,1532983017.525 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-07-30T20:36:57.526Z,1532983017.526 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-07-30T20:36:57.526Z,1532983017.526 [logger ThreadHandler](INFO): Protected caller Thread ID is 2137
2018-07-30T20:36:57.528Z,1532983017.528 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-07-30T20:36:57.529Z,1532983017.529 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-07-30T20:36:57.530Z,1532983017.530 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-07-30T20:36:57.726Z,1532983017.726 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-07-30T20:36:57.726Z,1532983017.726 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-07-30T20:36:57.869Z,1532983017.869 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-07-30T20:36:57.870Z,1532983017.870 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-07-30T20:36:58.216Z,1532983018.216 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-07-30T20:36:58.217Z,1532983018.217 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-07-30T20:36:58.355Z,1532983018.355 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-07-30T20:36:58.355Z,1532983018.355 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-07-30T20:36:58.437Z,1532983018.437 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-07-30T20:36:58.647Z,1532983018.647 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-07-30T20:36:58.648Z,1532983018.648 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-07-30T20:36:58.747Z,1532983018.747 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-07-30T20:36:58.747Z,1532983018.747 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-07-30T20:36:59.090Z,1532983019.090 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-07-30T20:36:59.091Z,1532983019.091 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-07-30T20:36:59.535Z,1532983019.535 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-07-30T20:36:59.535Z,1532983019.535 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-07-30T20:36:59.853Z,1532983019.853 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-07-30T20:36:59.853Z,1532983019.853 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-07-30T20:37:00.355Z,1532983020.355 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-07-30T20:37:00.355Z,1532983020.355 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-07-30T20:37:00.609Z,1532983020.609 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-07-30T20:37:00.610Z,1532983020.610 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-07-30T20:37:00.708Z,1532983020.708 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-07-30T20:37:00.709Z,1532983020.709 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-07-30T20:37:01.212Z,1532983021.212 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-07-30T20:37:01.213Z,1532983021.213 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-07-30T20:37:01.427Z,1532983021.427 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-07-30T20:37:01.429Z,1532983021.429 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2018-07-30T20:37:01.430Z,1532983021.430 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2018-07-30T20:37:01.660Z,1532983021.660 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-07-30T20:37:01.661Z,1532983021.661 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2018-07-30T20:37:01.759Z,1532983021.759 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2018-07-30T20:37:01.853Z,1532983021.853 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2018-07-30T20:37:01.957Z,1532983021.957 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2018-07-30T20:37:02.043Z,1532983022.043 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2018-07-30T20:37:02.175Z,1532983022.175 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2018-07-30T20:37:02.341Z,1532983022.341 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2018-07-30T20:37:02.453Z,1532983022.453 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2018-07-30T20:37:02.538Z,1532983022.538 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2018-07-30T20:37:02.622Z,1532983022.622 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2018-07-30T20:37:02.809Z,1532983022.809 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2018-07-30T20:37:02.810Z,1532983022.810 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-07-30T20:37:02.817Z,1532983022.817 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-07-30T20:37:02.944Z,1532983022.944 [SBIT](DEBUG): Construct Startup Built In Test.
2018-07-30T20:37:02.973Z,1532983022.973 [SBIT] Loaded
2018-07-30T20:37:02.974Z,1532983022.974 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-07-30T20:37:02.974Z,1532983022.974 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-07-30T20:37:03.002Z,1532983023.002 [IBIT] Loaded
2018-07-30T20:37:03.002Z,1532983023.002 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-07-30T20:37:03.005Z,1532983023.005 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-07-30T20:37:03.159Z,1532983023.159 [CBIT] Loaded
2018-07-30T20:37:03.160Z,1532983023.160 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-07-30T20:37:03.160Z,1532983023.160 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-07-30T20:37:03.161Z,1532983023.161 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-07-30T20:37:03.227Z,1532983023.227 [VerticalControl](DEBUG): Construct VerticalControl.
2018-07-30T20:37:03.325Z,1532983023.325 [VerticalControl] Loaded
2018-07-30T20:37:03.326Z,1532983023.326 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-07-30T20:37:03.326Z,1532983023.326 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-07-30T20:37:03.387Z,1532983023.387 [HorizontalControl] Loaded
2018-07-30T20:37:03.387Z,1532983023.387 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-07-30T20:37:03.388Z,1532983023.388 [SpeedControl](DEBUG): Construct SpeedControl.
2018-07-30T20:37:03.394Z,1532983023.394 [SpeedControl] Loaded
2018-07-30T20:37:03.394Z,1532983023.394 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-07-30T20:37:03.394Z,1532983023.394 [LoopControl](DEBUG): Construct LoopControl.
2018-07-30T20:37:03.395Z,1532983023.395 [LoopControl] Loaded
2018-07-30T20:37:03.395Z,1532983023.395 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-07-30T20:37:03.396Z,1532983023.396 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-07-30T20:37:03.396Z,1532983023.396 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-07-30T20:37:03.436Z,1532983023.436 [DepthRateCalculator] Loaded
2018-07-30T20:37:03.436Z,1532983023.436 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-07-30T20:37:03.442Z,1532983023.442 [PitchRateCalculator] Loaded
2018-07-30T20:37:03.442Z,1532983023.442 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-07-30T20:37:03.458Z,1532983023.458 [SpeedCalculator] Loaded
2018-07-30T20:37:03.458Z,1532983023.458 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-07-30T20:37:03.479Z,1532983023.479 [TempGradientCalculator] Loaded
2018-07-30T20:37:03.479Z,1532983023.479 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-07-30T20:37:03.495Z,1532983023.495 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-07-30T20:37:03.495Z,1532983023.495 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-07-30T20:37:03.500Z,1532983023.500 [YawRateCalculator] Loaded
2018-07-30T20:37:03.500Z,1532983023.500 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-07-30T20:37:03.529Z,1532983023.529 [ElevatorOffsetCalculator] Loaded
2018-07-30T20:37:03.530Z,1532983023.530 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-07-30T20:37:03.530Z,1532983023.530 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-07-30T20:37:03.530Z,1532983023.530 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-07-30T20:37:03.613Z,1532983023.613 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-07-30T20:37:03.613Z,1532983023.613 [StratificationFrontDetector](DEBUG): (re)initializing
2018-07-30T20:37:03.613Z,1532983023.613 [StratificationFrontDetector] Loaded
2018-07-30T20:37:03.613Z,1532983023.613 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-07-30T20:37:03.614Z,1532983023.614 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-07-30T20:37:03.614Z,1532983023.615 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-07-30T20:37:03.851Z,1532983023.851 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-07-30T20:37:03.852Z,1532983023.852 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-07-30T20:37:03.946Z,1532983023.946 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-07-30T20:37:03.946Z,1532983023.946 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-07-30T20:37:04.001Z,1532983024.001 [DeadReckonUsingSpeedCalculator] Loaded
2018-07-30T20:37:04.001Z,1532983024.001 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-07-30T20:37:04.056Z,1532983024.056 [DeadReckonWithRespectToSeafloor] Loaded
2018-07-30T20:37:04.056Z,1532983024.056 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2018-07-30T20:37:04.072Z,1532983024.072 [NavChart] Loaded
2018-07-30T20:37:04.072Z,1532983024.072 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-07-30T20:37:04.076Z,1532983024.076 [UniversalFixResidualReporter] Loaded
2018-07-30T20:37:04.076Z,1532983024.076 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-07-30T20:37:04.077Z,1532983024.077 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-07-30T20:37:04.077Z,1532983024.077 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-07-30T20:37:04.090Z,1532983024.090 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-07-30T20:37:04.091Z,1532983024.091 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-07-30T20:37:04.243Z,1532983024.243 [Aanderaa_O2] Loaded
2018-07-30T20:37:04.244Z,1532983024.244 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-07-30T20:37:04.294Z,1532983024.294 [WetLabsBB2FL] Loaded
2018-07-30T20:37:04.294Z,1532983024.294 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-07-30T20:37:04.295Z,1532983024.295 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406D34E0
2018-07-30T20:37:04.295Z,1532983024.295 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2216
2018-07-30T20:37:04.296Z,1532983024.296 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-07-30T20:37:04.297Z,1532983024.297 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-07-30T20:37:04.574Z,1532983024.574 [AHRS_M2] Loaded
2018-07-30T20:37:04.575Z,1532983024.575 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2018-07-30T20:37:04.642Z,1532983024.642 [DataOverHttps] Loaded
2018-07-30T20:37:04.642Z,1532983024.642 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-07-30T20:37:04.656Z,1532983024.656 [Depth_Keller] Loaded
2018-07-30T20:37:04.656Z,1532983024.656 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-07-30T20:37:04.661Z,1532983024.661 [DropWeight] Loaded
2018-07-30T20:37:04.661Z,1532983024.661 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-07-30T20:37:04.750Z,1532983024.750 [NAL9602] Loaded
2018-07-30T20:37:04.751Z,1532983024.751 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-07-30T20:37:04.756Z,1532983024.756 [Onboard] Loaded
2018-07-30T20:37:04.756Z,1532983024.756 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-07-30T20:37:04.763Z,1532983024.763 [Radio_Surface] Loaded
2018-07-30T20:37:04.764Z,1532983024.764 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-07-30T20:37:04.765Z,1532983024.765 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409294E0
2018-07-30T20:37:04.765Z,1532983024.765 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2217
2018-07-30T20:37:04.794Z,1532983024.794 [RDI_Pathfinder] Loaded
2018-07-30T20:37:04.794Z,1532983024.794 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2018-07-30T20:37:04.800Z,1532983024.800 [SCPI] Loaded
2018-07-30T20:37:04.800Z,1532983024.800 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2018-07-30T20:37:06.593Z,1532983026.593 [BPC1] Loaded
2018-07-30T20:37:06.593Z,1532983026.593 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-07-30T20:37:06.593Z,1532983026.593 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-07-30T20:37:06.594Z,1532983026.594 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-07-30T20:37:06.782Z,1532983026.782 [BuoyancyServo] Loaded
2018-07-30T20:37:06.782Z,1532983026.782 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-07-30T20:37:06.794Z,1532983026.794 [ElevatorServo] Loaded
2018-07-30T20:37:06.794Z,1532983026.794 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-07-30T20:37:06.805Z,1532983026.805 [MassServo] Loaded
2018-07-30T20:37:06.805Z,1532983026.805 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-07-30T20:37:06.817Z,1532983026.817 [RudderServo] Loaded
2018-07-30T20:37:06.817Z,1532983026.817 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-07-30T20:37:06.828Z,1532983026.828 [ThrusterServo] Loaded
2018-07-30T20:37:06.828Z,1532983026.828 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-07-30T20:37:06.828Z,1532983026.828 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-07-30T20:37:06.829Z,1532983026.829 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-07-30T20:37:06.927Z,1532983026.927 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-07-30T20:37:06.928Z,1532983026.928 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-07-30T20:37:06.951Z,1532983026.951 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-07-30T20:37:06.955Z,1532983026.955 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-07-30T20:37:06.956Z,1532983026.956 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-07-30T20:37:06.963Z,1532983026.963 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-07-30T20:37:06.964Z,1532983026.964 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409D24E0
2018-07-30T20:37:06.964Z,1532983026.964 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2218
2018-07-30T20:37:06.969Z,1532983026.969 [Supervisor](INFO): Main Thread ID is 795
2018-07-30T20:37:06.969Z,1532983026.969 [Supervisor](DEBUG): Running supervisor.
2018-07-30T20:37:06.970Z,1532983026.970 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2219
2018-07-30T20:37:06.973Z,1532983026.973 [controlThread ThreadHandler](INFO): Handler Thread ID is 2220
2018-07-30T20:37:06.973Z,1532983026.973 [controlThread](DEBUG): Initializing ControlThread
2018-07-30T20:37:06.974Z,1532983026.974 [SBIT](INFO): Initialize SBIT Component.
2018-07-30T20:37:06.975Z,1532983026.975 [SBIT](IMPORTANT): git: 2018-07-30
2018-07-30T20:37:06.975Z,1532983026.975 [SBIT](INFO): git hash: d4f8f0da1cb083ba9f96282d65d9cbbd19acaad4
2018-07-30T20:37:06.975Z,1532983026.975 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-07-30T20:37:06.977Z,1532983026.977 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-07-30T20:37:06.978Z,1532983026.978 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2018-07-30T20:37:06.978Z,1532983026.978 [IBIT](INFO): Initialize IBIT Component.
2018-07-30T20:37:06.979Z,1532983026.979 [CBIT](DEBUG): Initialize CBIT Component.
2018-07-30T20:37:06.980Z,1532983026.980 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-07-30T20:37:06.981Z,1532983026.981 [logger ThreadHandler](INFO): Handler Thread ID is 2221
2018-07-30T20:37:07.009Z,1532983027.009 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2222
2018-07-30T20:37:07.025Z,1532983027.025 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2223
2018-07-30T20:37:07.031Z,1532983027.031 [Radio_Surface](INFO): Powering up
2018-07-30T20:37:07.049Z,1532983027.049 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2224
2018-07-30T20:37:07.052Z,1532983027.052 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-07-30T20:37:07.052Z,1532983027.052 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-07-30T20:37:07.053Z,1532983027.053 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-07-30T20:37:07.053Z,1532983027.053 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-07-30T20:37:07.053Z,1532983027.053 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-07-30T20:37:07.053Z,1532983027.053 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-07-30T20:37:07.053Z,1532983027.053 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-07-30T20:37:07.054Z,1532983027.054 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-07-30T20:37:07.054Z,1532983027.054 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-07-30T20:37:07.054Z,1532983027.054 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-07-30T20:37:07.054Z,1532983027.054 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-07-30T20:37:07.054Z,1532983027.054 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-07-30T20:37:07.055Z,1532983027.055 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-07-30T20:37:07.055Z,1532983027.055 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-07-30T20:37:07.055Z,1532983027.055 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-07-30T20:37:07.055Z,1532983027.055 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-07-30T20:37:07.085Z,1532983027.085 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-07-30T20:37:07.086Z,1532983027.086 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-07-30T20:37:07.087Z,1532983027.087 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-07-30T20:37:07.088Z,1532983027.088 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-07-30T20:37:07.088Z,1532983027.088 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-07-30T20:37:07.088Z,1532983027.088 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-07-30T20:37:07.089Z,1532983027.089 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-07-30T20:37:07.089Z,1532983027.089 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-07-30T20:37:07.090Z,1532983027.090 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-07-30T20:37:07.090Z,1532983027.090 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-07-30T20:37:07.091Z,1532983027.091 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-07-30T20:37:07.092Z,1532983027.092 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-07-30T20:37:07.092Z,1532983027.092 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T20:37:07.093Z,1532983027.093 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T20:37:07.093Z,1532983027.093 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-30T20:37:07.094Z,1532983027.094 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T20:37:07.094Z,1532983027.094 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T20:37:07.094Z,1532983027.094 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-07-30T20:37:07.095Z,1532983027.095 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T20:37:07.095Z,1532983027.095 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T20:37:07.096Z,1532983027.096 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-07-30T20:37:07.096Z,1532983027.096 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-07-30T20:37:07.105Z,1532983027.105 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-07-30T20:37:07.141Z,1532983027.141 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-07-30T20:37:07.181Z,1532983027.181 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2018-07-30T20:37:07.181Z,1532983027.181 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-07-30T20:37:07.272Z,1532983027.272 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-07-30T20:37:07.289Z,1532983027.289 [Default:A.Wait](DEBUG): Construct Wait.
2018-07-30T20:37:07.291Z,1532983027.291 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-07-30T20:37:07.322Z,1532983027.322 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-07-30T20:37:07.341Z,1532983027.341 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-07-30T20:37:07.346Z,1532983027.346 [Default:E.Execute](DEBUG): Construct Execute.
2018-07-30T20:37:07.366Z,1532983027.366 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

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

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

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2018-07-30T20:37:07.371Z,1532983027.371 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,SCPI,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-07-30T20:37:07.419Z,1532983027.419 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-07-30T20:37:07.486Z,1532983027.486 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-07-30T20:37:08.089Z,1532983028.089 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:08.113Z,1532983028.113 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:08.133Z,1532983028.133 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:08.229Z,1532983028.229 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-30T20:37:08.237Z,1532983028.237 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-30T20:37:08.259Z,1532983028.259 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-07-30T20:37:08.265Z,1532983028.265 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-07-30T20:37:08.271Z,1532983028.271 [MassServo](DEBUG): Initializing EZServoServo.
2018-07-30T20:37:08.278Z,1532983028.278 [MassServo](DEBUG): Initializing MassServo.
2018-07-30T20:37:08.284Z,1532983028.284 [RudderServo](DEBUG): Initializing EZServoServo.
2018-07-30T20:37:08.289Z,1532983028.289 [RudderServo](DEBUG): Initializing RudderServo.
2018-07-30T20:37:08.295Z,1532983028.295 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-07-30T20:37:08.301Z,1532983028.301 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-07-30T20:37:08.542Z,1532983028.542 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:08.543Z,1532983028.543 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:08.544Z,1532983028.544 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:08.825Z,1532983028.825 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:08.826Z,1532983028.826 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:08.827Z,1532983028.827 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:09.173Z,1532983029.173 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-07-30T20:37:09.173Z,1532983029.173 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-07-30T20:37:09.173Z,1532983029.173 [BuoyancyServo] Communications Fault, FailCount= 1
2018-07-30T20:37:09.173Z,1532983029.173 [BuoyancyServo](ERROR): Communications Fault
2018-07-30T20:37:09.384Z,1532983029.384 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-07-30T20:37:09.421Z,1532983029.421 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:09.422Z,1532983029.422 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:09.423Z,1532983029.423 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:09.435Z,1532983029.435 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-30T20:37:09.436Z,1532983029.436 [BuoyancyServo](INFO): Powering down
2018-07-30T20:37:09.825Z,1532983029.825 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:09.826Z,1532983029.826 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:09.827Z,1532983029.827 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:10.221Z,1532983030.221 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:10.222Z,1532983030.222 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:10.223Z,1532983030.223 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:10.717Z,1532983030.717 [Aanderaa_O2](INFO): Powering down
2018-07-30T20:37:10.837Z,1532983030.837 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:10.838Z,1532983030.838 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:10.839Z,1532983030.839 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:11.110Z,1532983031.110 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:11.111Z,1532983031.111 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:11.112Z,1532983031.112 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:11.449Z,1532983031.449 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:11.450Z,1532983031.450 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:11.451Z,1532983031.451 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:11.506Z,1532983031.506 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-07-30T20:37:11.506Z,1532983031.506 [BuoyancyServo] No Fault, FailCount= 1
2018-07-30T20:37:11.849Z,1532983031.849 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:11.850Z,1532983031.850 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:11.851Z,1532983031.851 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:11.873Z,1532983031.873 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-30T20:37:11.993Z,1532983031.993 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-30T20:37:12.318Z,1532983032.318 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:12.319Z,1532983032.319 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:12.320Z,1532983032.320 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:12.646Z,1532983032.646 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:12.648Z,1532983032.648 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:12.649Z,1532983032.649 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:13.046Z,1532983033.046 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:13.047Z,1532983033.047 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:13.048Z,1532983033.048 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:13.462Z,1532983033.462 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:13.463Z,1532983033.463 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:13.463Z,1532983033.463 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:13.854Z,1532983033.854 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:13.855Z,1532983033.855 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:13.856Z,1532983033.856 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:14.250Z,1532983034.250 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:14.251Z,1532983034.251 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:14.252Z,1532983034.252 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:14.622Z,1532983034.622 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:14.623Z,1532983034.623 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:14.623Z,1532983034.623 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:15.022Z,1532983035.022 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:15.023Z,1532983035.023 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:15.023Z,1532983035.023 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:15.450Z,1532983035.450 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:15.451Z,1532983035.451 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:15.452Z,1532983035.452 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2018-07-30T20:37:21.424Z,1532983041.424 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2018-07-30T20:37:31.471Z,1532983051.471 [SBIT](IMPORTANT): Beginning Startup BIT
2018-07-30T20:37:31.484Z,1532983051.484 [CBIT](IMPORTANT): Beginning ground fault scan
2018-07-30T20:37:35.150Z,1532983055.150 [NAL9602](INFO): Powering up NAL9602
2018-07-30T20:37:42.514Z,1532983062.514 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.017039
CHAN A1 (24V): -0.021439
CHAN A2 (12V): -0.007142
CHAN A3 (5V): -0.003970
CHAN B0 (3.3V): -0.000507
CHAN B1 (3.15aV): -0.000529
CHAN B2 (3.15bV): 0.000013
CHAN B3 (GND): 0.001033
OPEN: 0.009534
Full Scale Calc: 4.765 mA, -1.589 mA
2018-07-30T20:37:46.362Z,1532983066.362 [NAL9602](INFO): NAL9602 initialized
2018-07-30T20:38:25.565Z,1532983105.565 [SBIT](IMPORTANT): SBIT PASSED
2018-07-30T20:38:25.619Z,1532983105.619 [CommandLine](IMPORTANT): got command configSet list
2018-07-30T20:38:25.619Z,1532983105.619 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-07-30T20:38:25.620Z,1532983105.620 [CommandLine](IMPORTANT): AHRS_M2.readAccelerations=1 bool;

2018-07-30T20:38:25.620Z,1532983105.620 [CommandLine](IMPORTANT): AHRS_M2.readMagnetics=1 bool;

2018-07-30T20:38:25.621Z,1532983105.621 [CommandLine](IMPORTANT): DeadReckonWithRespectToSeafloor.loadAtStartup=1 bool;

2018-07-30T20:38:25.621Z,1532983105.621 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2018-07-30T20:38:25.621Z,1532983105.621 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=160 cubic_centimeter;

2018-07-30T20:38:25.621Z,1532983105.621 [CommandLine](IMPORTANT): VerticalControl.massDefault=-1 millimeter;

2018-07-30T20:38:25.970Z,1532983105.970 [MissionManager](IMPORTANT): Started mission Startup
2018-07-30T20:38:25.970Z,1532983105.970 [Startup] Running Loop=1
2018-07-30T20:38:25.970Z,1532983105.970 [Startup](DEBUG): Aggregate::initialize Startup
2018-07-30T20:38:25.971Z,1532983105.971 [Startup:A.GoToSurface] Running Loop=1
2018-07-30T20:38:25.971Z,1532983105.971 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-07-30T20:38:25.971Z,1532983105.971 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-07-30T20:38:25.972Z,1532983105.972 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-07-30T20:38:25.972Z,1532983105.972 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-07-30T20:38:25.973Z,1532983105.973 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-07-30T20:38:25.973Z,1532983105.973 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-07-30T20:38:25.983Z,1532983105.983 [Startup:StartupSatComms] Running Loop=1
2018-07-30T20:38:25.983Z,1532983105.983 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-07-30T20:38:25.984Z,1532983105.984 [Startup:StartupSatComms:A] Running Loop=1
2018-07-30T20:38:26.389Z,1532983106.389 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-07-30T20:39:10.724Z,1532983150.724 [RDI_Pathfinder](ERROR): only read 3 of 5 data items
2018-07-30T20:39:10.724Z,1532983150.724 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,   -34,  -23,    -4,A
2018-07-30T20:39:10.753Z,1532983150.753 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-30T20:39:11.155Z,1532983151.155 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-30T20:39:23.504Z,1532983163.504 [NAL9602](INFO): SBD MO Status=2, MOMSN=560, MT Status=2, MTMSN=0
2018-07-30T20:39:23.504Z,1532983163.504 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-30T20:39:26.379Z,1532983166.379 [Startup:StartupSatComms:A](INFO): Timed out from 2018-07-30T20:38:25.0Z
2018-07-30T20:39:26.379Z,1532983166.379 [Startup:StartupSatComms:A] Stopped
2018-07-30T20:39:26.379Z,1532983166.379 [Startup:StartupSatComms:B] Running Loop=1
2018-07-30T20:39:26.747Z,1532983166.747 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-07-30T20:39:46.708Z,1532983186.708 [NAL9602](INFO): SBD MO Status=2, MOMSN=560, MT Status=2, MTMSN=0
2018-07-30T20:39:46.708Z,1532983186.708 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-30T20:40:07.148Z,1532983207.148 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-30T20:40:07.148Z,1532983207.148 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2018-07-30T20:40:07.148Z,1532983207.148 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-07-30T20:40:07.156Z,1532983207.156 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-30T20:40:07.156Z,1532983207.156 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-07-30T20:40:07.161Z,1532983207.161 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-07-30T20:40:07.163Z,1532983207.163 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-30T20:40:07.163Z,1532983207.163 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-07-30T20:40:07.163Z,1532983207.163 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-07-30T20:40:07.193Z,1532983207.193 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-07-30T20:40:07.193Z,1532983207.193 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-07-30T20:40:07.194Z,1532983207.194 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-07-30T20:40:07.606Z,1532983207.606 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-07-30T20:40:07.606Z,1532983207.606 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2018-07-30T20:40:07.607Z,1532983207.607 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-07-30T20:40:07.607Z,1532983207.607 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-07-30T20:40:07.607Z,1532983207.607 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-07-30T20:40:07.607Z,1532983207.607 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-07-30T20:40:07.963Z,1532983207.963 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-07-30T20:40:07.963Z,1532983207.963 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T20:40:07.964Z,1532983207.964 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T20:40:07.964Z,1532983207.964 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-30T20:40:07.965Z,1532983207.965 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T20:40:07.965Z,1532983207.965 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T20:40:07.965Z,1532983207.965 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-07-30T20:40:07.966Z,1532983207.966 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T20:40:07.966Z,1532983207.966 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T20:40:18.355Z,1532983218.355 [RDI_Pathfinder](ERROR): only read 1 of 1 data item for altitude
2018-07-30T20:40:18.355Z,1532983218.355 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G0e88,*14.604,*-0.116,*0.000
2018-07-30T20:40:18.413Z,1532983218.413 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.5 s old, using for 20.0 s.
2018-07-30T20:40:18.784Z,1532983218.784 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-30T20:40:19.151Z,1532983219.151 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-30T20:40:22.704Z,1532983222.704 [NAL9602](INFO): SBD MO Status=0, MOMSN=560, MT Status=0, MTMSN=0
2018-07-30T20:40:22.704Z,1532983222.704 [NAL9602](INFO): No messages in MT queue
2018-07-30T20:40:26.757Z,1532983226.757 [Startup:StartupSatComms:B](INFO): Timed out from 2018-07-30T20:39:26.4Z
2018-07-30T20:40:26.757Z,1532983226.757 [Startup:StartupSatComms:B] Stopped
2018-07-30T20:40:26.757Z,1532983226.757 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-07-30T20:40:26.757Z,1532983226.757 [Startup:StartupSatComms] Stopped
2018-07-30T20:40:26.757Z,1532983226.757 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-07-30T20:40:26.758Z,1532983226.758 [Startup](INFO): Completed Startup
2018-07-30T20:40:26.758Z,1532983226.758 [MissionManager](INFO): Startup is completed.
2018-07-30T20:40:26.758Z,1532983226.758 [MissionManager](INFO): Uninitializing Mission Startup
2018-07-30T20:40:26.759Z,1532983226.759 [Startup] Stopped
2018-07-30T20:40:26.759Z,1532983226.759 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-07-30T20:40:26.759Z,1532983226.759 [Startup:A.GoToSurface] Stopped
2018-07-30T20:40:26.759Z,1532983226.759 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-07-30T20:40:27.167Z,1532983227.167 [MissionManager](IMPORTANT): Started mission Default
2018-07-30T20:40:27.167Z,1532983227.167 [Default] Running Loop=1
2018-07-30T20:40:27.167Z,1532983227.167 [Default](DEBUG): Aggregate::initialize Default
2018-07-30T20:40:27.167Z,1532983227.167 [Default:B.GoToSurface] Running Loop=1
2018-07-30T20:40:27.167Z,1532983227.167 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-07-30T20:40:27.167Z,1532983227.167 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-07-30T20:40:27.168Z,1532983227.168 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-07-30T20:40:27.168Z,1532983227.168 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-07-30T20:40:27.173Z,1532983227.173 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-07-30T20:40:27.173Z,1532983227.173 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-07-30T20:40:27.173Z,1532983227.173 [Default:A.Wait] Running Loop=1
2018-07-30T20:40:27.173Z,1532983227.173 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-07-30T20:40:34.135Z,1532983234.135 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004487
2018-07-30T20:40:40.611Z,1532983240.611 [Default:A.Wait](INFO): Done Waiting.
2018-07-30T20:40:40.611Z,1532983240.611 [Default:A.Wait] Stopped
2018-07-30T20:40:40.611Z,1532983240.611 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-07-30T20:40:40.991Z,1532983240.991 [Default:CheckIn] Running Loop=1
2018-07-30T20:40:40.991Z,1532983240.991 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-30T20:40:40.991Z,1532983240.991 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-30T20:40:41.403Z,1532983241.403 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-07-30T20:40:42.540Z,1532983242.540 [NAL9602](INFO): SBD MO Status=1, MOMSN=561, MT Status=0, MTMSN=0
2018-07-30T20:40:42.593Z,1532983242.593 [NAL9602](INFO): Sent 25 bytes from file Logs/20180730T190059/Courier0030.lzma
2018-07-30T20:40:42.593Z,1532983242.593 [NAL9602](INFO): Packets left to send: 0
2018-07-30T20:40:42.595Z,1532983242.595 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180730T190059/Courier0030.lzma.parts/0000.sbd
2018-07-30T20:40:42.595Z,1532983242.595 [NAL9602](DEBUG): Completed sending Logs/20180730T190059/Courier0030.lzma
2018-07-30T20:41:13.067Z,1532983273.067 [DataOverHttps](IMPORTANT): SBD MTMSN=20180730T204112
2018-07-30T20:41:17.548Z,1532983277.548 [DataOverHttps](INFO): Received command:failc
2018-07-30T20:41:17.583Z,1532983277.583 [CommandLine](IMPORTANT): got command failComponent
2018-07-30T20:41:17.583Z,1532983277.583 [CommandLine](IMPORTANT): Failed components:
2018-07-30T20:41:17.583Z,1532983277.583 [CommandLine](IMPORTANT): No failed Components.
2018-07-30T20:42:29.318Z,1532983349.318 [BPC1](ERROR): Bank A: No match for stick 0 serial number:1717 in the onboard configuration file.
2018-07-30T20:42:29.319Z,1532983349.319 [BPC1](ERROR): Failed to parse Bank A battery data
2018-07-30T20:43:08.127Z,1532983388.127 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-30T20:43:08.128Z,1532983388.128 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2018-07-30T20:43:08.128Z,1532983388.128 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-07-30T20:43:08.130Z,1532983388.130 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-30T20:43:08.130Z,1532983388.130 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-07-30T20:43:08.130Z,1532983388.130 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-07-30T20:43:08.132Z,1532983388.132 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-30T20:43:08.132Z,1532983388.132 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-07-30T20:43:08.132Z,1532983388.132 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-07-30T20:43:08.175Z,1532983388.175 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-07-30T20:43:08.175Z,1532983388.175 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-07-30T20:43:08.175Z,1532983388.175 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-07-30T20:43:08.544Z,1532983388.544 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-07-30T20:43:08.544Z,1532983388.544 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2018-07-30T20:43:08.544Z,1532983388.544 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-07-30T20:43:08.552Z,1532983388.552 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-07-30T20:43:08.553Z,1532983388.553 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-07-30T20:43:08.553Z,1532983388.553 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-07-30T20:43:08.894Z,1532983388.894 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-07-30T20:43:08.895Z,1532983388.895 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T20:43:08.895Z,1532983388.895 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T20:43:08.895Z,1532983388.895 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-07-30T20:43:08.896Z,1532983388.896 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T20:43:08.896Z,1532983388.896 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T20:43:08.897Z,1532983388.897 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-07-30T20:43:08.897Z,1532983388.897 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T20:43:08.898Z,1532983388.898 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T20:43:18.623Z,1532983398.623 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,  -250,  +102,  +303,A
2018-07-30T20:43:18.632Z,1532983398.632 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-30T20:43:19.047Z,1532983399.047 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-30T20:44:26.183Z,1532983466.183 [NAL9602](INFO): GPS fix at 20180730T204424: (36.809265, -121.828770)
2018-07-30T20:44:26.266Z,1532983466.266 [Default:CheckIn:Read_GPS] Stopped
2018-07-30T20:44:26.266Z,1532983466.266 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-30T20:44:26.669Z,1532983466.669 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-07-30T20:44:37.349Z,1532983477.349 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20180730T203657/Courier0004.lzma
2018-07-30T20:44:38.102Z,1532983478.102 [DataOverHttps](INFO): Moved sent file to Logs/20180730T203657/Courier0004.lzma.bak
2018-07-30T20:44:38.103Z,1532983478.103 [DataOverHttps](IMPORTANT): SBD MOMSN=8386635, MTMSN=20180730T204437
2018-07-30T20:44:46.213Z,1532983486.213 [DataOverHttps](INFO): Received command:sched asap "load Science/sci2.xml;set sci2.MissionTimeout 60 minute;set sci2.NeedCommsTime 20 minute;set sci2.Lat1 36.807 degree;set sci2.Lon1 -121.8243 degree;set sci2.YoYoMaxDepth 40 meter;set sci2.YoYoMinAltitude 25 meter" 27dka 1 2
2018-07-30T20:44:46.242Z,1532983486.242 [CommandLine](IMPORTANT): got command schedule asap "load Science/sci2.xml;set sci2.MissionTimeout 60 minute;set sci2.NeedCommsTime 20 minute;set sci2.Lat1 36.807 degree;set sci2.Lon1 -121.8243 degree;set sci2.YoYoMaxDepth 40 meter;set sci2.YoYoMinAltitude 25 meter" 27dka 1 2.000000
2018-07-30T20:44:46.243Z,1532983486.243 [CommandLine](IMPORTANT): Scheduling command #1 of 2 with id=27dka
2018-07-30T20:44:46.244Z,1532983486.244 [CommandLine](IMPORTANT): Scheduled #1 (#1 of 2 with id='27dka'): "load Science/sci2.xml;set sci2.MissionTimeout 60 minute;set sci2.NeedCommsTime 20 minute;set sci2.Lat1 36.807 degree;set sci2.Lon1 -121.8243 degree;set sci2.YoYoMaxDepth 40 meter;set sci2.YoYoMinAltitude 25 meter" ASAP
2018-07-30T20:44:47.865Z,1532983487.865 [DataOverHttps](IMPORTANT): SBD MTMSN=20180730T204446
2018-07-30T20:44:53.327Z,1532983493.327 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180730T203657/Courier0007.lzma
2018-07-30T20:44:53.331Z,1532983493.331 [DataOverHttps](INFO): Received command:sched asap "set sci2.MaxDepth 50 meter;run " 27dka 2 2
2018-07-30T20:44:53.346Z,1532983493.346 [CommandLine](IMPORTANT): got command schedule asap "set sci2.MaxDepth 50 meter;run " 27dka 2 2.000000
2018-07-30T20:44:53.347Z,1532983493.347 [CommandLine](IMPORTANT): Scheduling command #2 of 2 with id=27dka
2018-07-30T20:44:53.348Z,1532983493.348 [CommandLine](IMPORTANT): Scheduled #2 (#2 of 2 with id='27dka'): "set sci2.MaxDepth 50 meter;run " ASAP
2018-07-30T20:44:53.521Z,1532983493.521 [CommandLine](IMPORTANT): got command load ./Missions/Science/sci2.xml
2018-07-30T20:44:53.521Z,1532983493.521 [MissionManager](INFO): Loading Mission: ./Missions/Science/sci2.xml
2018-07-30T20:44:53.633Z,1532983493.633 [MissionManager](INFO): DefineArg sci2.MissionTimeout = 2.000000 h
2018-07-30T20:44:53.637Z,1532983493.637 [MissionManager](INFO): DefineArg sci2.NeedCommsTime = 60.000000 min
2018-07-30T20:44:53.639Z,1532983493.639 [MissionManager](INFO): DefineArg sci2.Repeat = 1.000000 count
2018-07-30T20:44:53.643Z,1532983493.643 [MissionManager](INFO): DefineArg sci2.Lat1 = nan arcdeg
2018-07-30T20:44:53.646Z,1532983493.646 [MissionManager](INFO): DefineArg sci2.Lon1 = nan arcdeg
2018-07-30T20:44:53.649Z,1532983493.649 [MissionManager](INFO): DefineArg sci2.Lat2 = nan arcdeg
2018-07-30T20:44:53.651Z,1532983493.651 [MissionManager](INFO): DefineArg sci2.Lon2 = nan arcdeg
2018-07-30T20:44:53.670Z,1532983493.670 [MissionManager](INFO): DefineArg sci2.Lat3 = nan arcdeg
2018-07-30T20:44:53.675Z,1532983493.675 [MissionManager](INFO): DefineArg sci2.Lon3 = nan arcdeg
2018-07-30T20:44:53.730Z,1532983493.730 [MissionManager](INFO): DefineArg sci2.Lat4 = nan arcdeg
2018-07-30T20:44:53.733Z,1532983493.733 [MissionManager](INFO): DefineArg sci2.Lon4 = nan arcdeg
2018-07-30T20:44:53.749Z,1532983493.749 [MissionManager](INFO): DefineArg sci2.Lat5 = nan arcdeg
2018-07-30T20:44:53.752Z,1532983493.752 [MissionManager](INFO): DefineArg sci2.Lon5 = nan arcdeg
2018-07-30T20:44:53.755Z,1532983493.755 [MissionManager](INFO): DefineArg sci2.Lat6 = nan arcdeg
2018-07-30T20:44:53.774Z,1532983493.774 [MissionManager](INFO): DefineArg sci2.Lon6 = nan arcdeg
2018-07-30T20:44:53.789Z,1532983493.789 [MissionManager](INFO): DefineArg sci2.Lat7 = nan arcdeg
2018-07-30T20:44:53.792Z,1532983493.792 [MissionManager](INFO): DefineArg sci2.Lon7 = nan arcdeg
2018-07-30T20:44:53.795Z,1532983493.795 [MissionManager](INFO): DefineArg sci2.Speed = 1.000000 m/s
2018-07-30T20:44:53.810Z,1532983493.810 [MissionManager](INFO): DefineArg sci2.YoYoMinDepth = 2.000000 m
2018-07-30T20:44:53.813Z,1532983493.813 [MissionManager](INFO): DefineArg sci2.YoYoMaxDepth = 200.000000 m
2018-07-30T20:44:53.837Z,1532983493.837 [MissionManager](INFO): DefineArg sci2.YoYoMinAltitude = 9.000000 m
2018-07-30T20:44:53.840Z,1532983493.840 [MissionManager](INFO): DefineArg sci2.YoYoUpPitch = 20.000000 arcdeg
2018-07-30T20:44:53.843Z,1532983493.843 [MissionManager](INFO): DefineArg sci2.YoYoDownPitch = -20.000000 arcdeg
2018-07-30T20:44:53.855Z,1532983493.855 [MissionManager](INFO): DefineArg sci2.BuoyancyNeutral = 159.999996 cc
2018-07-30T20:44:53.859Z,1532983493.859 [MissionManager](INFO): DefineArg sci2.MassDefault = -0.100000 cm
2018-07-30T20:44:53.882Z,1532983493.882 [MissionManager](INFO): DefineArg sci2.MinAltitude = 5.000000 m
2018-07-30T20:44:53.885Z,1532983493.885 [MissionManager](INFO): DefineArg sci2.MaxDepth = 205.000000 m
2018-07-30T20:44:53.888Z,1532983493.888 [MissionManager](INFO): DefineArg sci2.MinOffshore = 2.000000 km
2018-07-30T20:44:53.888Z,1532983493.888 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2018-07-30T20:44:54.254Z,1532983494.254 [MissionManager](INFO): DefineArg sci2:Science.SampleISUS = 0 bool
2018-07-30T20:44:54.257Z,1532983494.257 [MissionManager](INFO): DefineArg sci2:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2018-07-30T20:44:54.260Z,1532983494.260 [MissionManager](INFO): DefineArg sci2:Science.SampleISUSminPitch = -60.000000 arcdeg
2018-07-30T20:44:54.277Z,1532983494.277 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectChlActive = 0 bool
2018-07-30T20:44:54.311Z,1532983494.311 [MissionManager](INFO): DefineArg sci2:Science.HighestChlPeakReportActive = 0 bool
2018-07-30T20:44:54.334Z,1532983494.334 [MissionManager](INFO): DefineArg sci2:Science.PatchTracking = 0 bool
2018-07-30T20:44:54.337Z,1532983494.337 [MissionManager](INFO): DefineArg sci2:Science.FilterWidthHorizontal = 3.000000 count
2018-07-30T20:44:54.340Z,1532983494.340 [MissionManager](INFO): DefineArg sci2:Science.NumProfilesSlidingwindow = 100.000000 count
2018-07-30T20:44:54.358Z,1532983494.358 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectNO3Active = 0 bool
2018-07-30T20:44:54.361Z,1532983494.361 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectOilActive = 0 bool
2018-07-30T20:44:54.364Z,1532983494.364 [MissionManager](INFO): DefineArg sci2:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2018-07-30T20:44:54.375Z,1532983494.375 [DataOverHttps](INFO): Moved sent file to Logs/20180730T203657/Courier0007.lzma.bak
2018-07-30T20:44:54.375Z,1532983494.375 [DataOverHttps](INFO): SBD MOMSN=8386640
2018-07-30T20:44:54.382Z,1532983494.382 [MissionManager](INFO): DefineArg sci2:Science.EnabledAanderaaO2 = 1.000000 bool
2018-07-30T20:44:54.406Z,1532983494.406 [MissionManager](INFO): DefineArg sci2:Science.EnabledNeilBrown = 0.000000 bool
2018-07-30T20:44:54.409Z,1532983494.409 [MissionManager](INFO): DefineArg sci2:Science.EnabledSeabird = 0.000000 bool
2018-07-30T20:44:54.424Z,1532983494.424 [MissionManager](INFO): DefineArg sci2:Science.EnabledTurbulence_NPS = 0.000000 bool
2018-07-30T20:44:54.448Z,1532983494.448 [MissionManager](INFO): DefineArg sci2:Science.EnabledWetLabsBB2FL = 1.000000 bool
2018-07-30T20:44:54.455Z,1532983494.455 [MissionManager](INFO): DefineArg sci2:Science.EnabledWetLabsSeaOWL_UV_A = 1.000000 bool
2018-07-30T20:44:54.471Z,1532983494.471 [MissionManager](INFO): DefineArg sci2:Science.EnabledISUS = 0.000000 bool
2018-07-30T20:44:54.482Z,1532983494.482 [MissionManager](INFO): DefineArg sci2:Science.LowPassWindowLength = 20.000000 count
2018-07-30T20:44:54.493Z,1532983494.493 [MissionManager](INFO): DefineArg sci2:Science.PeakChlShallowBound = nan m
2018-07-30T20:44:54.505Z,1532983494.505 [MissionManager](INFO): DefineArg sci2:Science.PeakChlDeepBound = nan m
2018-07-30T20:44:54.511Z,1532983494.511 [MissionManager](INFO): DefineOutput sci2:Science.PeakChl = 0.000000 ug/l
2018-07-30T20:44:54.523Z,1532983494.523 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlDepth = 0.000000 m
2018-07-30T20:44:54.526Z,1532983494.526 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlTemperature = 0.000000 degC
2018-07-30T20:44:54.538Z,1532983494.538 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlLatitude = nan arcdeg
2018-07-30T20:44:54.545Z,1532983494.545 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlLongitude = nan arcdeg
2018-07-30T20:44:54.552Z,1532983494.552 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3 = 0.000000 umol/l
2018-07-30T20:44:54.556Z,1532983494.556 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3Depth = 0.000000 m
2018-07-30T20:44:54.559Z,1532983494.559 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3Latitude = nan arcdeg
2018-07-30T20:44:54.570Z,1532983494.570 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3Longitude = nan arcdeg
2018-07-30T20:44:54.574Z,1532983494.574 [MissionManager](INFO): DefineOutput sci2:Science.PatchChl = 0.000000 m
2018-07-30T20:44:54.586Z,1532983494.586 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlDepth = 0.000000 m
2018-07-30T20:44:54.593Z,1532983494.593 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlLatitude = nan arcdeg
2018-07-30T20:44:54.596Z,1532983494.596 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlLongitude = nan arcdeg
2018-07-30T20:44:54.603Z,1532983494.603 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlDistance = nan m
2018-07-30T20:44:54.607Z,1532983494.607 [MissionManager](INFO): DefineOutput sci2:Science.PeakOil = 0.000000 kg/m3
2018-07-30T20:44:54.619Z,1532983494.619 [MissionManager](INFO): DefineOutput sci2:Science.PeakOilDepth = 0.000000 m
2018-07-30T20:44:54.626Z,1532983494.626 [MissionManager](INFO): DefineOutput sci2:Science.PeakOilLatitude = nan arcdeg
2018-07-30T20:44:54.634Z,1532983494.634 [MissionManager](INFO): DefineOutput sci2:Science.PeakOilLongitude = nan arcdeg
2018-07-30T20:44:54.666Z,1532983494.666 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-07-30T20:44:54.690Z,1532983494.690 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2018-07-30T20:44:54.712Z,1532983494.712 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-07-30T20:44:54.781Z,1532983494.781 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-07-30T20:44:54.807Z,1532983494.807 [MissionManager](INFO): RedefineArg sci2:Science.SampleISUS = value:1 bool
2018-07-30T20:44:54.813Z,1532983494.813 [MissionManager](INFO): RedefineArg sci2:Science.PeakDetectChlActive = value:1 bool
2018-07-30T20:44:54.813Z,1532983494.813 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2018-07-30T20:44:54.938Z,1532983494.938 [MissionManager](INFO): DefineArg sci2:NeedComms.DiveInterval = 3.000000 h
2018-07-30T20:44:54.949Z,1532983494.949 [MissionManager](INFO): DefineArg sci2:NeedComms.WaitForPitchUp = 10.000000 min
2018-07-30T20:44:54.952Z,1532983494.952 [MissionManager](INFO): DefineArg sci2:NeedComms.SurfacePitch = 20.000000 arcdeg
2018-07-30T20:44:54.959Z,1532983494.959 [MissionManager](INFO): DefineArg sci2:NeedComms.SurfaceDepthRate = nan m/s
2018-07-30T20:44:54.967Z,1532983494.967 [MissionManager](INFO): DefineArg sci2:NeedComms.SurfaceSpeed = 1.000000 m/s
2018-07-30T20:44:54.970Z,1532983494.970 [MissionManager](INFO): DefineArg sci2:NeedComms.GPSTimeout = 7.000000 min
2018-07-30T20:44:54.981Z,1532983494.981 [MissionManager](INFO): DefineArg sci2:NeedComms.CommsTimeout = 30.000000 min
2018-07-30T20:44:54.988Z,1532983494.988 [sci2:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-07-30T20:44:55.024Z,1532983495.024 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2018-07-30T20:44:55.061Z,1532983495.061 [MissionManager](INFO): DefineArg sci2:StandardEnvelopes.MinAltitude = 5.000000 m
2018-07-30T20:44:55.064Z,1532983495.064 [MissionManager](INFO): DefineArg sci2:StandardEnvelopes.MaxDepth = 200.000000 m
2018-07-30T20:44:55.067Z,1532983495.067 [MissionManager](INFO): DefineArg sci2:StandardEnvelopes.MinOffshore = 2000.000000 m
2018-07-30T20:44:55.069Z,1532983495.069 [sci2:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2018-07-30T20:44:55.076Z,1532983495.076 [sci2:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2018-07-30T20:44:55.104Z,1532983495.104 [sci2:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2018-07-30T20:44:55.187Z,1532983495.187 [sci2:I.Pitch](DEBUG): Construct.
2018-07-30T20:44:55.205Z,1532983495.205 [sci2:J.Buoyancy](DEBUG): Construct Buoyancy.
2018-07-30T20:44:55.208Z,1532983495.208 [sci2:K.SetSpeed](DEBUG): Construct.
2018-07-30T20:44:55.227Z,1532983495.227 [sci2:L.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2018-07-30T20:44:55.248Z,1532983495.248 [sci2:M.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2018-07-30T20:44:55.255Z,1532983495.255 [sci2:N.YoYo](DEBUG): Construct YoYo.
2018-07-30T20:44:55.321Z,1532983495.321 [sci2:Lap:Wpt1:A.Waypoint](DEBUG): Construct Waypoint.
2018-07-30T20:44:55.343Z,1532983495.343 [sci2:Lap:Wpt2:A.Waypoint](DEBUG): Construct Waypoint.
2018-07-30T20:44:55.361Z,1532983495.361 [sci2:Lap:Wpt3:A.Waypoint](DEBUG): Construct Waypoint.
2018-07-30T20:44:55.367Z,1532983495.367 [sci2:Lap:Wpt4:A.Waypoint](DEBUG): Construct Waypoint.
2018-07-30T20:44:55.390Z,1532983495.390 [sci2:Lap:Wpt5:A.Waypoint](DEBUG): Construct Waypoint.
2018-07-30T20:44:55.409Z,1532983495.409 [sci2:Lap:Wpt6:A.Waypoint](DEBUG): Construct Waypoint.
2018-07-30T20:44:55.415Z,1532983495.415 [sci2:Lap:Wpt7:A.Waypoint](DEBUG): Construct Waypoint.
2018-07-30T20:44:55.479Z,1532983495.479 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Control="Tethys/Control"
         xmlns:Guidance="Tethys/Guidance"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd"
       Id="sci2">

    <Description>
        Vehicle yo-yo's to the specified waypoints, with science turned on.
    </Description>
    <DefineArg Name="MissionTimeout"><Description>
        Maximum duration of mission
    </Description><Units:hour/><Value>2</Value></DefineArg>

    <DefineArg Name="NeedCommsTime"><Description>
        How often to surface for commumications
    </Description><Units:minute/><Value>60</Value></DefineArg>

    <DefineArg Name="Repeat"><Description>
        Number of times the vehicle should try to cycle through waypoints..
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="Lat1"><Description>
        Latitude of waypoint 1. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon1"><Description>
        Longitude of waypoint 1. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat2"><Description>
        Latitude of waypoint 2. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon2"><Description>
        Longitude of waypoint 2. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat3"><Description>
        Latitude of waypoint 3. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon3"><Description>
        Longitude of waypoint 3. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat4"><Description>
        Latitude of waypoint 4. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon4"><Description>
        Longitude of waypoint 4. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat5"><Description>
        Latitude of waypoint 5. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon5"><Description>
        Longitude of waypoint 5. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat6"><Description>
        Latitude of waypoint 6. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon6"><Description>
        Longitude of waypoint 6. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat7"><Description>
        Latitude of waypoint 7. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon7"><Description>
        Longitude of waypoint 7. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

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

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

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

    <DefineArg Name="YoYoMinAltitude"><Description>
        Minimum altitude while performing the YoYo behavior (for
        bottom-terminated YoYos).
    </Description><Units:meter/><Value>9</Value></DefineArg>

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

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

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

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

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

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

    <DefineArg Name="MinOffshore"><Description>
        Minimum offshore distance for the entire mission.
    </Description><Units:kilometer/><Value>2</Value></DefineArg>
    <Timeout Duration="MissionTimeout"/>

    <Insert Filename="Insert/Science.xml">
        <Description>
            Most missions will run the science sensors. If you don't place this
            aggregate above NeedComms, science instruments get turned off on the
            last upcast and while floating on the surface.
        </Description>
        <RedefineArg Name="SampleISUS"><True/></RedefineArg>
        <RedefineArg Name="PeakDetectChlActive"><True/></RedefineArg>
    </Insert>

    <Insert Filename="Insert/NeedComms.xml" Id="NeedComms"/>

    <Assign><Sequence/><Arg Name="NeedComms:DiveInterval"/><Arg Name="NeedCommsTime"/></Assign>

    <Insert Filename="Insert/StandardEnvelopes.xml"/>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MinAltitude"/><Arg Name="MinAltitude"/></Assign>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MaxDepth"/><Arg Name="MaxDepth"/></Assign>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MinOffshore"/><Arg Name="MinOffshore"/></Assign>

    <Call Id="StartingMission" RefId="NeedComms"/>

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

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

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

    <Guidance:DepthEnvelope>
        <Description>
            Another depth envelope for the YoYo behavior. This envelope should
            fall within the limits of the standard safety envelopes in
            Insert/StandardEnvelopes.xml in order to avoid commanding high pitch
            angles for depth-terminated YoYos.
        </Description>
        <Parallel/>
        <Setting><Guidance:DepthEnvelope.minDepth/><Arg Name="YoYoMinDepth"/></Setting>
        <Setting><Guidance:DepthEnvelope.maxDepth/><Arg Name="YoYoMaxDepth"/></Setting>
        <Setting><Guidance:DepthEnvelope.downPitch/><Arg Name="YoYoDownPitch"/></Setting>
        <Setting><Guidance:DepthEnvelope.upPitch/><Arg Name="YoYoUpPitch"/></Setting>
    </Guidance:DepthEnvelope>

    <Guidance:AltitudeEnvelope>
        <Description>
            Another altitude envelope for the YoYo behavior. This envelope
            should fall within the limits of the standard safety envelopes in
            Insert/StandardEnvelopes.xml in order to avoid commanding high pitch
            angles for bottom-terminated YoYos.
        </Description>
        <Parallel/>
        <Setting><Guidance:AltitudeEnvelope.minAltitude/><Arg Name="YoYoMinAltitude"/></Setting>
        <Setting><Guidance:AltitudeEnvelope.upPitch/><Arg Name="YoYoUpPitch"/></Setting>
    </Guidance:AltitudeEnvelope>

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

    <Aggregate Id="Lap">
        <Sequence Repeat="Repeat"/>

        <Aggregate Id="Wpt1">
            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat1"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon1"/></Setting>
            </Guidance:Waypoint>
        </Aggregate>

        <Aggregate Id="Wpt2">
            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat2"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon2"/></Setting>
            </Guidance:Waypoint>
        </Aggregate>

        <Aggregate Id="Wpt3">
            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat3"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon3"/></Setting>
            </Guidance:Waypoint>
        </Aggregate>

        <Aggregate Id="Wpt4">
            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat4"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon4"/></Setting>
            </Guidance:Waypoint>
        </Aggregate>

        <Aggregate Id="Wpt5">
            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat5"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon5"/></Setting>
            </Guidance:Waypoint>
        </Aggregate>

        <Aggregate Id="Wpt6">
            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat6"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon6"/></Setting>
            </Guidance:Waypoint>
        </Aggregate>

        <Aggregate Id="Wpt7">
            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat7"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon7"/></Setting>
            </Guidance:Waypoint>
        </Aggregate>
    </Aggregate>
</Mission>

2018-07-30T20:44:55.479Z,1532983495.479 [CommandLine](IMPORTANT): Loaded ./Missions/Science/sci2.xml
2018-07-30T20:45:03.256Z,1532983503.256 [NAL9602](INFO): Powering down
2018-07-30T20:45:07.877Z,1532983507.877 [CommandLine](IMPORTANT): got command set sci2.MissionTimeout 60.000000 minute
2018-07-30T20:45:07.878Z,1532983507.878 [CommandLine](IMPORTANT): got command set sci2.NeedCommsTime 20.000000 minute
2018-07-30T20:45:07.879Z,1532983507.879 [CommandLine](IMPORTANT): got command set sci2.Lat1 36.806999 degree
2018-07-30T20:45:07.880Z,1532983507.880 [CommandLine](IMPORTANT): got command set sci2.Lon1 -121.824303 degree
2018-07-30T20:45:07.880Z,1532983507.880 [CommandLine](IMPORTANT): got command set sci2.YoYoMaxDepth 40.000000 meter
2018-07-30T20:45:07.881Z,1532983507.881 [CommandLine](IMPORTANT): got command set sci2.YoYoMinAltitude 25.000000 meter
2018-07-30T20:45:09.423Z,1532983509.423 [CommandLine](IMPORTANT): got command set sci2.MaxDepth 50.000000 meter
2018-07-30T20:45:09.423Z,1532983509.423 [CommandLine](IMPORTANT): got command run 
2018-07-30T20:45:09.447Z,1532983509.447 [CommandLine](IMPORTANT): Running 
2018-07-30T20:45:12.142Z,1532983512.142 [Default] Stopped
2018-07-30T20:45:12.142Z,1532983512.142 [Default](DEBUG): Aggregate::uninitialize Default
2018-07-30T20:45:12.142Z,1532983512.142 [Default:B.GoToSurface] Stopped
2018-07-30T20:45:12.142Z,1532983512.142 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-07-30T20:45:12.142Z,1532983512.142 [Default:CheckIn] Stopped
2018-07-30T20:45:12.142Z,1532983512.142 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-30T20:45:12.143Z,1532983512.143 [Default:CheckIn:Read_Iridium] Stopped
2018-07-30T20:45:12.143Z,1532983512.143 [MissionManager](IMPORTANT): Started mission sci2
2018-07-30T20:45:12.143Z,1532983512.143 [sci2] Running Loop=1
2018-07-30T20:45:12.143Z,1532983512.143 [sci2](DEBUG): Aggregate::initialize sci2
2018-07-30T20:45:12.143Z,1532983512.143 [sci2:Science] Running Loop=1
2018-07-30T20:45:12.143Z,1532983512.143 [sci2:Science](DEBUG): Aggregate::initialize sci2:Science
2018-07-30T20:45:12.143Z,1532983512.143 [sci2:Science:A] Running Loop=1
2018-07-30T20:45:12.144Z,1532983512.144 [sci2:Science:B] Running Loop=1
2018-07-30T20:45:12.144Z,1532983512.144 [sci2:Science:C] Running Loop=1
2018-07-30T20:45:12.144Z,1532983512.144 [sci2:Science:D] Running Loop=1
2018-07-30T20:45:12.144Z,1532983512.144 [sci2:Science:E] Running Loop=1
2018-07-30T20:45:12.144Z,1532983512.144 [sci2:Science:F] Running Loop=1
2018-07-30T20:45:12.144Z,1532983512.144 [sci2:Science:G] Running Loop=1
2018-07-30T20:45:12.144Z,1532983512.144 [sci2:Science:Read_Oil] Running Loop=1
2018-07-30T20:45:12.144Z,1532983512.144 [sci2:Science:PeakDetectChl] Running Loop=1
2018-07-30T20:45:12.149Z,1532983512.149 [sci2:Science:PeakDetectChl](DEBUG): Aggregate::initialize sci2:Science:PeakDetectChl
2018-07-30T20:45:12.149Z,1532983512.149 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2018-07-30T20:45:12.149Z,1532983512.149 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-07-30T20:45:12.151Z,1532983512.151 [sci2:Science:HighestChlPeakReport] Running Loop=1
2018-07-30T20:45:12.151Z,1532983512.151 [sci2:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize sci2:Science:HighestChlPeakReport
2018-07-30T20:45:12.151Z,1532983512.151 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2018-07-30T20:45:12.151Z,1532983512.151 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2018-07-30T20:45:12.152Z,1532983512.152 [sci2:Science:PeakDetectNO3] Running Loop=1
2018-07-30T20:45:12.152Z,1532983512.152 [sci2:Science:PeakDetectNO3](DEBUG): Aggregate::initialize sci2:Science:PeakDetectNO3
2018-07-30T20:45:12.152Z,1532983512.152 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2018-07-30T20:45:12.152Z,1532983512.152 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-07-30T20:45:12.158Z,1532983512.158 [sci2:Science:PeakDetectOil] Running Loop=1
2018-07-30T20:45:12.158Z,1532983512.158 [sci2:Science:PeakDetectOil](DEBUG): Aggregate::initialize sci2:Science:PeakDetectOil
2018-07-30T20:45:12.158Z,1532983512.158 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2018-07-30T20:45:12.159Z,1532983512.159 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-07-30T20:45:12.165Z,1532983512.165 [sci2:StandardEnvelopes] Running Loop=1
2018-07-30T20:45:12.165Z,1532983512.165 [sci2:StandardEnvelopes](DEBUG): Aggregate::initialize sci2:StandardEnvelopes
2018-07-30T20:45:12.165Z,1532983512.165 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2018-07-30T20:45:12.165Z,1532983512.165 [sci2:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2018-07-30T20:45:12.165Z,1532983512.165 [sci2:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2018-07-30T20:45:12.165Z,1532983512.165 [sci2:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2018-07-30T20:45:12.165Z,1532983512.165 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2018-07-30T20:45:12.166Z,1532983512.166 [sci2:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2018-07-30T20:45:12.166Z,1532983512.166 [sci2:I.Pitch] Running Loop=1
2018-07-30T20:45:12.166Z,1532983512.166 [sci2:I.Pitch](DEBUG): Initialize.
2018-07-30T20:45:12.166Z,1532983512.166 [sci2:J.Buoyancy] Running Loop=1
2018-07-30T20:45:12.166Z,1532983512.166 [sci2:J.Buoyancy](DEBUG): Initialize Buoyancy Component.
2018-07-30T20:45:12.166Z,1532983512.166 [sci2:K.SetSpeed] Running Loop=1
2018-07-30T20:45:12.166Z,1532983512.166 [sci2:K.SetSpeed](DEBUG): Initialize.
2018-07-30T20:45:12.166Z,1532983512.166 [sci2:L.DepthEnvelope] Running Loop=1
2018-07-30T20:45:12.167Z,1532983512.167 [sci2:L.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2018-07-30T20:45:12.167Z,1532983512.167 [sci2:M.AltitudeEnvelope] Running Loop=1
2018-07-30T20:45:12.167Z,1532983512.167 [sci2:M.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2018-07-30T20:45:12.167Z,1532983512.167 [sci2:N.YoYo] Running Loop=1
2018-07-30T20:45:12.167Z,1532983512.167 [sci2:N.YoYo](DEBUG): Initialize YoYoComponent.
2018-07-30T20:45:12.168Z,1532983512.168 [sci2:C] Running Loop=1
2018-07-30T20:45:12.168Z,1532983512.168 [sci2:N.YoYo] Running Loop=1
2018-07-30T20:45:12.182Z,1532983512.182 [sci2:M.AltitudeEnvelope] Running Loop=1
2018-07-30T20:45:12.191Z,1532983512.191 [sci2:L.DepthEnvelope] Running Loop=1
2018-07-30T20:45:12.206Z,1532983512.206 [sci2:K.SetSpeed] Running Loop=1
2018-07-30T20:45:12.214Z,1532983512.214 [sci2:J.Buoyancy] Running Loop=1
2018-07-30T20:45:12.227Z,1532983512.227 [sci2:I.Pitch] Running Loop=1
2018-07-30T20:45:12.237Z,1532983512.237 [sci2:StandardEnvelopes] Running Loop=1
2018-07-30T20:45:12.237Z,1532983512.237 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2018-07-30T20:45:12.251Z,1532983512.251 [sci2:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2018-07-30T20:45:12.260Z,1532983512.260 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2018-07-30T20:45:12.271Z,1532983512.271 [sci2:C] Stopped
2018-07-30T20:45:12.271Z,1532983512.271 [sci2:E] Running Loop=1
2018-07-30T20:45:12.271Z,1532983512.271 [sci2:Science] Running Loop=1
2018-07-30T20:45:12.285Z,1532983512.285 [sci2:Science:PeakDetectOil] Stopped
2018-07-30T20:45:12.285Z,1532983512.285 [sci2:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectOil
2018-07-30T20:45:12.285Z,1532983512.285 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2018-07-30T20:45:12.285Z,1532983512.285 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-07-30T20:45:12.285Z,1532983512.285 [sci2:Science:PeakDetectNO3] Stopped
2018-07-30T20:45:12.285Z,1532983512.285 [sci2:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectNO3
2018-07-30T20:45:12.285Z,1532983512.285 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2018-07-30T20:45:12.285Z,1532983512.285 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-07-30T20:45:12.286Z,1532983512.286 [sci2:Science:HighestChlPeakReport] Stopped
2018-07-30T20:45:12.286Z,1532983512.286 [sci2:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize sci2:Science:HighestChlPeakReport
2018-07-30T20:45:12.286Z,1532983512.286 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2018-07-30T20:45:12.286Z,1532983512.286 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2018-07-30T20:45:12.286Z,1532983512.286 [sci2:Science:PeakDetectChl] Running Loop=1
2018-07-30T20:45:12.291Z,1532983512.291 [sci2:Science:Read_Oil] Running Loop=1
2018-07-30T20:45:12.291Z,1532983512.291 [sci2:Science:Read_Oil](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_petroleum_hydrocarbons_in_sea_water
2018-07-30T20:45:12.307Z,1532983512.307 [sci2:Science:G] Running Loop=1
2018-07-30T20:45:12.309Z,1532983512.309 [sci2:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2018-07-30T20:45:12.311Z,1532983512.311 [sci2:Science:F] Stopped
2018-07-30T20:45:12.312Z,1532983512.312 [sci2:Science:E] Stopped
2018-07-30T20:45:12.312Z,1532983512.312 [sci2:Science:D] Stopped
2018-07-30T20:45:12.312Z,1532983512.312 [sci2:Science:C] Stopped
2018-07-30T20:45:12.312Z,1532983512.312 [sci2:Science:B] Stopped
2018-07-30T20:45:12.312Z,1532983512.312 [sci2:Science:A] Running Loop=1
2018-07-30T20:45:12.314Z,1532983512.314 [sci2:Science:A](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_oxygen_in_sea_water
2018-07-30T20:45:12.411Z,1532983512.411 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20180730T203657/Courier0010.lzma
2018-07-30T20:45:12.492Z,1532983512.492 [sci2:E] Stopped
2018-07-30T20:45:12.492Z,1532983512.492 [sci2:F] Running Loop=1
2018-07-30T20:45:12.853Z,1532983512.853 [sci2:F] Stopped
2018-07-30T20:45:12.853Z,1532983512.853 [sci2:G] Running Loop=1
2018-07-30T20:45:13.185Z,1532983513.185 [ThrusterServo](FAULT): getVelocity uart error serial timeout
2018-07-30T20:45:13.185Z,1532983513.185 [ThrusterServo](FAULT): Thruster uart error: serial timeout
2018-07-30T20:45:13.185Z,1532983513.185 [ThrusterServo] Communications Fault, FailCount= 1
2018-07-30T20:45:13.185Z,1532983513.185 [ThrusterServo](ERROR): Communications Fault
2018-07-30T20:45:13.189Z,1532983513.189 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2018-07-30T20:45:13.214Z,1532983513.214 [DataOverHttps](INFO): Moved sent file to Logs/20180730T203657/Courier0010.lzma.bak
2018-07-30T20:45:13.215Z,1532983513.215 [DataOverHttps](INFO): SBD MOMSN=8386644
2018-07-30T20:45:13.322Z,1532983513.322 [sci2:G] Stopped
2018-07-30T20:45:13.322Z,1532983513.322 [sci2:StartingMission] Running Loop=1
2018-07-30T20:45:13.323Z,1532983513.323 [sci2:StartingMission](DEBUG): Aggregate::initialize sci2:StartingMission
2018-07-30T20:45:13.446Z,1532983513.446 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-07-30T20:45:13.446Z,1532983513.446 [ThrusterServo](INFO): Powering down
2018-07-30T20:45:13.698Z,1532983513.698 [sci2:NeedComms] Running Loop=1
2018-07-30T20:45:13.698Z,1532983513.698 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms
2018-07-30T20:45:13.698Z,1532983513.698 [sci2:NeedComms:B.GoToSurface] Running Loop=1
2018-07-30T20:45:13.699Z,1532983513.699 [sci2:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-07-30T20:45:13.699Z,1532983513.699 [sci2:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2018-07-30T20:45:13.699Z,1532983513.699 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2018-07-30T20:45:13.700Z,1532983513.700 [sci2:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2018-07-30T20:45:13.700Z,1532983513.700 [sci2:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-07-30T20:45:13.705Z,1532983513.705 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-07-30T20:45:13.705Z,1532983513.705 [sci2:NeedComms:A] Running Loop=1
2018-07-30T20:45:13.719Z,1532983513.719 [sci2:NeedComms:A](INFO): last time_fix was: 1532983464.000000 second since 1970/01/01T00:00:00Z 
2018-07-30T20:45:13.720Z,1532983513.720 [sci2:NeedComms:A] Stopped
2018-07-30T20:45:14.093Z,1532983514.093 [sci2:NeedComms:C] Running Loop=1
2018-07-30T20:45:14.329Z,1532983514.329 [CBIT](INFO): Clearing failed state for component ThrusterServo
2018-07-30T20:45:14.329Z,1532983514.329 [ThrusterServo] No Fault, FailCount= 1
2018-07-30T20:45:14.482Z,1532983514.482 [sci2:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-07-30T20:45:14.531Z,1532983514.531 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-07-30T20:45:14.537Z,1532983514.537 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-07-30T20:45:14.819Z,1532983514.819 [NAL9602](INFO): Powering up
2018-07-30T20:45:20.063Z,1532983520.063 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	651		338.431		105.813		14.290		30.397		30.084		38.474		8.391		416.9		568.2		319.6	



2018-07-30T20:45:20.063Z,1532983520.063 [Aanderaa_O2](DEBUG): Error parsing device response
2018-07-30T20:45:21.262Z,1532983521.262 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	651		339.238		106.067		14.291		30.369		30.056		38.444		8.388		424.3		567.7		319.6	


2018-07-30T20:45:21.262Z,1532983521.262 [Aanderaa_O2](DEBUG): Error parsing device response
2018-07-30T20:45:23.262Z,1532983523.262 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	651		339.482		106.144		14.291		30.361		30.048		38.438		8.390		424.3		566.9		319.6	

2018-07-30T20:45:23.262Z,1532983523.262 [Aanderaa_O2](DEBUG): Error parsing device response
2018-07-30T20:45:25.674Z,1532983525.674 [NAL9602](INFO): NAL9602 initialized
2018-07-30T20:46:07.767Z,1532983567.767 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-07-30T20:46:07.767Z,1532983567.767 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18073020471579,35.0, -0.1,   0.0,1448.BI,  -256,  +258,  +164,    -2,A
2018-07-30T20:46:07.787Z,1532983567.787 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-30T20:46:08.138Z,1532983568.138 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-30T20:46:08.943Z,1532983568.943 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-30T20:46:08.943Z,1532983568.943 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-07-30T20:46:08.943Z,1532983568.943 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-07-30T20:46:08.984Z,1532983568.984 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-07-30T20:46:09.416Z,1532983569.416 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-07-30T20:46:09.416Z,1532983569.416 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-07-30T20:46:09.727Z,1532983569.727 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-07-30T20:46:09.727Z,1532983569.727 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T20:46:09.727Z,1532983569.727 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T20:46:17.358Z,1532983577.358 [NAL9602](INFO): SBD MO Status=2, MOMSN=562, MT Status=2, MTMSN=0
2018-07-30T20:46:17.358Z,1532983577.358 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-30T20:46:44.720Z,1532983604.720 [NAL9602](INFO): SBD MO Status=2, MOMSN=562, MT Status=2, MTMSN=0
2018-07-30T20:46:44.721Z,1532983604.721 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-30T20:47:23.492Z,1532983643.492 [RDI_Pathfinder](ERROR): Failed to parse:

2018-07-30T20:47:23.501Z,1532983643.501 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-30T20:47:24.001Z,1532983644.001 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.9 s old, using for 20.0 s.
2018-07-30T20:47:24.322Z,1532983644.322 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-30T20:47:35.459Z,1532983655.459 [NAL9602](INFO): SBD MO Status=2, MOMSN=562, MT Status=2, MTMSN=0
2018-07-30T20:47:35.459Z,1532983655.459 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-30T20:48:03.451Z,1532983683.451 [NAL9602](INFO): SBD MO Status=0, MOMSN=562, MT Status=0, MTMSN=0
2018-07-30T20:48:03.452Z,1532983683.452 [NAL9602](INFO): No messages in MT queue
2018-07-30T20:49:09.911Z,1532983749.911 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-30T20:49:09.911Z,1532983749.911 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-07-30T20:49:09.911Z,1532983749.911 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-07-30T20:49:09.956Z,1532983749.956 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-07-30T20:49:10.365Z,1532983750.365 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-07-30T20:49:10.365Z,1532983750.365 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-07-30T20:49:10.708Z,1532983750.708 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-07-30T20:49:10.717Z,1532983750.717 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T20:49:10.717Z,1532983750.717 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T20:51:40.504Z,1532983900.504 [RDI_Pathfinder](ERROR): Failed to parse:
0e88,0e88,*14.590,*-0.117,*0.000
2018-07-30T20:51:40.529Z,1532983900.529 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-30T20:51:40.931Z,1532983900.931 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-30T20:51:41.329Z,1532983901.329 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-30T20:51:45.329Z,1532983905.329 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2018-07-30T20:51:45.329Z,1532983905.329 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,  .-125.70,       -3.67,  42.68,  0.00
2018-07-30T20:51:45.346Z,1532983905.346 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-30T20:51:45.731Z,1532983905.731 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-30T20:51:46.101Z,1532983906.101 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-30T20:51:47.543Z,1532983907.543 [CommandLine](IMPORTANT): got command failComponent
2018-07-30T20:51:47.543Z,1532983907.543 [CommandLine](IMPORTANT): Failed components:
2018-07-30T20:51:47.544Z,1532983907.544 [CommandLine](IMPORTANT): No failed Components.
2018-07-30T20:51:49.823Z,1532983909.823 [CommandLine](IMPORTANT): got command show stack
2018-07-30T20:51:49.823Z,1532983909.823 [CommandLine](IMPORTANT): Behavior Stack:

2018-07-30T20:51:49.824Z,1532983909.824 [sci2:Science](IMPORTANT): Priority 0: sci2:Science:A
2018-07-30T20:51:49.824Z,1532983909.824 [sci2:Science](IMPORTANT): Priority 1: sci2:Science:G
2018-07-30T20:51:49.825Z,1532983909.825 [sci2:Science](IMPORTANT): Priority 2: sci2:Science:Read_Oil
2018-07-30T20:51:49.825Z,1532983909.825 [sci2:Science:PeakDetectChl](IMPORTANT): Priority 3: sci2:Science:PeakDetectChl:A.PeakDetectVsDepth
2018-07-30T20:51:49.825Z,1532983909.825 [sci2:NeedComms](IMPORTANT): Priority 4: sci2:NeedComms:B.GoToSurface
2018-07-30T20:51:49.826Z,1532983909.826 [sci2:NeedComms](IMPORTANT): Priority 5: sci2:NeedComms:C
2018-07-30T20:51:49.826Z,1532983909.826 [sci2:StandardEnvelopes](IMPORTANT): Priority 6: sci2:StandardEnvelopes:A.AltitudeEnvelope
2018-07-30T20:51:49.826Z,1532983909.826 [sci2:StandardEnvelopes](IMPORTANT): Priority 7: sci2:StandardEnvelopes:B.DepthEnvelope
2018-07-30T20:51:49.827Z,1532983909.827 [sci2:StandardEnvelopes](IMPORTANT): Priority 8: sci2:StandardEnvelopes:C.OffshoreEnvelope
2018-07-30T20:51:49.827Z,1532983909.827 [sci2](IMPORTANT): Priority 9: sci2:I.Pitch
2018-07-30T20:51:49.827Z,1532983909.827 [sci2](IMPORTANT): Priority 10: sci2:J.Buoyancy
2018-07-30T20:51:49.828Z,1532983909.828 [sci2](IMPORTANT): Priority 11: sci2:K.SetSpeed
2018-07-30T20:51:49.828Z,1532983909.828 [sci2](IMPORTANT): Priority 12: sci2:L.DepthEnvelope
2018-07-30T20:51:49.828Z,1532983909.828 [sci2](IMPORTANT): Priority 13: sci2:M.AltitudeEnvelope
2018-07-30T20:51:49.829Z,1532983909.829 [sci2](IMPORTANT): Priority 14: sci2:N.YoYo
2018-07-30T20:51:51.335Z,1532983911.335 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,G,0e88,0e88,*14.597,*-0.110,*0.000
2018-07-30T20:51:51.344Z,1532983911.344 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-30T20:51:51.723Z,1532983911.723 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-30T20:51:52.111Z,1532983912.111 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-30T20:51:53.715Z,1532983913.715 [RDI_Pathfinder](ERROR): only read 3 of 5 data items
2018-07-30T20:51:53.715Z,1532983913.715 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,  -11,  -577,    -2,A
2018-07-30T20:51:53.777Z,1532983913.777 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-30T20:51:54.128Z,1532983914.128 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-30T20:51:57.713Z,1532983917.713 [RDI_Pathfinder](ERROR): Failed to parse:
64,  -171,  -244,    -2,A
2018-07-30T20:51:57.754Z,1532983917.754 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-30T20:51:58.107Z,1532983918.107 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-30T20:52:01.702Z,1532983921.702 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-07-30T20:52:01.703Z,1532983921.703 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,180730205309 .9,  0
2018-07-30T20:52:01.712Z,1532983921.712 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-30T20:52:02.116Z,1532983922.116 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-30T20:52:10.920Z,1532983930.920 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-30T20:52:10.921Z,1532983930.921 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2018-07-30T20:52:10.921Z,1532983930.921 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-07-30T20:52:10.962Z,1532983930.962 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-07-30T20:52:11.375Z,1532983931.375 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-07-30T20:52:11.375Z,1532983931.375 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2018-07-30T20:52:11.731Z,1532983931.731 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-07-30T20:52:11.732Z,1532983931.732 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T20:52:11.732Z,1532983931.732 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T20:52:14.147Z,1532983934.147 [sci2:NeedComms:C](INFO): Timed out from 2018-07-30T20:45:14.1Z
2018-07-30T20:52:14.147Z,1532983934.147 [sci2:NeedComms:C:A_Timeout] Running Loop=1
2018-07-30T20:52:14.147Z,1532983934.147 [sci2:NeedComms:C:A_Timeout](DEBUG): Aggregate::initialize sci2:NeedComms:C:A_Timeout
2018-07-30T20:52:14.148Z,1532983934.148 [sci2:NeedComms:C:A_Timeout:A] Running Loop=1
2018-07-30T20:52:14.148Z,1532983934.148 [sci2:NeedComms:C:A_Timeout:A](IMPORTANT): first GPS update timeout
2018-07-30T20:52:14.150Z,1532983934.150 [sci2:NeedComms:C:A_Timeout:A] Stopped
2018-07-30T20:52:14.150Z,1532983934.150 [sci2:NeedComms:C:A_Timeout](INFO): Completed sci2:NeedComms:C:A_Timeout
2018-07-30T20:52:14.150Z,1532983934.150 [sci2:NeedComms:C] Stopped
2018-07-30T20:52:14.150Z,1532983934.150 [sci2:NeedComms:D] Running Loop=1
2018-07-30T20:52:14.547Z,1532983934.547 [sci2:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-07-30T20:52:18.681Z,1532983938.681 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180730T203657/Courier0013.lzma
2018-07-30T20:52:19.453Z,1532983939.453 [DataOverHttps](INFO): Moved sent file to Logs/20180730T203657/Courier0013.lzma.bak
2018-07-30T20:52:19.453Z,1532983939.453 [DataOverHttps](INFO): SBD MOMSN=8386650
2018-07-30T20:52:30.338Z,1532983950.338 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180730T203657/Courier0016.lzma
2018-07-30T20:52:31.106Z,1532983951.106 [DataOverHttps](INFO): Moved sent file to Logs/20180730T203657/Courier0016.lzma.bak
2018-07-30T20:52:31.106Z,1532983951.106 [DataOverHttps](INFO): SBD MOMSN=8386652
2018-07-30T20:52:41.209Z,1532983961.209 [DataOverHttps](INFO): Sending 253 bytes from file Logs/20180730T190059/Express0031.lzma
2018-07-30T20:52:41.938Z,1532983961.938 [DataOverHttps](INFO): Moved sent file to Logs/20180730T190059/Express0031.lzma.bak
2018-07-30T20:52:41.939Z,1532983961.939 [DataOverHttps](INFO): SBD MOMSN=8386654
2018-07-30T20:52:45.983Z,1532983965.983 [NAL9602](INFO): Powering down
2018-07-30T20:52:51.513Z,1532983971.513 [DataOverHttps](INFO): Sending 821 bytes from file Logs/20180730T203657/Express0001.lzma
2018-07-30T20:52:52.701Z,1532983972.701 [DataOverHttps](INFO): Moved sent file to Logs/20180730T203657/Express0001.lzma.bak
2018-07-30T20:52:52.701Z,1532983972.701 [DataOverHttps](INFO): SBD MOMSN=8386661
2018-07-30T20:53:04.747Z,1532983984.747 [DataOverHttps](INFO): Sending 329 bytes from file Logs/20180730T203657/Express0005.lzma
2018-07-30T20:53:05.544Z,1532983985.544 [DataOverHttps](INFO): Moved sent file to Logs/20180730T203657/Express0005.lzma.bak
2018-07-30T20:53:05.544Z,1532983985.544 [DataOverHttps](INFO): SBD MOMSN=8386680
2018-07-30T20:53:18.510Z,1532983998.510 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180730T203657/Express0008.lzma
2018-07-30T20:53:19.334Z,1532983999.334 [DataOverHttps](INFO): Moved sent file to Logs/20180730T203657/Express0008.lzma.bak
2018-07-30T20:53:19.334Z,1532983999.334 [DataOverHttps](INFO): SBD MOMSN=8386687
2018-07-30T20:53:30.974Z,1532984010.974 [DataOverHttps](INFO): Sending 339 bytes from file Logs/20180730T203657/Express0011.lzma
2018-07-30T20:53:31.768Z,1532984011.768 [DataOverHttps](INFO): Moved sent file to Logs/20180730T203657/Express0011.lzma.bak
2018-07-30T20:53:31.769Z,1532984011.769 [DataOverHttps](INFO): SBD MOMSN=8386689
2018-07-30T20:53:41.976Z,1532984021.976 [DataOverHttps](INFO): Sending 264 bytes from file Logs/20180730T203657/Express0014.lzma
2018-07-30T20:53:42.702Z,1532984022.702 [DataOverHttps](INFO): Moved sent file to Logs/20180730T203657/Express0014.lzma.bak
2018-07-30T20:53:42.702Z,1532984022.702 [DataOverHttps](INFO): SBD MOMSN=8386695
2018-07-30T20:53:51.966Z,1532984031.966 [DataOverHttps](INFO): Sending 757 bytes from file Logs/20180730T203657/Express0017.lzma
2018-07-30T20:53:52.737Z,1532984032.737 [DataOverHttps](INFO): Moved sent file to Logs/20180730T203657/Express0017.lzma.bak
2018-07-30T20:53:52.737Z,1532984032.737 [DataOverHttps](INFO): SBD MOMSN=8386702
2018-07-30T20:53:53.252Z,1532984033.252 [sci2:NeedComms:D] Stopped
2018-07-30T20:53:53.269Z,1532984033.269 [sci2:NeedComms:E] Running Loop=1
2018-07-30T20:53:53.619Z,1532984033.619 [sci2:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-07-30T20:53:53.942Z,1532984033.942 [NAL9602](INFO): Powering up
2018-07-30T20:54:04.866Z,1532984044.866 [NAL9602](INFO): NAL9602 initialized
2018-07-30T20:55:08.670Z,1532984108.670 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,  -134,   -58,  -269,    +0,A
2018-07-30T20:55:08.689Z,1532984108.689 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-30T20:55:09.063Z,1532984109.063 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-30T20:55:11.888Z,1532984111.888 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-30T20:55:11.888Z,1532984111.888 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2018-07-30T20:55:11.888Z,1532984111.888 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-07-30T20:55:11.938Z,1532984111.938 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-07-30T20:55:12.350Z,1532984112.350 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-07-30T20:55:12.350Z,1532984112.350 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2018-07-30T20:55:12.695Z,1532984112.695 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-07-30T20:55:12.695Z,1532984112.695 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T20:55:12.696Z,1532984112.696 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T20:55:57.156Z,1532984157.156 [NAL9602](INFO): SBD MO Status=2, MOMSN=563, MT Status=2, MTMSN=0
2018-07-30T20:55:57.157Z,1532984157.157 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-30T20:56:12.939Z,1532984172.939 [NAL9602](INFO): SBD MO Status=2, MOMSN=563, MT Status=2, MTMSN=0
2018-07-30T20:56:12.940Z,1532984172.940 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-30T20:56:30.951Z,1532984190.951 [NAL9602](INFO): SBD MO Status=2, MOMSN=563, MT Status=2, MTMSN=0
2018-07-30T20:56:30.952Z,1532984190.952 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-30T20:56:48.139Z,1532984208.139 [NAL9602](INFO): SBD MO Status=2, MOMSN=563, MT Status=2, MTMSN=0
2018-07-30T20:56:48.140Z,1532984208.140 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-30T20:57:18.483Z,1532984238.483 [NAL9602](INFO): SBD MO Status=2, MOMSN=563, MT Status=2, MTMSN=0
2018-07-30T20:57:18.484Z,1532984238.484 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-30T20:58:00.290Z,1532984280.290 [NAL9602](INFO): SBD MO Status=0, MOMSN=563, MT Status=0, MTMSN=0
2018-07-30T20:58:00.290Z,1532984280.290 [NAL9602](INFO): No messages in MT queue
2018-07-30T20:58:01.078Z,1532984281.078 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-07-30T20:58:01.078Z,1532984281.078 [NAL9602] Data Fault, FailCount= 1
2018-07-30T20:58:01.079Z,1532984281.079 [NAL9602](ERROR): Data Fault
2018-07-30T20:58:01.215Z,1532984281.215 [CBIT](ERROR): Data Fault in component: NAL9602
2018-07-30T20:58:01.474Z,1532984281.474 [NAL9602](INFO): Powering down
2018-07-30T20:58:02.379Z,1532984282.379 [CBIT](INFO): Clearing failed state for component NAL9602
2018-07-30T20:58:02.379Z,1532984282.379 [NAL9602] No Fault, FailCount= 1
2018-07-30T20:58:12.724Z,1532984292.724 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-30T20:58:12.733Z,1532984292.733 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5
2018-07-30T20:58:12.733Z,1532984292.733 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-07-30T20:58:12.774Z,1532984292.774 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-07-30T20:58:13.186Z,1532984293.186 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-07-30T20:58:13.186Z,1532984293.186 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5
2018-07-30T20:58:13.535Z,1532984293.535 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-07-30T20:58:13.535Z,1532984293.535 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T20:58:13.535Z,1532984293.535 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T20:58:26.338Z,1532984306.338 [RDI_Pathfinder](ERROR): Unknown system health message
2018-07-30T20:58:26.338Z,1532984306.338 [RDI_Pathfinder] Hardware Fault, FailCount= 1
2018-07-30T20:58:26.338Z,1532984306.338 [RDI_Pathfinder](ERROR): Hardware Fault
2018-07-30T20:58:26.365Z,1532984306.365 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-30T20:58:26.410Z,1532984306.410 [CBIT](ERROR): Hardware Fault in component: RDI_Pathfinder
2018-07-30T20:58:26.773Z,1532984306.773 [RDI_Pathfinder](INFO): Powering down
2018-07-30T20:58:26.781Z,1532984306.781 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.9 s old, using for 20.0 s.
2018-07-30T20:58:27.111Z,1532984307.111 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-30T20:58:27.523Z,1532984307.523 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-07-30T20:58:27.577Z,1532984307.577 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-07-30T20:58:27.577Z,1532984307.577 [RDI_Pathfinder] No Fault, FailCount= 0
2018-07-30T20:58:27.917Z,1532984307.917 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-07-30T20:58:28.324Z,1532984308.324 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-07-30T20:58:28.730Z,1532984308.730 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-07-30T20:58:29.128Z,1532984309.128 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-07-30T20:58:29.533Z,1532984309.533 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-07-30T20:58:29.922Z,1532984309.922 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-07-30T20:58:30.329Z,1532984310.329 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-07-30T20:58:30.729Z,1532984310.729 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-07-30T20:58:31.112Z,1532984311.112 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-07-30T20:58:31.486Z,1532984311.486 [NAL9602](INFO): Powering up NAL9602
2018-07-30T20:58:31.514Z,1532984311.514 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-07-30T20:58:31.923Z,1532984311.923 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-07-30T20:58:32.335Z,1532984312.335 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-07-30T20:58:32.730Z,1532984312.730 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-07-30T20:58:33.129Z,1532984313.129 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-07-30T20:58:33.532Z,1532984313.532 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2018-07-30T20:58:33.911Z,1532984313.911 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2018-07-30T20:58:34.319Z,1532984314.319 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2018-07-30T20:58:34.730Z,1532984314.730 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2018-07-30T20:58:35.131Z,1532984315.131 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2018-07-30T20:58:35.533Z,1532984315.533 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2018-07-30T20:58:35.923Z,1532984315.923 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-07-30T20:58:36.311Z,1532984316.311 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2018-07-30T20:58:36.721Z,1532984316.721 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2018-07-30T20:58:37.119Z,1532984317.119 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2018-07-30T20:58:37.518Z,1532984317.518 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2018-07-30T20:58:37.915Z,1532984317.915 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2018-07-30T20:58:38.341Z,1532984318.341 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2018-07-30T20:58:38.708Z,1532984318.708 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2018-07-30T20:58:39.103Z,1532984319.103 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2018-07-30T20:58:39.524Z,1532984319.524 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2018-07-30T20:58:39.924Z,1532984319.924 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2018-07-30T20:58:40.319Z,1532984320.319 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2018-07-30T20:58:40.722Z,1532984320.722 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2018-07-30T20:58:41.146Z,1532984321.146 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2018-07-30T20:58:41.167Z,1532984321.167 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2018-07-30T20:58:41.537Z,1532984321.537 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2018-07-30T20:58:41.935Z,1532984321.935 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2018-07-30T20:58:42.345Z,1532984322.345 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2018-07-30T20:58:42.674Z,1532984322.674 [NAL9602](INFO): NAL9602 initialized
2018-07-30T20:58:42.707Z,1532984322.707 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2018-07-30T20:58:43.158Z,1532984323.158 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2018-07-30T20:58:43.540Z,1532984323.540 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2018-07-30T20:58:43.941Z,1532984323.941 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2018-07-30T20:58:44.336Z,1532984324.336 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2018-07-30T20:58:44.719Z,1532984324.719 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2018-07-30T20:58:45.156Z,1532984325.156 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2018-07-30T20:58:45.523Z,1532984325.523 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2018-07-30T21:00:53.634Z,1532984453.634 [sci2:NeedComms:E](INFO): Timed out from 2018-07-30T20:53:53.3Z
2018-07-30T21:00:53.634Z,1532984453.634 [sci2:NeedComms:E:A_Timeout] Running Loop=1
2018-07-30T21:00:53.634Z,1532984453.634 [sci2:NeedComms:E:A_Timeout](DEBUG): Aggregate::initialize sci2:NeedComms:E:A_Timeout
2018-07-30T21:00:53.635Z,1532984453.635 [sci2:NeedComms:E:A_Timeout:A] Running Loop=1
2018-07-30T21:00:53.635Z,1532984453.635 [sci2:NeedComms:E:A_Timeout:A](IMPORTANT): second GPS update timeout
2018-07-30T21:00:53.637Z,1532984453.637 [sci2:NeedComms:E:A_Timeout:A] Stopped
2018-07-30T21:00:53.637Z,1532984453.637 [sci2:NeedComms:E:A_Timeout](INFO): Completed sci2:NeedComms:E:A_Timeout
2018-07-30T21:00:53.637Z,1532984453.637 [sci2:NeedComms:E] Stopped
2018-07-30T21:00:53.638Z,1532984453.638 [sci2:NeedComms](INFO): Completed sci2:NeedComms
2018-07-30T21:00:53.638Z,1532984453.638 [sci2:NeedComms] Stopped
2018-07-30T21:00:53.638Z,1532984453.638 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms
2018-07-30T21:00:53.638Z,1532984453.638 [sci2:NeedComms:B.GoToSurface] Stopped
2018-07-30T21:00:53.638Z,1532984453.638 [sci2:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-07-30T21:00:54.026Z,1532984454.026 [sci2:StartingMission](INFO): Completed sci2:StartingMission
2018-07-30T21:00:54.027Z,1532984454.027 [sci2:StartingMission] Stopped
2018-07-30T21:00:54.027Z,1532984454.027 [sci2:StartingMission](DEBUG): Aggregate::uninitialize sci2:StartingMission
2018-07-30T21:00:54.027Z,1532984454.027 [sci2:Lap] Running Loop=1
2018-07-30T21:00:54.027Z,1532984454.027 [sci2:Lap](DEBUG): Aggregate::initialize sci2:Lap
2018-07-30T21:00:54.027Z,1532984454.027 [sci2:Lap:Wpt1] Running Loop=1
2018-07-30T21:00:54.027Z,1532984454.027 [sci2:Lap:Wpt1](DEBUG): Aggregate::initialize sci2:Lap:Wpt1
2018-07-30T21:00:54.027Z,1532984454.027 [sci2:Lap:Wpt1:A.Waypoint] Running Loop=1
2018-07-30T21:00:54.028Z,1532984454.028 [sci2:Lap:Wpt1:A.Waypoint](DEBUG): Initialize WaypointComponent.
2018-07-30T21:01:07.645Z,1532984467.645 [BuoyancyServo](FAULT): Overload Error
2018-07-30T21:01:07.645Z,1532984467.645 [BuoyancyServo] Hardware Fault, FailCount= 1
2018-07-30T21:01:07.645Z,1532984467.645 [BuoyancyServo](ERROR): Hardware Fault
2018-07-30T21:01:07.713Z,1532984467.713 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2018-07-30T21:01:08.012Z,1532984468.012 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-30T21:01:08.017Z,1532984468.017 [BuoyancyServo](INFO): Powering down
2018-07-30T21:01:10.041Z,1532984470.041 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-07-30T21:01:10.041Z,1532984470.041 [BuoyancyServo] No Fault, FailCount= 1
2018-07-30T21:01:10.433Z,1532984470.433 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-30T21:01:10.555Z,1532984470.555 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-30T21:01:11.974Z,1532984471.974 [Radio_Surface](INFO): Powering down
2018-07-30T21:01:13.578Z,1532984473.578 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-30T21:01:13.578Z,1532984473.578 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 6
2018-07-30T21:01:13.579Z,1532984473.579 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-07-30T21:01:13.689Z,1532984473.689 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-07-30T21:01:14.117Z,1532984474.117 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-07-30T21:01:14.117Z,1532984474.117 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 6
2018-07-30T21:01:14.389Z,1532984474.389 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-07-30T21:01:14.389Z,1532984474.389 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T21:01:14.390Z,1532984474.390 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T21:01:17.963Z,1532984477.963 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch -20.00 deg, mass-position -1.00 mm (1 active estimators).
2018-07-30T21:01:28.715Z,1532984488.715 [NAL9602](INFO): Powering down
2018-07-30T21:02:25.512Z,1532984545.512 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-07-30T21:02:27.958Z,1532984547.958 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position -1.00 mm (2 active estimators).
2018-07-30T21:03:01.547Z,1532984581.547 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-07-30T21:03:01.547Z,1532984581.547 [RDI_Pathfinder](ERROR): Failed to parse:
:TS   -85,    +0,A
2018-07-30T21:03:01.571Z,1532984581.571 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-30T21:03:01.967Z,1532984581.967 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-30T21:03:25.512Z,1532984605.512 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-07-30T21:03:59.916Z,1532984639.916 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.388657
2018-07-30T21:04:14.770Z,1532984654.770 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-30T21:04:14.770Z,1532984654.770 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 7
2018-07-30T21:04:14.770Z,1532984654.770 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-07-30T21:04:14.837Z,1532984654.837 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-07-30T21:04:15.230Z,1532984655.230 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-07-30T21:04:15.230Z,1532984655.230 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 7
2018-07-30T21:04:15.589Z,1532984655.589 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-07-30T21:04:15.589Z,1532984655.589 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T21:04:15.590Z,1532984655.590 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T21:04:53.229Z,1532984693.229 [sci2:NeedComms] Running Loop=1
2018-07-30T21:04:53.229Z,1532984693.229 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms
2018-07-30T21:04:53.229Z,1532984693.229 [sci2:NeedComms:B.GoToSurface] Running Loop=1
2018-07-30T21:04:53.229Z,1532984693.229 [sci2:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-07-30T21:04:53.230Z,1532984693.230 [sci2:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2018-07-30T21:04:53.230Z,1532984693.230 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2018-07-30T21:04:53.231Z,1532984693.231 [sci2:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2018-07-30T21:04:53.231Z,1532984693.231 [sci2:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-07-30T21:04:53.232Z,1532984693.232 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-07-30T21:04:53.237Z,1532984693.237 [sci2:NeedComms:A] Running Loop=1
2018-07-30T21:04:53.239Z,1532984693.239 [sci2:NeedComms:A](INFO): last time_fix was: 1532983464.000000 second since 1970/01/01T00:00:00Z 
2018-07-30T21:04:53.239Z,1532984693.239 [sci2:NeedComms:A] Stopped
2018-07-30T21:05:25.538Z,1532984725.538 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-07-30T21:06:00.140Z,1532984760.140 [sci2:NeedComms:C] Running Loop=1
2018-07-30T21:06:00.178Z,1532984760.178 [Radio_Surface](INFO): Powering up
2018-07-30T21:06:00.883Z,1532984760.883 [NAL9602](INFO): Powering up
2018-07-30T21:06:03.105Z,1532984763.105 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2018-07-30T21:06:03.105Z,1532984763.105 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2018-07-30T21:06:03.105Z,1532984763.105 [BuoyancyServo] Communications Fault, FailCount= 1
2018-07-30T21:06:03.105Z,1532984763.105 [BuoyancyServo](ERROR): Communications Fault
2018-07-30T21:06:03.273Z,1532984763.273 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-07-30T21:06:03.419Z,1532984763.419 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-30T21:06:03.419Z,1532984763.419 [BuoyancyServo](INFO): Powering down
2018-07-30T21:06:04.964Z,1532984764.964 [BuoyancyServo](FAULT): LCB fault:  Current Limiter Activated.
2018-07-30T21:06:04.964Z,1532984764.964 [BuoyancyServo] Hardware Fault, FailCount= 1
2018-07-30T21:06:04.968Z,1532984764.968 [BuoyancyServo](ERROR): Hardware Fault
2018-07-30T21:06:05.501Z,1532984765.501 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-07-30T21:06:05.501Z,1532984765.501 [BuoyancyServo] No Fault, FailCount= 1
2018-07-30T21:06:05.763Z,1532984765.763 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-30T21:06:05.881Z,1532984765.881 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-30T21:06:10.261Z,1532984770.261 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2018-07-30T21:06:10.262Z,1532984770.262 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2018-07-30T21:06:10.262Z,1532984770.262 [BuoyancyServo] Communications Fault, FailCount= 2
2018-07-30T21:06:10.263Z,1532984770.263 [BuoyancyServo](ERROR): Communications Fault
2018-07-30T21:06:10.309Z,1532984770.309 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-07-30T21:06:10.568Z,1532984770.568 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-30T21:06:10.569Z,1532984770.569 [BuoyancyServo](INFO): Powering down
2018-07-30T21:06:11.711Z,1532984771.711 [NAL9602](INFO): NAL9602 initialized
2018-07-30T21:06:12.171Z,1532984772.171 [BuoyancyServo](FAULT): LCB fault:  Current Limiter Activated.
2018-07-30T21:06:12.171Z,1532984772.171 [BuoyancyServo] Hardware Fault, FailCount= 2
2018-07-30T21:06:12.171Z,1532984772.171 [BuoyancyServo](ERROR): Hardware Fault
2018-07-30T21:06:12.681Z,1532984772.681 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-07-30T21:06:12.681Z,1532984772.681 [BuoyancyServo] No Fault, FailCount= 2
2018-07-30T21:06:13.013Z,1532984773.013 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-30T21:06:13.138Z,1532984773.138 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-30T21:06:17.697Z,1532984777.697 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2018-07-30T21:06:17.697Z,1532984777.697 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2018-07-30T21:06:17.697Z,1532984777.697 [BuoyancyServo] Communications Fault, FailCount= 3
2018-07-30T21:06:17.697Z,1532984777.697 [BuoyancyServo](ERROR): Communications Fault
2018-07-30T21:06:17.822Z,1532984777.822 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-07-30T21:06:17.943Z,1532984777.943 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-07-30T21:06:17.943Z,1532984777.943 [BuoyancyServo](INFO): Powering down
2018-07-30T21:06:19.529Z,1532984779.529 [BuoyancyServo](FAULT): LCB fault:  Current Limiter Activated.
2018-07-30T21:06:19.530Z,1532984779.530 [BuoyancyServo] Hardware Fault, FailCount= 3
2018-07-30T21:06:19.530Z,1532984779.530 [BuoyancyServo](ERROR): Hardware Fault
2018-07-30T21:06:20.017Z,1532984780.017 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-07-30T21:06:20.017Z,1532984780.017 [BuoyancyServo] No Fault, FailCount= 3
2018-07-30T21:06:20.378Z,1532984780.378 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-07-30T21:06:20.497Z,1532984780.497 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-07-30T21:06:23.498Z,1532984783.498 [RDI_Pathfinder](ERROR): Failed to parse:
:H88,0e89,*14.570,*-0.110,*0.000
2018-07-30T21:06:23.506Z,1532984783.506 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-30T21:06:23.884Z,1532984783.884 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-30T21:06:25.842Z,1532984785.842 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-07-30T21:06:53.533Z,1532984813.533 [Radio_Surface](INFO): Powering down
2018-07-30T21:06:58.305Z,1532984818.305 [RDI_Pathfinder](ERROR): Failed to parse:
:-227.63,      +209.16,        +4.16,  41.67,  0.00
2018-07-30T21:06:58.328Z,1532984818.328 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-30T21:06:58.691Z,1532984818.691 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-30T21:06:59.086Z,1532984819.086 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-30T21:06:59.594Z,1532984819.594 [Radio_Surface](INFO): Powering up
2018-07-30T21:07:11.492Z,1532984831.492 [RDI_Pathfinder](ERROR): Failed to parse:
0e88,0e88,*14.550,*0.000
2018-07-30T21:07:11.513Z,1532984831.513 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-30T21:07:11.890Z,1532984831.890 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-30T21:07:15.920Z,1532984835.920 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-30T21:07:15.920Z,1532984835.920 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 8
2018-07-30T21:07:15.920Z,1532984835.920 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-07-30T21:07:16.067Z,1532984836.067 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-07-30T21:07:16.433Z,1532984836.433 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-07-30T21:07:16.433Z,1532984836.433 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 8
2018-07-30T21:07:16.698Z,1532984836.698 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-07-30T21:07:16.698Z,1532984836.698 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T21:07:16.699Z,1532984836.699 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T21:07:21.451Z,1532984841.451 [NAL9602](INFO): SBD MO Status=2, MOMSN=564, MT Status=2, MTMSN=0
2018-07-30T21:07:21.452Z,1532984841.452 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-30T21:07:26.245Z,1532984846.245 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-07-30T21:07:37.047Z,1532984857.047 [NAL9602](INFO): SBD MO Status=2, MOMSN=564, MT Status=2, MTMSN=0
2018-07-30T21:07:37.048Z,1532984857.048 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-30T21:07:52.302Z,1532984872.302 [RDI_Pathfinder](ERROR): only read 3 of 5 data items
2018-07-30T21:07:52.302Z,1532984872.302 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,  -498,  +446, +1162,  :BS,  -498,  +446, +1162,A
2018-07-30T21:07:52.319Z,1532984872.319 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-30T21:07:52.699Z,1532984872.699 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-30T21:07:53.083Z,1532984873.083 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-30T21:07:53.847Z,1532984873.847 [NAL9602](INFO): SBD MO Status=2, MOMSN=564, MT Status=2, MTMSN=0
2018-07-30T21:07:53.848Z,1532984873.848 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2018-07-30T21:08:01.037Z,1532984881.037 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002831
2018-07-30T21:08:17.035Z,1532984897.035 [NAL9602](INFO): SBD MO Status=0, MOMSN=564, MT Status=0, MTMSN=0
2018-07-30T21:08:17.036Z,1532984897.036 [NAL9602](INFO): No messages in MT queue
2018-07-30T21:08:44.630Z,1532984924.630 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-07-30T21:08:44.630Z,1532984924.630 [NAL9602] Data Fault, FailCount= 1
2018-07-30T21:08:44.630Z,1532984924.630 [NAL9602](ERROR): Data Fault
2018-07-30T21:08:44.746Z,1532984924.746 [CBIT](ERROR): Data Fault in component: NAL9602
2018-07-30T21:08:45.026Z,1532984925.026 [NAL9602](INFO): Powering down
2018-07-30T21:08:45.906Z,1532984925.906 [CBIT](INFO): Clearing failed state for component NAL9602
2018-07-30T21:08:45.906Z,1532984925.906 [NAL9602] No Fault, FailCount= 1
2018-07-30T21:09:15.233Z,1532984955.233 [NAL9602](INFO): Powering up NAL9602
2018-07-30T21:09:26.030Z,1532984966.030 [NAL9602](INFO): NAL9602 initialized
2018-07-30T21:09:27.300Z,1532984967.300 [RDI_Pathfinder](ERROR): only read 1 of 1 data item for altitude
2018-07-30T21:09:27.300Z,1532984967.300 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G0e88,*14.584,*-0.114,*0.000
2018-07-30T21:09:27.319Z,1532984967.319 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-30T21:09:27.690Z,1532984967.690 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-30T21:09:28.063Z,1532984968.063 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-30T21:10:01.838Z,1532985001.838 [DataOverHttps](IMPORTANT): SBD MTMSN=20180730T211000
2018-07-30T21:10:06.042Z,1532985006.042 [DataOverHttps](INFO): Received command:stop
2018-07-30T21:10:06.094Z,1532985006.094 [CommandLine](IMPORTANT): got command stop
2018-07-30T21:10:06.094Z,1532985006.094 [CommandLine](IMPORTANT): Scheduling is paused
2018-07-30T21:10:06.094Z,1532985006.094 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2018-07-30T21:10:06.117Z,1532985006.117 [MissionManager](INFO): MissionManager is completed.
2018-07-30T21:10:06.118Z,1532985006.118 [MissionManager](INFO): Uninitializing Mission sci2
2018-07-30T21:10:06.118Z,1532985006.118 [sci2] Stopped
2018-07-30T21:10:06.118Z,1532985006.118 [sci2](DEBUG): Aggregate::uninitialize sci2
2018-07-30T21:10:06.118Z,1532985006.118 [sci2:Science] Stopped
2018-07-30T21:10:06.118Z,1532985006.118 [sci2:Science](DEBUG): Aggregate::uninitialize sci2:Science
2018-07-30T21:10:06.118Z,1532985006.118 [sci2:Science:A] Stopped
2018-07-30T21:10:06.118Z,1532985006.118 [sci2:Science:G] Stopped
2018-07-30T21:10:06.118Z,1532985006.118 [sci2:Science:Read_Oil] Stopped
2018-07-30T21:10:06.118Z,1532985006.118 [sci2:Science:PeakDetectChl] Stopped
2018-07-30T21:10:06.118Z,1532985006.118 [sci2:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectChl
2018-07-30T21:10:06.119Z,1532985006.119 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2018-07-30T21:10:06.119Z,1532985006.119 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-07-30T21:10:06.119Z,1532985006.119 [sci2:NeedComms] Stopped
2018-07-30T21:10:06.119Z,1532985006.119 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms
2018-07-30T21:10:06.119Z,1532985006.119 [sci2:NeedComms:B.GoToSurface] Stopped
2018-07-30T21:10:06.119Z,1532985006.119 [sci2:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-07-30T21:10:06.119Z,1532985006.119 [sci2:NeedComms:C] Stopped
2018-07-30T21:10:06.119Z,1532985006.119 [sci2:StandardEnvelopes] Stopped
2018-07-30T21:10:06.119Z,1532985006.119 [sci2:StandardEnvelopes](DEBUG): Aggregate::uninitialize sci2:StandardEnvelopes
2018-07-30T21:10:06.119Z,1532985006.119 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2018-07-30T21:10:06.119Z,1532985006.119 [sci2:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2018-07-30T21:10:06.120Z,1532985006.120 [sci2:StandardEnvelopes:B.DepthEnvelope] Stopped
2018-07-30T21:10:06.120Z,1532985006.120 [sci2:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2018-07-30T21:10:06.120Z,1532985006.120 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2018-07-30T21:10:06.120Z,1532985006.120 [sci2:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2018-07-30T21:10:06.120Z,1532985006.120 [sci2:I.Pitch] Stopped
2018-07-30T21:10:06.120Z,1532985006.120 [sci2:J.Buoyancy] Stopped
2018-07-30T21:10:06.120Z,1532985006.120 [sci2:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2018-07-30T21:10:06.120Z,1532985006.120 [sci2:K.SetSpeed] Stopped
2018-07-30T21:10:06.120Z,1532985006.120 [sci2:K.SetSpeed](DEBUG): Uninitialize.
2018-07-30T21:10:06.128Z,1532985006.128 [sci2:L.DepthEnvelope] Stopped
2018-07-30T21:10:06.129Z,1532985006.129 [sci2:L.DepthEnvelope](DEBUG): Uninitialize.
2018-07-30T21:10:06.129Z,1532985006.129 [sci2:M.AltitudeEnvelope] Stopped
2018-07-30T21:10:06.129Z,1532985006.129 [sci2:M.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2018-07-30T21:10:06.129Z,1532985006.129 [sci2:N.YoYo] Stopped
2018-07-30T21:10:06.129Z,1532985006.129 [sci2:N.YoYo](DEBUG): Uninitialize YoYoComponent.
2018-07-30T21:10:06.129Z,1532985006.129 [sci2:Lap] Stopped
2018-07-30T21:10:06.129Z,1532985006.129 [sci2:Lap](DEBUG): Aggregate::uninitialize sci2:Lap
2018-07-30T21:10:06.129Z,1532985006.129 [sci2:Lap:Wpt1] Stopped
2018-07-30T21:10:06.129Z,1532985006.129 [sci2:Lap:Wpt1](DEBUG): Aggregate::uninitialize sci2:Lap:Wpt1
2018-07-30T21:10:06.129Z,1532985006.129 [sci2:Lap:Wpt1:A.Waypoint] Stopped
2018-07-30T21:10:06.130Z,1532985006.130 [sci2:Lap:Wpt1:A.Waypoint](DEBUG): Uninitialize WaypointComponent.
2018-07-30T21:10:06.494Z,1532985006.494 [MissionManager](IMPORTANT): Started mission Default
2018-07-30T21:10:06.494Z,1532985006.494 [Default] Running Loop=1
2018-07-30T21:10:06.494Z,1532985006.494 [Default](DEBUG): Aggregate::initialize Default
2018-07-30T21:10:06.494Z,1532985006.494 [Default:B.GoToSurface] Running Loop=1
2018-07-30T21:10:06.494Z,1532985006.494 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-07-30T21:10:06.494Z,1532985006.494 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-07-30T21:10:06.495Z,1532985006.495 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-07-30T21:10:06.495Z,1532985006.495 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-07-30T21:10:06.495Z,1532985006.495 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-07-30T21:10:06.496Z,1532985006.496 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-07-30T21:10:06.500Z,1532985006.500 [Default:A.Wait] Running Loop=1
2018-07-30T21:10:06.501Z,1532985006.501 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-07-30T21:10:06.921Z,1532985006.921 [Aanderaa_O2](INFO): Powering down
2018-07-30T21:10:16.871Z,1532985016.871 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-30T21:10:16.871Z,1532985016.871 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 9
2018-07-30T21:10:16.871Z,1532985016.871 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-07-30T21:10:16.894Z,1532985016.894 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-07-30T21:10:17.318Z,1532985017.318 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-07-30T21:10:17.318Z,1532985017.318 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 9
2018-07-30T21:10:17.671Z,1532985017.671 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-07-30T21:10:17.671Z,1532985017.671 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T21:10:17.672Z,1532985017.672 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T21:10:19.684Z,1532985019.684 [Default:A.Wait](INFO): Done Waiting.
2018-07-30T21:10:19.685Z,1532985019.685 [Default:A.Wait] Stopped
2018-07-30T21:10:19.685Z,1532985019.685 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-07-30T21:10:20.068Z,1532985020.068 [Default:CheckIn] Running Loop=1
2018-07-30T21:10:20.068Z,1532985020.068 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-30T21:10:20.068Z,1532985020.068 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-30T21:12:07.563Z,1532985127.563 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,  -112,  +451,  +163,    +5,A
2018-07-30T21:12:07.575Z,1532985127.575 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-30T21:12:07.979Z,1532985127.979 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-30T21:12:57.138Z,1532985177.138 [NAL9602](INFO): GPS fix at 20180730T211255: (36.805265, -121.826049)
2018-07-30T21:12:57.206Z,1532985177.206 [UniversalFixResidualReporter](INFO): Fix residual: 58.0 %DT, over the last 773.6 m. Residual distance 449.1 m at bearing 8.8 degrees. Fix at (36.8053, -121.8260) with 506.4 m made good.
2018-07-30T21:12:57.207Z,1532985177.207 [Default:CheckIn:Read_GPS] Stopped
2018-07-30T21:12:57.207Z,1532985177.207 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-30T21:13:03.306Z,1532985183.306 [DataOverHttps](INFO): Sending 194 bytes from file Logs/20180730T203657/Courier0019.lzma
2018-07-30T21:13:04.480Z,1532985184.480 [DataOverHttps](INFO): Moved sent file to Logs/20180730T203657/Courier0019.lzma.bak
2018-07-30T21:13:04.481Z,1532985184.481 [DataOverHttps](INFO): SBD MOMSN=8386744
2018-07-30T21:13:16.192Z,1532985196.192 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180730T203657/Courier0022.lzma
2018-07-30T21:13:16.961Z,1532985196.961 [DataOverHttps](INFO): Moved sent file to Logs/20180730T203657/Courier0022.lzma.bak
2018-07-30T21:13:16.961Z,1532985196.961 [DataOverHttps](INFO): SBD MOMSN=8386747
2018-07-30T21:13:27.022Z,1532985207.022 [DataOverHttps](INFO): Sending 577 bytes from file Logs/20180730T203657/Express0020.lzma
2018-07-30T21:13:27.793Z,1532985207.793 [DataOverHttps](INFO): Moved sent file to Logs/20180730T203657/Express0020.lzma.bak
2018-07-30T21:13:27.793Z,1532985207.793 [DataOverHttps](INFO): SBD MOMSN=8386749
2018-07-30T21:13:32.418Z,1532985212.418 [NAL9602](INFO): Powering down
2018-07-30T21:13:37.990Z,1532985217.990 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180730T203657/Express0023.lzma
2018-07-30T21:13:39.051Z,1532985219.051 [DataOverHttps](INFO): Moved sent file to Logs/20180730T203657/Express0023.lzma.bak
2018-07-30T21:13:39.051Z,1532985219.051 [DataOverHttps](INFO): SBD MOMSN=8386765
2018-07-30T21:13:39.345Z,1532985219.345 [Default:CheckIn:Read_Iridium] Stopped
2018-07-30T21:13:39.346Z,1532985219.346 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-30T21:13:39.346Z,1532985219.346 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-30T21:18:39.686Z,1532985519.686 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-30T21:18:39.686Z,1532985519.686 [Default:CheckIn:C.Wait] Stopped
2018-07-30T21:18:39.686Z,1532985519.686 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-30T21:18:39.687Z,1532985519.687 [Default:CheckIn:D] Running Loop=1
2018-07-30T21:18:40.037Z,1532985520.037 [Default:CheckIn:D] Stopped
2018-07-30T21:18:40.037Z,1532985520.037 [Default:CheckIn:E] Running Loop=1
2018-07-30T21:18:40.460Z,1532985520.460 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.559017 min 
2018-07-30T21:18:40.466Z,1532985520.466 [Default:CheckIn:E] Stopped
2018-07-30T21:18:40.466Z,1532985520.466 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-30T21:18:40.467Z,1532985520.467 [Default:CheckIn] Stopped
2018-07-30T21:18:40.467Z,1532985520.467 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-30T21:18:40.467Z,1532985520.467 [Default:CheckIn](INFO): Running loop #2
2018-07-30T21:18:40.467Z,1532985520.467 [Default:CheckIn] Running Loop=2
2018-07-30T21:18:40.467Z,1532985520.467 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-30T21:18:40.467Z,1532985520.467 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-30T21:18:43.794Z,1532985523.794 [NAL9602](INFO): Powering up
2018-07-30T21:18:54.950Z,1532985534.950 [NAL9602](INFO): NAL9602 initialized
2018-07-30T21:19:19.752Z,1532985559.752 [NAL9602](INFO): SBD MO Status=0, MOMSN=565, MT Status=0, MTMSN=0
2018-07-30T21:19:19.752Z,1532985559.752 [NAL9602](INFO): No messages in MT queue
2018-07-30T21:21:36.050Z,1532985696.050 [NAL9602](INFO): GPS fix at 20180730T212135: (36.804674, -121.825504)
2018-07-30T21:21:36.124Z,1532985696.124 [Default:CheckIn:Read_GPS] Stopped
2018-07-30T21:21:36.125Z,1532985696.125 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-30T21:21:41.866Z,1532985701.866 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20180730T203657/Courier0025.lzma
2018-07-30T21:21:44.657Z,1532985704.657 [DataOverHttps](INFO): Moved sent file to Logs/20180730T203657/Courier0025.lzma.bak
2018-07-30T21:21:44.657Z,1532985704.657 [DataOverHttps](INFO): SBD MOMSN=8386769
2018-07-30T21:22:06.940Z,1532985726.940 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20180730T203657/Express0026.lzma
2018-07-30T21:22:08.937Z,1532985728.937 [DataOverHttps](INFO): Moved sent file to Logs/20180730T203657/Express0026.lzma.bak
2018-07-30T21:22:08.937Z,1532985728.937 [DataOverHttps](INFO): SBD MOMSN=8386772
2018-07-30T21:22:09.472Z,1532985729.472 [Default:CheckIn:Read_Iridium] Stopped
2018-07-30T21:22:09.473Z,1532985729.473 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-30T21:22:09.473Z,1532985729.473 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-30T21:22:13.845Z,1532985733.845 [NAL9602](INFO): Powering down
2018-07-30T21:27:09.998Z,1532986029.998 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-30T21:27:09.998Z,1532986029.998 [Default:CheckIn:C.Wait] Stopped
2018-07-30T21:27:09.998Z,1532986029.998 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-30T21:27:09.998Z,1532986029.998 [Default:CheckIn:D] Running Loop=1
2018-07-30T21:27:10.416Z,1532986030.416 [Default:CheckIn:D] Stopped
2018-07-30T21:27:10.416Z,1532986030.416 [Default:CheckIn:E] Running Loop=1
2018-07-30T21:27:10.797Z,1532986030.797 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.065367 min 
2018-07-30T21:27:10.797Z,1532986030.797 [Default:CheckIn:E] Stopped
2018-07-30T21:27:10.797Z,1532986030.797 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-30T21:27:10.797Z,1532986030.797 [Default:CheckIn] Stopped
2018-07-30T21:27:10.797Z,1532986030.797 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-30T21:27:10.798Z,1532986030.798 [Default:CheckIn](INFO): Running loop #3
2018-07-30T21:27:10.798Z,1532986030.798 [Default:CheckIn] Running Loop=3
2018-07-30T21:27:10.798Z,1532986030.798 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-30T21:27:10.798Z,1532986030.798 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-30T21:27:14.348Z,1532986034.348 [NAL9602](INFO): Powering up
2018-07-30T21:27:25.272Z,1532986045.272 [NAL9602](INFO): NAL9602 initialized
2018-07-30T21:27:44.469Z,1532986064.469 [NAL9602](INFO): SBD MO Status=0, MOMSN=566, MT Status=0, MTMSN=0
2018-07-30T21:27:44.469Z,1532986064.469 [NAL9602](INFO): No messages in MT queue
2018-07-30T21:28:14.649Z,1532986094.649 [RDI_Pathfinder](ERROR): only read 0 of 5 data items
2018-07-30T21:28:14.650Z,1532986094.650 [RDI_Pathfinder](ERROR): Failed to parse:
:BI  +134,   -51,    +9,A
2018-07-30T21:28:14.683Z,1532986094.683 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-30T21:28:14.690Z,1532986094.690 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-30T21:28:14.690Z,1532986094.690 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2018-07-30T21:28:14.690Z,1532986094.690 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-07-30T21:28:14.704Z,1532986094.704 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-07-30T21:28:15.046Z,1532986095.046 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-30T21:28:15.076Z,1532986095.076 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-07-30T21:28:15.076Z,1532986095.076 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2018-07-30T21:28:15.476Z,1532986095.476 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-07-30T21:28:15.481Z,1532986095.481 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T21:28:15.481Z,1532986095.481 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T21:31:15.498Z,1532986275.498 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-30T21:31:15.499Z,1532986275.499 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2018-07-30T21:31:15.499Z,1532986275.499 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-07-30T21:31:15.513Z,1532986275.513 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-07-30T21:31:15.909Z,1532986275.909 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-07-30T21:31:15.909Z,1532986275.909 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2018-07-30T21:31:16.302Z,1532986276.302 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-07-30T21:31:16.302Z,1532986276.302 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T21:31:16.302Z,1532986276.302 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T21:32:11.087Z,1532986331.087 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-07-30T21:27:10.8Z
2018-07-30T21:32:11.087Z,1532986331.087 [Default:CheckIn:Read_GPS] Stopped
2018-07-30T21:32:11.087Z,1532986331.087 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-30T21:32:14.927Z,1532986334.927 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180730T203657/Courier0028.lzma
2018-07-30T21:32:15.705Z,1532986335.705 [DataOverHttps](INFO): Moved sent file to Logs/20180730T203657/Courier0028.lzma.bak
2018-07-30T21:32:15.705Z,1532986335.705 [DataOverHttps](INFO): SBD MOMSN=8386881
2018-07-30T21:32:24.730Z,1532986344.730 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20180730T203657/Express0029.lzma
2018-07-30T21:32:25.473Z,1532986345.473 [DataOverHttps](INFO): Moved sent file to Logs/20180730T203657/Express0029.lzma.bak
2018-07-30T21:32:25.473Z,1532986345.473 [DataOverHttps](INFO): SBD MOMSN=8386883
2018-07-30T21:32:26.001Z,1532986346.001 [Default:CheckIn:Read_Iridium] Stopped
2018-07-30T21:32:26.001Z,1532986346.001 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-30T21:32:26.001Z,1532986346.001 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-30T21:32:41.697Z,1532986361.697 [NAL9602](INFO): Powering down
2018-07-30T21:33:03.332Z,1532986383.332 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-30T21:33:03.725Z,1532986383.725 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-30T21:33:04.125Z,1532986384.125 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-30T21:34:16.646Z,1532986456.646 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-30T21:34:16.646Z,1532986456.646 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2018-07-30T21:34:16.646Z,1532986456.646 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-07-30T21:34:16.675Z,1532986456.675 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-07-30T21:34:17.065Z,1532986457.065 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-07-30T21:34:17.065Z,1532986457.065 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2018-07-30T21:34:17.447Z,1532986457.447 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-07-30T21:34:17.447Z,1532986457.447 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T21:34:17.448Z,1532986457.448 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T21:35:05.529Z,1532986505.529 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-30T21:35:05.890Z,1532986505.890 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-30T21:35:06.300Z,1532986506.300 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-30T21:37:17.650Z,1532986637.650 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-30T21:37:17.650Z,1532986637.650 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2018-07-30T21:37:17.650Z,1532986637.650 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-07-30T21:37:17.677Z,1532986637.677 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-07-30T21:37:18.039Z,1532986638.039 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-07-30T21:37:18.039Z,1532986638.039 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2018-07-30T21:37:18.440Z,1532986638.440 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-07-30T21:37:18.445Z,1532986638.445 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T21:37:18.445Z,1532986638.445 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T21:37:26.432Z,1532986646.432 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-30T21:37:26.432Z,1532986646.432 [Default:CheckIn:C.Wait] Stopped
2018-07-30T21:37:26.432Z,1532986646.432 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-30T21:37:26.433Z,1532986646.433 [Default:CheckIn:D] Running Loop=1
2018-07-30T21:37:26.822Z,1532986646.822 [Default:CheckIn:D] Stopped
2018-07-30T21:37:26.822Z,1532986646.822 [Default:CheckIn:E] Running Loop=1
2018-07-30T21:37:27.239Z,1532986647.239 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.338796 min 
2018-07-30T21:37:27.240Z,1532986647.240 [Default:CheckIn:E] Stopped
2018-07-30T21:37:27.241Z,1532986647.241 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-30T21:37:27.241Z,1532986647.241 [Default:CheckIn] Stopped
2018-07-30T21:37:27.241Z,1532986647.241 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-30T21:37:27.242Z,1532986647.242 [Default:CheckIn](INFO): Running loop #4
2018-07-30T21:37:27.242Z,1532986647.242 [Default:CheckIn] Running Loop=4
2018-07-30T21:37:27.242Z,1532986647.242 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-30T21:37:27.242Z,1532986647.242 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-30T21:37:30.535Z,1532986650.535 [NAL9602](INFO): Powering up
2018-07-30T21:37:41.726Z,1532986661.726 [NAL9602](INFO): NAL9602 initialized
2018-07-30T21:37:42.526Z,1532986662.526 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-07-30T21:37:42.526Z,1532986662.526 [NAL9602] Data Fault, FailCount= 1
2018-07-30T21:37:42.526Z,1532986662.526 [NAL9602](ERROR): Data Fault
2018-07-30T21:37:42.642Z,1532986662.642 [CBIT](ERROR): Data Fault in component: NAL9602
2018-07-30T21:37:42.930Z,1532986662.930 [NAL9602](INFO): Powering down
2018-07-30T21:37:43.797Z,1532986663.797 [CBIT](INFO): Clearing failed state for component NAL9602
2018-07-30T21:37:43.797Z,1532986663.797 [NAL9602] No Fault, FailCount= 1
2018-07-30T21:38:13.330Z,1532986693.330 [NAL9602](INFO): Powering up NAL9602
2018-07-30T21:38:24.526Z,1532986704.526 [NAL9602](INFO): NAL9602 initialized
2018-07-30T21:38:52.656Z,1532986732.656 [NAL9602](INFO): SBD MO Status=0, MOMSN=567, MT Status=0, MTMSN=0
2018-07-30T21:38:52.661Z,1532986732.661 [NAL9602](INFO): No messages in MT queue
2018-07-30T21:40:18.513Z,1532986818.513 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-30T21:40:18.513Z,1532986818.513 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5
2018-07-30T21:40:18.513Z,1532986818.513 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-07-30T21:40:18.527Z,1532986818.527 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-07-30T21:40:18.925Z,1532986818.925 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-07-30T21:40:18.925Z,1532986818.925 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5
2018-07-30T21:40:19.330Z,1532986819.330 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-07-30T21:40:19.330Z,1532986819.330 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T21:40:19.330Z,1532986819.330 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T21:42:27.311Z,1532986947.311 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-07-30T21:37:27.2Z
2018-07-30T21:42:27.311Z,1532986947.311 [Default:CheckIn:Read_GPS] Stopped
2018-07-30T21:42:27.311Z,1532986947.311 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-30T21:42:31.516Z,1532986951.516 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180730T203657/Courier0031.lzma
2018-07-30T21:42:32.305Z,1532986952.305 [DataOverHttps](INFO): Moved sent file to Logs/20180730T203657/Courier0031.lzma.bak
2018-07-30T21:42:32.305Z,1532986952.305 [DataOverHttps](INFO): SBD MOMSN=8386896
2018-07-30T21:42:41.400Z,1532986961.400 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20180730T203657/Express0032.lzma
2018-07-30T21:42:42.181Z,1532986962.181 [DataOverHttps](INFO): Moved sent file to Logs/20180730T203657/Express0032.lzma.bak
2018-07-30T21:42:42.181Z,1532986962.181 [DataOverHttps](INFO): SBD MOMSN=8386898
2018-07-30T21:42:42.698Z,1532986962.698 [Default:CheckIn:Read_Iridium] Stopped
2018-07-30T21:42:42.698Z,1532986962.698 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-30T21:42:42.698Z,1532986962.698 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-30T21:43:01.594Z,1532986981.594 [NAL9602](INFO): Powering down
2018-07-30T21:43:19.647Z,1532986999.647 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-30T21:43:19.647Z,1532986999.647 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 6
2018-07-30T21:43:19.647Z,1532986999.647 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-07-30T21:43:19.685Z,1532986999.685 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-07-30T21:43:20.054Z,1532987000.054 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-07-30T21:43:20.054Z,1532987000.054 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 6
2018-07-30T21:43:20.443Z,1532987000.443 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-07-30T21:43:20.443Z,1532987000.443 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T21:43:20.443Z,1532987000.443 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T21:46:20.818Z,1532987180.818 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-30T21:46:20.818Z,1532987180.818 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 7
2018-07-30T21:46:20.818Z,1532987180.818 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-07-30T21:46:20.845Z,1532987180.845 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-07-30T21:46:21.250Z,1532987181.250 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-07-30T21:46:21.250Z,1532987181.250 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 7
2018-07-30T21:46:21.623Z,1532987181.623 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-07-30T21:46:21.623Z,1532987181.623 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T21:46:21.624Z,1532987181.624 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T21:46:57.499Z,1532987217.499 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-30T21:46:57.866Z,1532987217.866 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-30T21:46:58.318Z,1532987218.318 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-30T21:47:01.497Z,1532987221.497 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2018-07-30T21:47:01.891Z,1532987221.891 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2018-07-30T21:47:02.301Z,1532987222.301 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-07-30T21:47:02.688Z,1532987222.688 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-07-30T21:47:03.078Z,1532987223.078 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-07-30T21:47:03.506Z,1532987223.506 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-07-30T21:47:03.887Z,1532987223.887 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-07-30T21:47:04.309Z,1532987224.309 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-07-30T21:47:04.707Z,1532987224.707 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-07-30T21:47:05.066Z,1532987225.066 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-07-30T21:47:05.507Z,1532987225.507 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-07-30T21:47:05.882Z,1532987225.882 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-07-30T21:47:06.278Z,1532987226.278 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-07-30T21:47:06.696Z,1532987226.696 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-07-30T21:47:07.087Z,1532987227.087 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-07-30T21:47:07.505Z,1532987227.505 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-07-30T21:47:07.883Z,1532987227.883 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-07-30T21:47:08.291Z,1532987228.291 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-07-30T21:47:08.695Z,1532987228.695 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2018-07-30T21:47:09.109Z,1532987229.109 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2018-07-30T21:47:09.491Z,1532987229.491 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2018-07-30T21:47:09.870Z,1532987229.870 [Radio_Surface](INFO): Powering down
2018-07-30T21:47:09.874Z,1532987229.874 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -22.00 mm (3 active estimators).
2018-07-30T21:47:09.876Z,1532987229.876 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2018-07-30T21:47:10.296Z,1532987230.296 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2018-07-30T21:47:10.692Z,1532987230.692 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2018-07-30T21:47:11.130Z,1532987231.130 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-07-30T21:47:11.699Z,1532987231.699 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.6 s old, using for 20.0 s.
2018-07-30T21:47:12.110Z,1532987232.110 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.0 s old, using for 20.0 s.
2018-07-30T21:47:12.488Z,1532987232.488 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.4 s old, using for 20.0 s.
2018-07-30T21:47:12.883Z,1532987232.883 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.8 s old, using for 20.0 s.
2018-07-30T21:47:13.296Z,1532987233.296 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.2 s old, using for 20.0 s.
2018-07-30T21:47:13.698Z,1532987233.698 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.6 s old, using for 20.0 s.
2018-07-30T21:47:14.087Z,1532987234.087 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.0 s old, using for 20.0 s.
2018-07-30T21:47:14.511Z,1532987234.511 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.4 s old, using for 20.0 s.
2018-07-30T21:47:14.870Z,1532987234.870 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.8 s old, using for 20.0 s.
2018-07-30T21:47:15.316Z,1532987235.316 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.2 s old, using for 20.0 s.
2018-07-30T21:47:15.681Z,1532987235.681 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.6 s old, using for 20.0 s.
2018-07-30T21:47:15.929Z,1532987235.929 [Radio_Surface](INFO): Powering up
2018-07-30T21:47:16.080Z,1532987236.080 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.0 s old, using for 20.0 s.
2018-07-30T21:47:16.500Z,1532987236.500 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.4 s old, using for 20.0 s.
2018-07-30T21:47:16.885Z,1532987236.885 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.8 s old, using for 20.0 s.
2018-07-30T21:47:17.295Z,1532987237.295 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.2 s old, using for 20.0 s.
2018-07-30T21:47:17.695Z,1532987237.695 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.6 s old, using for 20.0 s.
2018-07-30T21:47:18.106Z,1532987238.106 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.0 s old, using for 20.0 s.
2018-07-30T21:47:18.511Z,1532987238.511 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.4 s old, using for 20.0 s.
2018-07-30T21:47:18.883Z,1532987238.883 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.8 s old, using for 20.0 s.
2018-07-30T21:47:19.306Z,1532987239.306 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.2 s old, using for 20.0 s.
2018-07-30T21:47:19.698Z,1532987239.698 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.6 s old, using for 20.0 s.
2018-07-30T21:47:20.109Z,1532987240.109 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.0 s old, using for 20.0 s.
2018-07-30T21:47:20.497Z,1532987240.497 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.4 s old, using for 20.0 s.
2018-07-30T21:47:20.882Z,1532987240.882 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.8 s old, using for 20.0 s.
2018-07-30T21:47:43.317Z,1532987263.317 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-07-30T21:47:43.317Z,1532987263.317 [Default:CheckIn:C.Wait] Stopped
2018-07-30T21:47:43.317Z,1532987263.317 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-07-30T21:47:43.317Z,1532987263.317 [Default:CheckIn:D] Running Loop=1
2018-07-30T21:47:43.717Z,1532987263.717 [Default:CheckIn:D] Stopped
2018-07-30T21:47:43.717Z,1532987263.717 [Default:CheckIn:E] Running Loop=1
2018-07-30T21:47:44.099Z,1532987264.099 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.620386 min 
2018-07-30T21:47:44.099Z,1532987264.099 [Default:CheckIn:E] Stopped
2018-07-30T21:47:44.099Z,1532987264.099 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-07-30T21:47:44.099Z,1532987264.099 [Default:CheckIn] Stopped
2018-07-30T21:47:44.099Z,1532987264.099 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-07-30T21:47:44.100Z,1532987264.100 [Default:CheckIn](INFO): Running loop #5
2018-07-30T21:47:44.100Z,1532987264.100 [Default:CheckIn] Running Loop=5
2018-07-30T21:47:44.100Z,1532987264.100 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-07-30T21:47:44.100Z,1532987264.100 [Default:CheckIn:Read_GPS] Running Loop=1
2018-07-30T21:47:44.846Z,1532987264.846 [NAL9602](INFO): Powering up
2018-07-30T21:47:55.661Z,1532987275.661 [NAL9602](INFO): NAL9602 initialized
2018-07-30T21:48:13.243Z,1532987293.243 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-07-30T21:48:14.055Z,1532987294.055 [NAL9602](INFO): SBD MO Status=0, MOMSN=568, MT Status=0, MTMSN=0
2018-07-30T21:48:14.056Z,1532987294.056 [NAL9602](INFO): No messages in MT queue
2018-07-30T21:48:26.462Z,1532987306.462 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-07-30T21:48:26.462Z,1532987306.462 [NAL9602] Data Fault, FailCount= 1
2018-07-30T21:48:26.462Z,1532987306.462 [NAL9602](ERROR): Data Fault
2018-07-30T21:48:26.551Z,1532987306.551 [CBIT](ERROR): Data Fault in component: NAL9602
2018-07-30T21:48:26.846Z,1532987306.846 [NAL9602](INFO): Powering down
2018-07-30T21:48:27.736Z,1532987307.736 [CBIT](INFO): Clearing failed state for component NAL9602
2018-07-30T21:48:27.736Z,1532987307.736 [NAL9602] No Fault, FailCount= 1
2018-07-30T21:48:57.250Z,1532987337.250 [NAL9602](INFO): Powering up NAL9602
2018-07-30T21:49:08.450Z,1532987348.450 [NAL9602](INFO): NAL9602 initialized
2018-07-30T21:49:14.044Z,1532987354.044 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-07-30T21:49:21.696Z,1532987361.696 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-07-30T21:49:21.696Z,1532987361.696 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 8
2018-07-30T21:49:21.697Z,1532987361.697 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2018-07-30T21:49:21.723Z,1532987361.723 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2018-07-30T21:49:22.165Z,1532987362.165 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2018-07-30T21:49:22.165Z,1532987362.165 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 8
2018-07-30T21:49:22.495Z,1532987362.495 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2018-07-30T21:49:22.495Z,1532987362.495 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2018-07-30T21:49:22.496Z,1532987362.496 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2018-07-30T21:49:33.253Z,1532987373.253 [NAL9602](INFO): GPS fix at 20180730T214931: (36.802677, -121.822903)
2018-07-30T21:49:33.327Z,1532987373.327 [UniversalFixResidualReporter](INFO): Fix residual: 88.1 %DT, over the last 540.9 m. Residual distance 476.7 m at bearing -41.8 degrees. Fix at (36.8027, -121.8229) with 320.8 m made good.
2018-07-30T21:49:33.327Z,1532987373.327 [Default:CheckIn:Read_GPS] Stopped
2018-07-30T21:49:33.328Z,1532987373.328 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-07-30T21:49:48.044Z,1532987388.044 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.203842
2018-07-30T21:50:16.170Z,1532987416.170 [NAL9602](INFO): SBD MO Status=1, MOMSN=569, MT Status=0, MTMSN=0
2018-07-30T21:50:16.221Z,1532987416.221 [NAL9602](INFO): Sent 73 bytes from file Logs/20180730T203657/Courier0034.lzma
2018-07-30T21:50:16.221Z,1532987416.221 [NAL9602](INFO): Packets left to send: 0
2018-07-30T21:50:16.223Z,1532987416.223 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180730T203657/Courier0034.lzma.parts/0000.sbd
2018-07-30T21:50:16.223Z,1532987416.223 [NAL9602](DEBUG): Completed sending Logs/20180730T203657/Courier0034.lzma
2018-07-30T21:50:19.127Z,1532987419.127 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-07-30T21:50:25.891Z,1532987425.891 [NAL9602](INFO): SBD MO Status=1, MOMSN=570, MT Status=0, MTMSN=0
2018-07-30T21:50:25.942Z,1532987425.942 [NAL9602](INFO): Sent 189 bytes from file Logs/20180730T203657/Express0035.lzma
2018-07-30T21:50:25.942Z,1532987425.942 [NAL9602](INFO): Packets left to send: 0
2018-07-30T21:50:25.943Z,1532987425.943 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180730T203657/Express0035.lzma.parts/0000.sbd
2018-07-30T21:50:25.944Z,1532987425.944 [NAL9602](DEBUG): Completed sending Logs/20180730T203657/Express0035.lzma
2018-07-30T21:50:33.115Z,1532987433.115 [NAL9602](INFO): SBD MO Status=0, MOMSN=571, MT Status=0, MTMSN=0
2018-07-30T21:50:33.258Z,1532987433.258 [Default:CheckIn:Read_Iridium] Stopped
2018-07-30T21:50:33.258Z,1532987433.258 [Default:CheckIn:C.Wait] Running Loop=1
2018-07-30T21:50:33.259Z,1532987433.259 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-07-30T21:50:53.180Z,1532987453.180 [DataOverHttps](IMPORTANT): SBD MTMSN=20180730T215052
2018-07-30T21:50:53.183Z,1532987453.183 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003129
2018-07-30T21:50:57.500Z,1532987457.500 [DataOverHttps](INFO): Received command:restart logs