2018-09-25T23:03:46.007Z,1537916626.007 [Supervisor](DEBUG): Initializing supervisor.
2018-09-25T23:03:46.010Z,1537916626.010 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-09-25T23:03:46.010Z,1537916626.010 [SyncHandler](INFO): Protected caller Thread ID is 2644
2018-09-25T23:03:46.011Z,1537916626.011 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-09-25T23:03:46.012Z,1537916626.012 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-09-25T23:03:46.012Z,1537916626.012 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2645
2018-09-25T23:03:46.015Z,1537916626.015 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-09-25T23:03:46.028Z,1537916626.028 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-09-25T23:03:46.029Z,1537916626.029 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-09-25T23:03:46.030Z,1537916626.030 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2646
2018-09-25T23:03:46.030Z,1537916626.030 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-09-25T23:03:46.031Z,1537916626.031 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-09-25T23:03:46.032Z,1537916626.032 [logger ThreadHandler](INFO): Protected caller Thread ID is 2647
2018-09-25T23:03:46.034Z,1537916626.034 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-09-25T23:03:46.034Z,1537916626.034 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-09-25T23:03:46.036Z,1537916626.036 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-09-25T23:03:46.232Z,1537916626.232 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-09-25T23:03:46.232Z,1537916626.232 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-09-25T23:03:46.378Z,1537916626.378 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-09-25T23:03:46.379Z,1537916626.379 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-09-25T23:03:46.747Z,1537916626.747 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-09-25T23:03:46.748Z,1537916626.748 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-09-25T23:03:46.890Z,1537916626.890 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-09-25T23:03:46.891Z,1537916626.891 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-09-25T23:03:46.971Z,1537916626.971 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-09-25T23:03:47.182Z,1537916627.182 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-09-25T23:03:47.182Z,1537916627.182 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-09-25T23:03:47.281Z,1537916627.281 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-09-25T23:03:47.282Z,1537916627.282 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-09-25T23:03:47.644Z,1537916627.644 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-09-25T23:03:47.645Z,1537916627.645 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-09-25T23:03:48.129Z,1537916628.129 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-09-25T23:03:48.130Z,1537916628.130 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-09-25T23:03:48.461Z,1537916628.461 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-09-25T23:03:48.461Z,1537916628.461 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-09-25T23:03:49.121Z,1537916629.121 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-09-25T23:03:49.122Z,1537916629.122 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-09-25T23:03:49.342Z,1537916629.342 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-09-25T23:03:49.342Z,1537916629.342 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-09-25T23:03:49.554Z,1537916629.554 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-09-25T23:03:49.555Z,1537916629.555 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-09-25T23:03:50.100Z,1537916630.100 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-09-25T23:03:50.101Z,1537916630.101 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-09-25T23:03:50.209Z,1537916630.209 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-09-25T23:03:50.211Z,1537916630.211 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2018-09-25T23:03:50.212Z,1537916630.212 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2018-09-25T23:03:50.445Z,1537916630.445 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-09-25T23:03:50.446Z,1537916630.446 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2018-09-25T23:03:50.547Z,1537916630.547 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2018-09-25T23:03:50.642Z,1537916630.642 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2018-09-25T23:03:50.747Z,1537916630.747 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2018-09-25T23:03:50.834Z,1537916630.834 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2018-09-25T23:03:50.973Z,1537916630.973 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2018-09-25T23:03:51.150Z,1537916631.150 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2018-09-25T23:03:51.263Z,1537916631.263 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2018-09-25T23:03:51.364Z,1537916631.364 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2018-09-25T23:03:51.448Z,1537916631.448 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2018-09-25T23:03:51.650Z,1537916631.650 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2018-09-25T23:03:51.650Z,1537916631.650 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-09-25T23:03:51.657Z,1537916631.657 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-09-25T23:03:51.787Z,1537916631.787 [SBIT](DEBUG): Construct Startup Built In Test.
2018-09-25T23:03:51.819Z,1537916631.819 [SBIT] Loaded
2018-09-25T23:03:51.819Z,1537916631.819 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-09-25T23:03:51.820Z,1537916631.820 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-09-25T23:03:51.848Z,1537916631.848 [IBIT] Loaded
2018-09-25T23:03:51.849Z,1537916631.849 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-09-25T23:03:51.852Z,1537916631.852 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-09-25T23:03:52.014Z,1537916632.014 [CBIT] Loaded
2018-09-25T23:03:52.014Z,1537916632.014 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-09-25T23:03:52.014Z,1537916632.014 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-09-25T23:03:52.015Z,1537916632.015 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-09-25T23:03:52.083Z,1537916632.083 [VerticalControl](DEBUG): Construct VerticalControl.
2018-09-25T23:03:52.188Z,1537916632.188 [VerticalControl] Loaded
2018-09-25T23:03:52.189Z,1537916632.189 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-09-25T23:03:52.190Z,1537916632.190 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-09-25T23:03:52.253Z,1537916632.253 [HorizontalControl] Loaded
2018-09-25T23:03:52.253Z,1537916632.253 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-09-25T23:03:52.254Z,1537916632.254 [SpeedControl](DEBUG): Construct SpeedControl.
2018-09-25T23:03:52.260Z,1537916632.260 [SpeedControl] Loaded
2018-09-25T23:03:52.260Z,1537916632.260 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-09-25T23:03:52.261Z,1537916632.261 [LoopControl](DEBUG): Construct LoopControl.
2018-09-25T23:03:52.261Z,1537916632.261 [LoopControl] Loaded
2018-09-25T23:03:52.262Z,1537916632.262 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-09-25T23:03:52.262Z,1537916632.262 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-09-25T23:03:52.263Z,1537916632.263 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-09-25T23:03:52.305Z,1537916632.305 [DepthRateCalculator] Loaded
2018-09-25T23:03:52.305Z,1537916632.305 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-09-25T23:03:52.311Z,1537916632.311 [PitchRateCalculator] Loaded
2018-09-25T23:03:52.311Z,1537916632.311 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-09-25T23:03:52.328Z,1537916632.328 [SpeedCalculator] Loaded
2018-09-25T23:03:52.328Z,1537916632.328 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-09-25T23:03:52.350Z,1537916632.350 [TempGradientCalculator] Loaded
2018-09-25T23:03:52.351Z,1537916632.351 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-09-25T23:03:52.356Z,1537916632.356 [YawRateCalculator] Loaded
2018-09-25T23:03:52.356Z,1537916632.356 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-09-25T23:03:52.387Z,1537916632.387 [ElevatorOffsetCalculator] Loaded
2018-09-25T23:03:52.388Z,1537916632.388 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-09-25T23:03:52.388Z,1537916632.388 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-09-25T23:03:52.389Z,1537916632.389 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-09-25T23:03:52.455Z,1537916632.455 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-09-25T23:03:52.455Z,1537916632.455 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-09-25T23:03:52.702Z,1537916632.702 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-09-25T23:03:52.703Z,1537916632.703 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-09-25T23:03:52.803Z,1537916632.803 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-09-25T23:03:52.803Z,1537916632.803 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-09-25T23:03:52.860Z,1537916632.860 [DeadReckonUsingSpeedCalculator] Loaded
2018-09-25T23:03:52.860Z,1537916632.860 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-09-25T23:03:52.877Z,1537916632.877 [NavChart] Loaded
2018-09-25T23:03:52.877Z,1537916632.877 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-09-25T23:03:52.886Z,1537916632.886 [UniversalFixResidualReporter] Loaded
2018-09-25T23:03:52.886Z,1537916632.886 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-09-25T23:03:52.887Z,1537916632.887 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-09-25T23:03:52.887Z,1537916632.887 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-09-25T23:03:52.901Z,1537916632.901 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-09-25T23:03:52.901Z,1537916632.901 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-09-25T23:03:53.072Z,1537916633.072 [Aanderaa_O2] Loaded
2018-09-25T23:03:53.073Z,1537916633.073 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-09-25T23:03:53.155Z,1537916633.155 [CTD_NeilBrown] Loaded
2018-09-25T23:03:53.155Z,1537916633.155 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-09-25T23:03:53.156Z,1537916633.156 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 406DB4E0
2018-09-25T23:03:53.156Z,1537916633.156 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 2726
2018-09-25T23:03:53.203Z,1537916633.203 [WetLabsSeaOWL_UV_A] Loaded
2018-09-25T23:03:53.204Z,1537916633.204 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2018-09-25T23:03:53.205Z,1537916633.205 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 4070B4E0
2018-09-25T23:03:53.205Z,1537916633.205 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 2727
2018-09-25T23:03:53.206Z,1537916633.206 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-09-25T23:03:53.206Z,1537916633.206 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-09-25T23:03:53.519Z,1537916633.519 [AHRS_M2] Loaded
2018-09-25T23:03:53.520Z,1537916633.520 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2018-09-25T23:03:53.590Z,1537916633.590 [DataOverHttps] Loaded
2018-09-25T23:03:53.590Z,1537916633.590 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-09-25T23:03:53.603Z,1537916633.603 [Depth_Keller] Loaded
2018-09-25T23:03:53.604Z,1537916633.604 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-09-25T23:03:53.609Z,1537916633.609 [DropWeight] Loaded
2018-09-25T23:03:53.609Z,1537916633.609 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-09-25T23:03:53.704Z,1537916633.704 [NAL9602] Loaded
2018-09-25T23:03:53.705Z,1537916633.705 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-09-25T23:03:53.710Z,1537916633.710 [Onboard] Loaded
2018-09-25T23:03:53.710Z,1537916633.710 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-09-25T23:03:53.717Z,1537916633.717 [Radio_Surface] Loaded
2018-09-25T23:03:53.718Z,1537916633.718 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-09-25T23:03:53.719Z,1537916633.719 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096C4E0
2018-09-25T23:03:53.719Z,1537916633.719 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2728
2018-09-25T23:03:53.749Z,1537916633.749 [RDI_Pathfinder] Loaded
2018-09-25T23:03:53.749Z,1537916633.749 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2018-09-25T23:03:53.756Z,1537916633.756 [SCPI] Loaded
2018-09-25T23:03:53.756Z,1537916633.756 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2018-09-25T23:03:55.937Z,1537916635.937 [BPC1] Loaded
2018-09-25T23:03:55.937Z,1537916635.937 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-09-25T23:03:55.937Z,1537916635.937 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-09-25T23:03:55.938Z,1537916635.938 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-09-25T23:03:56.039Z,1537916636.039 [BuoyancyServo] Loaded
2018-09-25T23:03:56.040Z,1537916636.040 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-09-25T23:03:56.052Z,1537916636.052 [ElevatorServo] Loaded
2018-09-25T23:03:56.052Z,1537916636.052 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-09-25T23:03:56.063Z,1537916636.063 [MassServo] Loaded
2018-09-25T23:03:56.064Z,1537916636.064 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-09-25T23:03:56.075Z,1537916636.075 [RudderServo] Loaded
2018-09-25T23:03:56.075Z,1537916636.075 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-09-25T23:03:56.087Z,1537916636.087 [ThrusterServo] Loaded
2018-09-25T23:03:56.087Z,1537916636.087 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-09-25T23:03:56.088Z,1537916636.088 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-09-25T23:03:56.088Z,1537916636.088 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-09-25T23:03:56.190Z,1537916636.190 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-09-25T23:03:56.191Z,1537916636.191 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-09-25T23:03:56.215Z,1537916636.215 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-09-25T23:03:56.219Z,1537916636.219 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-09-25T23:03:56.220Z,1537916636.220 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-09-25T23:03:56.227Z,1537916636.227 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-09-25T23:03:56.228Z,1537916636.228 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A154E0
2018-09-25T23:03:56.228Z,1537916636.228 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2729
2018-09-25T23:03:56.233Z,1537916636.233 [Supervisor](INFO): Main Thread ID is 2529
2018-09-25T23:03:56.233Z,1537916636.233 [Supervisor](DEBUG): Running supervisor.
2018-09-25T23:03:56.234Z,1537916636.234 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2730
2018-09-25T23:03:56.236Z,1537916636.236 [controlThread ThreadHandler](INFO): Handler Thread ID is 2731
2018-09-25T23:03:56.237Z,1537916636.237 [controlThread](DEBUG): Initializing ControlThread
2018-09-25T23:03:56.238Z,1537916636.238 [SBIT](INFO): Initialize SBIT Component.
2018-09-25T23:03:56.238Z,1537916636.238 [SBIT](IMPORTANT): git: 2018-09-20-6-g2020657
2018-09-25T23:03:56.238Z,1537916636.238 [SBIT](INFO): git hash: 202065703f1079d7bb18a36bece2379a1b2f12f2
2018-09-25T23:03:56.239Z,1537916636.239 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-09-25T23:03:56.239Z,1537916636.239 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-09-25T23:03:56.240Z,1537916636.240 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2018-09-25T23:03:56.240Z,1537916636.240 [IBIT](INFO): Initialize IBIT Component.
2018-09-25T23:03:56.241Z,1537916636.241 [CBIT](DEBUG): Initialize CBIT Component.
2018-09-25T23:03:56.242Z,1537916636.242 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-09-25T23:03:56.243Z,1537916636.243 [logger ThreadHandler](INFO): Handler Thread ID is 2732
2018-09-25T23:03:56.269Z,1537916636.269 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 2733
2018-09-25T23:03:56.270Z,1537916636.270 [CTD_NeilBrown](INFO): Powering down
2018-09-25T23:03:56.298Z,1537916636.298 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 2734
2018-09-25T23:03:56.298Z,1537916636.298 [WetLabsSeaOWL_UV_A](INFO): Powering down
2018-09-25T23:03:56.333Z,1537916636.333 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2735
2018-09-25T23:03:56.339Z,1537916636.339 [Radio_Surface](INFO): Powering up
2018-09-25T23:03:56.345Z,1537916636.345 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2736
2018-09-25T23:03:56.348Z,1537916636.348 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-09-25T23:03:56.348Z,1537916636.348 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-09-25T23:03:56.349Z,1537916636.349 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-09-25T23:03:56.351Z,1537916636.351 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-09-25T23:03:56.352Z,1537916636.352 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-09-25T23:03:56.352Z,1537916636.352 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-09-25T23:03:56.353Z,1537916636.353 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-09-25T23:03:56.353Z,1537916636.353 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-09-25T23:03:56.354Z,1537916636.354 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-09-25T23:03:56.354Z,1537916636.354 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-09-25T23:03:56.354Z,1537916636.354 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-09-25T23:03:56.355Z,1537916636.355 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-09-25T23:03:56.355Z,1537916636.355 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-09-25T23:03:56.356Z,1537916636.356 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-09-25T23:03:56.357Z,1537916636.357 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-09-25T23:03:56.357Z,1537916636.357 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-09-25T23:03:56.357Z,1537916636.357 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-09-25T23:03:56.357Z,1537916636.357 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-09-25T23:03:56.358Z,1537916636.358 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-09-25T23:03:56.358Z,1537916636.358 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-09-25T23:03:56.358Z,1537916636.358 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-09-25T23:03:56.358Z,1537916636.358 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-09-25T23:03:56.358Z,1537916636.358 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-09-25T23:03:56.359Z,1537916636.359 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-09-25T23:03:56.359Z,1537916636.359 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-09-25T23:03:56.359Z,1537916636.359 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-09-25T23:03:56.359Z,1537916636.359 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-09-25T23:03:56.359Z,1537916636.359 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-09-25T23:03:56.361Z,1537916636.361 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-09-25T23:03:56.361Z,1537916636.361 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-25T23:03:56.362Z,1537916636.362 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-25T23:03:56.362Z,1537916636.362 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-25T23:03:56.363Z,1537916636.363 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-09-25T23:03:56.363Z,1537916636.363 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-09-25T23:03:56.374Z,1537916636.374 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-09-25T23:03:56.406Z,1537916636.406 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-09-25T23:03:56.442Z,1537916636.442 [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-09-25T23:03:56.443Z,1537916636.443 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-09-25T23:03:56.535Z,1537916636.535 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-09-25T23:03:56.537Z,1537916636.537 [Default:A.Wait](DEBUG): Construct Wait.
2018-09-25T23:03:56.539Z,1537916636.539 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-09-25T23:03:56.585Z,1537916636.585 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-09-25T23:03:56.588Z,1537916636.588 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-09-25T23:03:56.610Z,1537916636.610 [Default:E.Execute](DEBUG): Construct Execute.
2018-09-25T23:03:56.614Z,1537916636.614 [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 on</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-09-25T23:03:56.625Z,1537916636.625 [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,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-09-25T23:03:56.693Z,1537916636.693 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-09-25T23:03:56.742Z,1537916636.742 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-09-25T23:03:56.758Z,1537916636.758 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2018-09-25T23:03:56.908Z,1537916636.908 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-09-25T23:03:57.009Z,1537916637.009 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-25T23:03:57.034Z,1537916637.034 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-25T23:03:57.173Z,1537916637.173 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-25T23:03:57.181Z,1537916637.181 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-25T23:03:57.195Z,1537916637.195 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-09-25T23:03:57.201Z,1537916637.201 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-09-25T23:03:57.223Z,1537916637.223 [MassServo](DEBUG): Initializing EZServoServo.
2018-09-25T23:03:57.229Z,1537916637.229 [MassServo](DEBUG): Initializing MassServo.
2018-09-25T23:03:57.243Z,1537916637.243 [RudderServo](DEBUG): Initializing EZServoServo.
2018-09-25T23:03:57.249Z,1537916637.249 [RudderServo](DEBUG): Initializing RudderServo.
2018-09-25T23:03:57.255Z,1537916637.255 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-09-25T23:03:57.261Z,1537916637.261 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-09-25T23:03:57.485Z,1537916637.485 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-09-25T23:03:57.488Z,1537916637.488 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-25T23:03:57.497Z,1537916637.497 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-25T23:03:57.792Z,1537916637.792 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-25T23:03:57.793Z,1537916637.793 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-25T23:03:58.117Z,1537916638.117 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-09-25T23:03:58.117Z,1537916638.117 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-09-25T23:03:58.117Z,1537916638.117 [BuoyancyServo] Communications Fault, FailCount= 1
2018-09-25T23:03:58.117Z,1537916638.117 [BuoyancyServo](ERROR): Communications Fault
2018-09-25T23:03:58.329Z,1537916638.329 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-09-25T23:03:58.377Z,1537916638.377 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-25T23:03:58.385Z,1537916638.385 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-25T23:03:58.399Z,1537916638.399 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-25T23:03:58.400Z,1537916638.400 [BuoyancyServo](INFO): Powering down
2018-09-25T23:03:58.768Z,1537916638.768 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-25T23:03:58.769Z,1537916638.769 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-25T23:03:59.172Z,1537916639.172 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-25T23:03:59.174Z,1537916639.174 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-25T23:03:59.568Z,1537916639.568 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-25T23:03:59.570Z,1537916639.570 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-25T23:04:00.057Z,1537916640.057 [Aanderaa_O2](INFO): Powering down
2018-09-25T23:04:00.176Z,1537916640.176 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-25T23:04:00.178Z,1537916640.178 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-25T23:04:00.401Z,1537916640.401 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout
2018-09-25T23:04:00.440Z,1537916640.440 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-25T23:04:00.442Z,1537916640.442 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-25T23:04:00.527Z,1537916640.527 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-09-25T23:04:00.527Z,1537916640.527 [BuoyancyServo] No Fault, FailCount= 1
2018-09-25T23:04:00.789Z,1537916640.789 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-25T23:04:00.910Z,1537916640.910 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-25T23:04:10.058Z,1537916650.058 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2018-09-25T23:04:16.868Z,1537916656.868 [SBIT](IMPORTANT): Beginning Startup BIT
2018-09-25T23:04:16.880Z,1537916656.880 [CBIT](IMPORTANT): Beginning ground fault scan
2018-09-25T23:04:23.723Z,1537916663.723 [NAL9602](INFO): Powering up NAL9602
2018-09-25T23:04:27.802Z,1537916667.802 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.010618
CHAN A1 (24V): 0.003172
CHAN A2 (12V): -0.007304
CHAN A3 (5V): -0.002089
CHAN B0 (3.3V): 0.000329
CHAN B1 (3.15aV): 0.000314
CHAN B2 (3.15bV): -0.000046
CHAN B3 (GND): 0.002018
OPEN: 0.005339
Full Scale Calc: 4.765 mA, -1.589 mA
2018-09-25T23:04:31.549Z,1537916671.549 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.799999 degree
2018-09-25T23:04:31.551Z,1537916671.551 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2018-09-25T23:04:31.552Z,1537916671.552 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.900002 degree
2018-09-25T23:04:31.553Z,1537916671.553 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2018-09-25T23:04:31.892Z,1537916671.892 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,SCPI,BPC1,Depth_Keller,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-09-25T23:04:34.707Z,1537916674.707 [NAL9602](INFO): NAL9602 initialized
2018-09-25T23:04:48.128Z,1537916688.128 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1537916672.000000 second
2018-09-25T23:04:53.426Z,1537916693.426 [CommandLine](IMPORTANT): got command get longitude
2018-09-25T23:04:53.426Z,1537916693.426 [CommandLine](IMPORTANT): longitude -121.900002 arcdeg
2018-09-25T23:04:59.843Z,1537916699.843 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-09-25T23:05:10.624Z,1537916710.624 [SBIT](IMPORTANT): SBIT PASSED
2018-09-25T23:05:10.676Z,1537916710.676 [CommandLine](IMPORTANT): got command configSet list
2018-09-25T23:05:10.676Z,1537916710.676 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-09-25T23:05:10.677Z,1537916710.677 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval=1 hour;

2018-09-25T23:05:10.677Z,1537916710.677 [CommandLine](IMPORTANT): CTD_NeilBrown.minSalinityBound=-10 practical_salinity_unit;

2018-09-25T23:05:10.678Z,1537916710.678 [CommandLine](IMPORTANT): IBIT.batteryVoltageThreshold=13.7 volt;

2018-09-25T23:05:10.678Z,1537916710.678 [CommandLine](IMPORTANT): RDI_PathfinderUp.loadAtStartup=0 bool;

2018-09-25T23:05:10.678Z,1537916710.678 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=205 cubic_centimeter;

2018-09-25T23:05:10.678Z,1537916710.678 [CommandLine](IMPORTANT): VerticalControl.massDefault=0 millimeter;

2018-09-25T23:05:11.037Z,1537916711.037 [MissionManager](IMPORTANT): Started mission Startup
2018-09-25T23:05:11.038Z,1537916711.038 [Startup] Running Loop=1
2018-09-25T23:05:11.038Z,1537916711.038 [Startup](DEBUG): Aggregate::initialize Startup
2018-09-25T23:05:11.038Z,1537916711.038 [Startup:A.GoToSurface] Running Loop=1
2018-09-25T23:05:11.038Z,1537916711.038 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-25T23:05:11.038Z,1537916711.038 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-09-25T23:05:11.039Z,1537916711.039 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-09-25T23:05:11.039Z,1537916711.039 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-09-25T23:05:11.040Z,1537916711.040 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-25T23:05:11.040Z,1537916711.040 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-25T23:05:11.047Z,1537916711.047 [Startup:StartupSatComms] Running Loop=1
2018-09-25T23:05:11.047Z,1537916711.047 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-09-25T23:05:11.047Z,1537916711.047 [Startup:StartupSatComms:A] Running Loop=1
2018-09-25T23:05:11.433Z,1537916711.433 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-09-25T23:05:11.435Z,1537916711.435 [Startup:StartupSatComms:A] Stopped
2018-09-25T23:05:11.435Z,1537916711.435 [Startup:StartupSatComms:B] Running Loop=1
2018-09-25T23:05:11.845Z,1537916711.845 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-09-25T23:05:28.606Z,1537916728.606 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1537916672.000000 second
2018-09-25T23:05:42.658Z,1537916742.658 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2018-09-25T23:06:11.853Z,1537916771.853 [Startup:StartupSatComms:B](INFO): Timed out from 2018-09-25T23:05:11.4Z
2018-09-25T23:06:11.854Z,1537916771.854 [Startup:StartupSatComms:B] Stopped
2018-09-25T23:06:11.854Z,1537916771.854 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-09-25T23:06:11.854Z,1537916771.854 [Startup:StartupSatComms] Stopped
2018-09-25T23:06:11.854Z,1537916771.854 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-09-25T23:06:11.855Z,1537916771.855 [Startup](INFO): Completed Startup
2018-09-25T23:06:11.855Z,1537916771.855 [MissionManager](INFO): Startup is completed.
2018-09-25T23:06:11.855Z,1537916771.855 [MissionManager](INFO): Uninitializing Mission Startup
2018-09-25T23:06:11.855Z,1537916771.855 [Startup] Stopped
2018-09-25T23:06:11.856Z,1537916771.856 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-09-25T23:06:11.856Z,1537916771.856 [Startup:A.GoToSurface] Stopped
2018-09-25T23:06:11.856Z,1537916771.856 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-25T23:06:12.217Z,1537916772.217 [MissionManager](IMPORTANT): Started mission Default
2018-09-25T23:06:12.217Z,1537916772.217 [Default] Running Loop=1
2018-09-25T23:06:12.217Z,1537916772.217 [Default](DEBUG): Aggregate::initialize Default
2018-09-25T23:06:12.218Z,1537916772.218 [Default:B.GoToSurface] Running Loop=1
2018-09-25T23:06:12.218Z,1537916772.218 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-25T23:06:12.218Z,1537916772.218 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-09-25T23:06:12.218Z,1537916772.218 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-09-25T23:06:12.219Z,1537916772.219 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-09-25T23:06:12.219Z,1537916772.219 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-25T23:06:12.219Z,1537916772.219 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-25T23:06:12.220Z,1537916772.220 [Default:A.Wait] Running Loop=1
2018-09-25T23:06:12.220Z,1537916772.220 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-09-25T23:06:25.080Z,1537916785.080 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004265
2018-09-25T23:06:25.550Z,1537916785.550 [Default:A.Wait](INFO): Done Waiting.
2018-09-25T23:06:25.550Z,1537916785.550 [Default:A.Wait] Stopped
2018-09-25T23:06:25.551Z,1537916785.551 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-09-25T23:06:25.989Z,1537916785.989 [Default:CheckIn] Running Loop=1
2018-09-25T23:06:25.989Z,1537916785.989 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-09-25T23:06:25.990Z,1537916785.990 [Default:CheckIn:Read_GPS] Running Loop=1
2018-09-25T23:06:26.371Z,1537916786.371 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-09-25T23:06:42.134Z,1537916802.134 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1537916800.000000 second
2018-09-25T23:06:42.342Z,1537916802.342 [Default:CheckIn:Read_GPS] Stopped
2018-09-25T23:06:42.342Z,1537916802.342 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-09-25T23:06:42.742Z,1537916802.742 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-09-25T23:06:46.867Z,1537916806.867 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20180925T223216/Courier0016.lzma
2018-09-25T23:06:47.635Z,1537916807.635 [DataOverHttps](INFO): Moved sent file to Logs/20180925T223216/Courier0016.lzma.bak
2018-09-25T23:06:47.635Z,1537916807.635 [DataOverHttps](INFO): SBD MOMSN=8589621
2018-09-25T23:06:57.613Z,1537916817.613 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2018-09-25T23:06:57.613Z,1537916817.613 [RDI_Pathfinder] Communications Fault, FailCount= 1
2018-09-25T23:06:57.613Z,1537916817.613 [RDI_Pathfinder](ERROR): Communications Fault
2018-09-25T23:06:57.673Z,1537916817.673 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-09-25T23:06:57.717Z,1537916817.717 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2018-09-25T23:06:57.873Z,1537916817.873 [RDI_Pathfinder](INFO): Powering down
2018-09-25T23:07:02.219Z,1537916822.219 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-09-25T23:07:02.219Z,1537916822.219 [RDI_Pathfinder] No Fault, FailCount= 1
2018-09-25T23:07:05.094Z,1537916825.094 [CommandLine](IMPORTANT): got command load ./Missions/Science/trackPatchFDOM.xml
2018-09-25T23:07:05.094Z,1537916825.094 [MissionManager](INFO): Loading Mission: ./Missions/Science/trackPatchFDOM.xml
2018-09-25T23:07:05.583Z,1537916825.583 [MissionManager](INFO): DefineArg trackPatchFDOM.MinAltitude = 5.000000 m
2018-09-25T23:07:05.586Z,1537916825.586 [MissionManager](INFO): DefineArg trackPatchFDOM.MaxDepth = 65.000000 m
2018-09-25T23:07:05.589Z,1537916825.589 [MissionManager](INFO): DefineArg trackPatchFDOM.MinWaterDepth = 10.000000 m
2018-09-25T23:07:05.592Z,1537916825.592 [MissionManager](INFO): DefineArg trackPatchFDOM.MinOffshore = 2000.000000 m
2018-09-25T23:07:05.607Z,1537916825.607 [MissionManager](INFO): DefineArg trackPatchFDOM.NeedCommsTime = 180.000000 min
2018-09-25T23:07:05.642Z,1537916825.642 [MissionManager](INFO): DefineArg trackPatchFDOM.MissionTimeout = 56.000000 h
2018-09-25T23:07:05.646Z,1537916825.646 [MissionManager](INFO): DefineArg trackPatchFDOM.MassDefault = 0.000000 cm
2018-09-25T23:07:05.678Z,1537916825.678 [MissionManager](INFO): DefineArg trackPatchFDOM.BuoyancyNeutral = 205.000004 cc
2018-09-25T23:07:05.681Z,1537916825.681 [MissionManager](INFO): DefineArg trackPatchFDOM.YoYoMinDepth = 5.000000 m
2018-09-25T23:07:05.684Z,1537916825.684 [MissionManager](INFO): DefineArg trackPatchFDOM.YoYoMaxDepth = 25.000000 m
2018-09-25T23:07:05.707Z,1537916825.707 [MissionManager](INFO): DefineArg trackPatchFDOM.YoYoUpPitch = 20.000000 arcdeg
2018-09-25T23:07:05.710Z,1537916825.710 [MissionManager](INFO): DefineArg trackPatchFDOM.YoYoDownPitch = -20.000000 arcdeg
2018-09-25T23:07:05.723Z,1537916825.723 [MissionManager](INFO): DefineArg trackPatchFDOM.Speed = 1.000000 m/s
2018-09-25T23:07:05.726Z,1537916825.726 [MissionManager](INFO): DefineArg trackPatchFDOM.WpMaxDistance = 1000.000000 m
2018-09-25T23:07:05.749Z,1537916825.749 [MissionManager](INFO): DefineArg trackPatchFDOM.WpTimeout = 8000.000000 s
2018-09-25T23:07:05.752Z,1537916825.752 [MissionManager](INFO): DefineArg trackPatchFDOM.WaterFrame = 1 bool
2018-09-25T23:07:05.761Z,1537916825.761 [MissionManager](INFO): DefineArg trackPatchFDOM.Repeat = 20.000000 count
2018-09-25T23:07:05.765Z,1537916825.765 [MissionManager](INFO): DefineArg trackPatchFDOM.Turn = 144.000000 arcdeg
2018-09-25T23:07:05.779Z,1537916825.779 [MissionManager](INFO): DefineArg trackPatchFDOM.Reverse = 180.000000 arcdeg
2018-09-25T23:07:05.789Z,1537916825.789 [MissionManager](INFO): DefineArg trackPatchFDOM.TwoPi = 360.000000 arcdeg
2018-09-25T23:07:05.793Z,1537916825.793 [MissionManager](INFO): DefineArg trackPatchFDOM.TimeWindowPeakRpt = 0.000000 min
2018-09-25T23:07:05.823Z,1537916825.823 [MissionManager](INFO): DefineArg trackPatchFDOM.LowPassWindowLen = 20.000000 count
2018-09-25T23:07:05.827Z,1537916825.827 [MissionManager](INFO): DefineArg trackPatchFDOM.PeakShallowBnd = 2.000000 m
2018-09-25T23:07:05.830Z,1537916825.830 [MissionManager](INFO): DefineArg trackPatchFDOM.PeakDeepBnd = 4.000000 m
2018-09-25T23:07:05.833Z,1537916825.833 [MissionManager](INFO): DefineArg trackPatchFDOM.FilterWidth = 4.000000 count
2018-09-25T23:07:05.836Z,1537916825.836 [MissionManager](INFO): DefineArg trackPatchFDOM.BeginThreshold = nan ug/l
2018-09-25T23:07:05.864Z,1537916825.864 [MissionManager](INFO): DefineArg trackPatchFDOM.OffPeakFraction = 80.000000 %
2018-09-25T23:07:05.889Z,1537916825.889 [MissionManager](INFO): DefineArg trackPatchFDOM.PatchMode = 0 bool
2018-09-25T23:07:05.892Z,1537916825.892 [MissionManager](INFO): DefineArg trackPatchFDOM.PatchTrackingHorizontal = 1 bool
2018-09-25T23:07:05.895Z,1537916825.895 [MissionManager](INFO): DefineArg trackPatchFDOM.WpBearing = -90.000000 arcdeg
2018-09-25T23:07:05.910Z,1537916825.910 [MissionManager](INFO): DefineOutput trackPatchFDOM.PatchDetectOn = 0 bool
2018-09-25T23:07:05.913Z,1537916825.913 [MissionManager](INFO): DefineOutput trackPatchFDOM.WpDone = 0 bool
2018-09-25T23:07:05.943Z,1537916825.943 [MissionManager](INFO): DefineOutput trackPatchFDOM.PatchFDOM = nan ug/l
2018-09-25T23:07:05.955Z,1537916825.955 [MissionManager](INFO): DefineOutput trackPatchFDOM.PatchFDOMDepth = nan m
2018-09-25T23:07:05.978Z,1537916825.978 [MissionManager](INFO): DefineOutput trackPatchFDOM.PatchFDOMLatitude = nan arcdeg
2018-09-25T23:07:05.991Z,1537916825.991 [MissionManager](INFO): DefineOutput trackPatchFDOM.PatchFDOMLongitude = nan arcdeg
2018-09-25T23:07:05.994Z,1537916825.994 [MissionManager](INFO): DefineOutput trackPatchFDOM.PatchFDOMDistance = 0.000000 m
2018-09-25T23:07:05.995Z,1537916825.995 [trackPatchFDOM:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2018-09-25T23:07:06.038Z,1537916826.038 [trackPatchFDOM:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2018-09-25T23:07:06.074Z,1537916826.074 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml
2018-09-25T23:07:06.148Z,1537916826.148 [MissionManager](INFO): DefineArg trackPatchFDOM:SurfaceCommsSplit.SurfaceDepthRate = nan m/s
2018-09-25T23:07:06.180Z,1537916826.180 [MissionManager](INFO): DefineArg trackPatchFDOM:SurfaceCommsSplit.SurfacePitch = 20.000000 arcdeg
2018-09-25T23:07:06.183Z,1537916826.183 [MissionManager](INFO): DefineArg trackPatchFDOM:SurfaceCommsSplit.SurfaceSpeed = 1.000000 m/s
2018-09-25T23:07:06.208Z,1537916826.208 [MissionManager](INFO): DefineArg trackPatchFDOM:SurfaceCommsSplit.IridiumTimeout = 30.000000 min
2018-09-25T23:07:06.211Z,1537916826.211 [MissionManager](INFO): DefineArg trackPatchFDOM:SurfaceCommsSplit.UnpackSplitAtSurface = 0 bool
2018-09-25T23:07:06.212Z,1537916826.212 [trackPatchFDOM:SurfaceCommsSplit:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-09-25T23:07:06.294Z,1537916826.294 [MissionManager](INFO): RedefineArg trackPatchFDOM:SurfaceCommsSplit.SurfacePitch = value:20.000000 arcdeg
2018-09-25T23:07:06.296Z,1537916826.296 [MissionManager](INFO): RedefineArg trackPatchFDOM:SurfaceCommsSplit.SurfaceSpeed = value:1.000000 m/s
2018-09-25T23:07:06.298Z,1537916826.298 [MissionManager](INFO): RedefineArg trackPatchFDOM:SurfaceCommsSplit.UnpackSplitAtSurface = value:1 bool
2018-09-25T23:07:06.299Z,1537916826.299 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml
2018-09-25T23:07:06.399Z,1537916826.399 [MissionManager](INFO): DefineArg trackPatchFDOM:SurfaceComms:SurfaceComms.SurfaceDepthRate = nan m/s
2018-09-25T23:07:06.428Z,1537916826.428 [MissionManager](INFO): DefineArg trackPatchFDOM:SurfaceComms:SurfaceComms.SurfacePitch = 20.000000 arcdeg
2018-09-25T23:07:06.435Z,1537916826.435 [MissionManager](INFO): DefineArg trackPatchFDOM:SurfaceComms:SurfaceComms.SurfaceSpeed = 1.000000 m/s
2018-09-25T23:07:06.444Z,1537916826.444 [MissionManager](INFO): DefineArg trackPatchFDOM:SurfaceComms:SurfaceComms.IridiumTimeout = 30.000000 min
2018-09-25T23:07:06.463Z,1537916826.463 [MissionManager](INFO): DefineArg trackPatchFDOM:SurfaceComms:SurfaceComms.UnpackSplitAtSurface = 0 bool
2018-09-25T23:07:06.464Z,1537916826.464 [trackPatchFDOM:SurfaceComms:SurfaceComms:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-09-25T23:07:06.530Z,1537916826.530 [MissionManager](INFO): RedefineArg trackPatchFDOM:SurfaceComms:SurfaceComms.SurfacePitch = value:20.000000 arcdeg
2018-09-25T23:07:06.532Z,1537916826.532 [MissionManager](INFO): RedefineArg trackPatchFDOM:SurfaceComms:SurfaceComms.SurfaceSpeed = value:1.000000 m/s
2018-09-25T23:07:06.559Z,1537916826.559 [trackPatchFDOM:F.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2018-09-25T23:07:06.571Z,1537916826.571 [trackPatchFDOM:G.WaterDepthEnvelope](DEBUG): Construct WaterDepthEnvelope.
2018-09-25T23:07:06.610Z,1537916826.610 [trackPatchFDOM:H.Pitch](DEBUG): Construct.
2018-09-25T23:07:06.616Z,1537916826.616 [trackPatchFDOM:I.Buoyancy](DEBUG): Construct Buoyancy.
2018-09-25T23:07:06.669Z,1537916826.669 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2018-09-25T23:07:07.224Z,1537916827.224 [MissionManager](INFO): DefineArg trackPatchFDOM:Science.SampleISUS = 0 bool
2018-09-25T23:07:07.227Z,1537916827.227 [MissionManager](INFO): DefineArg trackPatchFDOM:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2018-09-25T23:07:07.250Z,1537916827.250 [MissionManager](INFO): DefineArg trackPatchFDOM:Science.SampleISUSminPitch = -60.000000 arcdeg
2018-09-25T23:07:07.265Z,1537916827.265 [MissionManager](INFO): DefineArg trackPatchFDOM:Science.PeakDetectChlActive = 0 bool
2018-09-25T23:07:07.268Z,1537916827.268 [MissionManager](INFO): DefineArg trackPatchFDOM:Science.TimeWindowPeakReport = nan min
2018-09-25T23:07:07.270Z,1537916827.270 [MissionManager](INFO): DefineArg trackPatchFDOM:Science.HighestChlPeakReportActive = 0 bool
2018-09-25T23:07:07.285Z,1537916827.285 [MissionManager](INFO): DefineArg trackPatchFDOM:Science.PatchTracking = 0 bool
2018-09-25T23:07:07.288Z,1537916827.288 [MissionManager](INFO): DefineArg trackPatchFDOM:Science.FilterWidthHorizontal = 3.000000 count
2018-09-25T23:07:07.291Z,1537916827.291 [MissionManager](INFO): DefineArg trackPatchFDOM:Science.NumProfilesSlidingwindow = 100.000000 count
2018-09-25T23:07:07.314Z,1537916827.314 [MissionManager](INFO): DefineArg trackPatchFDOM:Science.OffPeakFractionHorizontal = 80.000000 %
2018-09-25T23:07:07.317Z,1537916827.317 [MissionManager](INFO): DefineArg trackPatchFDOM:Science.PeakDetectNO3Active = 0 bool
2018-09-25T23:07:07.319Z,1537916827.319 [MissionManager](INFO): DefineArg trackPatchFDOM:Science.PeakDetectOilActive = 0 bool
2018-09-25T23:07:07.338Z,1537916827.338 [MissionManager](INFO): DefineArg trackPatchFDOM:Science.PeakDetectFDOMActive = 0 bool
2018-09-25T23:07:07.341Z,1537916827.341 [MissionManager](INFO): DefineArg trackPatchFDOM:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2018-09-25T23:07:07.344Z,1537916827.344 [MissionManager](INFO): DefineArg trackPatchFDOM:Science.EnabledAanderaaO2 = 1.000000 bool
2018-09-25T23:07:07.372Z,1537916827.372 [MissionManager](INFO): DefineArg trackPatchFDOM:Science.EnabledNeilBrown = 1.000000 bool
2018-09-25T23:07:07.375Z,1537916827.375 [MissionManager](INFO): DefineArg trackPatchFDOM:Science.EnabledSeabird = 0.000000 bool
2018-09-25T23:07:07.395Z,1537916827.395 [MissionManager](INFO): DefineArg trackPatchFDOM:Science.EnabledTurbulence_NPS = 0.000000 bool
2018-09-25T23:07:07.399Z,1537916827.399 [MissionManager](INFO): DefineArg trackPatchFDOM:Science.EnabledWetLabsBB2FL = 0.000000 bool
2018-09-25T23:07:07.406Z,1537916827.406 [MissionManager](INFO): DefineArg trackPatchFDOM:Science.EnabledWetLabsSeaOWL_UV_A = 1.000000 bool
2018-09-25T23:07:07.410Z,1537916827.410 [MissionManager](INFO): DefineArg trackPatchFDOM:Science.EnabledISUS = 0.000000 bool
2018-09-25T23:07:07.435Z,1537916827.435 [MissionManager](INFO): DefineArg trackPatchFDOM:Science.LowPassWindowLength = 20.000000 count
2018-09-25T23:07:07.448Z,1537916827.448 [MissionManager](INFO): DefineArg trackPatchFDOM:Science.PeakChlShallowBound = nan m
2018-09-25T23:07:07.459Z,1537916827.459 [MissionManager](INFO): DefineArg trackPatchFDOM:Science.PeakChlDeepBound = nan m
2018-09-25T23:07:07.462Z,1537916827.462 [MissionManager](INFO): DefineArg trackPatchFDOM:Science.PeakShallowBound = nan m
2018-09-25T23:07:07.481Z,1537916827.481 [MissionManager](INFO): DefineArg trackPatchFDOM:Science.PeakDeepBound = nan m
2018-09-25T23:07:07.484Z,1537916827.484 [MissionManager](INFO): DefineOutput trackPatchFDOM:Science.PeakChl = 0.000000 ug/l
2018-09-25T23:07:07.487Z,1537916827.487 [MissionManager](INFO): DefineOutput trackPatchFDOM:Science.PeakChlDepth = 0.000000 m
2018-09-25T23:07:07.506Z,1537916827.506 [MissionManager](INFO): DefineOutput trackPatchFDOM:Science.PeakChlTemperature = 0.000000 degC
2018-09-25T23:07:07.509Z,1537916827.509 [MissionManager](INFO): DefineOutput trackPatchFDOM:Science.PeakChlLatitude = nan arcdeg
2018-09-25T23:07:07.512Z,1537916827.512 [MissionManager](INFO): DefineOutput trackPatchFDOM:Science.PeakChlLongitude = nan arcdeg
2018-09-25T23:07:07.547Z,1537916827.547 [MissionManager](INFO): DefineOutput trackPatchFDOM:Science.PeakNO3 = 0.000000 umol/l
2018-09-25T23:07:07.559Z,1537916827.559 [MissionManager](INFO): DefineOutput trackPatchFDOM:Science.PeakNO3Depth = 0.000000 m
2018-09-25T23:07:07.575Z,1537916827.575 [MissionManager](INFO): DefineOutput trackPatchFDOM:Science.PeakNO3Latitude = nan arcdeg
2018-09-25T23:07:07.586Z,1537916827.586 [MissionManager](INFO): DefineOutput trackPatchFDOM:Science.PeakNO3Longitude = nan arcdeg
2018-09-25T23:07:07.590Z,1537916827.590 [MissionManager](INFO): DefineOutput trackPatchFDOM:Science.PatchChl = 0.000000 m
2018-09-25T23:07:07.606Z,1537916827.606 [MissionManager](INFO): DefineOutput trackPatchFDOM:Science.PatchChlDepth = 0.000000 m
2018-09-25T23:07:07.618Z,1537916827.618 [MissionManager](INFO): DefineOutput trackPatchFDOM:Science.PatchChlLatitude = nan arcdeg
2018-09-25T23:07:07.626Z,1537916827.626 [MissionManager](INFO): DefineOutput trackPatchFDOM:Science.PatchChlLongitude = nan arcdeg
2018-09-25T23:07:07.641Z,1537916827.641 [MissionManager](INFO): DefineOutput trackPatchFDOM:Science.PatchChlDistance = nan m
2018-09-25T23:07:07.653Z,1537916827.653 [MissionManager](INFO): DefineOutput trackPatchFDOM:Science.PeakOil = 0.000000 kg/m3
2018-09-25T23:07:07.656Z,1537916827.656 [MissionManager](INFO): DefineOutput trackPatchFDOM:Science.PeakOilDepth = 0.000000 m
2018-09-25T23:07:07.671Z,1537916827.671 [MissionManager](INFO): DefineOutput trackPatchFDOM:Science.PeakOilLatitude = nan arcdeg
2018-09-25T23:07:07.683Z,1537916827.683 [MissionManager](INFO): DefineOutput trackPatchFDOM:Science.PeakOilLongitude = nan arcdeg
2018-09-25T23:07:07.691Z,1537916827.691 [MissionManager](INFO): DefineOutput trackPatchFDOM:Science.PeakFDOM = 0.000000 ppb
2018-09-25T23:07:07.714Z,1537916827.714 [MissionManager](INFO): DefineOutput trackPatchFDOM:Science.PeakFDOMDepth = 0.000000 m
2018-09-25T23:07:07.725Z,1537916827.725 [MissionManager](INFO): DefineOutput trackPatchFDOM:Science.PeakFDOMLatitude = nan arcdeg
2018-09-25T23:07:07.741Z,1537916827.741 [MissionManager](INFO): DefineOutput trackPatchFDOM:Science.PeakFDOMLongitude = nan arcdeg
2018-09-25T23:07:07.744Z,1537916827.744 [MissionManager](INFO): DefineOutput trackPatchFDOM:Science.PatchFDOMDistance = nan m
2018-09-25T23:07:07.806Z,1537916827.806 [trackPatchFDOM:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-09-25T23:07:07.868Z,1537916827.868 [trackPatchFDOM:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2018-09-25T23:07:07.917Z,1537916827.917 [trackPatchFDOM:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-09-25T23:07:07.953Z,1537916827.953 [trackPatchFDOM:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-09-25T23:07:07.971Z,1537916827.971 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2018-09-25T23:07:08.000Z,1537916828.000 [MissionManager](INFO): RedefineArg trackPatchFDOM:Science.PeakDetectFDOMActive = value:1 bool
2018-09-25T23:07:08.014Z,1537916828.014 [MissionManager](INFO): RedefineArg trackPatchFDOM:Science.TimeWindowPeakReport = reader:trackPatchFDOM.TimeWindowPeakRpt
2018-09-25T23:07:08.016Z,1537916828.016 [MissionManager](INFO): RedefineArg trackPatchFDOM:Science.PatchTracking = value:1 bool
2018-09-25T23:07:08.018Z,1537916828.018 [MissionManager](INFO): RedefineArg trackPatchFDOM:Science.LowPassWindowLength = reader:trackPatchFDOM.LowPassWindowLen
2018-09-25T23:07:08.020Z,1537916828.020 [MissionManager](INFO): RedefineArg trackPatchFDOM:Science.PeakShallowBound = reader:trackPatchFDOM.PeakShallowBnd
2018-09-25T23:07:08.049Z,1537916828.049 [MissionManager](INFO): RedefineArg trackPatchFDOM:Science.PeakDeepBound = reader:trackPatchFDOM.PeakDeepBnd
2018-09-25T23:07:08.051Z,1537916828.051 [MissionManager](INFO): RedefineArg trackPatchFDOM:Science.OffPeakFractionHorizontal = reader:trackPatchFDOM.OffPeakFraction
2018-09-25T23:07:08.052Z,1537916828.052 [trackPatchFDOM:K.SetSpeed](DEBUG): Construct.
2018-09-25T23:07:08.075Z,1537916828.075 [trackPatchFDOM:L.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2018-09-25T23:07:08.105Z,1537916828.105 [trackPatchFDOM:M.YoYo](DEBUG): Construct YoYo.
2018-09-25T23:07:08.139Z,1537916828.139 [trackPatchFDOM:PatchDetectHorizontal:PatchDetection:PeakDetectHorizontalTrigger.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2018-09-25T23:07:08.234Z,1537916828.234 [trackPatchFDOM:PatchDetectHorizontal:CrossLeg:CrossSurfaceWp.Waypoint](DEBUG): Construct Waypoint.
2018-09-25T23:07:08.281Z,1537916828.281 [trackPatchFDOM:PatchDetectHorizontal:CrossLeg:CrossPeakWp.Waypoint](DEBUG): Construct Waypoint.
2018-09-25T23:07:08.361Z,1537916828.361 [trackPatchFDOM:PatchDetectHorizontal:InLegWp.Waypoint](DEBUG): Construct Waypoint.
2018-09-25T23:07:08.423Z,1537916828.423 [trackPatchFDOM:PatchDetectHorizontal:OutLeg:OutLegWp.Waypoint](DEBUG): Construct Waypoint.
2018-09-25T23:07:08.618Z,1537916828.618 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Trigger="Tethys/Trigger" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://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/Trigger http://okeanids.mbari.org/tethys/Xml/Trigger.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="trackPatchFDOM">

    <Description>
        Vehicle runs yo-yos, with patch detect enabled for FDOM concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate
        concentration.
    </Description>

<!-- Vehicle safety parameters -->

    <DefineArg Name="MinAltitude"><Description>
        Minimum altitude 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>65</Value></DefineArg>

    <DefineArg Name="MinWaterDepth"><Description>
        Minimum water depth for the entire mission.
    </Description><Units:meter/><Value>10</Value></DefineArg>

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

    <DefineArg Name="NeedCommsTime"><Description>
        Maximum time between surfacings for communications
    </Description><Units:minute/><Value>180</Value></DefineArg>

    <DefineArg Name="MissionTimeout"><Description>
        Timeout for the entire mission
    </Description><Units:hour/><Value>56</Value></DefineArg>

    <DefineArg Name="MassDefault"><Description>
        Static setting for mass during the mission. Defaults to massDefault
        setting in the Config/Control.cfg file.
    </Description><Control:VerticalControl.massDefault/></DefineArg>

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

<!-- Basic guidance settings -->

    <DefineArg Name="YoYoMinDepth"><Description>
        Minimum depth during yo-yo's.
    </Description><Units:meter/><Value>5.0</Value></DefineArg>

    <DefineArg Name="YoYoMaxDepth"><Description>
        Maximum depth during yo-yo's.
    </Description><Units:meter/><Value>25.0</Value></DefineArg>

    <DefineArg Name="YoYoUpPitch"><Description>
        Pitch when yo-yo's are ascending.
    </Description><Units:degree/><Value>20.0</Value></DefineArg>

    <DefineArg Name="YoYoDownPitch"><Description>
        Pitch when yo-yo's are descending.
    </Description><Units:degree/><Value>-20.0</Value></DefineArg>

    <DefineArg Name="Speed"><Description>
        Vehicle speed.
    </Description><Units:meter_per_second/><Value>1</Value></DefineArg>

    <DefineArg Name="WpMaxDistance"><Description>
        Maximum length of each tracking leg
<!--debug
    </Description><Units:meter/><Value>7500</Value></DefineArg>
-->
    </Description><Units:meter/><Value>1000</Value></DefineArg>

    <DefineArg Name="WpTimeout"><Description>
        Maximum duration of each tracking leg
    </Description><Units:second/><Value>8000</Value></DefineArg>

    <DefineArg Name="WaterFrame"><Description>
        Set WaterFrame true to drive towards waypoints in the water frame of
        reference
    </Description><True/></DefineArg>

    <DefineArg Name="Repeat"><Description>
        Maximum number of tracking legs
    </Description><Units:count/><Value>20</Value></DefineArg>

<!-- Constant settings for each tracking leg -->

    <DefineArg Name="Turn"><Description>
        Amount to turn at each leg
    </Description><Units:degree/><Value>144</Value></DefineArg>

    <DefineArg Name="Reverse"><Description>
        Change in bearing which reverses vehicle direction
    </Description><Units:degree/><Value>180</Value></DefineArg>

    <DefineArg Name="TwoPi"><Description>
        Applied to stop run-up of angles
    </Description><Units:degree/><Value>360</Value></DefineArg>

    <DefineArg Name="TimeWindowPeakRpt"><Description>
        If greater than zero, report a peak every window. If NaN or zero, this
        variable is ignored.
    </Description><Units:minute/><Value>0</Value></DefineArg>

    <DefineArg Name="LowPassWindowLen"><Description>
        Low-pass window length (based on depth sensor sampling interval 0.4
        second) for low-pass filtering.
    </Description><Units:count/><Value>20</Value></DefineArg>

    <DefineArg Name="PeakShallowBnd"><Description>
        Shallow depth bound for detecting peak on each descent or ascent
        profile.
    </Description><Units:meter/><Value>2</Value></DefineArg>

    <DefineArg Name="PeakDeepBnd"><Description>
        Deep depth bound for detecting peak on each descent or ascent
        profile.
    </Description><Units:meter/><Value>4</Value></DefineArg>

    <DefineArg Name="FilterWidth"><Description>
        Width of boxcar filter used in horizontal patch detection
    </Description><Units:count/><Value>4</Value></DefineArg>

    <DefineArg Name="BeginThreshold"><Description>
        If non-NaN, sets threshold for starting horizontal patch detection
    </Description><Units:microgram_per_liter/><Value>NaN</Value></DefineArg>

    <DefineArg Name="OffPeakFraction"><Description>
        When filtered horizontal value is this fraction of the peak, consider it
        outside the patch.
    </Description><Units:percent/><Value>80</Value></DefineArg>

    <DefineArg Name="PatchMode"><Description>
        If true, use center of "in peak" range as the turn-around point. If
        false use maximum horizontal peak location as the turn-around point.
    </Description><False/></DefineArg>

    <DefineArg Name="PatchTrackingHorizontal"><Description>
        Set true to turn on horizontal patch tracking
    </Description><True/></DefineArg>    

<!-- Variable Settings for each tracking leg -->

    <DefineArg Name="WpBearing"><Description>
        Bearing being followed in the current leg. Set this to initialize start
        direction of patch tracking.
    </Description><Units:degree/><Value>-90</Value></DefineArg>

    <DefineOutput Name="PatchDetectOn"><Description>
        Mission sets this to true when the patch has been crossed.
    </Description><False/></DefineOutput>

    <DefineOutput Name="WpDone"><Description>
        Mission sets this to true when the cross-patch waypoint has been
        reached.
    </Description><False/></DefineOutput>

    <DefineOutput Name="PatchFDOM"><Units:microgram_per_liter/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PatchFDOMDepth"><Units:meter/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PatchFDOMLatitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PatchFDOMLongitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PatchFDOMDistance"><Units:meter/><Value>0</Value></DefineOutput>

<!-- The timeout -->

    <Timeout Duration="MissionTimeout"/>

<!-- And now the mission -->

    <Guidance:AltitudeEnvelope>
        <Parallel/>
        <Setting><Guidance:AltitudeEnvelope.minAltitude/><Arg Name="MinAltitude"/></Setting>
    </Guidance:AltitudeEnvelope>

    <Guidance:DepthEnvelope>
        <Parallel/>
        <Setting><Guidance:DepthEnvelope.maxDepth/><Arg Name="MaxDepth"/></Setting>
    </Guidance:DepthEnvelope>

    <Insert Filename="Insert/Surface.xml" Id="SurfaceCommsSplit">
        <RedefineArg Name="SurfacePitch"><Units:degree/><Value>20</Value></RedefineArg>
        <RedefineArg Name="SurfaceSpeed"><Units:meter_per_second/><Value>1</Value></RedefineArg>
        <RedefineArg Name="UnpackSplitAtSurface"><True/></RedefineArg>
    </Insert>

    <Aggregate Id="SurfaceComms">

        <When>
            <Called/>
        </When>

        <Insert Filename="Insert/Surface.xml">
            <RedefineArg Name="SurfacePitch"><Units:degree/><Value>20</Value></RedefineArg>
            <RedefineArg Name="SurfaceSpeed"><Units:meter_per_second/><Value>1</Value></RedefineArg>
        </Insert>

    </Aggregate>

<!-- GPS Update.  Don't go too long w/o a GPS fix. -->

    <Aggregate Id="NeedComms">

        <When>
            <Universal:platform_pitch_angle/>
            <Gt><Units:degree/><Value>0</Value></Gt>
            <And><Elapsed><Universal:time_fix/></Elapsed><Gt><Arg Name="NeedCommsTime"/></Gt></And>
            <Or><Elapsed><Universal:time_fix/></Elapsed><Gt><Arg Name="NeedCommsTime"/><Add><Units:minute/><Value>10</Value></Add></Gt></Or>
        </When>

        <Syslog Severity="Important">NeedComms Timeout Surfacing</Syslog>

        <Call RefId="SurfaceComms"/>

    </Aggregate>

    <Guidance:OffshoreEnvelope>
        <Parallel/>
        <Setting><Guidance:OffshoreEnvelope.minOffshore/><Arg Name="MinOffshore"/></Setting>
    </Guidance:OffshoreEnvelope>

    <Guidance:WaterDepthEnvelope>
        <Parallel/>
        <Setting><Guidance:WaterDepthEnvelope.minWaterDepth/><Arg Name="MinWaterDepth"/></Setting>
    </Guidance:WaterDepthEnvelope>

    <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>

<!-- If we're executing here or below, lets' get science data! -->

    <Insert Filename="Insert/Science.xml">
        <RedefineArg Name="PeakDetectFDOMActive"><True/></RedefineArg>
        <RedefineArg Name="TimeWindowPeakReport"><Arg Name="TimeWindowPeakRpt"/></RedefineArg>
        <RedefineArg Name="PatchTracking"><True/></RedefineArg>
        <RedefineArg Name="LowPassWindowLength"><Arg Name="LowPassWindowLen"/></RedefineArg>
        <RedefineArg Name="PeakShallowBound"><Arg Name="PeakShallowBnd"/></RedefineArg>
        <RedefineArg Name="PeakDeepBound"><Arg Name="PeakDeepBnd"/></RedefineArg>
        <RedefineArg Name="OffPeakFractionHorizontal"><Arg Name="OffPeakFraction"/></RedefineArg>
    </Insert>

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

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

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

    <Aggregate Id="PatchDetectHorizontal">

        <Sequence Repeat="Repeat"/>

        <Aggregate Id="PatchDetection">

            <When>
                <Arg Name="PatchDetectOn"/>
            </When>

            <Trigger:PeakDetectHorizontal Id="PeakDetectHorizontalTrigger">
                <Sequence/>
                
                <Setting><Trigger:PeakDetectHorizontal.patchTracking/><Arg Name="PatchTrackingHorizontal"/></Setting>
                <Setting><Trigger:PeakDetectHorizontal.detectFrom/><Arg Name="Science:PeakFDOM"/></Setting>
                <Setting><Trigger:PeakDetectHorizontal.depthFrom/><Arg Name="Science:PeakFDOMDepth"/></Setting>
                <Setting><Trigger:PeakDetectHorizontal.latitudeFrom/><Arg Name="Science:PeakFDOMLatitude"/></Setting>
                <Setting><Trigger:PeakDetectHorizontal.longitudeFrom/><Arg Name="Science:PeakFDOMLongitude"/></Setting>
                <Setting><Trigger:PeakDetectHorizontal.beginThreshold/><Arg Name="BeginThreshold"/></Setting>
                <Setting><Trigger:PeakDetectHorizontal.offPeakFraction/><Arg Name="OffPeakFraction"/></Setting>
                <Setting><Trigger:PeakDetectHorizontal.filterWidth/><Arg Name="FilterWidth"/></Setting>
                <Setting><Trigger:PeakDetectHorizontal.centerPeak/><Arg Name="PatchMode"/></Setting>
                <OutputArg Name="PatchFDOM"><Trigger:PeakDetectHorizontal.peakDetect/></OutputArg>
                <OutputArg Name="PatchFDOMDepth"><Trigger:PeakDetectHorizontal.peakDepth/></OutputArg>
                <OutputArg Name="PatchFDOMLatitude"><Trigger:PeakDetectHorizontal.peakLatitude/></OutputArg>
                <OutputArg Name="PatchFDOMLongitude"><Trigger:PeakDetectHorizontal.peakLongitude/></OutputArg>
                <OutputArg Name="PatchFDOMDistance"><Trigger:PeakDetectHorizontal.peakDistance/></OutputArg>
            </Trigger:PeakDetectHorizontal>

            <Syslog Severity="Important">Fell below OffPeakFraction. Patch value is <Arg Name="PatchFDOM"/><Units:part_per_billion/> at lat = <Arg Name="PatchFDOMLatitude"/><Units:degree/>, lon = <Arg Name="PatchFDOMLongitude"/><Units:degree/>, dep = <Arg Name="PatchFDOMDepth"/><Units:meter/>, distance = <Arg Name="PatchFDOMDistance"/><Units:meter/></Syslog>

            <SendData Service="Express"><Arg Name="PatchFDOM"/><Arg Name="PatchFDOMDepth"/><Arg Name="PatchFDOMLatitude"/><Arg Name="PatchFDOMLongitude"/>
            </SendData>

            <Assign Id="AssignPatchDetectOff"><Sequence/><Arg Name="PatchDetectOn"/><False/></Assign>

        </Aggregate>

        <Assign Id="AssignPatchDetectOn"><Sequence/><Arg Name="PatchDetectOn"/><True/></Assign>

        <Syslog Severity="Important">Next: bearing for CrossLeg: <Arg Name="WpBearing"/><Units:degree/>. WpMaxDistance = <Arg Name="WpMaxDistance"/><Units:meter/>. Current lat, lon = <Universal:latitude/><Units:degree/>, <Universal:longitude/><Units:degree/>.</Syslog>

        <Aggregate Id="CrossLeg">

            <Description>
                This is a long leg across the width of the patch. Also the first
                leg in a mission run. Two waypoints run in parallel: a short run
                to the surfacing point, and a longer run to the edge of the
                patch.
            </Description>

            <Sequence/>

            <Break><Arg Name="WpDone"/>
                <Or><Arg Name="PatchDetectOn"/><Eq><False/></Eq></Or>
            </Break>

            <Guidance:Waypoint Id="CrossSurfaceWp">
                <Sequence/>
                <Timeout Duration="WpTimeout"/>
                <Setting><Guidance:Waypoint.distanceDelta/><Arg Name="PatchFDOMDistance"/></Setting>
                <Setting><Guidance:Waypoint.distanceDeltaBearing/><Arg Name="WpBearing"/></Setting>
                <Setting><Guidance:Waypoint.waterFrame/><Arg Name="WaterFrame"/></Setting>
            </Guidance:Waypoint>

            <Call RefId="SurfaceComms"/>

            <Guidance:Waypoint Id="CrossPeakWp">
                <Sequence/>
                <Timeout Duration="WpTimeout"/>
                <Setting><Guidance:Waypoint.distanceDelta/><Arg Name="WpMaxDistance"/></Setting>
                <Setting><Guidance:Waypoint.distanceDeltaBearing/><Arg Name="WpBearing"/></Setting>
                <Setting><Guidance:Waypoint.waterFrame/><Arg Name="WaterFrame"/></Setting>
            </Guidance:Waypoint>

            <Syslog Severity="Important">CrossPeakWp done. Lat, lon = <Universal:latitude/><Units:degree/>, <Universal:longitude/><Units:degree/>. Patch value is <Arg Name="PatchFDOM"/><Units:part_per_billion/> at lat = <Arg Name="PatchFDOMLatitude"/><Units:degree/>, lon = <Arg Name="PatchFDOMLongitude"/><Units:degree/>, dep = <Arg Name="PatchFDOMDepth"/><Units:meter/>, distance = <Arg Name="PatchFDOMDistance"/><Units:meter/></Syslog>

            <Assign Id="AssignPatchDetectOffInCrossLeg"><Sequence/><Arg Name="PatchDetectOn"/><False/></Assign>

            <Assign Id="AssignWpDoneInCrossLeg"><Sequence/><Arg Name="WpDone"/><True/></Assign>

        </Aggregate>

        <Assign Id="AssignWpBearingForInLeg"><Sequence/><Arg Name="WpBearing"/><Arg Name="WpBearing"/><Add><Arg Name="Reverse"/></Add><Mod><Arg Name="TwoPi"/>
        </Mod></Assign>

        <Syslog Severity="Important">Next: bearing for InLeg: <Arg Name="WpBearing"/><Units:degree/>. Current lat, lon = <Universal:latitude/><Units:degree/>, <Universal:longitude/><Units:degree/>. Patch value is <Arg Name="PatchFDOM"/><Units:part_per_billion/> at lat = <Arg Name="PatchFDOMLatitude"/><Units:degree/>, lon = <Arg Name="PatchFDOMLongitude"/><Units:degree/>, dep = <Arg Name="PatchFDOMDepth"/><Units:meter/>, distance = <Arg Name="PatchFDOMDistance"/><Units:meter/></Syslog>

        <Guidance:Waypoint Id="InLegWp">
            <Description>
                Simply drive back towards the patch center
            </Description>
            <Sequence/>
            <Timeout Duration="WpTimeout"/>
            <Setting><Guidance:Waypoint.distanceDelta/><Arg Name="PatchFDOMDistance"/></Setting>
            <Setting><Guidance:Waypoint.distanceDeltaBearing/><Arg Name="WpBearing"/></Setting>
            <Setting><Guidance:Waypoint.waterFrame/><Arg Name="WaterFrame"/></Setting>
        </Guidance:Waypoint>

        <Assign Id="AssignWpBearingForOutLeg"><Sequence/><Arg Name="WpBearing"/><Arg Name="WpBearing"/><Sub><Arg Name="Turn"/></Sub><Mod><Arg Name="TwoPi"/>
        </Mod></Assign>

        <Syslog Severity="Important">Next: bearing for OutLeg: <Arg Name="WpBearing"/><Units:degree/>. WpMaxDistance = <Arg Name="WpMaxDistance"/><Units:meter/>. Current lat, lon = <Universal:latitude/><Units:degree/>, <Universal:longitude/><Units:degree/>. Patch value is <Arg Name="PatchFDOM"/><Units:part_per_billion/> at lat = <Arg Name="PatchFDOMLatitude"/><Units:degree/>, lon = <Arg Name="PatchFDOMLongitude"/><Units:degree/>, dep = <Arg Name="PatchFDOMDepth"/><Units:meter/>, distance = <Arg Name="PatchFDOMDistance"/><Units:meter/></Syslog>

        <Call RefId="SurfaceCommsSplit"/>

        <Assign Id="AssignPatchDetectOnForOutLeg"><Sequence/><Arg Name="PatchDetectOn"/><True/></Assign>

        <Aggregate Id="OutLeg">

            <Description>
                Drive outward until edge of patch is detected.
            </Description>

            <Sequence/>

            <Break><Arg Name="WpDone"/>
                <Or><Arg Name="PatchDetectOn"/><Eq><False/></Eq></Or>
            </Break>

            <Guidance:Waypoint Id="OutLegWp">
                <Sequence/>
                <Timeout Duration="WpTimeout"/>
                <Setting><Guidance:Waypoint.distanceDelta/><Arg Name="WpMaxDistance"/></Setting>
                <Setting><Guidance:Waypoint.distanceDeltaBearing/><Arg Name="WpBearing"/></Setting>
                <Setting><Guidance:Waypoint.waterFrame/><Arg Name="WaterFrame"/></Setting>
            </Guidance:Waypoint>

            <Assign Id="AssignPatchDetectOffInOutLeg"><Sequence/><Arg Name="PatchDetectOn"/><False/></Assign>

            <Assign Id="AssignWpDoneInOutLeg"><Sequence/><Arg Name="WpDone"/><True/></Assign>

        </Aggregate>

        <Assign Id="AssignWpBearingForCrossLeg"><Sequence/><Arg Name="WpBearing"/><Arg Name="WpBearing"/><Add><Arg Name="Reverse"/></Add><Mod><Arg Name="TwoPi"/>
        </Mod></Assign>

    </Aggregate>

</Mission>


2018-09-25T23:07:08.619Z,1537916828.619 [CommandLine](IMPORTANT): Loaded ./Missions/Science/trackPatchFDOM.xml
2018-09-25T23:07:10.909Z,1537916830.909 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20180925T230311/Courier0000.lzma
2018-09-25T23:07:11.674Z,1537916831.674 [DataOverHttps](INFO): Moved sent file to Logs/20180925T230311/Courier0000.lzma.bak
2018-09-25T23:07:11.675Z,1537916831.675 [DataOverHttps](INFO): SBD MOMSN=8589623
2018-09-25T23:07:16.800Z,1537916836.800 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2018-09-25T23:07:28.517Z,1537916848.517 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20180925T230345/Courier0000.lzma
2018-09-25T23:07:29.316Z,1537916849.316 [DataOverHttps](INFO): Moved sent file to Logs/20180925T230345/Courier0000.lzma.bak
2018-09-25T23:07:29.317Z,1537916849.317 [DataOverHttps](INFO): SBD MOMSN=8589630
2018-09-25T23:07:41.174Z,1537916861.174 [CommandLine](IMPORTANT): got command set trackPatchFDOM.MissionTimeout 2.000000 hour
2018-09-25T23:07:41.175Z,1537916861.175 [CommandLine](IMPORTANT): got command set trackPatchFDOM.YoYoMinDepth 0.500000 meter
2018-09-25T23:07:41.175Z,1537916861.175 [CommandLine](IMPORTANT): got command set trackPatchFDOM.YoYoMaxDepth 3.700000 meter
2018-09-25T23:07:41.176Z,1537916861.176 [CommandLine](IMPORTANT): got command set trackPatchFDOM.PeakShallowBnd 0.300000 meter
2018-09-25T23:07:41.217Z,1537916861.217 [CommandLine](IMPORTANT): got command set trackPatchFDOM.PeakDeepBnd 3.500000 meter
2018-09-25T23:07:43.349Z,1537916863.349 [DataOverHttps](INFO): Sending 43 bytes from file Logs/20180925T230345/Courier0004.lzma
2018-09-25T23:07:44.124Z,1537916864.124 [DataOverHttps](INFO): Moved sent file to Logs/20180925T230345/Courier0004.lzma.bak
2018-09-25T23:07:44.124Z,1537916864.124 [DataOverHttps](INFO): SBD MOMSN=8589633
2018-09-25T23:07:56.440Z,1537916876.440 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2018-09-25T23:07:56.594Z,1537916876.594 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20180925T223216/Express0017.lzma
2018-09-25T23:07:57.366Z,1537916877.366 [DataOverHttps](INFO): Moved sent file to Logs/20180925T223216/Express0017.lzma.bak
2018-09-25T23:07:57.366Z,1537916877.366 [DataOverHttps](INFO): SBD MOMSN=8589635
2018-09-25T23:08:04.515Z,1537916884.515 [CommandLine](IMPORTANT): got command show variable organic
2018-09-25T23:08:04.518Z,1537916884.518 [CommandLine](IMPORTANT): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate (unknown)
2018-09-25T23:08:04.777Z,1537916884.777 [CommandLine](IMPORTANT): WetLabsSeaOWL_UV_A.concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate (part_per_billion)
2018-09-25T23:08:07.316Z,1537916887.316 [DataOverHttps](INFO): Sending 728 bytes from file Logs/20180925T230311/Express0001.lzma
2018-09-25T23:08:08.065Z,1537916888.065 [DataOverHttps](INFO): Moved sent file to Logs/20180925T230311/Express0001.lzma.bak
2018-09-25T23:08:08.065Z,1537916888.065 [DataOverHttps](INFO): SBD MOMSN=8589637
2018-09-25T23:08:20.706Z,1537916900.706 [CommandLine](IMPORTANT): got command report touch concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate
2018-09-25T23:08:21.479Z,1537916901.479 [DataOverHttps](INFO): Sending 1044 bytes from file Logs/20180925T230345/Express0001.lzma
2018-09-25T23:08:22.238Z,1537916902.238 [DataOverHttps](INFO): Moved sent file to Logs/20180925T230345/Express0001.lzma.bak
2018-09-25T23:08:22.238Z,1537916902.238 [DataOverHttps](INFO): SBD MOMSN=8589663
2018-09-25T23:08:23.830Z,1537916903.830 [CommandLine](IMPORTANT): got command show variable peakFDOM
2018-09-25T23:08:24.071Z,1537916904.071 [CommandLine](IMPORTANT): trackPatchFDOM:Science.PeakFDOM (part_per_billion)
2018-09-25T23:08:24.072Z,1537916904.072 [CommandLine](IMPORTANT): trackPatchFDOM:Science.PeakFDOMDepth (meter)
2018-09-25T23:08:24.072Z,1537916904.072 [CommandLine](IMPORTANT): trackPatchFDOM:Science.PeakFDOMLatitude (degree)
2018-09-25T23:08:24.072Z,1537916904.072 [CommandLine](IMPORTANT): trackPatchFDOM:Science.PeakFDOMLongitude (degree)
2018-09-25T23:08:31.870Z,1537916911.870 [CommandLine](IMPORTANT): got command report touch trackPatchFDOM:Science.PeakFDOM
2018-09-25T23:08:34.104Z,1537916914.104 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20180925T230345/Express0005.lzma
2018-09-25T23:08:34.883Z,1537916914.883 [DataOverHttps](INFO): Moved sent file to Logs/20180925T230345/Express0005.lzma.bak
2018-09-25T23:08:34.883Z,1537916914.883 [DataOverHttps](INFO): SBD MOMSN=8589688
2018-09-25T23:08:35.584Z,1537916915.584 [Default:CheckIn:Read_Iridium] Stopped
2018-09-25T23:08:35.584Z,1537916915.584 [Default:CheckIn:C.Wait] Running Loop=1
2018-09-25T23:08:35.584Z,1537916915.584 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-09-25T23:09:08.170Z,1537916948.170 [CommandLine](IMPORTANT): got command run
2018-09-25T23:09:08.174Z,1537916948.174 [CommandLine](IMPORTANT): Running 
2018-09-25T23:09:08.440Z,1537916948.440 [Default] Stopped
2018-09-25T23:09:08.440Z,1537916948.440 [Default](DEBUG): Aggregate::uninitialize Default
2018-09-25T23:09:08.440Z,1537916948.440 [Default:B.GoToSurface] Stopped
2018-09-25T23:09:08.440Z,1537916948.440 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-25T23:09:08.440Z,1537916948.440 [Default:CheckIn] Stopped
2018-09-25T23:09:08.445Z,1537916948.445 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-09-25T23:09:08.445Z,1537916948.445 [Default:CheckIn:C.Wait] Stopped
2018-09-25T23:09:08.445Z,1537916948.445 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-09-25T23:09:08.445Z,1537916948.445 [MissionManager](IMPORTANT): Started mission trackPatchFDOM
2018-09-25T23:09:08.446Z,1537916948.446 [trackPatchFDOM] Running Loop=1
2018-09-25T23:09:08.446Z,1537916948.446 [trackPatchFDOM](DEBUG): Aggregate::initialize trackPatchFDOM
2018-09-25T23:09:08.446Z,1537916948.446 [trackPatchFDOM:A.AltitudeEnvelope] Running Loop=1
2018-09-25T23:09:08.446Z,1537916948.446 [trackPatchFDOM:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2018-09-25T23:09:08.446Z,1537916948.446 [trackPatchFDOM:B.DepthEnvelope] Running Loop=1
2018-09-25T23:09:08.446Z,1537916948.446 [trackPatchFDOM:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2018-09-25T23:09:08.447Z,1537916948.447 [trackPatchFDOM:F.OffshoreEnvelope] Running Loop=1
2018-09-25T23:09:08.447Z,1537916948.447 [trackPatchFDOM:F.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2018-09-25T23:09:08.447Z,1537916948.447 [trackPatchFDOM:G.WaterDepthEnvelope] Running Loop=1
2018-09-25T23:09:08.447Z,1537916948.447 [trackPatchFDOM:G.WaterDepthEnvelope](DEBUG): Initialize WaterDepthEnvelopeComponent.
2018-09-25T23:09:08.447Z,1537916948.447 [trackPatchFDOM:H.Pitch] Running Loop=1
2018-09-25T23:09:08.447Z,1537916948.447 [trackPatchFDOM:H.Pitch](DEBUG): Initialize.
2018-09-25T23:09:08.447Z,1537916948.447 [trackPatchFDOM:I.Buoyancy] Running Loop=1
2018-09-25T23:09:08.447Z,1537916948.447 [trackPatchFDOM:I.Buoyancy](DEBUG): Initialize Buoyancy Component.
2018-09-25T23:09:08.447Z,1537916948.447 [trackPatchFDOM:Science] Running Loop=1
2018-09-25T23:09:08.448Z,1537916948.448 [trackPatchFDOM:Science](DEBUG): Aggregate::initialize trackPatchFDOM:Science
2018-09-25T23:09:08.448Z,1537916948.448 [trackPatchFDOM:Science:A] Running Loop=1
2018-09-25T23:09:08.448Z,1537916948.448 [trackPatchFDOM:Science:B] Running Loop=1
2018-09-25T23:09:08.448Z,1537916948.448 [trackPatchFDOM:Science:C] Running Loop=1
2018-09-25T23:09:08.448Z,1537916948.448 [trackPatchFDOM:Science:D] Running Loop=1
2018-09-25T23:09:08.448Z,1537916948.448 [trackPatchFDOM:Science:E] Running Loop=1
2018-09-25T23:09:08.448Z,1537916948.448 [trackPatchFDOM:Science:F] Running Loop=1
2018-09-25T23:09:08.448Z,1537916948.448 [trackPatchFDOM:Science:G] Running Loop=1
2018-09-25T23:09:08.448Z,1537916948.448 [trackPatchFDOM:Science:Read_Oil] Running Loop=1
2018-09-25T23:09:08.448Z,1537916948.448 [trackPatchFDOM:Science:PeakDetectChl] Running Loop=1
2018-09-25T23:09:08.448Z,1537916948.448 [trackPatchFDOM:Science:PeakDetectChl](DEBUG): Aggregate::initialize trackPatchFDOM:Science:PeakDetectChl
2018-09-25T23:09:08.453Z,1537916948.453 [trackPatchFDOM:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2018-09-25T23:09:08.453Z,1537916948.453 [trackPatchFDOM:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-09-25T23:09:08.455Z,1537916948.455 [trackPatchFDOM:Science:HighestChlPeakReport] Running Loop=1
2018-09-25T23:09:08.455Z,1537916948.455 [trackPatchFDOM:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize trackPatchFDOM:Science:HighestChlPeakReport
2018-09-25T23:09:08.455Z,1537916948.455 [trackPatchFDOM:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2018-09-25T23:09:08.455Z,1537916948.455 [trackPatchFDOM:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2018-09-25T23:09:08.456Z,1537916948.456 [trackPatchFDOM:Science:PeakDetectNO3] Running Loop=1
2018-09-25T23:09:08.456Z,1537916948.456 [trackPatchFDOM:Science:PeakDetectNO3](DEBUG): Aggregate::initialize trackPatchFDOM:Science:PeakDetectNO3
2018-09-25T23:09:08.456Z,1537916948.456 [trackPatchFDOM:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2018-09-25T23:09:08.456Z,1537916948.456 [trackPatchFDOM:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-09-25T23:09:08.466Z,1537916948.466 [trackPatchFDOM:Science:PeakDetectOil] Running Loop=1
2018-09-25T23:09:08.466Z,1537916948.466 [trackPatchFDOM:Science:PeakDetectOil](DEBUG): Aggregate::initialize trackPatchFDOM:Science:PeakDetectOil
2018-09-25T23:09:08.466Z,1537916948.466 [trackPatchFDOM:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2018-09-25T23:09:08.467Z,1537916948.467 [trackPatchFDOM:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-09-25T23:09:08.468Z,1537916948.468 [trackPatchFDOM:Science:PeakDetectFDOM] Running Loop=1
2018-09-25T23:09:08.468Z,1537916948.468 [trackPatchFDOM:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize trackPatchFDOM:Science:PeakDetectFDOM
2018-09-25T23:09:08.468Z,1537916948.468 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2018-09-25T23:09:08.468Z,1537916948.468 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-09-25T23:09:08.470Z,1537916948.470 [trackPatchFDOM:K.SetSpeed] Running Loop=1
2018-09-25T23:09:08.470Z,1537916948.470 [trackPatchFDOM:K.SetSpeed](DEBUG): Initialize.
2018-09-25T23:09:08.471Z,1537916948.471 [trackPatchFDOM:L.DepthEnvelope] Running Loop=1
2018-09-25T23:09:08.471Z,1537916948.471 [trackPatchFDOM:L.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2018-09-25T23:09:08.471Z,1537916948.471 [trackPatchFDOM:M.YoYo] Running Loop=1
2018-09-25T23:09:08.471Z,1537916948.471 [trackPatchFDOM:M.YoYo](DEBUG): Initialize YoYoComponent.
2018-09-25T23:09:08.471Z,1537916948.471 [trackPatchFDOM:SurfaceCommsSplit] Running Loop=1
2018-09-25T23:09:08.472Z,1537916948.472 [trackPatchFDOM:SurfaceCommsSplit](DEBUG): Aggregate::initialize trackPatchFDOM:SurfaceCommsSplit
2018-09-25T23:09:08.472Z,1537916948.472 [trackPatchFDOM:SurfaceCommsSplit:A.GoToSurface] Running Loop=1
2018-09-25T23:09:08.472Z,1537916948.472 [trackPatchFDOM:SurfaceCommsSplit:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-25T23:09:08.472Z,1537916948.472 [trackPatchFDOM:SurfaceCommsSplit:A.GoToSurface](INFO): Received depth rate setting nan m/s.
2018-09-25T23:09:08.477Z,1537916948.477 [trackPatchFDOM:SurfaceCommsSplit:A.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2018-09-25T23:09:08.478Z,1537916948.478 [trackPatchFDOM:SurfaceCommsSplit:A.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2018-09-25T23:09:08.478Z,1537916948.478 [trackPatchFDOM:SurfaceCommsSplit:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-25T23:09:08.479Z,1537916948.479 [trackPatchFDOM:SurfaceCommsSplit:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-25T23:09:08.479Z,1537916948.479 [trackPatchFDOM:M.YoYo] Running Loop=1
2018-09-25T23:09:08.494Z,1537916948.494 [trackPatchFDOM:L.DepthEnvelope] Running Loop=1
2018-09-25T23:09:08.504Z,1537916948.504 [trackPatchFDOM:K.SetSpeed] Running Loop=1
2018-09-25T23:09:08.518Z,1537916948.518 [trackPatchFDOM:Science] Running Loop=1
2018-09-25T23:09:08.538Z,1537916948.538 [trackPatchFDOM:Science:PeakDetectFDOM] Running Loop=1
2018-09-25T23:09:08.555Z,1537916948.555 [trackPatchFDOM:Science:PeakDetectOil] Stopped
2018-09-25T23:09:08.555Z,1537916948.555 [trackPatchFDOM:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize trackPatchFDOM:Science:PeakDetectOil
2018-09-25T23:09:08.555Z,1537916948.555 [trackPatchFDOM:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2018-09-25T23:09:08.555Z,1537916948.555 [trackPatchFDOM:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-09-25T23:09:08.555Z,1537916948.555 [trackPatchFDOM:Science:PeakDetectNO3] Stopped
2018-09-25T23:09:08.555Z,1537916948.555 [trackPatchFDOM:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize trackPatchFDOM:Science:PeakDetectNO3
2018-09-25T23:09:08.555Z,1537916948.555 [trackPatchFDOM:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2018-09-25T23:09:08.555Z,1537916948.555 [trackPatchFDOM:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-09-25T23:09:08.555Z,1537916948.555 [trackPatchFDOM:Science:HighestChlPeakReport] Stopped
2018-09-25T23:09:08.556Z,1537916948.556 [trackPatchFDOM:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize trackPatchFDOM:Science:HighestChlPeakReport
2018-09-25T23:09:08.556Z,1537916948.556 [trackPatchFDOM:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2018-09-25T23:09:08.556Z,1537916948.556 [trackPatchFDOM:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2018-09-25T23:09:08.556Z,1537916948.556 [trackPatchFDOM:Science:PeakDetectChl] Stopped
2018-09-25T23:09:08.556Z,1537916948.556 [trackPatchFDOM:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize trackPatchFDOM:Science:PeakDetectChl
2018-09-25T23:09:08.556Z,1537916948.556 [trackPatchFDOM:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2018-09-25T23:09:08.556Z,1537916948.556 [trackPatchFDOM:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-09-25T23:09:08.556Z,1537916948.556 [trackPatchFDOM:Science:Read_Oil] Running Loop=1
2018-09-25T23:09:08.557Z,1537916948.557 [trackPatchFDOM:Science:Read_Oil](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2018-09-25T23:09:08.558Z,1537916948.558 [trackPatchFDOM:Science:G] Stopped
2018-09-25T23:09:08.559Z,1537916948.559 [trackPatchFDOM:Science:F] Stopped
2018-09-25T23:09:08.559Z,1537916948.559 [trackPatchFDOM:Science:E] Stopped
2018-09-25T23:09:08.559Z,1537916948.559 [trackPatchFDOM:Science:D] Stopped
2018-09-25T23:09:08.559Z,1537916948.559 [trackPatchFDOM:Science:C] Running Loop=1
2018-09-25T23:09:08.559Z,1537916948.559 [trackPatchFDOM:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2018-09-25T23:09:08.560Z,1537916948.560 [trackPatchFDOM:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2018-09-25T23:09:08.590Z,1537916948.590 [trackPatchFDOM:Science:B] Stopped
2018-09-25T23:09:08.590Z,1537916948.590 [trackPatchFDOM:Science:A] Running Loop=1
2018-09-25T23:09:08.590Z,1537916948.590 [trackPatchFDOM:Science:A](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_oxygen_in_sea_water
2018-09-25T23:09:08.592Z,1537916948.592 [trackPatchFDOM:I.Buoyancy] Running Loop=1
2018-09-25T23:09:08.597Z,1537916948.597 [trackPatchFDOM:H.Pitch] Running Loop=1
2018-09-25T23:09:08.602Z,1537916948.602 [trackPatchFDOM:G.WaterDepthEnvelope] Running Loop=1
2018-09-25T23:09:08.607Z,1537916948.607 [trackPatchFDOM:F.OffshoreEnvelope] Running Loop=1
2018-09-25T23:09:08.647Z,1537916948.647 [trackPatchFDOM:SurfaceCommsSplit:B] Running Loop=1
2018-09-25T23:09:08.648Z,1537916948.648 [trackPatchFDOM:SurfaceCommsSplit:B](DEBUG): Aggregate::initialize trackPatchFDOM:SurfaceCommsSplit:B
2018-09-25T23:09:08.648Z,1537916948.648 [trackPatchFDOM:SurfaceCommsSplit:B:A] Running Loop=1
2018-09-25T23:09:08.648Z,1537916948.648 [trackPatchFDOM:B.DepthEnvelope] Running Loop=1
2018-09-25T23:09:08.673Z,1537916948.673 [trackPatchFDOM:A.AltitudeEnvelope] Running Loop=1
2018-09-25T23:09:08.820Z,1537916948.820 [trackPatchFDOM:SurfaceCommsSplit:B:A](INFO): ##UnpackSplit##
2018-09-25T23:09:08.822Z,1537916948.822 [trackPatchFDOM:SurfaceCommsSplit:B:A] Stopped
2018-09-25T23:09:08.822Z,1537916948.822 [trackPatchFDOM:SurfaceCommsSplit:B](INFO): Completed trackPatchFDOM:SurfaceCommsSplit:B
2018-09-25T23:09:08.822Z,1537916948.822 [trackPatchFDOM:SurfaceCommsSplit:B] Stopped
2018-09-25T23:09:08.822Z,1537916948.822 [trackPatchFDOM:SurfaceCommsSplit:B](DEBUG): Aggregate::uninitialize trackPatchFDOM:SurfaceCommsSplit:B
2018-09-25T23:09:08.823Z,1537916948.823 [trackPatchFDOM:SurfaceCommsSplit:C] Running Loop=1
2018-09-25T23:09:08.823Z,1537916948.823 [trackPatchFDOM:SurfaceCommsSplit:C](DEBUG): Aggregate::initialize trackPatchFDOM:SurfaceCommsSplit:C
2018-09-25T23:09:08.823Z,1537916948.823 [trackPatchFDOM:SurfaceCommsSplit:C:A] Running Loop=1
2018-09-25T23:09:08.881Z,1537916948.881 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2018-09-25T23:09:08.890Z,1537916948.890 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2018-09-25T23:09:08.917Z,1537916948.917 [WetLabsSeaOWL_UV_A](INFO): Opening uart, block timeout 10ths=4
2018-09-25T23:09:08.917Z,1537916948.917 [WetLabsSeaOWL_UV_A](INFO): Powering up
2018-09-25T23:09:09.206Z,1537916949.206 [trackPatchFDOM:M.YoYo] Preempted
2018-09-25T23:09:09.207Z,1537916949.207 [trackPatchFDOM:L.DepthEnvelope] Preempted
2018-09-25T23:09:09.207Z,1537916949.207 [trackPatchFDOM:K.SetSpeed] Preempted
2018-09-25T23:09:09.207Z,1537916949.207 [trackPatchFDOM:Science] Preempted
2018-09-25T23:09:09.207Z,1537916949.207 [trackPatchFDOM:Science:A] Preempted
2018-09-25T23:09:09.207Z,1537916949.207 [trackPatchFDOM:Science:C] Preempted
2018-09-25T23:09:09.207Z,1537916949.207 [trackPatchFDOM:Science:Read_Oil] Preempted
2018-09-25T23:09:09.207Z,1537916949.207 [trackPatchFDOM:Science:PeakDetectFDOM] Preempted
2018-09-25T23:09:09.207Z,1537916949.207 [trackPatchFDOM:I.Buoyancy] Preempted
2018-09-25T23:09:09.207Z,1537916949.207 [trackPatchFDOM:H.Pitch] Preempted
2018-09-25T23:09:09.207Z,1537916949.207 [trackPatchFDOM:G.WaterDepthEnvelope] Preempted
2018-09-25T23:09:09.207Z,1537916949.207 [trackPatchFDOM:F.OffshoreEnvelope] Preempted
2018-09-25T23:09:09.213Z,1537916949.213 [trackPatchFDOM:SurfaceCommsSplit:C:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-09-25T23:09:09.214Z,1537916949.214 [trackPatchFDOM:SurfaceCommsSplit:C:A] Stopped
2018-09-25T23:09:09.215Z,1537916949.215 [trackPatchFDOM:SurfaceCommsSplit:C:B] Running Loop=1
2018-09-25T23:09:09.301Z,1537916949.301 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:09:09.619Z,1537916949.619 [trackPatchFDOM:SurfaceCommsSplit:C:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-09-25T23:09:11.101Z,1537916951.101 [WetLabsSeaOWL_UV_A](INFO): Powering down
2018-09-25T23:09:11.235Z,1537916951.235 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate nan ppb
2018-09-25T23:09:14.774Z,1537916954.774 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180925T230345/Courier0007.lzma
2018-09-25T23:09:15.258Z,1537916955.258 [Aanderaa_O2](INFO): Powering down
2018-09-25T23:09:15.550Z,1537916955.550 [DataOverHttps](INFO): Moved sent file to Logs/20180925T230345/Courier0007.lzma.bak
2018-09-25T23:09:15.550Z,1537916955.550 [DataOverHttps](INFO): SBD MOMSN=8589703
2018-09-25T23:09:32.079Z,1537916972.079 [DataOverHttps](INFO): Sending 550 bytes from file Logs/20180925T230345/Express0008.lzma
2018-09-25T23:09:32.850Z,1537916972.850 [DataOverHttps](INFO): Moved sent file to Logs/20180925T230345/Express0008.lzma.bak
2018-09-25T23:09:32.851Z,1537916972.851 [DataOverHttps](INFO): SBD MOMSN=8589776
2018-09-25T23:09:33.514Z,1537916973.514 [trackPatchFDOM:SurfaceCommsSplit:C:B] Stopped
2018-09-25T23:09:33.514Z,1537916973.514 [trackPatchFDOM:SurfaceCommsSplit:C:C] Running Loop=1
2018-09-25T23:09:33.738Z,1537916973.738 [trackPatchFDOM:SurfaceCommsSplit:C:C](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-09-25T23:09:33.740Z,1537916973.740 [trackPatchFDOM:SurfaceCommsSplit:C:C] Stopped
2018-09-25T23:09:33.740Z,1537916973.740 [trackPatchFDOM:SurfaceCommsSplit:C](INFO): Completed trackPatchFDOM:SurfaceCommsSplit:C
2018-09-25T23:09:33.740Z,1537916973.740 [trackPatchFDOM:SurfaceCommsSplit:C] Stopped
2018-09-25T23:09:33.740Z,1537916973.740 [trackPatchFDOM:SurfaceCommsSplit:C](DEBUG): Aggregate::uninitialize trackPatchFDOM:SurfaceCommsSplit:C
2018-09-25T23:09:33.757Z,1537916973.757 [trackPatchFDOM:SurfaceCommsSplit](INFO): Completed trackPatchFDOM:SurfaceCommsSplit
2018-09-25T23:09:33.757Z,1537916973.757 [trackPatchFDOM:SurfaceCommsSplit] Stopped
2018-09-25T23:09:33.757Z,1537916973.757 [trackPatchFDOM:SurfaceCommsSplit](DEBUG): Aggregate::uninitialize trackPatchFDOM:SurfaceCommsSplit
2018-09-25T23:09:33.757Z,1537916973.757 [trackPatchFDOM:SurfaceCommsSplit:A.GoToSurface] Stopped
2018-09-25T23:09:33.758Z,1537916973.758 [trackPatchFDOM:SurfaceCommsSplit:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-25T23:09:33.758Z,1537916973.758 [trackPatchFDOM:PatchDetectHorizontal] Running Loop=1
2018-09-25T23:09:33.758Z,1537916973.758 [trackPatchFDOM:PatchDetectHorizontal](DEBUG): Aggregate::initialize trackPatchFDOM:PatchDetectHorizontal
2018-09-25T23:09:33.758Z,1537916973.758 [trackPatchFDOM:PatchDetectHorizontal:AssignPatchDetectOn] Running Loop=1
2018-09-25T23:09:36.979Z,1537916976.979 [trackPatchFDOM:PatchDetectHorizontal:AssignPatchDetectOn] Stopped
2018-09-25T23:09:36.979Z,1537916976.979 [trackPatchFDOM:PatchDetectHorizontal:C] Running Loop=1
2018-09-25T23:09:36.979Z,1537916976.979 [trackPatchFDOM:M.YoYo] Running Loop=1
2018-09-25T23:09:36.980Z,1537916976.980 [trackPatchFDOM:L.DepthEnvelope] Running Loop=1
2018-09-25T23:09:36.981Z,1537916976.981 [trackPatchFDOM:K.SetSpeed] Running Loop=1
2018-09-25T23:09:36.981Z,1537916976.981 [trackPatchFDOM:Science] Running Loop=1
2018-09-25T23:09:36.983Z,1537916976.983 [trackPatchFDOM:Science:PeakDetectFDOM] Running Loop=1
2018-09-25T23:09:36.983Z,1537916976.983 [trackPatchFDOM:Science:Read_Oil] Running Loop=1
2018-09-25T23:09:36.984Z,1537916976.984 [trackPatchFDOM:Science:C] Running Loop=1
2018-09-25T23:09:36.984Z,1537916976.984 [trackPatchFDOM:Science:A] Running Loop=1
2018-09-25T23:09:36.984Z,1537916976.984 [trackPatchFDOM:I.Buoyancy] Running Loop=1
2018-09-25T23:09:36.984Z,1537916976.984 [trackPatchFDOM:H.Pitch] Running Loop=1
2018-09-25T23:09:36.985Z,1537916976.985 [trackPatchFDOM:G.WaterDepthEnvelope] Running Loop=1
2018-09-25T23:09:36.985Z,1537916976.985 [trackPatchFDOM:F.OffshoreEnvelope] Running Loop=1
2018-09-25T23:09:37.108Z,1537916977.108 [trackPatchFDOM:PatchDetectHorizontal:C](IMPORTANT): Next: bearing for CrossLeg: -90.000000 arcdeg . WpMaxDistance = 1000.000000 m . Current lat, lon = 36.799999 arcdeg , -121.900002 arcdeg .
2018-09-25T23:09:37.114Z,1537916977.114 [trackPatchFDOM:PatchDetectHorizontal:C] Stopped
2018-09-25T23:09:37.114Z,1537916977.114 [trackPatchFDOM:PatchDetectHorizontal:CrossLeg] Running Loop=1
2018-09-25T23:09:37.114Z,1537916977.114 [trackPatchFDOM:PatchDetectHorizontal:CrossLeg](DEBUG): Aggregate::initialize trackPatchFDOM:PatchDetectHorizontal:CrossLeg
2018-09-25T23:09:37.114Z,1537916977.114 [trackPatchFDOM:PatchDetectHorizontal:CrossLeg:CrossSurfaceWp.Waypoint] Running Loop=1
2018-09-25T23:09:37.114Z,1537916977.114 [trackPatchFDOM:PatchDetectHorizontal:CrossLeg:CrossSurfaceWp.Waypoint](DEBUG): Initialize WaypointComponent.
2018-09-25T23:09:37.121Z,1537916977.121 [trackPatchFDOM:PatchDetectHorizontal:PatchDetection] Running Loop=1
2018-09-25T23:09:37.121Z,1537916977.121 [trackPatchFDOM:PatchDetectHorizontal:PatchDetection](DEBUG): Aggregate::initialize trackPatchFDOM:PatchDetectHorizontal:PatchDetection
2018-09-25T23:09:37.121Z,1537916977.121 [trackPatchFDOM:PatchDetectHorizontal:PatchDetection:PeakDetectHorizontalTrigger.PeakDetectHorizontal] Running Loop=1
2018-09-25T23:09:37.121Z,1537916977.121 [trackPatchFDOM:PatchDetectHorizontal:PatchDetection:PeakDetectHorizontalTrigger.PeakDetectHorizontal](INFO): Initialize.
2018-09-25T23:09:37.314Z,1537916977.314 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2018-09-25T23:09:37.363Z,1537916977.363 [WetLabsSeaOWL_UV_A](INFO): Opening uart, block timeout 10ths=4
2018-09-25T23:09:37.363Z,1537916977.363 [WetLabsSeaOWL_UV_A](INFO): Powering up
2018-09-25T23:09:37.528Z,1537916977.528 [trackPatchFDOM:PatchDetectHorizontal:CrossLeg:CrossSurfaceWp.Waypoint] Stopped
2018-09-25T23:09:37.528Z,1537916977.528 [trackPatchFDOM:PatchDetectHorizontal:CrossLeg:CrossSurfaceWp.Waypoint](DEBUG): Uninitialize WaypointComponent.
2018-09-25T23:09:37.528Z,1537916977.528 [trackPatchFDOM:PatchDetectHorizontal:CrossLeg:B] Running Loop=1
2018-09-25T23:09:37.545Z,1537916977.545 [trackPatchFDOM:PatchDetectHorizontal:CrossLeg:B](DEBUG): Aggregate::initialize trackPatchFDOM:PatchDetectHorizontal:CrossLeg:B
2018-09-25T23:09:37.729Z,1537916977.729 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:09:37.897Z,1537916977.897 [ThrusterServo](FAULT): getVelocity uart error serial timeout
2018-09-25T23:09:37.897Z,1537916977.897 [ThrusterServo](FAULT): Thruster uart error: serial timeout
2018-09-25T23:09:37.897Z,1537916977.897 [ThrusterServo] Communications Fault, FailCount= 1
2018-09-25T23:09:37.897Z,1537916977.897 [ThrusterServo](ERROR): Communications Fault
2018-09-25T23:09:37.902Z,1537916977.902 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2018-09-25T23:09:38.054Z,1537916978.054 [trackPatchFDOM:SurfaceComms] Running Loop=1
2018-09-25T23:09:38.054Z,1537916978.054 [trackPatchFDOM:SurfaceComms](DEBUG): Aggregate::initialize trackPatchFDOM:SurfaceComms
2018-09-25T23:09:38.055Z,1537916978.055 [trackPatchFDOM:SurfaceComms:SurfaceComms] Running Loop=1
2018-09-25T23:09:38.055Z,1537916978.055 [trackPatchFDOM:SurfaceComms:SurfaceComms](DEBUG): Aggregate::initialize trackPatchFDOM:SurfaceComms:SurfaceComms
2018-09-25T23:09:38.055Z,1537916978.055 [trackPatchFDOM:SurfaceComms:SurfaceComms:A.GoToSurface] Running Loop=1
2018-09-25T23:09:38.055Z,1537916978.055 [trackPatchFDOM:SurfaceComms:SurfaceComms:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-25T23:09:38.055Z,1537916978.055 [trackPatchFDOM:SurfaceComms:SurfaceComms:A.GoToSurface](INFO): Received depth rate setting nan m/s.
2018-09-25T23:09:38.056Z,1537916978.056 [trackPatchFDOM:SurfaceComms:SurfaceComms:A.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2018-09-25T23:09:38.056Z,1537916978.056 [trackPatchFDOM:SurfaceComms:SurfaceComms:A.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2018-09-25T23:09:38.056Z,1537916978.056 [trackPatchFDOM:SurfaceComms:SurfaceComms:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-25T23:09:38.061Z,1537916978.061 [trackPatchFDOM:SurfaceComms:SurfaceComms:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-25T23:09:38.079Z,1537916978.079 [trackPatchFDOM:SurfaceComms:SurfaceComms:B] Running Loop=1
2018-09-25T23:09:38.079Z,1537916978.079 [trackPatchFDOM:SurfaceComms:SurfaceComms:B](DEBUG): Aggregate::initialize trackPatchFDOM:SurfaceComms:SurfaceComms:B
2018-09-25T23:09:38.079Z,1537916978.079 [trackPatchFDOM:SurfaceComms:SurfaceComms:B:A] Running Loop=1
2018-09-25T23:09:38.137Z,1537916978.137 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:09:38.164Z,1537916978.164 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-09-25T23:09:38.164Z,1537916978.164 [ThrusterServo](INFO): Powering down
2018-09-25T23:09:38.367Z,1537916978.367 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-09-25T23:09:38.528Z,1537916978.528 [trackPatchFDOM:SurfaceComms:SurfaceComms:B] Stopped
2018-09-25T23:09:38.528Z,1537916978.528 [trackPatchFDOM:SurfaceComms:SurfaceComms:B](DEBUG): Aggregate::uninitialize trackPatchFDOM:SurfaceComms:SurfaceComms:B
2018-09-25T23:09:38.528Z,1537916978.528 [trackPatchFDOM:SurfaceComms:SurfaceComms:B:A] Stopped
2018-09-25T23:09:38.528Z,1537916978.528 [trackPatchFDOM:SurfaceComms:SurfaceComms:C] Running Loop=1
2018-09-25T23:09:38.528Z,1537916978.528 [trackPatchFDOM:SurfaceComms:SurfaceComms:C](DEBUG): Aggregate::initialize trackPatchFDOM:SurfaceComms:SurfaceComms:C
2018-09-25T23:09:38.549Z,1537916978.549 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:09:38.970Z,1537916978.970 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1537916928.000000 second
2018-09-25T23:09:39.105Z,1537916979.105 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:09:39.922Z,1537916979.922 [trackPatchFDOM:SurfaceComms:SurfaceComms:C:A] Running Loop=1
2018-09-25T23:09:40.125Z,1537916980.125 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:09:40.247Z,1537916980.247 [trackPatchFDOM:M.YoYo] Preempted
2018-09-25T23:09:40.247Z,1537916980.247 [trackPatchFDOM:L.DepthEnvelope] Preempted
2018-09-25T23:09:40.248Z,1537916980.248 [trackPatchFDOM:K.SetSpeed] Preempted
2018-09-25T23:09:40.248Z,1537916980.248 [trackPatchFDOM:Science] Preempted
2018-09-25T23:09:40.248Z,1537916980.248 [trackPatchFDOM:Science:A] Preempted
2018-09-25T23:09:40.248Z,1537916980.248 [trackPatchFDOM:Science:C] Preempted
2018-09-25T23:09:40.248Z,1537916980.248 [trackPatchFDOM:Science:Read_Oil] Preempted
2018-09-25T23:09:40.248Z,1537916980.248 [trackPatchFDOM:Science:PeakDetectFDOM] Preempted
2018-09-25T23:09:40.248Z,1537916980.248 [trackPatchFDOM:I.Buoyancy] Preempted
2018-09-25T23:09:40.248Z,1537916980.248 [trackPatchFDOM:H.Pitch] Preempted
2018-09-25T23:09:40.248Z,1537916980.248 [trackPatchFDOM:G.WaterDepthEnvelope] Preempted
2018-09-25T23:09:40.248Z,1537916980.248 [trackPatchFDOM:F.OffshoreEnvelope] Preempted
2018-09-25T23:09:40.254Z,1537916980.254 [trackPatchFDOM:SurfaceComms:SurfaceComms:C:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-09-25T23:09:40.255Z,1537916980.255 [trackPatchFDOM:SurfaceComms:SurfaceComms:C:A] Stopped
2018-09-25T23:09:40.256Z,1537916980.256 [trackPatchFDOM:SurfaceComms:SurfaceComms:C:B] Running Loop=1
2018-09-25T23:09:40.470Z,1537916980.470 [CBIT](INFO): Clearing failed state for component ThrusterServo
2018-09-25T23:09:40.470Z,1537916980.470 [ThrusterServo] No Fault, FailCount= 1
2018-09-25T23:09:40.646Z,1537916980.646 [trackPatchFDOM:SurfaceComms:SurfaceComms:C:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-09-25T23:09:40.737Z,1537916980.737 [WetLabsSeaOWL_UV_A](INFO): Powering down
2018-09-25T23:09:40.795Z,1537916980.795 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-09-25T23:09:40.801Z,1537916980.801 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-09-25T23:09:40.807Z,1537916980.807 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate nan ppb
2018-09-25T23:09:41.137Z,1537916981.137 [Aanderaa_O2](INFO): Powering down
2018-09-25T23:09:41.661Z,1537916981.661 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2018-09-25T23:09:41.725Z,1537916981.725 [ThrusterServo](FAULT): Thruster failed to initialize
2018-09-25T23:09:41.725Z,1537916981.725 [ThrusterServo] Communications Fault, FailCount= 2
2018-09-25T23:09:41.725Z,1537916981.725 [ThrusterServo](ERROR): Communications Fault
2018-09-25T23:09:41.729Z,1537916981.729 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2018-09-25T23:09:41.875Z,1537916981.875 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-09-25T23:09:41.875Z,1537916981.875 [ThrusterServo](INFO): Powering down
2018-09-25T23:09:45.380Z,1537916985.380 [DataOverHttps](INFO): Sending 42 bytes from file Logs/20180925T230345/Courier0010.lzma
2018-09-25T23:09:45.633Z,1537916985.633 [CBIT](INFO): Clearing failed state for component ThrusterServo
2018-09-25T23:09:45.633Z,1537916985.633 [ThrusterServo] No Fault, FailCount= 2
2018-09-25T23:09:45.823Z,1537916985.823 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-09-25T23:09:45.824Z,1537916985.824 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-09-25T23:09:46.133Z,1537916986.133 [DataOverHttps](INFO): Moved sent file to Logs/20180925T230345/Courier0010.lzma.bak
2018-09-25T23:09:46.133Z,1537916986.133 [DataOverHttps](INFO): SBD MOMSN=8589798
2018-09-25T23:09:55.887Z,1537916995.887 [DataOverHttps](INFO): Sending 600 bytes from file Logs/20180925T230345/Express0011.lzma
2018-09-25T23:09:56.642Z,1537916996.642 [DataOverHttps](INFO): Moved sent file to Logs/20180925T230345/Express0011.lzma.bak
2018-09-25T23:09:56.642Z,1537916996.642 [DataOverHttps](INFO): SBD MOMSN=8589800
2018-09-25T23:09:57.389Z,1537916997.389 [trackPatchFDOM:SurfaceComms:SurfaceComms:C:B] Stopped
2018-09-25T23:09:57.389Z,1537916997.389 [trackPatchFDOM:SurfaceComms:SurfaceComms:C:C] Running Loop=1
2018-09-25T23:09:57.515Z,1537916997.515 [trackPatchFDOM:SurfaceComms:SurfaceComms:C:C](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-09-25T23:09:57.522Z,1537916997.522 [trackPatchFDOM:SurfaceComms:SurfaceComms:C:C] Stopped
2018-09-25T23:09:57.523Z,1537916997.523 [trackPatchFDOM:SurfaceComms:SurfaceComms:C](INFO): Completed trackPatchFDOM:SurfaceComms:SurfaceComms:C
2018-09-25T23:09:57.523Z,1537916997.523 [trackPatchFDOM:SurfaceComms:SurfaceComms:C] Stopped
2018-09-25T23:09:57.523Z,1537916997.523 [trackPatchFDOM:SurfaceComms:SurfaceComms:C](DEBUG): Aggregate::uninitialize trackPatchFDOM:SurfaceComms:SurfaceComms:C
2018-09-25T23:09:57.524Z,1537916997.524 [trackPatchFDOM:SurfaceComms:SurfaceComms](INFO): Completed trackPatchFDOM:SurfaceComms:SurfaceComms
2018-09-25T23:09:57.524Z,1537916997.524 [trackPatchFDOM:SurfaceComms:SurfaceComms] Stopped
2018-09-25T23:09:57.524Z,1537916997.524 [trackPatchFDOM:SurfaceComms:SurfaceComms](DEBUG): Aggregate::uninitialize trackPatchFDOM:SurfaceComms:SurfaceComms
2018-09-25T23:09:57.524Z,1537916997.524 [trackPatchFDOM:SurfaceComms:SurfaceComms:A.GoToSurface] Stopped
2018-09-25T23:09:57.525Z,1537916997.525 [trackPatchFDOM:SurfaceComms:SurfaceComms:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-25T23:09:57.525Z,1537916997.525 [trackPatchFDOM:SurfaceComms](INFO): Completed trackPatchFDOM:SurfaceComms
2018-09-25T23:09:57.526Z,1537916997.526 [trackPatchFDOM:SurfaceComms] Stopped
2018-09-25T23:09:57.526Z,1537916997.526 [trackPatchFDOM:SurfaceComms](DEBUG): Aggregate::uninitialize trackPatchFDOM:SurfaceComms
2018-09-25T23:09:57.906Z,1537916997.906 [trackPatchFDOM:PatchDetectHorizontal:CrossLeg:B](INFO): Completed trackPatchFDOM:PatchDetectHorizontal:CrossLeg:B
2018-09-25T23:09:57.906Z,1537916997.906 [trackPatchFDOM:PatchDetectHorizontal:CrossLeg:B] Stopped
2018-09-25T23:09:57.907Z,1537916997.907 [trackPatchFDOM:PatchDetectHorizontal:CrossLeg:B](DEBUG): Aggregate::uninitialize trackPatchFDOM:PatchDetectHorizontal:CrossLeg:B
2018-09-25T23:09:57.907Z,1537916997.907 [trackPatchFDOM:PatchDetectHorizontal:CrossLeg:CrossPeakWp.Waypoint] Running Loop=1
2018-09-25T23:09:57.907Z,1537916997.907 [trackPatchFDOM:PatchDetectHorizontal:CrossLeg:CrossPeakWp.Waypoint](DEBUG): Initialize WaypointComponent.
2018-09-25T23:09:57.909Z,1537916997.909 [trackPatchFDOM:M.YoYo] Running Loop=1
2018-09-25T23:09:57.909Z,1537916997.909 [trackPatchFDOM:L.DepthEnvelope] Running Loop=1
2018-09-25T23:09:57.910Z,1537916997.910 [trackPatchFDOM:K.SetSpeed] Running Loop=1
2018-09-25T23:09:57.911Z,1537916997.911 [trackPatchFDOM:Science] Running Loop=1
2018-09-25T23:09:57.913Z,1537916997.913 [trackPatchFDOM:Science:PeakDetectFDOM] Running Loop=1
2018-09-25T23:09:57.913Z,1537916997.913 [trackPatchFDOM:Science:Read_Oil] Running Loop=1
2018-09-25T23:09:57.913Z,1537916997.913 [trackPatchFDOM:Science:C] Running Loop=1
2018-09-25T23:09:57.914Z,1537916997.914 [trackPatchFDOM:Science:A] Running Loop=1
2018-09-25T23:09:57.914Z,1537916997.914 [trackPatchFDOM:I.Buoyancy] Running Loop=1
2018-09-25T23:09:57.914Z,1537916997.914 [trackPatchFDOM:H.Pitch] Running Loop=1
2018-09-25T23:09:57.915Z,1537916997.915 [trackPatchFDOM:G.WaterDepthEnvelope] Running Loop=1
2018-09-25T23:09:57.915Z,1537916997.915 [trackPatchFDOM:F.OffshoreEnvelope] Running Loop=1
2018-09-25T23:09:58.118Z,1537916998.118 [WetLabsSeaOWL_UV_A](INFO): Opening uart, block timeout 10ths=4
2018-09-25T23:09:58.119Z,1537916998.119 [WetLabsSeaOWL_UV_A](INFO): Powering up
2018-09-25T23:09:58.170Z,1537916998.170 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2018-09-25T23:09:58.281Z,1537916998.281 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:09:58.689Z,1537916998.689 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:09:59.097Z,1537916999.097 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:09:59.505Z,1537916999.505 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:10:00.625Z,1537917000.625 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:10:01.611Z,1537917001.611 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 5.132864 ppb
2018-09-25T23:10:02.723Z,1537917002.723 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 5.254304 ppb
2018-09-25T23:10:02.884Z,1537917002.884 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-25T23:10:02.885Z,1537917002.885 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18092516150905,35.0, -0.1,   0.0,14
2018-09-25T23:10:03.850Z,1537917003.850 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 5.294784 ppb
2018-09-25T23:10:04.270Z,1537917004.270 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:10:04.710Z,1537917004.710 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.076480 ppb
2018-09-25T23:10:05.824Z,1537917005.824 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 2.971232 ppb
2018-09-25T23:10:06.710Z,1537917006.710 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.019808 ppb
2018-09-25T23:10:07.121Z,1537917007.121 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:10:08.202Z,1537917008.202 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.003616 ppb
2018-09-25T23:10:08.825Z,1537917008.825 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	648		247.553		95.334		24.638		28.582		29.810		39.072		9.262		718.6		873.7		19.6	



2018-09-25T23:10:08.825Z,1537917008.825 [Aanderaa_O2](DEBUG): Error parsing device response
2018-09-25T23:10:09.002Z,1537917009.002 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 2.995520 ppb
2018-09-25T23:10:10.023Z,1537917010.023 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	648		248.023		95.501		24.630		28.564		29.792		39.052		9.260		717.9		872.8		19.9	



2018-09-25T23:10:10.023Z,1537917010.023 [Aanderaa_O2](DEBUG): Error parsing device response
2018-09-25T23:10:10.278Z,1537917010.278 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 2.598816 ppb
2018-09-25T23:10:10.442Z,1537917010.442 [NAL9602](INFO): Powering down
2018-09-25T23:10:10.828Z,1537917010.828 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	648		248.605		95.722		24.628		28.539		29.767		39.025		9.257		712.5		872.6		19.9	

2018-09-25T23:10:10.828Z,1537917010.828 [Aanderaa_O2](DEBUG): Error parsing device response
2018-09-25T23:10:11.166Z,1537917011.166 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 2.639296 ppb
2018-09-25T23:10:12.182Z,1537917012.182 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.173632 ppb
2018-09-25T23:10:13.223Z,1537917013.223 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	648		248.433		95.663		24.632		28.545		29.773		39.029		9.256		706.1		871.9		19.8	

2018-09-25T23:10:13.223Z,1537917013.223 [Aanderaa_O2](DEBUG): Error parsing device response
2018-09-25T23:10:13.386Z,1537917013.386 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.238400 ppb
2018-09-25T23:10:14.634Z,1537917014.634 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.335552 ppb
2018-09-25T23:10:15.614Z,1537917015.614 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.691776 ppb
2018-09-25T23:10:16.603Z,1537917016.603 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.554144 ppb
2018-09-25T23:10:17.813Z,1537917017.813 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.853696 ppb
2018-09-25T23:10:18.674Z,1537917018.674 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 4.234208 ppb
2018-09-25T23:10:19.105Z,1537917019.105 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:10:19.811Z,1537917019.811 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.902272 ppb
2018-09-25T23:10:19.921Z,1537917019.921 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:10:20.937Z,1537917020.937 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:10:21.004Z,1537917021.004 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.805120 ppb
2018-09-25T23:10:21.818Z,1537917021.818 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.707968 ppb
2018-09-25T23:10:21.953Z,1537917021.953 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:10:23.146Z,1537917023.146 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.805120 ppb
2018-09-25T23:10:23.875Z,1537917023.875 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.821312 ppb
2018-09-25T23:10:24.593Z,1537917024.593 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:10:24.797Z,1537917024.797 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:10:25.026Z,1537917025.026 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.967040 ppb
2018-09-25T23:10:25.629Z,1537917025.629 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:10:26.328Z,1537917026.328 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.934656 ppb
2018-09-25T23:10:27.156Z,1537917027.156 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.772736 ppb
2018-09-25T23:10:28.202Z,1537917028.202 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.732256 ppb
2018-09-25T23:10:29.265Z,1537917029.265 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:10:29.442Z,1537917029.442 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.740352 ppb
2018-09-25T23:10:30.226Z,1537917030.226 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.748448 ppb
2018-09-25T23:10:31.558Z,1537917031.558 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.732256 ppb
2018-09-25T23:10:32.673Z,1537917032.673 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.748448 ppb
2018-09-25T23:10:33.737Z,1537917033.737 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:10:33.961Z,1537917033.961 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:10:37.046Z,1537917037.046 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.732256 ppb
2018-09-25T23:10:37.398Z,1537917037.398 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:10:37.585Z,1537917037.585 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:10:38.131Z,1537917038.131 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.707968 ppb
2018-09-25T23:10:38.924Z,1537917038.924 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.675584 ppb
2018-09-25T23:10:40.041Z,1537917040.041 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.675584 ppb
2018-09-25T23:10:41.234Z,1537917041.234 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.691776 ppb
2018-09-25T23:10:42.130Z,1537917042.130 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.683680 ppb
2018-09-25T23:10:42.739Z,1537917042.739 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2018-09-25T23:10:43.068Z,1537917043.068 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:10:43.253Z,1537917043.253 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.691776 ppb
2018-09-25T23:10:44.090Z,1537917044.090 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:10:44.431Z,1537917044.431 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.675584 ppb
2018-09-25T23:10:45.258Z,1537917045.258 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.683680 ppb
2018-09-25T23:10:46.570Z,1537917046.570 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.691776 ppb
2018-09-25T23:10:46.937Z,1537917046.937 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:10:47.690Z,1537917047.690 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.659392 ppb
2018-09-25T23:10:48.488Z,1537917048.488 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.716064 ppb
2018-09-25T23:10:49.679Z,1537917049.679 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.699872 ppb
2018-09-25T23:10:50.597Z,1537917050.597 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:10:50.989Z,1537917050.989 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.707968 ppb
2018-09-25T23:10:51.194Z,1537917051.194 [CommandLine](IMPORTANT): got command report
2018-09-25T23:10:51.194Z,1537917051.194 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate reporting when touched
2018-09-25T23:10:51.194Z,1537917051.194 [Reporter](INFO): trackPatchFDOM:Science.PeakFDOM reporting when touched
2018-09-25T23:10:51.660Z,1537917051.660 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.707968 ppb
2018-09-25T23:10:52.866Z,1537917052.866 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.602720 ppb
2018-09-25T23:10:53.429Z,1537917053.429 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:10:53.679Z,1537917053.679 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 3.643200 ppb
2018-09-25T23:10:54.253Z,1537917054.253 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:10:55.018Z,1537917055.018 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 4.039904 ppb
2018-09-25T23:10:56.030Z,1537917056.030 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 4.841408 ppb
2018-09-25T23:10:56.891Z,1537917056.891 [Reporter](INFO): concentration_of_colored_dissolved_organic_matter_in_sea_water_expressed_as_equivalent_mass_fraction_of_quinine_sulfate_dihydrate 4.080384 ppb
2018-09-25T23:10:57.591Z,1537917057.591 [CommandLine](IMPORTANT): got command report clear
2018-09-25T23:11:00.749Z,1537917060.749 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:11:01.757Z,1537917061.757 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:11:02.954Z,1537917062.954 [CommandLine](IMPORTANT): got command show variable peakFDOM
2018-09-25T23:11:03.225Z,1537917063.225 [CommandLine](IMPORTANT): trackPatchFDOM:Science.PeakFDOM (part_per_billion)
2018-09-25T23:11:03.225Z,1537917063.225 [CommandLine](IMPORTANT): trackPatchFDOM:Science.PeakFDOMDepth (meter)
2018-09-25T23:11:03.226Z,1537917063.226 [CommandLine](IMPORTANT): trackPatchFDOM:Science.PeakFDOMLatitude (degree)
2018-09-25T23:11:03.227Z,1537917063.227 [CommandLine](IMPORTANT): trackPatchFDOM:Science.PeakFDOMLongitude (degree)
2018-09-25T23:11:09.073Z,1537917069.073 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:11:10.093Z,1537917070.093 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:11:10.905Z,1537917070.905 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:11:12.447Z,1537917072.447 [CommandLine](IMPORTANT): got command report touch trackPatchFDOM:Science.PeakFDOM
2018-09-25T23:11:13.961Z,1537917073.961 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:11:17.413Z,1537917077.413 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:11:18.485Z,1537917078.485 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:11:20.241Z,1537917080.241 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:11:22.078Z,1537917082.078 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:11:24.956Z,1537917084.956 [RDI_Pathfinder](ERROR): only read 3 of 5 data items
2018-09-25T23:11:24.956Z,1537917084.956 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,32768,-32768,-32768,V
2018-09-25T23:11:25.730Z,1537917085.730 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:11:35.885Z,1537917095.885 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:11:38.729Z,1537917098.729 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:11:39.537Z,1537917099.537 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:11:44.217Z,1537917104.217 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:11:51.733Z,1537917111.733 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:11:59.223Z,1537917119.223 [CommandLine](IMPORTANT): got command get Onboard.Humidity
2018-09-25T23:11:59.223Z,1537917119.223 [CommandLine](IMPORTANT): Onboard.Humidity 29.294348 %
2018-09-25T23:12:00.457Z,1537917120.457 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:12:02.615Z,1537917122.615 [CommandLine](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch
2018-09-25T23:12:02.616Z,1537917122.616 [CommandLine](IMPORTANT): Onboard.Pressure 9.594965 psi
2018-09-25T23:12:18.545Z,1537917138.545 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:12:21.393Z,1537917141.393 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:12:21.789Z,1537917141.789 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:12:25.037Z,1537917145.037 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:12:26.873Z,1537917146.873 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:12:27.685Z,1537917147.685 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:12:30.529Z,1537917150.529 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:12:31.536Z,1537917151.536 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2018-09-25T23:12:31.542Z,1537917151.542 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0    +0.00,   0.00,315.00
2018-09-25T23:12:31.898Z,1537917151.898 [Radio_Surface](INFO): Powering down
2018-09-25T23:12:32.758Z,1537917152.758 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 0.00 mm (1 active estimators).
2018-09-25T23:12:33.365Z,1537917153.365 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:12:35.805Z,1537917155.805 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:12:37.241Z,1537917157.241 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:12:38.737Z,1537917158.737 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch -20.00 deg, mass-position 0.00 mm (2 active estimators).
2018-09-25T23:12:41.110Z,1537917161.110 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:12:41.949Z,1537917161.949 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:12:42.407Z,1537917162.407 [Radio_Surface](INFO): Powering up
2018-09-25T23:12:44.773Z,1537917164.773 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:12:47.617Z,1537917167.617 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:12:48.062Z,1537917168.062 [Radio_Surface](INFO): Powering down
2018-09-25T23:12:49.245Z,1537917169.245 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:12:52.838Z,1537917172.838 [CTD_NeilBrown](ERROR): Bad response:   0.58
2018-09-25T23:12:53.548Z,1537917173.548 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2018-09-25T23:12:53.549Z,1537917173.549 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-09-25T23:12:53.549Z,1537917173.549 [trackPatchFDOM:Science:PeakDetectFDOM:B] Running Loop=1
2018-09-25T23:12:53.626Z,1537917173.626 [Reporter](INFO): trackPatchFDOM:Science.PeakFDOM 0.453376 ppb
2018-09-25T23:12:53.981Z,1537917173.981 [trackPatchFDOM:Science:PeakDetectFDOM:B] Stopped
2018-09-25T23:12:53.981Z,1537917173.981 [trackPatchFDOM:Science:PeakDetectFDOM](INFO): Completed trackPatchFDOM:Science:PeakDetectFDOM
2018-09-25T23:12:54.121Z,1537917174.121 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:12:54.126Z,1537917174.126 [Radio_Surface](INFO): Powering up
2018-09-25T23:12:54.351Z,1537917174.351 [trackPatchFDOM:Science:PeakDetectFDOM] Running Loop=1
2018-09-25T23:12:54.352Z,1537917174.352 [trackPatchFDOM:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize trackPatchFDOM:Science:PeakDetectFDOM
2018-09-25T23:12:54.352Z,1537917174.352 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2018-09-25T23:12:54.352Z,1537917174.352 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-09-25T23:12:55.957Z,1537917175.957 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:12:57.785Z,1537917177.785 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:12:57.989Z,1537917177.989 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:12:59.609Z,1537917179.609 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:12:59.817Z,1537917179.817 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:13:05.301Z,1537917185.301 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:13:05.856Z,1537917185.856 [Radio_Surface](INFO): Powering down
2018-09-25T23:13:07.153Z,1537917187.153 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:13:08.145Z,1537917188.145 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:13:08.958Z,1537917188.958 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:13:10.785Z,1537917190.785 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:13:11.801Z,1537917191.801 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:13:12.354Z,1537917192.354 [Radio_Surface](INFO): Powering up
2018-09-25T23:13:12.613Z,1537917192.613 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:13:18.293Z,1537917198.293 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:13:22.769Z,1537917202.769 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:13:23.085Z,1537917203.085 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.004620
2018-09-25T23:13:23.298Z,1537917203.298 [Radio_Surface](INFO): Powering down
2018-09-25T23:13:30.489Z,1537917210.489 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:13:30.974Z,1537917210.974 [Radio_Surface](INFO): Powering up
2018-09-25T23:13:32.317Z,1537917212.317 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:13:33.341Z,1537917213.341 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:13:34.153Z,1537917214.153 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:13:39.841Z,1537917219.841 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:13:40.653Z,1537917220.653 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:13:45.317Z,1537917225.317 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:13:49.997Z,1537917229.997 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:13:53.642Z,1537917233.642 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:13:57.309Z,1537917237.309 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:13:58.327Z,1537917238.327 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-25T23:14:02.930Z,1537917242.930 [Radio_Surface](INFO): Powering down
2018-09-25T23:14:08.089Z,1537917248.089 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:14:08.289Z,1537917248.289 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:14:08.697Z,1537917248.697 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:14:09.109Z,1537917249.109 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:14:10.347Z,1537917250.347 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2018-09-25T23:14:10.348Z,1537917250.348 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.0  +0.00,        +0.00,   0.00,414.99
2018-09-25T23:14:12.785Z,1537917252.785 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:14:12.977Z,1537917252.977 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:14:18.694Z,1537917258.694 [Radio_Surface](INFO): Powering up
2018-09-25T23:14:19.277Z,1537917259.277 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:14:20.289Z,1537917260.289 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:14:22.121Z,1537917262.121 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:14:28.417Z,1537917268.417 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:14:31.485Z,1537917271.485 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:14:33.884Z,1537917273.884 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-25T23:14:34.105Z,1537917274.105 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:14:36.474Z,1537917276.474 [Radio_Surface](INFO): Powering down
2018-09-25T23:14:36.949Z,1537917276.949 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:14:37.603Z,1537917277.603 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2018-09-25T23:14:37.603Z,1537917277.603 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-09-25T23:14:37.603Z,1537917277.603 [trackPatchFDOM:Science:PeakDetectFDOM:B] Running Loop=1
2018-09-25T23:14:37.730Z,1537917277.730 [Reporter](INFO): trackPatchFDOM:Science.PeakFDOM 0.455400 ppb
2018-09-25T23:14:37.964Z,1537917277.964 [trackPatchFDOM:Science:PeakDetectFDOM:B] Stopped
2018-09-25T23:14:37.964Z,1537917277.964 [trackPatchFDOM:Science:PeakDetectFDOM](INFO): Completed trackPatchFDOM:Science:PeakDetectFDOM
2018-09-25T23:14:38.416Z,1537917278.416 [trackPatchFDOM:Science:PeakDetectFDOM] Running Loop=1
2018-09-25T23:14:38.416Z,1537917278.416 [trackPatchFDOM:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize trackPatchFDOM:Science:PeakDetectFDOM
2018-09-25T23:14:38.416Z,1537917278.416 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2018-09-25T23:14:38.416Z,1537917278.416 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-09-25T23:14:40.747Z,1537917280.747 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-25T23:14:40.747Z,1537917280.747 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18092516194705,35.0, -0.1,   0.0,10
2018-09-25T23:14:41.613Z,1537917281.613 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:14:42.546Z,1537917282.546 [Radio_Surface](INFO): Powering up
2018-09-25T23:14:48.121Z,1537917288.121 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:14:50.965Z,1537917290.965 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:14:52.793Z,1537917292.793 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:14:53.977Z,1537917293.977 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2018-09-25T23:14:53.977Z,1537917293.977 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-09-25T23:14:53.977Z,1537917293.977 [trackPatchFDOM:Science:PeakDetectFDOM:B] Running Loop=1
2018-09-25T23:14:54.035Z,1537917294.035 [Reporter](INFO): trackPatchFDOM:Science.PeakFDOM 0.464710 ppb
2018-09-25T23:14:54.368Z,1537917294.368 [trackPatchFDOM:Science:PeakDetectFDOM:B] Stopped
2018-09-25T23:14:54.368Z,1537917294.368 [trackPatchFDOM:Science:PeakDetectFDOM](INFO): Completed trackPatchFDOM:Science:PeakDetectFDOM
2018-09-25T23:14:54.690Z,1537917294.690 [Radio_Surface](INFO): Powering down
2018-09-25T23:14:54.800Z,1537917294.800 [trackPatchFDOM:Science:PeakDetectFDOM] Running Loop=1
2018-09-25T23:14:54.801Z,1537917294.801 [trackPatchFDOM:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize trackPatchFDOM:Science:PeakDetectFDOM
2018-09-25T23:14:54.801Z,1537917294.801 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2018-09-25T23:14:54.801Z,1537917294.801 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-09-25T23:15:00.346Z,1537917300.346 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2018-09-25T23:15:00.347Z,1537917300.347 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0    +0.00,        +0.00,   0.00,464.99
2018-09-25T23:15:01.741Z,1537917301.741 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:15:02.365Z,1537917302.365 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:15:03.582Z,1537917303.582 [Radio_Surface](INFO): Powering up
2018-09-25T23:15:08.249Z,1537917308.249 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:15:09.081Z,1537917309.081 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-25T23:15:11.536Z,1537917311.536 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2018-09-25T23:15:11.536Z,1537917311.536 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0    +0.00,        +0.00,   0.0
2018-09-25T23:15:11.905Z,1537917311.905 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:15:12.718Z,1537917312.718 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:15:14.734Z,1537917314.734 [RDI_Pathfinder](ERROR): only read 2 of 5 data items
2018-09-25T23:15:14.735Z,1537917314.735 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768
2018-09-25T23:15:20.225Z,1537917320.225 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:15:21.045Z,1537917321.045 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:15:22.061Z,1537917322.061 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:15:30.350Z,1537917330.350 [RDI_Pathfinder](ERROR): Unknown system health message
2018-09-25T23:15:30.350Z,1537917330.350 [RDI_Pathfinder] Hardware Fault, FailCount= 1
2018-09-25T23:15:30.350Z,1537917330.350 [RDI_Pathfinder](ERROR): Hardware Fault
2018-09-25T23:15:30.481Z,1537917330.481 [CBIT](ERROR): Hardware Fault in component: RDI_Pathfinder
2018-09-25T23:15:30.777Z,1537917330.777 [RDI_Pathfinder](INFO): Powering down
2018-09-25T23:15:31.621Z,1537917331.621 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2018-09-25T23:15:31.621Z,1537917331.621 [RDI_Pathfinder] No Fault, FailCount= 0
2018-09-25T23:15:40.545Z,1537917340.545 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:15:43.381Z,1537917343.381 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:15:44.286Z,1537917344.286 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-25T23:15:45.124Z,1537917345.124 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2018-09-25T23:15:47.282Z,1537917347.282 [CommandLine](IMPORTANT): got command show stack
2018-09-25T23:15:47.282Z,1537917347.282 [CommandLine](IMPORTANT): Behavior Stack:

2018-09-25T23:15:47.283Z,1537917347.283 [trackPatchFDOM](IMPORTANT): Priority 0: trackPatchFDOM:A.AltitudeEnvelope
2018-09-25T23:15:47.283Z,1537917347.283 [trackPatchFDOM](IMPORTANT): Priority 1: trackPatchFDOM:B.DepthEnvelope
2018-09-25T23:15:47.283Z,1537917347.283 [trackPatchFDOM](IMPORTANT): Priority 2: trackPatchFDOM:F.OffshoreEnvelope
2018-09-25T23:15:47.284Z,1537917347.284 [trackPatchFDOM](IMPORTANT): Priority 3: trackPatchFDOM:G.WaterDepthEnvelope
2018-09-25T23:15:47.284Z,1537917347.284 [trackPatchFDOM](IMPORTANT): Priority 4: trackPatchFDOM:H.Pitch
2018-09-25T23:15:47.284Z,1537917347.284 [trackPatchFDOM](IMPORTANT): Priority 5: trackPatchFDOM:I.Buoyancy
2018-09-25T23:15:47.286Z,1537917347.286 [trackPatchFDOM:Science](IMPORTANT): Priority 6: trackPatchFDOM:Science:A
2018-09-25T23:15:47.286Z,1537917347.286 [trackPatchFDOM:Science](IMPORTANT): Priority 7: trackPatchFDOM:Science:C
2018-09-25T23:15:47.287Z,1537917347.287 [trackPatchFDOM:Science](IMPORTANT): Priority 8: trackPatchFDOM:Science:Read_Oil
2018-09-25T23:15:47.287Z,1537917347.287 [trackPatchFDOM:Science:PeakDetectFDOM](IMPORTANT): Priority 9: trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth
2018-09-25T23:15:47.287Z,1537917347.287 [trackPatchFDOM](IMPORTANT): Priority 10: trackPatchFDOM:K.SetSpeed
2018-09-25T23:15:47.288Z,1537917347.288 [trackPatchFDOM](IMPORTANT): Priority 11: trackPatchFDOM:L.DepthEnvelope
2018-09-25T23:15:47.288Z,1537917347.288 [trackPatchFDOM](IMPORTANT): Priority 12: trackPatchFDOM:M.YoYo
2018-09-25T23:15:47.289Z,1537917347.289 [trackPatchFDOM:PatchDetectHorizontal:PatchDetection](IMPORTANT): Priority 13: trackPatchFDOM:PatchDetectHorizontal:PatchDetection:PeakDetectHorizontalTrigger.PeakDetectHorizontal
2018-09-25T23:15:47.289Z,1537917347.289 [trackPatchFDOM:PatchDetectHorizontal:CrossLeg](IMPORTANT): Priority 14: trackPatchFDOM:PatchDetectHorizontal:CrossLeg:CrossPeakWp.Waypoint
2018-09-25T23:15:51.330Z,1537917351.330 [CommandLine](IMPORTANT): got command report
2018-09-25T23:15:51.330Z,1537917351.330 [Reporter](INFO): trackPatchFDOM:Science.PeakFDOM reporting when touched
2018-09-25T23:15:54.361Z,1537917354.361 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:15:58.013Z,1537917358.013 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:15:59.021Z,1537917359.021 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:16:13.037Z,1537917373.037 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:16:13.877Z,1537917373.877 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:16:13.958Z,1537917373.958 [Radio_Surface](INFO): Powering down
2018-09-25T23:16:16.285Z,1537917376.285 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:16:19.094Z,1537917379.094 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-25T23:16:21.161Z,1537917381.161 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:16:22.997Z,1537917382.997 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:16:24.013Z,1537917384.013 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:16:24.062Z,1537917384.062 [Radio_Surface](INFO): Powering up
2018-09-25T23:16:24.817Z,1537917384.817 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:16:25.837Z,1537917385.837 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:16:31.317Z,1537917391.317 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:16:34.163Z,1537917394.163 [Radio_Surface](INFO): Powering down
2018-09-25T23:16:39.653Z,1537917399.653 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:16:40.685Z,1537917400.685 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:16:44.294Z,1537917404.294 [Radio_Surface](INFO): Powering up
2018-09-25T23:16:44.321Z,1537917404.321 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:16:45.341Z,1537917405.341 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:16:52.645Z,1537917412.645 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:16:54.297Z,1537917414.297 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-25T23:16:55.497Z,1537917415.497 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:16:57.325Z,1537917417.325 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:16:59.654Z,1537917419.654 [Radio_Surface](INFO): Powering down
2018-09-25T23:17:00.985Z,1537917420.985 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:17:01.185Z,1537917421.185 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:17:03.551Z,1537917423.551 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2018-09-25T23:17:03.825Z,1537917423.825 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:17:08.542Z,1537917428.542 [Radio_Surface](INFO): Powering up
2018-09-25T23:17:09.309Z,1537917429.309 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:17:16.817Z,1537917436.817 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:17:17.637Z,1537917437.637 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:17:18.653Z,1537917438.653 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:17:19.046Z,1537917439.046 [Radio_Surface](INFO): Powering down
2018-09-25T23:17:20.473Z,1537917440.473 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:17:21.081Z,1537917441.081 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:17:22.305Z,1537917442.305 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:17:29.885Z,1537917449.885 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-25T23:17:29.966Z,1537917449.966 [Radio_Surface](INFO): Powering up
2018-09-25T23:17:31.049Z,1537917451.049 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:17:32.885Z,1537917452.885 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:17:33.484Z,1537917453.484 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:17:36.835Z,1537917456.835 [Radio_Surface](INFO): Powering down
2018-09-25T23:17:38.573Z,1537917458.573 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:17:39.389Z,1537917459.389 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:17:42.033Z,1537917462.033 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:17:42.894Z,1537917462.894 [Radio_Surface](INFO): Powering up
2018-09-25T23:17:49.359Z,1537917469.359 [Radio_Surface](INFO): Powering down
2018-09-25T23:17:52.377Z,1537917472.377 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:17:55.229Z,1537917475.229 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:17:55.418Z,1537917475.418 [Radio_Surface](INFO): Powering up
2018-09-25T23:17:56.041Z,1537917476.041 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:18:00.718Z,1537917480.718 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:18:01.095Z,1537917481.095 [Radio_Surface](INFO): Powering down
2018-09-25T23:18:01.313Z,1537917481.313 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:18:02.749Z,1537917482.749 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:18:04.686Z,1537917484.686 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-25T23:18:10.281Z,1537917490.281 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:18:10.386Z,1537917490.386 [Radio_Surface](INFO): Powering up
2018-09-25T23:18:12.117Z,1537917492.117 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:18:14.961Z,1537917494.961 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:18:16.781Z,1537917496.781 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:18:17.601Z,1537917497.601 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:18:25.113Z,1537917505.113 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:18:36.085Z,1537917516.085 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:18:37.097Z,1537917517.097 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:18:38.929Z,1537917518.929 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:18:39.889Z,1537917519.889 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-25T23:18:42.373Z,1537917522.373 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:18:48.277Z,1537917528.277 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:18:49.901Z,1537917529.901 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:18:50.102Z,1537917530.102 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:18:52.822Z,1537917532.822 [Radio_Surface](INFO): Powering down
2018-09-25T23:18:53.757Z,1537917533.757 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:18:59.286Z,1537917539.286 [Radio_Surface](INFO): Powering up
2018-09-25T23:19:03.913Z,1537917543.913 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:19:08.585Z,1537917548.585 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:19:11.429Z,1537917551.429 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:19:15.080Z,1537917555.080 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-25T23:19:15.889Z,1537917555.889 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:19:21.538Z,1537917561.538 [Radio_Surface](INFO): Powering down
2018-09-25T23:19:22.775Z,1537917562.775 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2018-09-25T23:19:22.775Z,1537917562.775 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-09-25T23:19:22.775Z,1537917562.775 [trackPatchFDOM:Science:PeakDetectFDOM:B] Running Loop=1
2018-09-25T23:19:22.890Z,1537917562.890 [Reporter](INFO): trackPatchFDOM:Science.PeakFDOM 0.475236 ppb
2018-09-25T23:19:23.202Z,1537917563.202 [trackPatchFDOM:Science:PeakDetectFDOM:B] Stopped
2018-09-25T23:19:23.202Z,1537917563.202 [trackPatchFDOM:Science:PeakDetectFDOM](INFO): Completed trackPatchFDOM:Science:PeakDetectFDOM
2018-09-25T23:19:23.597Z,1537917563.597 [trackPatchFDOM:Science:PeakDetectFDOM] Running Loop=1
2018-09-25T23:19:23.597Z,1537917563.597 [trackPatchFDOM:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize trackPatchFDOM:Science:PeakDetectFDOM
2018-09-25T23:19:23.597Z,1537917563.597 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2018-09-25T23:19:23.597Z,1537917563.597 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-09-25T23:19:25.241Z,1537917565.241 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:19:26.756Z,1537917566.756 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2018-09-25T23:19:26.761Z,1537917566.761 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-09-25T23:19:26.761Z,1537917566.761 [trackPatchFDOM:Science:PeakDetectFDOM:B] Running Loop=1
2018-09-25T23:19:26.846Z,1537917566.846 [Reporter](INFO): trackPatchFDOM:Science.PeakFDOM 0.476045 ppb
2018-09-25T23:19:26.865Z,1537917566.865 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:19:27.186Z,1537917567.186 [trackPatchFDOM:Science:PeakDetectFDOM:B] Stopped
2018-09-25T23:19:27.186Z,1537917567.186 [trackPatchFDOM:Science:PeakDetectFDOM](INFO): Completed trackPatchFDOM:Science:PeakDetectFDOM
2018-09-25T23:19:27.561Z,1537917567.561 [trackPatchFDOM:Science:PeakDetectFDOM] Running Loop=1
2018-09-25T23:19:27.561Z,1537917567.561 [trackPatchFDOM:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize trackPatchFDOM:Science:PeakDetectFDOM
2018-09-25T23:19:27.561Z,1537917567.561 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2018-09-25T23:19:27.561Z,1537917567.561 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-09-25T23:19:28.414Z,1537917568.414 [Radio_Surface](INFO): Powering up
2018-09-25T23:19:28.898Z,1537917568.898 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:19:29.709Z,1537917569.709 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:19:34.585Z,1537917574.585 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:19:40.069Z,1537917580.069 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:19:41.082Z,1537917581.082 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:19:41.893Z,1537917581.893 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:19:43.725Z,1537917583.725 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:19:45.948Z,1537917585.948 [RDI_Pathfinder](ERROR): Failed to parse:

2018-09-25T23:19:46.569Z,1537917586.569 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:19:49.413Z,1537917589.413 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:19:49.426Z,1537917589.426 [Radio_Surface](INFO): Powering down
2018-09-25T23:19:50.689Z,1537917590.689 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-25T23:19:52.750Z,1537917592.750 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2018-09-25T23:19:52.750Z,1537917592.750 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-09-25T23:19:52.751Z,1537917592.751 [trackPatchFDOM:Science:PeakDetectFDOM:B] Running Loop=1
2018-09-25T23:19:52.834Z,1537917592.834 [Reporter](INFO): trackPatchFDOM:Science.PeakFDOM 0.474831 ppb
2018-09-25T23:19:53.199Z,1537917593.199 [trackPatchFDOM:Science:PeakDetectFDOM:B] Stopped
2018-09-25T23:19:53.200Z,1537917593.200 [trackPatchFDOM:Science:PeakDetectFDOM](INFO): Completed trackPatchFDOM:Science:PeakDetectFDOM
2018-09-25T23:19:53.585Z,1537917593.585 [trackPatchFDOM:Science:PeakDetectFDOM] Running Loop=1
2018-09-25T23:19:53.585Z,1537917593.585 [trackPatchFDOM:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize trackPatchFDOM:Science:PeakDetectFDOM
2018-09-25T23:19:53.586Z,1537917593.586 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2018-09-25T23:19:53.586Z,1537917593.586 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-09-25T23:19:55.702Z,1537917595.702 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:19:56.772Z,1537917596.772 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2018-09-25T23:19:56.772Z,1537917596.772 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-09-25T23:19:56.777Z,1537917596.777 [trackPatchFDOM:Science:PeakDetectFDOM:B] Running Loop=1
2018-09-25T23:19:56.863Z,1537917596.863 [Reporter](INFO): trackPatchFDOM:Science.PeakFDOM 0.472807 ppb
2018-09-25T23:19:57.177Z,1537917597.177 [trackPatchFDOM:Science:PeakDetectFDOM:B] Stopped
2018-09-25T23:19:57.178Z,1537917597.178 [trackPatchFDOM:Science:PeakDetectFDOM](INFO): Completed trackPatchFDOM:Science:PeakDetectFDOM
2018-09-25T23:19:57.562Z,1537917597.562 [trackPatchFDOM:Science:PeakDetectFDOM] Running Loop=1
2018-09-25T23:19:57.563Z,1537917597.563 [trackPatchFDOM:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize trackPatchFDOM:Science:PeakDetectFDOM
2018-09-25T23:19:57.563Z,1537917597.563 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2018-09-25T23:19:57.564Z,1537917597.564 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2018-09-25T23:19:59.569Z,1537917599.569 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:20:01.401Z,1537917601.401 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:20:03.221Z,1537917603.221 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:20:06.069Z,1537917606.069 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:20:18.702Z,1537917618.702 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:20:19.717Z,1537917619.717 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:20:20.158Z,1537917620.158 [Radio_Surface](INFO): Powering up
2018-09-25T23:20:20.529Z,1537917620.529 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:20:25.480Z,1537917625.480 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-25T23:20:27.021Z,1537917627.021 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:20:28.045Z,1537917628.045 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:20:30.677Z,1537917630.677 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:20:33.549Z,1537917633.549 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:20:37.997Z,1537917637.997 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:20:41.045Z,1537917641.045 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:20:41.876Z,1537917641.876 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:20:42.873Z,1537917642.873 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:20:44.693Z,1537917644.693 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:20:45.239Z,1537917645.239 [Radio_Surface](INFO): Powering down
2018-09-25T23:20:48.357Z,1537917648.357 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:20:48.957Z,1537917648.957 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:20:56.505Z,1537917656.505 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:20:56.705Z,1537917656.705 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:20:57.117Z,1537917657.117 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:20:57.325Z,1537917657.325 [CTD_NeilBrown](DEBUG): serial timeout
2018-09-25T23:20:58.383Z,1537917658.383 [VerticalControl](CRITICAL): Excessive depth excursion=13.445644 m, failToGoUpDepth_=5.232462 m, depthRate=0.614393 m/s, pitch=-3.067076 deg.
2018-09-25T23:20:58.423Z,1537917658.423 [CommandLine](FAULT): Scheduling is paused
2018-09-25T23:20:58.423Z,1537917658.423 [CBIT](INFO): Critical error at 20180925T232058
2018-09-25T23:20:58.424Z,1537917658.424 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-09-25T23:20:58.815Z,1537917658.815 [MissionManager](INFO): MissionManager is completed.
2018-09-25T23:20:58.815Z,1537917658.815 [MissionManager](INFO): Uninitializing Mission trackPatchFDOM
2018-09-25T23:20:58.815Z,1537917658.815 [trackPatchFDOM] Stopped
2018-09-25T23:20:58.815Z,1537917658.815 [trackPatchFDOM](DEBUG): Aggregate::uninitialize trackPatchFDOM
2018-09-25T23:20:58.816Z,1537917658.816 [trackPatchFDOM:A.AltitudeEnvelope] Stopped
2018-09-25T23:20:58.816Z,1537917658.816 [trackPatchFDOM:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2018-09-25T23:20:58.816Z,1537917658.816 [trackPatchFDOM:B.DepthEnvelope] Stopped
2018-09-25T23:20:58.816Z,1537917658.816 [trackPatchFDOM:B.DepthEnvelope](DEBUG): Uninitialize.
2018-09-25T23:20:58.816Z,1537917658.816 [trackPatchFDOM:F.OffshoreEnvelope] Stopped
2018-09-25T23:20:58.816Z,1537917658.816 [trackPatchFDOM:F.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2018-09-25T23:20:58.816Z,1537917658.816 [trackPatchFDOM:G.WaterDepthEnvelope] Stopped
2018-09-25T23:20:58.816Z,1537917658.816 [trackPatchFDOM:G.WaterDepthEnvelope](DEBUG): Uninitialize WaterDepthEnvelopeComponent.
2018-09-25T23:20:58.816Z,1537917658.816 [trackPatchFDOM:H.Pitch] Stopped
2018-09-25T23:20:58.816Z,1537917658.816 [trackPatchFDOM:I.Buoyancy] Stopped
2018-09-25T23:20:58.816Z,1537917658.816 [trackPatchFDOM:I.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2018-09-25T23:20:58.816Z,1537917658.816 [trackPatchFDOM:Science] Stopped
2018-09-25T23:20:58.825Z,1537917658.825 [trackPatchFDOM:Science](DEBUG): Aggregate::uninitialize trackPatchFDOM:Science
2018-09-25T23:20:58.825Z,1537917658.825 [trackPatchFDOM:Science:A] Stopped
2018-09-25T23:20:58.825Z,1537917658.825 [trackPatchFDOM:Science:C] Stopped
2018-09-25T23:20:58.825Z,1537917658.825 [trackPatchFDOM:Science:Read_Oil] Stopped
2018-09-25T23:20:58.825Z,1537917658.825 [trackPatchFDOM:Science:PeakDetectFDOM] Stopped
2018-09-25T23:20:58.825Z,1537917658.825 [trackPatchFDOM:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize trackPatchFDOM:Science:PeakDetectFDOM
2018-09-25T23:20:58.825Z,1537917658.825 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2018-09-25T23:20:58.826Z,1537917658.826 [trackPatchFDOM:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2018-09-25T23:20:58.826Z,1537917658.826 [trackPatchFDOM:K.SetSpeed] Stopped
2018-09-25T23:20:58.826Z,1537917658.826 [trackPatchFDOM:K.SetSpeed](DEBUG): Uninitialize.
2018-09-25T23:20:58.826Z,1537917658.826 [trackPatchFDOM:L.DepthEnvelope] Stopped
2018-09-25T23:20:58.826Z,1537917658.826 [trackPatchFDOM:L.DepthEnvelope](DEBUG): Uninitialize.
2018-09-25T23:20:58.826Z,1537917658.826 [trackPatchFDOM:M.YoYo] Stopped
2018-09-25T23:20:58.826Z,1537917658.826 [trackPatchFDOM:M.YoYo](DEBUG): Uninitialize YoYoComponent.
2018-09-25T23:20:58.826Z,1537917658.826 [trackPatchFDOM:PatchDetectHorizontal] Stopped
2018-09-25T23:20:58.826Z,1537917658.826 [trackPatchFDOM:PatchDetectHorizontal](DEBUG): Aggregate::uninitialize trackPatchFDOM:PatchDetectHorizontal
2018-09-25T23:20:58.826Z,1537917658.826 [trackPatchFDOM:PatchDetectHorizontal:PatchDetection] Stopped
2018-09-25T23:20:58.826Z,1537917658.826 [trackPatchFDOM:PatchDetectHorizontal:PatchDetection](DEBUG): Aggregate::uninitialize trackPatchFDOM:PatchDetectHorizontal:PatchDetection
2018-09-25T23:20:58.827Z,1537917658.827 [trackPatchFDOM:PatchDetectHorizontal:PatchDetection:PeakDetectHorizontalTrigger.PeakDetectHorizontal] Stopped
2018-09-25T23:20:58.827Z,1537917658.827 [trackPatchFDOM:PatchDetectHorizontal:PatchDetection:PeakDetectHorizontalTrigger.PeakDetectHorizontal](DEBUG): Uninitialize.
2018-09-25T23:20:58.828Z,1537917658.828 [trackPatchFDOM:PatchDetectHorizontal:PatchDetection:PeakDetectHorizontalTrigger.PeakDetectHorizontal](INFO): Initialize.
2018-09-25T23:20:58.828Z,1537917658.828 [trackPatchFDOM:PatchDetectHorizontal:CrossLeg] Stopped
2018-09-25T23:20:58.828Z,1537917658.828 [trackPatchFDOM:PatchDetectHorizontal:CrossLeg](DEBUG): Aggregate::uninitialize trackPatchFDOM:PatchDetectHorizontal:CrossLeg
2018-09-25T23:20:58.828Z,1537917658.828 [trackPatchFDOM:PatchDetectHorizontal:CrossLeg:CrossPeakWp.Waypoint] Stopped
2018-09-25T23:20:58.828Z,1537917658.828 [trackPatchFDOM:PatchDetectHorizontal:CrossLeg:CrossPeakWp.Waypoint](DEBUG): Uninitialize WaypointComponent.
2018-09-25T23:20:59.160Z,1537917659.160 [MissionManager](IMPORTANT): Started mission Default
2018-09-25T23:20:59.160Z,1537917659.160 [Default] Running Loop=1
2018-09-25T23:20:59.160Z,1537917659.160 [Default](DEBUG): Aggregate::initialize Default
2018-09-25T23:20:59.160Z,1537917659.160 [Default:B.GoToSurface] Running Loop=1
2018-09-25T23:20:59.160Z,1537917659.160 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-25T23:20:59.165Z,1537917659.165 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-09-25T23:20:59.165Z,1537917659.165 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-09-25T23:20:59.166Z,1537917659.166 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-09-25T23:20:59.166Z,1537917659.166 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-25T23:20:59.166Z,1537917659.166 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-25T23:20:59.167Z,1537917659.167 [Default:A.Wait] Running Loop=1
2018-09-25T23:20:59.167Z,1537917659.167 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-09-25T23:20:59.169Z,1537917659.169 [WetLabsSeaOWL_UV_A](INFO): Powering down
2018-09-25T23:20:59.589Z,1537917659.589 [Aanderaa_O2](INFO): Powering down
2018-09-25T23:20:59.641Z,1537917659.641 [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-09-25T23:21:01.351Z,1537917661.351 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-25T23:21:08.537Z,1537917668.537 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2018-09-25T23:21:08.537Z,1537917668.537 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2018-09-25T23:21:08.537Z,1537917668.537 [BuoyancyServo] Communications Fault, FailCount= 1
2018-09-25T23:21:08.537Z,1537917668.537 [BuoyancyServo](ERROR): Communications Fault
2018-09-25T23:21:08.621Z,1537917668.621 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-09-25T23:21:08.736Z,1537917668.736 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-25T23:21:08.745Z,1537917668.745 [BuoyancyServo](INFO): Powering down
2018-09-25T23:21:10.307Z,1537917670.307 [BuoyancyServo](FAULT): LCB fault:  Current Limiter Activated.
2018-09-25T23:21:10.307Z,1537917670.307 [BuoyancyServo] Hardware Fault, FailCount= 1
2018-09-25T23:21:10.307Z,1537917670.307 [BuoyancyServo](ERROR): Hardware Fault
2018-09-25T23:21:10.921Z,1537917670.921 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-09-25T23:21:10.921Z,1537917670.921 [BuoyancyServo] No Fault, FailCount= 1
2018-09-25T23:21:11.135Z,1537917671.135 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-25T23:21:11.254Z,1537917671.254 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-25T23:21:12.324Z,1537917672.324 [Default:A.Wait](INFO): Done Waiting.
2018-09-25T23:21:12.324Z,1537917672.324 [Default:A.Wait] Stopped
2018-09-25T23:21:12.324Z,1537917672.324 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-09-25T23:21:32.714Z,1537917692.714 [Default:CheckIn] Running Loop=1
2018-09-25T23:21:32.715Z,1537917692.715 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-09-25T23:21:32.715Z,1537917692.715 [Default:CheckIn:Read_GPS] Running Loop=1
2018-09-25T23:21:32.935Z,1537917692.935 [Radio_Surface](INFO): Powering up
2018-09-25T23:21:33.458Z,1537917693.458 [NAL9602](INFO): Powering up
2018-09-25T23:21:35.996Z,1537917695.996 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-25T23:21:44.406Z,1537917704.406 [NAL9602](INFO): NAL9602 initialized
2018-09-25T23:21:45.202Z,1537917705.202 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-09-25T23:21:45.203Z,1537917705.203 [NAL9602] Data Fault, FailCount= 1
2018-09-25T23:21:45.203Z,1537917705.203 [NAL9602](ERROR): Data Fault
2018-09-25T23:21:45.362Z,1537917705.362 [CBIT](ERROR): Data Fault in component: NAL9602
2018-09-25T23:21:45.606Z,1537917705.606 [NAL9602](INFO): Powering down
2018-09-25T23:21:46.519Z,1537917706.519 [CBIT](INFO): Clearing failed state for component NAL9602
2018-09-25T23:21:46.519Z,1537917706.519 [NAL9602] No Fault, FailCount= 1
2018-09-25T23:22:11.200Z,1537917731.200 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-25T23:22:16.003Z,1537917736.003 [NAL9602](INFO): Powering up NAL9602
2018-09-25T23:22:20.507Z,1537917740.507 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1537917696.000000 second
2018-09-25T23:22:27.206Z,1537917747.206 [NAL9602](INFO): NAL9602 initialized
2018-09-25T23:22:31.171Z,1537917751.171 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1537917696.000000 second
2018-09-25T23:22:31.276Z,1537917751.276 [UniversalFixResidualReporter](INFO): Fix residual: 100.0 %DT, over the last 689.5 m. Residual distance 689.5 m at bearing -101.0 degrees. Fix at (36.8000, -121.9000) with 0.0 m made good.
2018-09-25T23:22:31.277Z,1537917751.277 [Default:CheckIn:Read_GPS] Stopped
2018-09-25T23:22:31.277Z,1537917751.277 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-09-25T23:22:46.040Z,1537917766.040 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-25T23:22:50.994Z,1537917770.994 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1537917824.000000 second
2018-09-25T23:23:21.640Z,1537917801.640 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-25T23:23:56.445Z,1537917836.445 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-09-25T23:24:07.059Z,1537917847.059 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002746
2018-09-25T23:24:11.293Z,1537917851.293 [DataOverHttps](INFO): Sending 389 bytes from file Logs/20180925T230345/Courier0013.lzma
2018-09-25T23:24:12.086Z,1537917852.086 [DataOverHttps](INFO): Moved sent file to Logs/20180925T230345/Courier0013.lzma.bak
2018-09-25T23:24:12.086Z,1537917852.086 [DataOverHttps](INFO): SBD MOMSN=8589899
2018-09-25T23:24:22.486Z,1537917862.486 [DataOverHttps](INFO): Sending 1805 bytes from file Logs/20180925T230345/Express0014.lzma
2018-09-25T23:24:23.253Z,1537917863.253 [DataOverHttps](INFO): Moved sent file to Logs/20180925T230345/Express0014.lzma.bak
2018-09-25T23:24:23.254Z,1537917863.254 [DataOverHttps](INFO): SBD MOMSN=8589902
2018-09-25T23:24:23.908Z,1537917863.908 [Default:CheckIn:Read_Iridium] Stopped
2018-09-25T23:24:23.908Z,1537917863.908 [Default:CheckIn:C.Wait] Running Loop=1
2018-09-25T23:24:23.908Z,1537917863.908 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-09-25T23:26:13.997Z,1537917973.997 [DataOverHttps](IMPORTANT): SBD MTMSN=20180925T232534
2018-09-25T23:26:18.154Z,1537917978.154 [DataOverHttps](INFO): Received command:restart logs