2019-07-25T00:02:19.058Z,1564012939.058 [Supervisor](DEBUG): Initializing supervisor.
2019-07-25T00:02:19.061Z,1564012939.061 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-07-25T00:02:19.061Z,1564012939.061 [SyncHandler](INFO): Protected caller Thread ID is 7511
2019-07-25T00:02:19.062Z,1564012939.062 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-07-25T00:02:19.063Z,1564012939.063 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-07-25T00:02:19.063Z,1564012939.063 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7512
2019-07-25T00:02:19.066Z,1564012939.066 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-07-25T00:02:19.077Z,1564012939.077 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-07-25T00:02:19.078Z,1564012939.078 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-07-25T00:02:19.079Z,1564012939.079 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7513
2019-07-25T00:02:19.080Z,1564012939.080 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-07-25T00:02:19.081Z,1564012939.081 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-07-25T00:02:19.081Z,1564012939.081 [logger ThreadHandler](INFO): Protected caller Thread ID is 7514
2019-07-25T00:02:19.083Z,1564012939.083 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-07-25T00:02:19.083Z,1564012939.083 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-07-25T00:02:19.085Z,1564012939.085 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-07-25T00:02:19.178Z,1564012939.178 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-07-25T00:02:19.179Z,1564012939.179 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-07-25T00:02:19.378Z,1564012939.378 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-07-25T00:02:19.378Z,1564012939.378 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-07-25T00:02:19.513Z,1564012939.513 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-07-25T00:02:19.514Z,1564012939.514 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-07-25T00:02:20.076Z,1564012940.076 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-07-25T00:02:20.076Z,1564012940.076 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-07-25T00:02:20.518Z,1564012940.518 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-07-25T00:02:20.518Z,1564012940.518 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-07-25T00:02:20.992Z,1564012940.992 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-07-25T00:02:20.993Z,1564012940.993 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-07-25T00:02:21.297Z,1564012941.297 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-07-25T00:02:21.298Z,1564012941.298 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-07-25T00:02:21.630Z,1564012941.630 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-07-25T00:02:21.631Z,1564012941.631 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-07-25T00:02:22.055Z,1564012942.055 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-07-25T00:02:22.055Z,1564012942.055 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-07-25T00:02:22.204Z,1564012942.204 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-07-25T00:02:22.205Z,1564012942.205 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-07-25T00:02:22.308Z,1564012942.308 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-07-25T00:02:22.309Z,1564012942.309 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-07-25T00:02:22.388Z,1564012942.388 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-07-25T00:02:22.487Z,1564012942.487 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-07-25T00:02:22.488Z,1564012942.488 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-07-25T00:02:22.684Z,1564012942.684 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-07-25T00:02:22.684Z,1564012942.684 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-07-25T00:02:22.896Z,1564012942.896 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-07-25T00:02:22.899Z,1564012942.899 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-07-25T00:02:22.899Z,1564012942.899 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-07-25T00:02:22.982Z,1564012942.982 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-07-25T00:02:23.210Z,1564012943.210 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-07-25T00:02:23.211Z,1564012943.211 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-07-25T00:02:23.307Z,1564012943.307 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-07-25T00:02:23.479Z,1564012943.479 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-07-25T00:02:23.693Z,1564012943.693 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-07-25T00:02:23.815Z,1564012943.815 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-07-25T00:02:24.152Z,1564012944.152 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-07-25T00:02:24.394Z,1564012944.394 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-07-25T00:02:24.811Z,1564012944.811 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-07-25T00:02:24.923Z,1564012944.923 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-07-25T00:02:25.021Z,1564012945.021 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-07-25T00:02:25.027Z,1564012945.027 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-07-25T00:02:25.124Z,1564012945.124 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-07-25T00:02:25.125Z,1564012945.125 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-07-25T00:02:25.232Z,1564012945.232 [BuoyancyServo] Loaded
2019-07-25T00:02:25.233Z,1564012945.233 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-07-25T00:02:25.248Z,1564012945.248 [ElevatorServo] Loaded
2019-07-25T00:02:25.248Z,1564012945.248 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-07-25T00:02:25.262Z,1564012945.262 [MassServo] Loaded
2019-07-25T00:02:25.262Z,1564012945.262 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-07-25T00:02:25.277Z,1564012945.277 [RudderServo] Loaded
2019-07-25T00:02:25.278Z,1564012945.278 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-07-25T00:02:25.292Z,1564012945.292 [ThrusterServo] Loaded
2019-07-25T00:02:25.292Z,1564012945.292 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-07-25T00:02:25.293Z,1564012945.293 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-07-25T00:02:25.293Z,1564012945.293 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-07-25T00:02:25.428Z,1564012945.428 [SBIT](DEBUG): Construct Startup Built In Test.
2019-07-25T00:02:25.457Z,1564012945.457 [SBIT] Loaded
2019-07-25T00:02:25.457Z,1564012945.457 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-07-25T00:02:25.458Z,1564012945.458 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-07-25T00:02:25.485Z,1564012945.485 [IBIT] Loaded
2019-07-25T00:02:25.485Z,1564012945.485 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-07-25T00:02:25.488Z,1564012945.488 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-07-25T00:02:25.875Z,1564012945.875 [CBIT] Loaded
2019-07-25T00:02:25.875Z,1564012945.875 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-07-25T00:02:25.875Z,1564012945.875 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-07-25T00:02:25.876Z,1564012945.876 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-07-25T00:02:26.076Z,1564012946.076 [Aanderaa_O2] Loaded
2019-07-25T00:02:26.076Z,1564012946.076 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-07-25T00:02:26.095Z,1564012946.095 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-07-25T00:02:26.100Z,1564012946.100 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-07-25T00:02:26.114Z,1564012946.114 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-07-25T00:02:26.119Z,1564012946.119 [CTD_Seabird](INFO): created writer for : depth
2019-07-25T00:02:26.119Z,1564012946.119 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-07-25T00:02:26.124Z,1564012946.124 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-07-25T00:02:26.125Z,1564012946.125 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-07-25T00:02:26.158Z,1564012946.158 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-07-25T00:02:26.159Z,1564012946.159 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-07-25T00:02:26.164Z,1564012946.164 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-07-25T00:02:26.165Z,1564012946.165 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-07-25T00:02:26.175Z,1564012946.175 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-07-25T00:02:26.176Z,1564012946.176 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-07-25T00:02:26.181Z,1564012946.181 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-07-25T00:02:26.206Z,1564012946.206 [CTD_Seabird] Loaded
2019-07-25T00:02:26.207Z,1564012946.207 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-07-25T00:02:26.208Z,1564012946.208 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4066A4E0
2019-07-25T00:02:26.208Z,1564012946.208 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7595
2019-07-25T00:02:26.238Z,1564012946.238 [ESPComponent] Loaded
2019-07-25T00:02:26.238Z,1564012946.238 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-07-25T00:02:26.252Z,1564012946.252 [PAR_Licor] Loaded
2019-07-25T00:02:26.253Z,1564012946.253 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-07-25T00:02:26.300Z,1564012946.300 [WetLabsBB2FL] Loaded
2019-07-25T00:02:26.300Z,1564012946.300 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-07-25T00:02:26.301Z,1564012946.301 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4069A4E0
2019-07-25T00:02:26.302Z,1564012946.302 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7596
2019-07-25T00:02:26.302Z,1564012946.302 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-07-25T00:02:26.303Z,1564012946.303 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-07-25T00:02:26.344Z,1564012946.344 [DepthRateCalculator] Loaded
2019-07-25T00:02:26.344Z,1564012946.344 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-07-25T00:02:26.351Z,1564012946.351 [PitchRateCalculator] Loaded
2019-07-25T00:02:26.351Z,1564012946.351 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-07-25T00:02:26.368Z,1564012946.368 [SpeedCalculator] Loaded
2019-07-25T00:02:26.369Z,1564012946.369 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-07-25T00:02:26.389Z,1564012946.389 [TempGradientCalculator] Loaded
2019-07-25T00:02:26.390Z,1564012946.390 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-07-25T00:02:26.395Z,1564012946.395 [YawRateCalculator] Loaded
2019-07-25T00:02:26.396Z,1564012946.396 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-07-25T00:02:26.428Z,1564012946.428 [ElevatorOffsetCalculator] Loaded
2019-07-25T00:02:26.428Z,1564012946.428 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-07-25T00:02:26.428Z,1564012946.428 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-07-25T00:02:26.429Z,1564012946.429 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-07-25T00:02:26.461Z,1564012946.461 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-07-25T00:02:26.461Z,1564012946.461 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-07-25T00:02:26.563Z,1564012946.563 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-07-25T00:02:26.564Z,1564012946.564 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-07-25T00:02:26.585Z,1564012946.585 [NavChart] Loaded
2019-07-25T00:02:26.585Z,1564012946.585 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-07-25T00:02:26.589Z,1564012946.589 [UniversalFixResidualReporter] Loaded
2019-07-25T00:02:26.590Z,1564012946.590 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-07-25T00:02:26.590Z,1564012946.590 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-07-25T00:02:26.591Z,1564012946.591 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-07-25T00:02:26.939Z,1564012946.939 [AHRS_M2] Loaded
2019-07-25T00:02:26.939Z,1564012946.939 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-07-25T00:02:27.012Z,1564012947.012 [DataOverHttps] Loaded
2019-07-25T00:02:27.012Z,1564012947.012 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-07-25T00:02:27.013Z,1564012947.013 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409734E0
2019-07-25T00:02:27.014Z,1564012947.014 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7597
2019-07-25T00:02:27.027Z,1564012947.027 [Depth_Keller] Loaded
2019-07-25T00:02:27.027Z,1564012947.027 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-07-25T00:02:27.032Z,1564012947.032 [DropWeight] Loaded
2019-07-25T00:02:27.032Z,1564012947.032 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-07-25T00:02:27.161Z,1564012947.161 [DVL_micro] Loaded
2019-07-25T00:02:27.162Z,1564012947.162 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2019-07-25T00:02:27.251Z,1564012947.251 [NAL9602] Loaded
2019-07-25T00:02:27.251Z,1564012947.251 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-07-25T00:02:27.256Z,1564012947.256 [Onboard] Loaded
2019-07-25T00:02:27.256Z,1564012947.256 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-07-25T00:02:27.260Z,1564012947.260 [Radio_Surface] Loaded
2019-07-25T00:02:27.260Z,1564012947.260 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-07-25T00:02:27.261Z,1564012947.261 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A34E0
2019-07-25T00:02:27.261Z,1564012947.261 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7598
2019-07-25T00:02:27.388Z,1564012947.388 [DAT] Loaded
2019-07-25T00:02:27.388Z,1564012947.388 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2019-07-25T00:02:28.668Z,1564012948.668 [BPC1] Loaded
2019-07-25T00:02:28.668Z,1564012948.668 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-07-25T00:02:28.669Z,1564012948.669 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-07-25T00:02:28.669Z,1564012948.669 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-07-25T00:02:28.739Z,1564012948.739 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-07-25T00:02:28.740Z,1564012948.740 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-07-25T00:02:28.808Z,1564012948.808 [VerticalControl](DEBUG): Construct VerticalControl.
2019-07-25T00:02:28.890Z,1564012948.890 [VerticalControl] Loaded
2019-07-25T00:02:28.891Z,1564012948.891 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-07-25T00:02:28.891Z,1564012948.891 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-07-25T00:02:28.949Z,1564012948.949 [HorizontalControl] Loaded
2019-07-25T00:02:28.949Z,1564012948.949 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-07-25T00:02:28.950Z,1564012948.950 [SpeedControl](DEBUG): Construct SpeedControl.
2019-07-25T00:02:28.952Z,1564012948.952 [SpeedControl] Loaded
2019-07-25T00:02:28.952Z,1564012948.952 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-07-25T00:02:28.953Z,1564012948.953 [LoopControl](DEBUG): Construct LoopControl.
2019-07-25T00:02:28.953Z,1564012948.953 [LoopControl] Loaded
2019-07-25T00:02:28.953Z,1564012948.953 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-07-25T00:02:28.954Z,1564012948.954 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-07-25T00:02:28.954Z,1564012948.954 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-07-25T00:02:29.002Z,1564012949.002 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-07-25T00:02:29.002Z,1564012949.002 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-07-25T00:02:29.457Z,1564012949.457 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-07-25T00:02:29.461Z,1564012949.461 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-07-25T00:02:29.462Z,1564012949.462 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-07-25T00:02:29.468Z,1564012949.468 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-07-25T00:02:29.469Z,1564012949.469 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ADE4E0
2019-07-25T00:02:29.470Z,1564012949.470 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7599
2019-07-25T00:02:29.475Z,1564012949.475 [Supervisor](INFO): Main Thread ID is 7510
2019-07-25T00:02:29.475Z,1564012949.475 [Supervisor](DEBUG): Running supervisor.
2019-07-25T00:02:29.475Z,1564012949.475 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7600
2019-07-25T00:02:29.478Z,1564012949.478 [controlThread ThreadHandler](INFO): Handler Thread ID is 7601
2019-07-25T00:02:29.478Z,1564012949.478 [controlThread](DEBUG): Initializing ControlThread
2019-07-25T00:02:29.480Z,1564012949.480 [SBIT](INFO): Initialize SBIT Component.
2019-07-25T00:02:29.480Z,1564012949.480 [SBIT](IMPORTANT): git: 2019-07-23
2019-07-25T00:02:29.481Z,1564012949.481 [SBIT](INFO): git hash: 66f82fd8c0ed4c5b011038c11738fd4b7b507f51
2019-07-25T00:02:29.481Z,1564012949.481 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-07-25T00:02:29.482Z,1564012949.482 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-07-25T00:02:29.484Z,1564012949.484 [SBIT](INFO): Beginning SBIT in 139.000000 seconds.
2019-07-25T00:02:29.484Z,1564012949.484 [IBIT](INFO): Initialize IBIT Component.
2019-07-25T00:02:29.485Z,1564012949.485 [CBIT](DEBUG): Initialize CBIT Component.
2019-07-25T00:02:29.486Z,1564012949.486 [logger ThreadHandler](INFO): Handler Thread ID is 7602
2019-07-25T00:02:29.498Z,1564012949.498 [CBIT](DEBUG): Initialized mux pins.
2019-07-25T00:02:29.498Z,1564012949.498 [CBIT](DEBUG): Initializing the watchdog timer.
2019-07-25T00:02:29.506Z,1564012949.506 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7603
2019-07-25T00:02:29.507Z,1564012949.507 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-07-25T00:02:29.510Z,1564012949.510 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-07-25T00:02:29.512Z,1564012949.512 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7605
2019-07-25T00:02:29.513Z,1564012949.513 [WetLabsBB2FL](INFO): Powering down
2019-07-25T00:02:29.522Z,1564012949.522 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-07-25T00:02:29.522Z,1564012949.522 [CBIT](DEBUG): Initializing heartbeat.
2019-07-25T00:02:29.542Z,1564012949.542 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7606
2019-07-25T00:02:29.543Z,1564012949.543 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-07-25T00:02:29.554Z,1564012949.554 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7607
2019-07-25T00:02:29.570Z,1564012949.570 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7608
2019-07-25T00:02:29.573Z,1564012949.573 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-07-25T00:02:29.574Z,1564012949.574 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-07-25T00:02:29.574Z,1564012949.574 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-07-25T00:02:29.574Z,1564012949.574 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-07-25T00:02:29.574Z,1564012949.574 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-07-25T00:02:29.575Z,1564012949.575 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-07-25T00:02:29.575Z,1564012949.575 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-07-25T00:02:29.575Z,1564012949.575 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-07-25T00:02:29.575Z,1564012949.575 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-07-25T00:02:29.575Z,1564012949.575 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-07-25T00:02:29.576Z,1564012949.576 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-07-25T00:02:29.576Z,1564012949.576 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-07-25T00:02:29.576Z,1564012949.576 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-07-25T00:02:29.576Z,1564012949.576 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-07-25T00:02:29.576Z,1564012949.576 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-07-25T00:02:29.576Z,1564012949.576 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-07-25T00:02:29.594Z,1564012949.594 [CBIT](DEBUG): Deactivating GF circuits.
2019-07-25T00:02:29.594Z,1564012949.594 [CBIT](DEBUG): Deactivating emergency mode.
2019-07-25T00:02:29.630Z,1564012949.630 [CBIT](DEBUG): Backplane powered.
2019-07-25T00:02:29.634Z,1564012949.634 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-07-25T00:02:29.635Z,1564012949.635 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-07-25T00:02:29.635Z,1564012949.635 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-07-25T00:02:29.635Z,1564012949.635 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-07-25T00:02:29.636Z,1564012949.636 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-07-25T00:02:29.636Z,1564012949.636 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-07-25T00:02:29.637Z,1564012949.637 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-07-25T00:02:29.638Z,1564012949.638 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-07-25T00:02:29.649Z,1564012949.649 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-07-25T00:02:29.658Z,1564012949.658 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-07-25T00:02:29.659Z,1564012949.659 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-07-25T00:02:29.660Z,1564012949.660 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-07-25T00:02:29.661Z,1564012949.661 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-07-25T00:02:29.695Z,1564012949.695 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-07-25T00:02:29.742Z,1564012949.742 [MissionManager](DEBUG):
2019-07-25T00:02:29.743Z,1564012949.743 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-07-25T00:02:29.824Z,1564012949.824 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-07-25T00:02:29.825Z,1564012949.825 [Default:A.Wait](DEBUG): Construct Wait.
2019-07-25T00:02:29.827Z,1564012949.827 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-07-25T00:02:29.875Z,1564012949.875 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-07-25T00:02:29.878Z,1564012949.878 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-07-25T00:02:29.892Z,1564012949.892 [Default:E.Execute](DEBUG): Construct Execute.
2019-07-25T00:02:29.895Z,1564012949.895 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2019-07-25T00:02:29.922Z,1564012949.922 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-07-25T00:02:29.958Z,1564012949.958 [Radio_Surface](INFO): Powering up
2019-07-25T00:02:29.975Z,1564012949.975 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-07-25T00:02:30.018Z,1564012950.018 [Depth_Keller](ERROR): Pressure reading out of range: 1895.684326 decibar
2019-07-25T00:02:30.042Z,1564012950.042 [DVL_micro](INFO): Initializing
2019-07-25T00:02:30.078Z,1564012950.078 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2019-07-25T00:02:30.078Z,1564012950.078 [DAT](INFO): Powering up
2019-07-25T00:02:30.078Z,1564012950.078 [DAT](DEBUG): Initializing DAT.
2019-07-25T00:02:30.142Z,1564012950.142 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-07-25T00:02:30.168Z,1564012950.168 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-07-25T00:02:30.174Z,1564012950.174 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2019-07-25T00:02:30.210Z,1564012950.210 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-07-25T00:02:30.211Z,1564012950.211 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-07-25T00:02:30.214Z,1564012950.214 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-07-25T00:02:30.215Z,1564012950.215 [MassServo](DEBUG): Initializing EZServoServo.
2019-07-25T00:02:30.227Z,1564012950.227 [MassServo](DEBUG): Initializing MassServo.
2019-07-25T00:02:30.228Z,1564012950.228 [RudderServo](DEBUG): Initializing EZServoServo.
2019-07-25T00:02:30.238Z,1564012950.238 [RudderServo](DEBUG): Initializing RudderServo.
2019-07-25T00:02:30.239Z,1564012950.239 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-07-25T00:02:30.250Z,1564012950.250 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-07-25T00:02:31.186Z,1564012951.186 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-07-25T00:02:31.186Z,1564012951.186 [RudderServo](FAULT): Rudder failed to initialize
2019-07-25T00:02:31.186Z,1564012951.186 [RudderServo] Communications Fault, FailCount= 1
2019-07-25T00:02:31.186Z,1564012951.186 [RudderServo](ERROR): Communications Fault
2019-07-25T00:02:31.297Z,1564012951.297 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-07-25T00:02:31.495Z,1564012951.495 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-07-25T00:02:31.495Z,1564012951.495 [RudderServo](INFO): Powering down
2019-07-25T00:02:32.180Z,1564012952.180 [RudderServo](DEBUG): Initializing EZServoServo.
2019-07-25T00:02:32.299Z,1564012952.299 [RudderServo](DEBUG): Initializing RudderServo.
2019-07-25T00:02:32.303Z,1564012952.303 [CBIT](INFO): Clearing failed state for component RudderServo
2019-07-25T00:02:32.303Z,1564012952.303 [RudderServo] No Fault, FailCount= 1
2019-07-25T00:02:33.442Z,1564012953.442 [Aanderaa_O2](INFO): Powering down
2019-07-25T00:02:43.260Z,1564012963.260 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005142
2019-07-25T00:02:45.470Z,1564012965.470 [DAT](INFO): setting local address to 3
2019-07-25T00:02:45.871Z,1564012965.871 [DAT](INFO): set local address to 3
2019-07-25T00:02:57.580Z,1564012977.580 [NAL9602](INFO): Powering up NAL9602
2019-07-25T00:03:08.484Z,1564012988.484 [NAL9602](INFO): NAL9602 initialized
2019-07-25T00:03:09.302Z,1564012989.302 [NAL9602](DEBUG): Fix Requested
2019-07-25T00:03:18.603Z,1564012998.603 [DVL_micro](ERROR): only read 45 of 46 data items
2019-07-25T00:03:18.603Z,1564012998.603 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 40 111 8 146 2 3 2 2 42.7 -46.7 82.2 2 -49.7 39.2 82.2 2 42 -46 82 2 -49 39 82 2 1. 189.3 -3.0 18.4 0.005 35.0 1489 81
2019-07-25T00:03:21.839Z,1564013001.839 [DVL_micro](ERROR): only read 45 of 46 data items
2019-07-25T00:03:21.839Z,1564013001.839 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 4411 2 3 2 2 101.4 -141.5 34.5 2 -122.9 123.3 34.5 2 101 -141 34 2 -122 123 34 2 1.00 -1.58 189.3 -3.0 18.5 0.005 35.0 1489 66
2019-07-25T00:04:49.144Z,1564013089.144 [SBIT](IMPORTANT): Beginning Startup BIT
2019-07-25T00:04:49.152Z,1564013089.152 [CBIT](IMPORTANT): Beginning ground fault scan
2019-07-25T00:04:49.856Z,1564013089.856 [CommandLine](IMPORTANT): got command failComponent
2019-07-25T00:04:49.856Z,1564013089.856 [CommandLine](IMPORTANT): Failed components:
2019-07-25T00:04:49.856Z,1564013089.856 [CommandLine](IMPORTANT): No failed Components.
2019-07-25T00:05:00.146Z,1564013100.146 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.083129
CHAN A1 (24V): 0.034346
CHAN A2 (12V): -0.028912
CHAN A3 (5V): -0.055197
CHAN B0 (3.3V): -0.030442
CHAN B1 (3.15aV): -0.017110
CHAN B2 (3.15bV): -0.010951
CHAN B3 (GND): -0.033857
OPEN: 0.009049
Full Scale Calc: 4.765 mA, -1.589 mA
2019-07-25T00:05:29.770Z,1564013129.770 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-07-25T00:05:29.770Z,1564013129.770 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-25T00:05:29.791Z,1564013129.791 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-25T00:05:30.166Z,1564013130.166 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-25T00:05:30.166Z,1564013130.166 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-07-25T00:05:34.324Z,1564013134.324 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-07-25T00:05:34.324Z,1564013134.324 [DVL_micro] Data Fault, FailCount= 1
2019-07-25T00:05:34.324Z,1564013134.324 [DVL_micro](ERROR): Data Fault
2019-07-25T00:05:34.423Z,1564013134.423 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-07-25T00:05:34.798Z,1564013134.798 [DVL_micro](INFO): uninitialize:Powering down
2019-07-25T00:05:35.576Z,1564013135.576 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-07-25T00:05:35.576Z,1564013135.576 [DVL_micro] No Fault, FailCount= 1
2019-07-25T00:05:35.945Z,1564013135.945 [DVL_micro](INFO): Initializing
2019-07-25T00:05:42.460Z,1564013142.460 [SBIT](IMPORTANT): SBIT PASSED
2019-07-25T00:05:42.500Z,1564013142.500 [CommandLine](IMPORTANT): got command configSet list
2019-07-25T00:05:42.500Z,1564013142.500 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-07-25T00:05:42.501Z,1564013142.501 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;
2019-07-25T00:05:42.501Z,1564013142.501 [CommandLine](IMPORTANT): BuoyancyServo.fastPumpDepth=5 meter;
2019-07-25T00:05:42.501Z,1564013142.501 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=400 cubic_centimeter;
2019-07-25T00:05:42.501Z,1564013142.501 [CommandLine](IMPORTANT): VerticalControl.kiDepthBuoy=0.3 reciprocal_second;
2019-07-25T00:05:42.502Z,1564013142.502 [CommandLine](IMPORTANT): VerticalControl.massDefault=30 millimeter;
2019-07-25T00:05:42.831Z,1564013142.831 [MissionManager](IMPORTANT): Started mission Startup
2019-07-25T00:05:42.831Z,1564013142.831 [Startup] Running Loop=1
2019-07-25T00:05:42.832Z,1564013142.832 [Startup](DEBUG): Aggregate::initialize Startup
2019-07-25T00:05:42.832Z,1564013142.832 [Startup:A.GoToSurface] Running Loop=1
2019-07-25T00:05:42.832Z,1564013142.832 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-07-25T00:05:42.832Z,1564013142.832 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-07-25T00:05:42.833Z,1564013142.833 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-07-25T00:05:42.833Z,1564013142.833 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-07-25T00:05:42.833Z,1564013142.833 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-07-25T00:05:42.834Z,1564013142.834 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-07-25T00:05:42.835Z,1564013142.835 [Startup:StartupSatComms] Running Loop=1
2019-07-25T00:05:42.836Z,1564013142.836 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-07-25T00:05:42.836Z,1564013142.836 [Startup:StartupSatComms:A] Running Loop=1
2019-07-25T00:05:43.232Z,1564013143.232 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-07-25T00:06:43.014Z,1564013203.014 [Startup:StartupSatComms:A](INFO): Timed out from 2019-07-25T00:05:42.8Z
2019-07-25T00:06:43.015Z,1564013203.015 [Startup:StartupSatComms:A] Stopped
2019-07-25T00:06:43.015Z,1564013203.015 [Startup:StartupSatComms:B] Running Loop=1
2019-07-25T00:06:43.479Z,1564013203.479 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-07-25T00:06:49.273Z,1564013209.273 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190724T232310/Courier0015.lzma
2019-07-25T00:06:50.079Z,1564013210.079 [DataOverHttps](INFO): Moved sent file to Logs/20190724T232310/Courier0015.lzma.bak
2019-07-25T00:06:50.080Z,1564013210.080 [DataOverHttps](INFO): SBD MOMSN=11473341
2019-07-25T00:07:00.611Z,1564013220.611 [CommandLine](IMPORTANT): got command show stack
2019-07-25T00:07:00.611Z,1564013220.611 [CommandLine](IMPORTANT): Behavior Stack:
2019-07-25T00:07:00.611Z,1564013220.611 [Startup](IMPORTANT): Priority 0: Startup:A.GoToSurface
2019-07-25T00:07:00.611Z,1564013220.611 [Startup:StartupSatComms](IMPORTANT): Priority 1: Startup:StartupSatComms:B
2019-07-25T00:07:01.777Z,1564013221.777 [DataOverHttps](INFO): Sending 86 bytes from file Logs/20190724T232310/Express0016.lzma
2019-07-25T00:07:02.587Z,1564013222.587 [DataOverHttps](INFO): Moved sent file to Logs/20190724T232310/Express0016.lzma.bak
2019-07-25T00:07:02.588Z,1564013222.588 [DataOverHttps](INFO): SBD MOMSN=11473343
2019-07-25T00:07:06.180Z,1564013226.180 [CommandLine](IMPORTANT): got command show variable elevatorAngle
2019-07-25T00:07:06.299Z,1564013226.299 [CommandLine](IMPORTANT): VerticalControl.elevatorAngleAction (radian)
2019-07-25T00:07:06.300Z,1564013226.300 [CommandLine](IMPORTANT): VerticalControl.elevatorAngleCmd (radian)
2019-07-25T00:07:16.939Z,1564013236.939 [DataOverHttps](INFO): Sending 875 bytes from file Logs/20190725T000219/Express0001.lzma
2019-07-25T00:07:17.744Z,1564013237.744 [DataOverHttps](INFO): Moved sent file to Logs/20190725T000219/Express0001.lzma.bak
2019-07-25T00:07:17.744Z,1564013237.744 [DataOverHttps](INFO): SBD MOMSN=11473346
2019-07-25T00:07:18.194Z,1564013238.194 [Startup:StartupSatComms:B] Stopped
2019-07-25T00:07:18.195Z,1564013238.195 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-07-25T00:07:18.195Z,1564013238.195 [Startup:StartupSatComms] Stopped
2019-07-25T00:07:18.195Z,1564013238.195 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-07-25T00:07:18.196Z,1564013238.196 [Startup](INFO): Completed Startup
2019-07-25T00:07:18.196Z,1564013238.196 [MissionManager](INFO): Startup is completed.
2019-07-25T00:07:18.196Z,1564013238.196 [MissionManager](INFO): Uninitializing Mission Startup
2019-07-25T00:07:18.196Z,1564013238.196 [Startup] Stopped
2019-07-25T00:07:18.196Z,1564013238.196 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-07-25T00:07:18.196Z,1564013238.196 [Startup:A.GoToSurface] Stopped
2019-07-25T00:07:18.196Z,1564013238.196 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-07-25T00:07:18.577Z,1564013238.577 [MissionManager](IMPORTANT): Started mission Default
2019-07-25T00:07:18.577Z,1564013238.577 [Default] Running Loop=1
2019-07-25T00:07:18.577Z,1564013238.577 [Default](DEBUG): Aggregate::initialize Default
2019-07-25T00:07:18.577Z,1564013238.577 [Default:B.GoToSurface] Running Loop=1
2019-07-25T00:07:18.577Z,1564013238.577 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-07-25T00:07:18.578Z,1564013238.578 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-07-25T00:07:18.578Z,1564013238.578 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-07-25T00:07:18.579Z,1564013238.579 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-07-25T00:07:18.579Z,1564013238.579 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-07-25T00:07:18.579Z,1564013238.579 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-07-25T00:07:18.580Z,1564013238.580 [Default:A.Wait] Running Loop=1
2019-07-25T00:07:18.580Z,1564013238.580 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-07-25T00:07:31.924Z,1564013251.924 [Default:A.Wait](INFO): Done Waiting.
2019-07-25T00:07:31.924Z,1564013251.924 [Default:A.Wait] Stopped
2019-07-25T00:07:31.924Z,1564013251.924 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-07-25T00:07:32.371Z,1564013252.371 [Default:CheckIn] Running Loop=1
2019-07-25T00:07:32.371Z,1564013252.371 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-25T00:07:32.371Z,1564013252.371 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-25T00:07:32.736Z,1564013252.736 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-07-25T00:07:39.692Z,1564013259.692 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.799999 degree
2019-07-25T00:07:39.694Z,1564013259.694 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2019-07-25T00:07:39.695Z,1564013259.695 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.900002 degree
2019-07-25T00:07:39.696Z,1564013259.696 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2019-07-25T00:07:39.995Z,1564013259.995 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-07-25T00:07:40.381Z,1564013260.381 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2019-07-25T00:07:40.381Z,1564013260.381 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-07-25T00:07:40.381Z,1564013260.381 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-25T00:07:40.391Z,1564013260.391 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-25T00:07:40.801Z,1564013260.801 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-25T00:07:40.801Z,1564013260.801 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-07-25T00:07:41.619Z,1564013261.619 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2019-07-25T00:07:41.619Z,1564013261.619 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-07-25T00:07:41.619Z,1564013261.619 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-25T00:07:41.638Z,1564013261.638 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-25T00:07:42.031Z,1564013262.031 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-25T00:07:42.032Z,1564013262.032 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-07-25T00:07:42.851Z,1564013262.851 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2019-07-25T00:07:42.851Z,1564013262.851 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-07-25T00:07:42.851Z,1564013262.851 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-25T00:07:42.865Z,1564013262.865 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-25T00:07:43.263Z,1564013263.263 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-25T00:07:43.263Z,1564013263.263 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-07-25T00:07:44.013Z,1564013264.013 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2019-07-25T00:07:44.013Z,1564013264.013 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-07-25T00:07:44.013Z,1564013264.013 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-25T00:07:44.023Z,1564013264.023 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-25T00:07:44.430Z,1564013264.430 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-25T00:07:44.430Z,1564013264.430 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-07-25T00:07:45.254Z,1564013265.254 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2019-07-25T00:07:45.254Z,1564013265.254 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-07-25T00:07:45.254Z,1564013265.254 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-25T00:07:45.264Z,1564013265.264 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-25T00:07:45.653Z,1564013265.653 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-25T00:07:45.653Z,1564013265.653 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-07-25T00:07:46.441Z,1564013266.441 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2019-07-25T00:07:46.441Z,1564013266.441 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2019-07-25T00:07:46.441Z,1564013266.441 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-25T00:07:46.491Z,1564013266.491 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-25T00:07:46.858Z,1564013266.858 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-25T00:07:46.858Z,1564013266.858 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2019-07-25T00:07:47.676Z,1564013267.676 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2019-07-25T00:07:47.676Z,1564013267.676 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2019-07-25T00:07:47.676Z,1564013267.676 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-25T00:07:47.686Z,1564013267.686 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-25T00:07:48.087Z,1564013268.087 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-25T00:07:48.087Z,1564013268.087 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2019-07-25T00:07:48.887Z,1564013268.887 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2019-07-25T00:07:48.887Z,1564013268.887 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2019-07-25T00:07:48.887Z,1564013268.887 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-25T00:07:48.897Z,1564013268.897 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-25T00:07:49.291Z,1564013269.291 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-25T00:07:49.291Z,1564013269.291 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2019-07-25T00:07:50.215Z,1564013270.215 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2019-07-25T00:07:50.215Z,1564013270.215 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2019-07-25T00:07:50.215Z,1564013270.215 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-25T00:07:50.238Z,1564013270.238 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-25T00:07:50.558Z,1564013270.558 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-25T00:07:50.558Z,1564013270.558 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2019-07-25T00:07:51.439Z,1564013271.439 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2019-07-25T00:07:51.439Z,1564013271.439 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2019-07-25T00:07:51.439Z,1564013271.439 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-25T00:07:51.473Z,1564013271.473 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-25T00:07:51.744Z,1564013271.744 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-25T00:07:51.744Z,1564013271.744 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2019-07-25T00:07:52.531Z,1564013272.531 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2019-07-25T00:07:52.531Z,1564013272.531 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2019-07-25T00:07:52.531Z,1564013272.531 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-25T00:07:52.545Z,1564013272.545 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-25T00:07:52.947Z,1564013272.947 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-25T00:07:52.947Z,1564013272.947 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2019-07-25T00:07:53.748Z,1564013273.748 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2019-07-25T00:07:53.748Z,1564013273.748 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2019-07-25T00:07:53.749Z,1564013273.749 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-25T00:07:53.771Z,1564013273.771 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-25T00:07:54.139Z,1564013274.139 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-25T00:07:54.139Z,1564013274.139 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2019-07-25T00:07:54.944Z,1564013274.944 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2019-07-25T00:07:54.944Z,1564013274.944 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2019-07-25T00:07:54.944Z,1564013274.944 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-25T00:07:54.954Z,1564013274.954 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-25T00:07:55.339Z,1564013275.339 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-25T00:07:55.339Z,1564013275.339 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2019-07-25T00:07:56.151Z,1564013276.151 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2019-07-25T00:07:56.151Z,1564013276.151 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2019-07-25T00:07:56.151Z,1564013276.151 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-25T00:07:56.161Z,1564013276.161 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-25T00:07:56.563Z,1564013276.563 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1564013312.000000 second
2019-07-25T00:07:56.573Z,1564013276.573 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-25T00:07:56.573Z,1564013276.573 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2019-07-25T00:07:56.969Z,1564013276.969 [Default:CheckIn:Read_GPS] Stopped
2019-07-25T00:07:56.970Z,1564013276.970 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-25T00:07:57.352Z,1564013277.352 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-07-25T00:08:01.012Z,1564013281.012 [CommandLine](IMPORTANT): got command show stack
2019-07-25T00:08:01.012Z,1564013281.012 [CommandLine](IMPORTANT): Behavior Stack:
2019-07-25T00:08:01.012Z,1564013281.012 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface
2019-07-25T00:08:01.012Z,1564013281.012 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:Read_Iridium
2019-07-25T00:08:03.201Z,1564013283.201 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20190725T000219/Courier0004.lzma
2019-07-25T00:08:04.007Z,1564013284.007 [DataOverHttps](INFO): Moved sent file to Logs/20190725T000219/Courier0004.lzma.bak
2019-07-25T00:08:04.007Z,1564013284.007 [DataOverHttps](INFO): SBD MOMSN=11473370
2019-07-25T00:08:11.476Z,1564013291.476 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-07-25T00:08:15.438Z,1564013295.438 [DataOverHttps](INFO): Sending 485 bytes from file Logs/20190725T000219/Express0005.lzma
2019-07-25T00:08:16.243Z,1564013296.243 [DataOverHttps](INFO): Moved sent file to Logs/20190725T000219/Express0005.lzma.bak
2019-07-25T00:08:16.244Z,1564013296.244 [DataOverHttps](INFO): SBD MOMSN=11473372
2019-07-25T00:08:16.757Z,1564013296.757 [Default:CheckIn:Read_Iridium] Stopped
2019-07-25T00:08:16.758Z,1564013296.758 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-25T00:08:16.758Z,1564013296.758 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-25T00:08:40.155Z,1564013320.155 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-07-25T00:08:40.155Z,1564013320.155 [DVL_micro] Data Fault, FailCount= 1
2019-07-25T00:08:40.155Z,1564013320.155 [DVL_micro](ERROR): Data Fault
2019-07-25T00:08:40.213Z,1564013320.213 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-07-25T00:08:40.622Z,1564013320.622 [DVL_micro](INFO): uninitialize:Powering down
2019-07-25T00:08:41.396Z,1564013321.396 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-07-25T00:08:41.396Z,1564013321.396 [DVL_micro] No Fault, FailCount= 1
2019-07-25T00:08:41.776Z,1564013321.776 [DVL_micro](INFO): Initializing
2019-07-25T00:08:42.175Z,1564013322.175 [NAL9602](INFO): Not Powering down - fast GPS
2019-07-25T00:08:53.276Z,1564013333.276 [DataOverHttps](IMPORTANT): SBD MTMSN=20190725T000837
2019-07-25T00:08:56.603Z,1564013336.603 [CommandLine](IMPORTANT): got command show best height_above_sea_floor
2019-07-25T00:08:56.603Z,1564013336.603 [CommandLine](IMPORTANT): height_above_sea_floor best is null
2019-07-25T00:09:00.162Z,1564013340.162 [DataOverHttps](INFO): Received command:sched asap "load Science/profile_station.xml;set profile_station.MissionTimeout 2 hour;set profile_station.NeedCommsTime 10 minute;set profile_station.Lat 36.797 degree;set profile_station.Lon -121.847 degree" 3pq8 1 2
2019-07-25T00:09:00.192Z,1564013340.192 [CommandLine](IMPORTANT): got command schedule asap "load Science/profile_station.xml;set profile_station.MissionTimeout 2 hour;set profile_station.NeedCommsTime 10 minute;set profile_station.Lat 36.797 degree;set profile_station.Lon -121.847 degree" 3pq8 1 2.000000
2019-07-25T00:09:00.193Z,1564013340.193 [CommandLine](IMPORTANT): Scheduling command #1 of 2 with id=3pq8
2019-07-25T00:09:00.194Z,1564013340.194 [CommandLine](IMPORTANT): Scheduled #1 (#1 of 2 with id='3pq8'): "load Science/profile_station.xml;set profile_station.MissionTimeout 2 hour;set profile_station.NeedCommsTime 10 minute;set profile_station.Lat 36.797 degree;set profile_station.Lon -121.847 degree" ASAP
2019-07-25T00:09:00.967Z,1564013340.967 [DataOverHttps](IMPORTANT): SBD MTMSN=20190725T000845
2019-07-25T00:09:01.796Z,1564013341.796 [CommandLine](IMPORTANT): got command
2019-07-25T00:09:01.796Z,1564013341.796 [CommandLine](FAULT): Incomplete syntax. Try: help
2019-07-25T00:09:07.390Z,1564013347.390 [DataOverHttps](INFO): Received command:sched asap "set profile_station.YoYoMaxDepth 30 meter;set profile_station.MaxDepth 35 meter;set profile_station.MinOffshore 0.5 kilometer;run " 3pq8 2 2
2019-07-25T00:09:07.421Z,1564013347.421 [CommandLine](IMPORTANT): got command schedule asap "set profile_station.YoYoMaxDepth 30 meter;set profile_station.MaxDepth 35 meter;set profile_station.MinOffshore 0.5 kilometer;run " 3pq8 2 2.000000
2019-07-25T00:09:07.421Z,1564013347.421 [CommandLine](IMPORTANT): Scheduling command #2 of 2 with id=3pq8
2019-07-25T00:09:07.422Z,1564013347.422 [CommandLine](IMPORTANT): Scheduled #2 (#2 of 2 with id='3pq8'): "set profile_station.YoYoMaxDepth 30 meter;set profile_station.MaxDepth 35 meter;set profile_station.MinOffshore 0.5 kilometer;run " ASAP
2019-07-25T00:09:07.542Z,1564013347.542 [CommandLine](IMPORTANT): got command load ./Missions/Science/profile_station.xml
2019-07-25T00:09:07.542Z,1564013347.542 [MissionManager](INFO): Loading Mission: ./Missions/Science/profile_station.xml
2019-07-25T00:09:07.688Z,1564013347.688 [MissionManager](INFO): DefineArg profile_station.MissionTimeout = 4.000000 h
2019-07-25T00:09:07.695Z,1564013347.695 [MissionManager](INFO): DefineArg profile_station.NeedCommsTime = 60.000000 min
2019-07-25T00:09:07.707Z,1564013347.707 [MissionManager](INFO): DefineArg profile_station.Lat = 36.806966 arcdeg
2019-07-25T00:09:07.728Z,1564013347.728 [MissionManager](INFO): DefineArg profile_station.Lon = -121.824326 arcdeg
2019-07-25T00:09:07.735Z,1564013347.735 [MissionManager](INFO): DefineArg profile_station.Radius = 300.000000 m
2019-07-25T00:09:07.743Z,1564013347.743 [MissionManager](INFO): DefineArg profile_station.YoYoMinDepth = 2.000000 m
2019-07-25T00:09:07.752Z,1564013347.752 [MissionManager](INFO): DefineArg profile_station.YoYoMaxDepth = 200.000000 m
2019-07-25T00:09:07.782Z,1564013347.782 [MissionManager](INFO): DefineArg profile_station.YoYoMinAltitude = 7.000000 m
2019-07-25T00:09:07.788Z,1564013347.788 [MissionManager](INFO): DefineArg profile_station.YoYoPitch = 20.000000 arcdeg
2019-07-25T00:09:07.792Z,1564013347.792 [MissionManager](INFO): DefineArg profile_station.Speed = 1.000000 m/s
2019-07-25T00:09:07.796Z,1564013347.796 [MissionManager](INFO): DefineArg profile_station.CircleMaxError = 100.000000 m
2019-07-25T00:09:07.810Z,1564013347.810 [MissionManager](INFO): DefineArg profile_station.CircleTurnToPort = 0 bool
2019-07-25T00:09:07.813Z,1564013347.813 [MissionManager](INFO): DefineArg profile_station.KwpHeading = 0.010000 rad/m
2019-07-25T00:09:07.821Z,1564013347.821 [MissionManager](INFO): DefineArg profile_station.MinAltitude = 5.000000 m
2019-07-25T00:09:07.829Z,1564013347.829 [MissionManager](INFO): DefineArg profile_station.MaxDepth = 205.000000 m
2019-07-25T00:09:07.832Z,1564013347.832 [MissionManager](INFO): DefineArg profile_station.MinOffshore = 2.000000 km
2019-07-25T00:09:07.833Z,1564013347.833 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2019-07-25T00:09:08.254Z,1564013348.254 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUS = 0 bool
2019-07-25T00:09:08.258Z,1564013348.258 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUSmaxPitch = 60.000000 arcdeg
2019-07-25T00:09:08.269Z,1564013348.269 [MissionManager](INFO): DefineArg profile_station:Science.SampleISUSminPitch = -60.000000 arcdeg
2019-07-25T00:09:08.284Z,1564013348.284 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectChlActive = 0 bool
2019-07-25T00:09:08.287Z,1564013348.287 [MissionManager](INFO): DefineArg profile_station:Science.TimeWindowPeakReport = nan min
2019-07-25T00:09:08.290Z,1564013348.290 [MissionManager](INFO): DefineArg profile_station:Science.HighestChlPeakReportActive = 0 bool
2019-07-25T00:09:08.293Z,1564013348.293 [MissionManager](INFO): DefineArg profile_station:Science.PatchTracking = 0 bool
2019-07-25T00:09:08.302Z,1564013348.302 [MissionManager](INFO): DefineArg profile_station:Science.FilterWidthHorizontal = 3.000000 count
2019-07-25T00:09:08.306Z,1564013348.306 [MissionManager](INFO): DefineArg profile_station:Science.NumProfilesSlidingwindow = 100.000000 count
2019-07-25T00:09:08.309Z,1564013348.309 [MissionManager](INFO): DefineArg profile_station:Science.OffPeakFractionHorizontal = 80.000000 %
2019-07-25T00:09:08.320Z,1564013348.320 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectNO3Active = 0 bool
2019-07-25T00:09:08.323Z,1564013348.323 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectOilActive = 0 bool
2019-07-25T00:09:08.334Z,1564013348.334 [MissionManager](INFO): DefineArg profile_station:Science.PeakDetectFDOMActive = 0 bool
2019-07-25T00:09:08.337Z,1564013348.337 [MissionManager](INFO): DefineArg profile_station:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2019-07-25T00:09:08.355Z,1564013348.355 [MissionManager](INFO): DefineArg profile_station:Science.EnabledAanderaaO2 = 1.000000 bool
2019-07-25T00:09:08.360Z,1564013348.360 [MissionManager](INFO): DefineArg profile_station:Science.EnabledNeilBrown = 0.000000 bool
2019-07-25T00:09:08.376Z,1564013348.376 [MissionManager](INFO): DefineArg profile_station:Science.EnabledSeabird = 1.000000 bool
2019-07-25T00:09:08.394Z,1564013348.394 [MissionManager](INFO): DefineArg profile_station:Science.EnabledTurbulence_NPS = 0.000000 bool
2019-07-25T00:09:08.402Z,1564013348.402 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsBB2FL = 1.000000 bool
2019-07-25T00:09:08.405Z,1564013348.405 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2019-07-25T00:09:08.413Z,1564013348.413 [MissionManager](INFO): DefineArg profile_station:Science.EnabledWetLabsUBAT = 0.000000 bool
2019-07-25T00:09:08.468Z,1564013348.468 [MissionManager](INFO): DefineArg profile_station:Science.EnabledISUS = 0.000000 bool
2019-07-25T00:09:08.486Z,1564013348.486 [MissionManager](INFO): DefineArg profile_station:Science.LowPassWindowLength = 20.000000 count
2019-07-25T00:09:08.497Z,1564013348.497 [MissionManager](INFO): DefineArg profile_station:Science.MedianFilterLen = 5.000000 count
2019-07-25T00:09:08.508Z,1564013348.508 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlShallowBound = nan m
2019-07-25T00:09:08.515Z,1564013348.515 [MissionManager](INFO): DefineArg profile_station:Science.PeakChlDeepBound = nan m
2019-07-25T00:09:08.522Z,1564013348.522 [MissionManager](INFO): DefineArg profile_station:Science.PeakShallowBound = nan m
2019-07-25T00:09:08.525Z,1564013348.525 [MissionManager](INFO): DefineArg profile_station:Science.PeakDeepBound = nan m
2019-07-25T00:09:08.536Z,1564013348.536 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChl = 0.000000 ug/l
2019-07-25T00:09:08.540Z,1564013348.540 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlDepth = 0.000000 m
2019-07-25T00:09:08.555Z,1564013348.555 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlTemperature = 0.000000 degC
2019-07-25T00:09:08.564Z,1564013348.564 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLatitude = nan arcdeg
2019-07-25T00:09:08.572Z,1564013348.572 [MissionManager](INFO): DefineOutput profile_station:Science.PeakChlLongitude = nan arcdeg
2019-07-25T00:09:08.584Z,1564013348.584 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3 = 0.000000 umol/l
2019-07-25T00:09:08.591Z,1564013348.591 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Depth = 0.000000 m
2019-07-25T00:09:08.599Z,1564013348.599 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Latitude = nan arcdeg
2019-07-25T00:09:08.612Z,1564013348.612 [MissionManager](INFO): DefineOutput profile_station:Science.PeakNO3Longitude = nan arcdeg
2019-07-25T00:09:08.619Z,1564013348.619 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChl = 0.000000 m
2019-07-25T00:09:08.626Z,1564013348.626 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDepth = 0.000000 m
2019-07-25T00:09:08.629Z,1564013348.629 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLatitude = nan arcdeg
2019-07-25T00:09:08.636Z,1564013348.636 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlLongitude = nan arcdeg
2019-07-25T00:09:08.655Z,1564013348.655 [MissionManager](INFO): DefineOutput profile_station:Science.PatchChlDistance = nan m
2019-07-25T00:09:08.663Z,1564013348.663 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOil = 0.000000 kg/m3
2019-07-25T00:09:08.670Z,1564013348.670 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilDepth = 0.000000 m
2019-07-25T00:09:08.673Z,1564013348.673 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLatitude = nan arcdeg
2019-07-25T00:09:08.680Z,1564013348.680 [MissionManager](INFO): DefineOutput profile_station:Science.PeakOilLongitude = nan arcdeg
2019-07-25T00:09:08.692Z,1564013348.692 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOM = 0.000000 ppb
2019-07-25T00:09:08.699Z,1564013348.699 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMDepth = 0.000000 m
2019-07-25T00:09:08.703Z,1564013348.703 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMLatitude = nan arcdeg
2019-07-25T00:09:08.712Z,1564013348.712 [MissionManager](INFO): DefineOutput profile_station:Science.PeakFDOMLongitude = nan arcdeg
2019-07-25T00:09:08.717Z,1564013348.717 [MissionManager](INFO): DefineOutput profile_station:Science.PatchFDOMDistance = nan m
2019-07-25T00:09:08.776Z,1564013348.776 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-07-25T00:09:08.805Z,1564013348.805 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2019-07-25T00:09:08.898Z,1564013348.898 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-07-25T00:09:08.921Z,1564013348.921 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-07-25T00:09:08.948Z,1564013348.948 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-07-25T00:09:08.979Z,1564013348.979 [MissionManager](INFO): RedefineArg profile_station:Science.SampleISUS = value:1 bool
2019-07-25T00:09:08.981Z,1564013348.981 [MissionManager](INFO): RedefineArg profile_station:Science.PeakDetectChlActive = value:1 bool
2019-07-25T00:09:08.981Z,1564013348.981 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2019-07-25T00:09:09.077Z,1564013349.077 [MissionManager](INFO): DefineArg profile_station:NeedComms.DiveInterval = 3.000000 h
2019-07-25T00:09:09.080Z,1564013349.080 [MissionManager](INFO): DefineArg profile_station:NeedComms.WaitForPitchUp = 10.000000 min
2019-07-25T00:09:09.097Z,1564013349.097 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfacePitch = 20.000000 arcdeg
2019-07-25T00:09:09.101Z,1564013349.101 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceDepthRate = nan m/s
2019-07-25T00:09:09.104Z,1564013349.104 [MissionManager](INFO): DefineArg profile_station:NeedComms.SurfaceSpeed = 1.000000 m/s
2019-07-25T00:09:09.119Z,1564013349.119 [MissionManager](INFO): DefineArg profile_station:NeedComms.GPSTimeout = 7.000000 min
2019-07-25T00:09:09.123Z,1564013349.123 [MissionManager](INFO): DefineArg profile_station:NeedComms.CommsTimeout = 30.000000 min
2019-07-25T00:09:09.128Z,1564013349.128 [profile_station:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-07-25T00:09:09.183Z,1564013349.183 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2019-07-25T00:09:09.288Z,1564013349.288 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinAltitude = 5.000000 m
2019-07-25T00:09:09.291Z,1564013349.291 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MaxDepth = 200.000000 m
2019-07-25T00:09:09.309Z,1564013349.309 [MissionManager](INFO): DefineArg profile_station:StandardEnvelopes.MinOffshore = 2000.000000 m
2019-07-25T00:09:09.310Z,1564013349.310 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2019-07-25T00:09:09.318Z,1564013349.318 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2019-07-25T00:09:09.357Z,1564013349.357 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2019-07-25T00:09:09.388Z,1564013349.388 [profile_station:I.Pitch](DEBUG): Construct.
2019-07-25T00:09:09.393Z,1564013349.393 [profile_station:J.Buoyancy](DEBUG): Construct Buoyancy.
2019-07-25T00:09:09.397Z,1564013349.397 [profile_station:K.SetSpeed](DEBUG): Construct.
2019-07-25T00:09:09.421Z,1564013349.421 [profile_station:L.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2019-07-25T00:09:09.439Z,1564013349.439 [profile_station:M.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2019-07-25T00:09:09.469Z,1564013349.469 [profile_station:N.YoYo](DEBUG): Construct YoYo.
2019-07-25T00:09:09.483Z,1564013349.483 [profile_station:CircleWrapper:B.Circle](DEBUG): Construct.
2019-07-25T00:09:09.559Z,1564013349.559 [MissionManager](DEBUG):
This mission yoyos in a circle around a specified location.
Maximum duration of mission
4
Elapsed time after previous surface communications when vehicle will
begin to ascend for additional surface communications
60
The latitude of the center of the circle.
36.806966
The longitude of the center of the circle.
-121.824326
Radius to circle at
300
Minimum depth while performing the YoYo behavior.
2
Maximum depth while performing the YoYo behavior.
200
Minimum altitude while performing the YoYo behavior (for
bottom-terminated YoYos).
7
Pitch (plus and minus) for yo-yo behavior.
20
Vehicle speed.
1
If this distance away from the circle, drive straight towards (or away
from the center). Otherwise, try to reduce distance from the ideal
circle.
100
If true, vehicle turns to the left around the center point. If false,
vehicle turns to the right.
Used to relax waypoint cross-track error constant that is adjusted for
docking. (You can override this setting by passing an argument.)
0.010
Minimum height above the sea floor for the entire mission.
5
Maximum depth for the entire mission.
205
Minimum offshore distance for the entire mission.
2
Most missions will run the science sensors. If you don't place this
aggregate above NeedComms, science instruments get turned off on the
last upcast and while floating on the surface.
Another depth envelope for the YoYo behavior. This envelope should
fall within the limits of the standard safety envelopes in
Insert/StandardEnvelopes.xml in order to avoid commanding high pitch
angles for depth-terminated YoYos.
Another altitude envelope for the YoYo behavior. This envelope
should fall within the limits of the standard safety envelopes in
Insert/StandardEnvelopes.xml in order to avoid commanding high pitch
angles for bottom-terminated YoYos.
2019-07-25T00:09:09.560Z,1564013349.560 [CommandLine](IMPORTANT): Loaded ./Missions/Science/profile_station.xml
2019-07-25T00:09:19.053Z,1564013359.053 [CommandLine](IMPORTANT): got command set profile_station.MissionTimeout 2.000000 hour
2019-07-25T00:09:19.065Z,1564013359.065 [CommandLine](IMPORTANT): got command set profile_station.NeedCommsTime 10.000000 minute
2019-07-25T00:09:19.066Z,1564013359.066 [CommandLine](IMPORTANT): got command set profile_station.Lat 36.797001 degree
2019-07-25T00:09:19.067Z,1564013359.067 [CommandLine](IMPORTANT): got command set profile_station.Lon -121.847000 degree
2019-07-25T00:09:26.099Z,1564013366.099 [CommandLine](IMPORTANT): got command set profile_station.YoYoMaxDepth 30.000000 meter
2019-07-25T00:09:26.100Z,1564013366.100 [CommandLine](IMPORTANT): got command set profile_station.MaxDepth 35.000000 meter
2019-07-25T00:09:26.100Z,1564013366.100 [CommandLine](IMPORTANT): got command set profile_station.MinOffshore 0.500000 kilometer
2019-07-25T00:09:26.101Z,1564013366.101 [CommandLine](IMPORTANT): got command run
2019-07-25T00:09:26.104Z,1564013366.104 [CommandLine](IMPORTANT): Running
2019-07-25T00:09:26.277Z,1564013366.277 [Default] Stopped
2019-07-25T00:09:26.277Z,1564013366.277 [Default](DEBUG): Aggregate::uninitialize Default
2019-07-25T00:09:26.277Z,1564013366.277 [Default:B.GoToSurface] Stopped
2019-07-25T00:09:26.278Z,1564013366.278 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-07-25T00:09:26.278Z,1564013366.278 [Default:CheckIn] Stopped
2019-07-25T00:09:26.278Z,1564013366.278 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-25T00:09:26.278Z,1564013366.278 [Default:CheckIn:C.Wait] Stopped
2019-07-25T00:09:26.278Z,1564013366.278 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-25T00:09:26.278Z,1564013366.278 [MissionManager](IMPORTANT): Started mission profile_station
2019-07-25T00:09:26.278Z,1564013366.278 [profile_station] Running Loop=1
2019-07-25T00:09:26.279Z,1564013366.279 [profile_station](DEBUG): Aggregate::initialize profile_station
2019-07-25T00:09:26.279Z,1564013366.279 [profile_station:Science] Running Loop=1
2019-07-25T00:09:26.279Z,1564013366.279 [profile_station:Science](DEBUG): Aggregate::initialize profile_station:Science
2019-07-25T00:09:26.279Z,1564013366.279 [profile_station:Science:A] Running Loop=1
2019-07-25T00:09:26.279Z,1564013366.279 [profile_station:Science:B] Running Loop=1
2019-07-25T00:09:26.279Z,1564013366.279 [profile_station:Science:C] Running Loop=1
2019-07-25T00:09:26.279Z,1564013366.279 [profile_station:Science:D] Running Loop=1
2019-07-25T00:09:26.279Z,1564013366.279 [profile_station:Science:E] Running Loop=1
2019-07-25T00:09:26.279Z,1564013366.279 [profile_station:Science:F] Running Loop=1
2019-07-25T00:09:26.279Z,1564013366.279 [profile_station:Science:G] Running Loop=1
2019-07-25T00:09:26.279Z,1564013366.279 [profile_station:Science:H] Running Loop=1
2019-07-25T00:09:26.279Z,1564013366.279 [profile_station:Science:Read_Oil] Running Loop=1
2019-07-25T00:09:26.279Z,1564013366.279 [profile_station:Science:PeakDetectChl] Running Loop=1
2019-07-25T00:09:26.280Z,1564013366.280 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectChl
2019-07-25T00:09:26.280Z,1564013366.280 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2019-07-25T00:09:26.280Z,1564013366.280 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-07-25T00:09:26.282Z,1564013366.282 [profile_station:Science:HighestChlPeakReport] Running Loop=1
2019-07-25T00:09:26.282Z,1564013366.282 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize profile_station:Science:HighestChlPeakReport
2019-07-25T00:09:26.282Z,1564013366.282 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2019-07-25T00:09:26.282Z,1564013366.282 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2019-07-25T00:09:26.283Z,1564013366.283 [profile_station:Science:PeakDetectNO3] Running Loop=1
2019-07-25T00:09:26.283Z,1564013366.283 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectNO3
2019-07-25T00:09:26.283Z,1564013366.283 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2019-07-25T00:09:26.283Z,1564013366.283 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-07-25T00:09:26.285Z,1564013366.285 [profile_station:Science:PeakDetectOil] Running Loop=1
2019-07-25T00:09:26.285Z,1564013366.285 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectOil
2019-07-25T00:09:26.285Z,1564013366.285 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2019-07-25T00:09:26.285Z,1564013366.285 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-07-25T00:09:26.298Z,1564013366.298 [profile_station:Science:PeakDetectFDOM] Running Loop=1
2019-07-25T00:09:26.298Z,1564013366.298 [profile_station:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize profile_station:Science:PeakDetectFDOM
2019-07-25T00:09:26.299Z,1564013366.299 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2019-07-25T00:09:26.299Z,1564013366.299 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-07-25T00:09:26.301Z,1564013366.301 [profile_station:StandardEnvelopes] Running Loop=1
2019-07-25T00:09:26.301Z,1564013366.301 [profile_station:StandardEnvelopes](DEBUG): Aggregate::initialize profile_station:StandardEnvelopes
2019-07-25T00:09:26.301Z,1564013366.301 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2019-07-25T00:09:26.301Z,1564013366.301 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2019-07-25T00:09:26.302Z,1564013366.302 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2019-07-25T00:09:26.302Z,1564013366.302 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2019-07-25T00:09:26.302Z,1564013366.302 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2019-07-25T00:09:26.302Z,1564013366.302 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2019-07-25T00:09:26.302Z,1564013366.302 [profile_station:I.Pitch] Running Loop=1
2019-07-25T00:09:26.302Z,1564013366.302 [profile_station:I.Pitch](DEBUG): Initialize.
2019-07-25T00:09:26.303Z,1564013366.303 [profile_station:J.Buoyancy] Running Loop=1
2019-07-25T00:09:26.303Z,1564013366.303 [profile_station:J.Buoyancy](DEBUG): Initialize Buoyancy Component.
2019-07-25T00:09:26.303Z,1564013366.303 [profile_station:K.SetSpeed] Running Loop=1
2019-07-25T00:09:26.303Z,1564013366.303 [profile_station:K.SetSpeed](DEBUG): Initialize.
2019-07-25T00:09:26.303Z,1564013366.303 [profile_station:L.DepthEnvelope] Running Loop=1
2019-07-25T00:09:26.303Z,1564013366.303 [profile_station:L.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2019-07-25T00:09:26.303Z,1564013366.303 [profile_station:M.AltitudeEnvelope] Running Loop=1
2019-07-25T00:09:26.303Z,1564013366.303 [profile_station:M.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2019-07-25T00:09:26.303Z,1564013366.303 [profile_station:N.YoYo] Running Loop=1
2019-07-25T00:09:26.304Z,1564013366.304 [profile_station:N.YoYo](DEBUG): Initialize YoYoComponent.
2019-07-25T00:09:26.304Z,1564013366.304 [profile_station:C] Running Loop=1
2019-07-25T00:09:26.304Z,1564013366.304 [profile_station:N.YoYo] Running Loop=1
2019-07-25T00:09:26.305Z,1564013366.305 [profile_station:M.AltitudeEnvelope] Running Loop=1
2019-07-25T00:09:26.305Z,1564013366.305 [profile_station:L.DepthEnvelope] Running Loop=1
2019-07-25T00:09:26.322Z,1564013366.322 [profile_station:K.SetSpeed] Running Loop=1
2019-07-25T00:09:26.322Z,1564013366.322 [profile_station:J.Buoyancy] Running Loop=1
2019-07-25T00:09:26.322Z,1564013366.322 [profile_station:I.Pitch] Running Loop=1
2019-07-25T00:09:26.323Z,1564013366.323 [profile_station:StandardEnvelopes] Running Loop=1
2019-07-25T00:09:26.323Z,1564013366.323 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2019-07-25T00:09:26.323Z,1564013366.323 [profile_station:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2019-07-25T00:09:26.323Z,1564013366.323 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2019-07-25T00:09:26.324Z,1564013366.324 [profile_station:C] Stopped
2019-07-25T00:09:26.324Z,1564013366.324 [profile_station:E] Running Loop=1
2019-07-25T00:09:26.324Z,1564013366.324 [profile_station:Science] Running Loop=1
2019-07-25T00:09:26.325Z,1564013366.325 [profile_station:Science:PeakDetectFDOM] Stopped
2019-07-25T00:09:26.325Z,1564013366.325 [profile_station:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectFDOM
2019-07-25T00:09:26.325Z,1564013366.325 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2019-07-25T00:09:26.326Z,1564013366.326 [profile_station:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-07-25T00:09:26.326Z,1564013366.326 [profile_station:Science:PeakDetectOil] Stopped
2019-07-25T00:09:26.326Z,1564013366.326 [profile_station:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectOil
2019-07-25T00:09:26.326Z,1564013366.326 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2019-07-25T00:09:26.326Z,1564013366.326 [profile_station:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-07-25T00:09:26.326Z,1564013366.326 [profile_station:Science:PeakDetectNO3] Stopped
2019-07-25T00:09:26.326Z,1564013366.326 [profile_station:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectNO3
2019-07-25T00:09:26.326Z,1564013366.326 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2019-07-25T00:09:26.326Z,1564013366.326 [profile_station:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-07-25T00:09:26.326Z,1564013366.326 [profile_station:Science:HighestChlPeakReport] Stopped
2019-07-25T00:09:26.326Z,1564013366.326 [profile_station:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize profile_station:Science:HighestChlPeakReport
2019-07-25T00:09:26.327Z,1564013366.327 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2019-07-25T00:09:26.327Z,1564013366.327 [profile_station:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2019-07-25T00:09:26.327Z,1564013366.327 [profile_station:Science:PeakDetectChl] Running Loop=1
2019-07-25T00:09:26.327Z,1564013366.327 [profile_station:Science:Read_Oil] Stopped
2019-07-25T00:09:26.327Z,1564013366.327 [profile_station:Science:H] Stopped
2019-07-25T00:09:26.327Z,1564013366.327 [profile_station:Science:G] Running Loop=1
2019-07-25T00:09:26.328Z,1564013366.328 [profile_station:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2019-07-25T00:09:26.328Z,1564013366.328 [profile_station:Science:F] Stopped
2019-07-25T00:09:26.328Z,1564013366.328 [profile_station:Science:E] Stopped
2019-07-25T00:09:26.328Z,1564013366.328 [profile_station:Science:D] Running Loop=1
2019-07-25T00:09:26.329Z,1564013366.329 [profile_station:Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2019-07-25T00:09:26.329Z,1564013366.329 [profile_station:Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2019-07-25T00:09:26.329Z,1564013366.329 [profile_station:Science:C] Running Loop=1
2019-07-25T00:09:26.342Z,1564013366.342 [profile_station:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2019-07-25T00:09:26.343Z,1564013366.343 [profile_station:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2019-07-25T00:09:26.343Z,1564013366.343 [profile_station:Science:B] Stopped
2019-07-25T00:09:26.343Z,1564013366.343 [profile_station:Science:A] Running Loop=1
2019-07-25T00:09:26.344Z,1564013366.344 [profile_station:Science:A](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_oxygen_in_sea_water
2019-07-25T00:09:26.494Z,1564013366.494 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-07-25T00:09:26.494Z,1564013366.494 [WetLabsBB2FL](INFO): Powering up
2019-07-25T00:09:26.664Z,1564013366.664 [profile_station:E] Stopped
2019-07-25T00:09:26.664Z,1564013366.664 [profile_station:F] Running Loop=1
2019-07-25T00:09:27.074Z,1564013367.074 [profile_station:F] Stopped
2019-07-25T00:09:27.074Z,1564013367.074 [profile_station:G] Running Loop=1
2019-07-25T00:09:27.106Z,1564013367.106 [BuoyancyServo](FAULT): Reading outside of valid range:0.000000
2019-07-25T00:09:27.106Z,1564013367.106 [BuoyancyServo](FAULT): Buoyancy engine reporting null position
2019-07-25T00:09:27.107Z,1564013367.107 [BuoyancyServo] Hardware Fault, FailCount= 1
2019-07-25T00:09:27.107Z,1564013367.107 [BuoyancyServo](ERROR): Hardware Fault
2019-07-25T00:09:27.227Z,1564013367.227 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-07-25T00:09:27.265Z,1564013367.265 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2019-07-25T00:09:27.470Z,1564013367.470 [profile_station:G] Stopped
2019-07-25T00:09:27.470Z,1564013367.470 [profile_station:StartingMission] Running Loop=1
2019-07-25T00:09:27.470Z,1564013367.470 [profile_station:StartingMission](DEBUG): Aggregate::initialize profile_station:StartingMission
2019-07-25T00:09:27.867Z,1564013367.867 [profile_station:M.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2019-07-25T00:09:27.869Z,1564013367.869 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2019-07-25T00:09:27.869Z,1564013367.869 [profile_station:NeedComms] Running Loop=1
2019-07-25T00:09:27.869Z,1564013367.869 [profile_station:NeedComms](DEBUG): Aggregate::initialize profile_station:NeedComms
2019-07-25T00:09:27.869Z,1564013367.869 [profile_station:NeedComms:B.GoToSurface] Running Loop=1
2019-07-25T00:09:27.869Z,1564013367.869 [profile_station:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-07-25T00:09:27.870Z,1564013367.870 [profile_station:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2019-07-25T00:09:27.870Z,1564013367.870 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2019-07-25T00:09:27.871Z,1564013367.871 [profile_station:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2019-07-25T00:09:27.871Z,1564013367.871 [profile_station:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-07-25T00:09:27.871Z,1564013367.871 [profile_station:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-07-25T00:09:27.872Z,1564013367.872 [profile_station:NeedComms:A] Running Loop=1
2019-07-25T00:09:27.873Z,1564013367.873 [profile_station:NeedComms:A](INFO): last time_fix was: 1564013312.000000 second since 1970/01/01T00:00:00Z
2019-07-25T00:09:27.873Z,1564013367.873 [profile_station:NeedComms:A] Stopped
2019-07-25T00:09:28.291Z,1564013368.291 [profile_station:NeedComms:C] Running Loop=1
2019-07-25T00:09:28.663Z,1564013368.663 [profile_station:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-07-25T00:09:29.076Z,1564013369.076 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-07-25T00:09:29.076Z,1564013369.076 [BuoyancyServo](INFO): Powering down
2019-07-25T00:09:29.858Z,1564013369.858 [NAL9602](DEBUG): Fix Requested
2019-07-25T00:09:29.898Z,1564013369.898 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2019-07-25T00:09:29.898Z,1564013369.898 [BuoyancyServo] No Fault, FailCount= 1
2019-07-25T00:09:30.239Z,1564013370.239 [CTD_Seabird](ERROR): Failed to parse device response:
2019-07-25T00:09:30.325Z,1564013370.325 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-07-25T00:09:30.326Z,1564013370.326 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-07-25T00:09:32.249Z,1564013372.249 [CTD_Seabird](ERROR): Salinity reading out of range: 0.350017 psu
2019-07-25T00:09:33.253Z,1564013373.253 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349956 psu
2019-07-25T00:09:33.508Z,1564013373.508 [DVL_micro](ERROR): only read 45 of 46 data items
2019-07-25T00:09:33.508Z,1564013373.508 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 92 288 16 -16 2 3 2 2 101.4 405.7 102.4 2 -40.3 -416.2 102.4 2 101 405 102 2 -40 -416 102 2 1.03 -1.52 188.5 -3..005 35.0 1489 123
2019-07-25T00:09:34.257Z,1564013374.257 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349891 psu
2019-07-25T00:09:35.261Z,1564013375.261 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349826 psu
2019-07-25T00:09:36.265Z,1564013376.265 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349823 psu
2019-07-25T00:09:37.269Z,1564013377.269 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349817 psu
2019-07-25T00:09:38.273Z,1564013378.273 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349756 psu
2019-07-25T00:09:38.756Z,1564013378.756 [CommandLine](IMPORTANT): got command show variable speedContorl
2019-07-25T00:09:39.277Z,1564013379.277 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349754 psu
2019-07-25T00:09:40.281Z,1564013380.281 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349745 psu
2019-07-25T00:09:41.284Z,1564013381.284 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349681 psu
2019-07-25T00:09:42.288Z,1564013382.288 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349673 psu
2019-07-25T00:09:42.772Z,1564013382.772 [CommandLine](IMPORTANT): got command show variable speedControl
2019-07-25T00:09:42.927Z,1564013382.927 [CommandLine](IMPORTANT): SpeedControl.loadAtStartup (bool)
2019-07-25T00:09:42.928Z,1564013382.928 [CommandLine](IMPORTANT): SpeedControl.propPitch (meter_per_radian)
2019-07-25T00:09:42.982Z,1564013382.982 [CommandLine](IMPORTANT): SpeedControl.propOmegaAction (radian_per_second)
2019-07-25T00:09:42.983Z,1564013382.983 [CommandLine](IMPORTANT): SpeedControl.speedCmd (meter_per_second)
2019-07-25T00:09:43.292Z,1564013383.292 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349671 psu
2019-07-25T00:09:44.296Z,1564013384.296 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349606 psu
2019-07-25T00:09:45.300Z,1564013385.300 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349597 psu
2019-07-25T00:09:46.304Z,1564013386.304 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349534 psu
2019-07-25T00:09:47.308Z,1564013387.308 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349522 psu
2019-07-25T00:09:48.312Z,1564013388.312 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349516 psu
2019-07-25T00:09:49.316Z,1564013389.316 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349405 psu
2019-07-25T00:09:50.320Z,1564013390.320 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349404 psu
2019-07-25T00:09:51.324Z,1564013391.324 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349357 psu
2019-07-25T00:09:52.328Z,1564013392.328 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349358 psu
2019-07-25T00:09:52.748Z,1564013392.748 [CommandLine](IMPORTANT): got command report mod SpeedControl.speedCmd
2019-07-25T00:09:52.999Z,1564013392.999 [Reporter](INFO): SpeedControl.speedCmd 0.000000 m/s
2019-07-25T00:09:53.332Z,1564013393.332 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349302 psu
2019-07-25T00:09:54.336Z,1564013394.336 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349302 psu
2019-07-25T00:09:55.340Z,1564013395.340 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349239 psu
2019-07-25T00:09:56.344Z,1564013396.344 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349181 psu
2019-07-25T00:09:57.348Z,1564013397.348 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349170 psu
2019-07-25T00:09:58.352Z,1564013398.352 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349109 psu
2019-07-25T00:09:59.356Z,1564013399.356 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349098 psu
2019-07-25T00:10:00.360Z,1564013400.360 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349094 psu
2019-07-25T00:10:00.965Z,1564013400.965 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-07-25T00:10:01.038Z,1564013401.038 [NAL9602](FAULT): received:
+CSQ:0
OK
2019-07-25T00:10:01.038Z,1564013401.038 [NAL9602] Data Fault, FailCount= 1
2019-07-25T00:10:01.038Z,1564013401.038 [NAL9602](ERROR): Data Fault
2019-07-25T00:10:01.141Z,1564013401.141 [CBIT](ERROR): Data Fault in component: NAL9602
2019-07-25T00:10:01.364Z,1564013401.364 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349031 psu
2019-07-25T00:10:01.368Z,1564013401.368 [NAL9602](INFO): Powering down
2019-07-25T00:10:02.225Z,1564013402.225 [CBIT](INFO): Clearing failed state for component NAL9602
2019-07-25T00:10:02.225Z,1564013402.225 [NAL9602] No Fault, FailCount= 1
2019-07-25T00:10:02.368Z,1564013402.368 [CTD_Seabird](ERROR): Salinity reading out of range: 0.349021 psu
2019-07-25T00:10:03.372Z,1564013403.372 [CTD_Seabird](ERROR): Salinity reading out of range: 0.348957 psu
2019-07-25T00:10:04.376Z,1564013404.376 [CTD_Seabird](ERROR): Salinity reading out of range: 0.348954 psu
2019-07-25T00:10:05.039Z,1564013405.039 [BPC1](ERROR): Battery stick #38 (s/n: 0139) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-07-25T00:10:05.039Z,1564013405.039 [BPC1](ERROR): Battery stick #39 (s/n: 00F0) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-07-25T00:10:05.040Z,1564013405.040 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 5.
2019-07-25T00:10:05.047Z,1564013405.047 [BPC1](INFO): Received data from all battery sticks.
2019-07-25T00:10:05.380Z,1564013405.380 [CTD_Seabird](ERROR): Salinity reading out of range: 0.348889 psu
2019-07-25T00:10:06.384Z,1564013406.384 [CTD_Seabird](ERROR): Salinity reading out of range: 0.348896 psu
2019-07-25T00:10:06.455Z,1564013406.455 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1564013440.000000 second
2019-07-25T00:10:07.388Z,1564013407.388 [CTD_Seabird](ERROR): Salinity reading out of range: 0.348841 psu
2019-07-25T00:10:08.392Z,1564013408.392 [CTD_Seabird](ERROR): Salinity reading out of range: 0.348788 psu
2019-07-25T00:10:09.396Z,1564013409.396 [CTD_Seabird](ERROR): Salinity reading out of range: 0.348724 psu
2019-07-25T00:10:10.400Z,1564013410.400 [CTD_Seabird](ERROR): Salinity reading out of range: 0.348726 psu
2019-07-25T00:10:11.405Z,1564013411.405 [CTD_Seabird](ERROR): Salinity reading out of range: 0.348668 psu
2019-07-25T00:10:12.409Z,1564013412.409 [CTD_Seabird](ERROR): Salinity reading out of range: 0.348662 psu
2019-07-25T00:10:13.413Z,1564013413.413 [CTD_Seabird](ERROR): Salinity reading out of range: 0.348597 psu
2019-07-25T00:10:14.417Z,1564013414.417 [CTD_Seabird](ERROR): Salinity reading out of range: 0.348543 psu
2019-07-25T00:10:15.421Z,1564013415.421 [CTD_Seabird](ERROR): Salinity reading out of range: 0.348487 psu
2019-07-25T00:10:16.428Z,1564013416.428 [CTD_Seabird](ERROR): Salinity reading out of range: 0.348479 psu
2019-07-25T00:10:17.432Z,1564013417.432 [CTD_Seabird](ERROR): Salinity reading out of range: 0.348423 psu
2019-07-25T00:10:18.436Z,1564013418.436 [CTD_Seabird](ERROR): Salinity reading out of range: 0.348367 psu
2019-07-25T00:10:19.440Z,1564013419.440 [CTD_Seabird](ERROR): Salinity reading out of range: 0.348364 psu
2019-07-25T00:10:20.444Z,1564013420.444 [CTD_Seabird](ERROR): Salinity reading out of range: 0.348309 psu
2019-07-25T00:10:21.448Z,1564013421.448 [CTD_Seabird](ERROR): Salinity reading out of range: 0.348317 psu
2019-07-25T00:10:22.452Z,1564013422.452 [CTD_Seabird](ERROR): Salinity reading out of range: 0.348254 psu
2019-07-25T00:10:23.456Z,1564013423.456 [CTD_Seabird](ERROR): Salinity reading out of range: 0.348203 psu
2019-07-25T00:10:24.460Z,1564013424.460 [CTD_Seabird](ERROR): Salinity reading out of range: 0.348198 psu
2019-07-25T00:10:25.464Z,1564013425.464 [CTD_Seabird](ERROR): Salinity reading out of range: 0.348137 psu
2019-07-25T00:10:26.468Z,1564013426.468 [CTD_Seabird](ERROR): Salinity reading out of range: 0.348075 psu
2019-07-25T00:10:27.472Z,1564013427.472 [CTD_Seabird](ERROR): Salinity reading out of range: 0.348073 psu
2019-07-25T00:10:28.476Z,1564013428.476 [CTD_Seabird](ERROR): Salinity reading out of range: 0.348003 psu
2019-07-25T00:10:29.480Z,1564013429.480 [CTD_Seabird](ERROR): Salinity reading out of range: 0.347949 psu
2019-07-25T00:10:30.485Z,1564013430.485 [CTD_Seabird](ERROR): Salinity reading out of range: 0.347944 psu
2019-07-25T00:10:31.488Z,1564013431.488 [CTD_Seabird](ERROR): Salinity reading out of range: 0.347884 psu
2019-07-25T00:10:31.708Z,1564013431.708 [NAL9602](INFO): Powering up NAL9602
2019-07-25T00:10:32.492Z,1564013432.492 [CTD_Seabird](ERROR): Salinity reading out of range: 0.347878 psu
2019-07-25T00:10:33.496Z,1564013433.496 [CTD_Seabird](ERROR): Salinity reading out of range: 0.347816 psu
2019-07-25T00:10:34.500Z,1564013434.500 [CTD_Seabird](ERROR): Salinity reading out of range: 0.347751 psu
2019-07-25T00:10:35.504Z,1564013435.504 [CTD_Seabird](ERROR): Salinity reading out of range: 0.347695 psu
2019-07-25T00:10:36.508Z,1564013436.508 [CTD_Seabird](ERROR): Salinity reading out of range: 0.347685 psu
2019-07-25T00:10:37.512Z,1564013437.512 [CTD_Seabird](ERROR): Salinity reading out of range: 0.347626 psu
2019-07-25T00:10:38.516Z,1564013438.516 [CTD_Seabird](ERROR): Salinity reading out of range: 0.347622 psu
2019-07-25T00:10:39.520Z,1564013439.520 [CTD_Seabird](ERROR): Salinity reading out of range: 0.347558 psu
2019-07-25T00:10:40.525Z,1564013440.525 [CTD_Seabird](ERROR): Salinity reading out of range: 0.347495 psu
2019-07-25T00:10:41.529Z,1564013441.529 [CTD_Seabird](ERROR): Salinity reading out of range: 0.347487 psu
2019-07-25T00:10:42.533Z,1564013442.533 [CTD_Seabird](ERROR): Salinity reading out of range: 0.347483 psu
2019-07-25T00:10:42.607Z,1564013442.607 [NAL9602](INFO): NAL9602 initialized
2019-07-25T00:10:43.418Z,1564013443.418 [NAL9602](DEBUG): Fix Requested
2019-07-25T00:10:43.537Z,1564013443.537 [CTD_Seabird](ERROR): Salinity reading out of range: 0.347420 psu
2019-07-25T00:10:44.541Z,1564013444.541 [CTD_Seabird](ERROR): Salinity reading out of range: 0.347365 psu
2019-07-25T00:10:45.544Z,1564013445.544 [CTD_Seabird](ERROR): Salinity reading out of range: 0.347303 psu
2019-07-25T00:10:46.548Z,1564013446.548 [CTD_Seabird](ERROR): Salinity reading out of range: 0.347297 psu
2019-07-25T00:10:47.578Z,1564013447.578 [CTD_Seabird](ERROR): Salinity reading out of range: 0.347238 psu
2019-07-25T00:10:48.556Z,1564013448.556 [CTD_Seabird](ERROR): Salinity reading out of range: 0.347178 psu
2019-07-25T00:10:49.560Z,1564013449.560 [CTD_Seabird](ERROR): Salinity reading out of range: 0.347171 psu
2019-07-25T00:10:50.564Z,1564013450.564 [CTD_Seabird](ERROR): Salinity reading out of range: 0.347113 psu
2019-07-25T00:10:51.568Z,1564013451.568 [CTD_Seabird](ERROR): Salinity reading out of range: 0.347109 psu
2019-07-25T00:10:52.572Z,1564013452.572 [CTD_Seabird](ERROR): Salinity reading out of range: 0.347045 psu
2019-07-25T00:10:52.709Z,1564013452.709 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2019-07-25T00:10:53.576Z,1564013453.576 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346930 psu
2019-07-25T00:10:54.580Z,1564013454.580 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346876 psu
2019-07-25T00:10:55.584Z,1564013455.584 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346874 psu
2019-07-25T00:10:56.588Z,1564013456.588 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346814 psu
2019-07-25T00:10:57.628Z,1564013457.628 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346765 psu
2019-07-25T00:10:58.631Z,1564013458.631 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346775 psu
2019-07-25T00:10:59.635Z,1564013459.635 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346726 psu
2019-07-25T00:11:00.639Z,1564013460.639 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346784 psu
2019-07-25T00:11:01.643Z,1564013461.643 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346676 psu
2019-07-25T00:11:02.635Z,1564013462.635 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346674 psu
2019-07-25T00:11:03.639Z,1564013463.639 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346626 psu
2019-07-25T00:11:04.643Z,1564013464.643 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346568 psu
2019-07-25T00:11:05.647Z,1564013465.647 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346624 psu
2019-07-25T00:11:06.651Z,1564013466.651 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346626 psu
2019-07-25T00:11:07.643Z,1564013467.643 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346566 psu
2019-07-25T00:11:07.864Z,1564013467.864 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1564013440.000000 second
2019-07-25T00:11:08.108Z,1564013468.108 [profile_station:NeedComms:C] Stopped
2019-07-25T00:11:08.108Z,1564013468.108 [profile_station:NeedComms:D] Running Loop=1
2019-07-25T00:11:08.491Z,1564013468.491 [profile_station:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-07-25T00:11:08.651Z,1564013468.651 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346510 psu
2019-07-25T00:11:09.121Z,1564013469.121 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20190725T000219/Courier0007.lzma
2019-07-25T00:11:09.612Z,1564013469.612 [DataOverHttps](INFO): Moved sent file to Logs/20190725T000219/Courier0007.lzma.bak
2019-07-25T00:11:09.613Z,1564013469.613 [DataOverHttps](INFO): SBD MOMSN=11473396
2019-07-25T00:11:09.651Z,1564013469.651 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346506 psu
2019-07-25T00:11:10.659Z,1564013470.659 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346454 psu
2019-07-25T00:11:11.659Z,1564013471.659 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346384 psu
2019-07-25T00:11:12.652Z,1564013472.652 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346388 psu
2019-07-25T00:11:13.656Z,1564013473.656 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346609 psu
2019-07-25T00:11:14.660Z,1564013474.660 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346329 psu
2019-07-25T00:11:15.664Z,1564013475.664 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346268 psu
2019-07-25T00:11:16.668Z,1564013476.668 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346271 psu
2019-07-25T00:11:17.040Z,1564013477.040 [CommandLine](IMPORTANT): got command show stack
2019-07-25T00:11:17.040Z,1564013477.040 [CommandLine](IMPORTANT): Behavior Stack:
2019-07-25T00:11:17.041Z,1564013477.041 [profile_station:Science](IMPORTANT): Priority 0: profile_station:Science:A
2019-07-25T00:11:17.041Z,1564013477.041 [profile_station:Science](IMPORTANT): Priority 1: profile_station:Science:C
2019-07-25T00:11:17.042Z,1564013477.042 [profile_station:Science](IMPORTANT): Priority 2: profile_station:Science:D
2019-07-25T00:11:17.042Z,1564013477.042 [profile_station:Science](IMPORTANT): Priority 3: profile_station:Science:G
2019-07-25T00:11:17.042Z,1564013477.042 [profile_station:Science:PeakDetectChl](IMPORTANT): Priority 4: profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth
2019-07-25T00:11:17.043Z,1564013477.043 [profile_station:NeedComms](IMPORTANT): Priority 5: profile_station:NeedComms:B.GoToSurface
2019-07-25T00:11:17.043Z,1564013477.043 [profile_station:NeedComms](IMPORTANT): Priority 6: profile_station:NeedComms:D
2019-07-25T00:11:17.043Z,1564013477.043 [profile_station:StandardEnvelopes](IMPORTANT): Priority 7: profile_station:StandardEnvelopes:A.AltitudeEnvelope
2019-07-25T00:11:17.044Z,1564013477.044 [profile_station:StandardEnvelopes](IMPORTANT): Priority 8: profile_station:StandardEnvelopes:B.DepthEnvelope
2019-07-25T00:11:17.044Z,1564013477.044 [profile_station:StandardEnvelopes](IMPORTANT): Priority 9: profile_station:StandardEnvelopes:C.OffshoreEnvelope
2019-07-25T00:11:17.044Z,1564013477.044 [profile_station](IMPORTANT): Priority 10: profile_station:I.Pitch
2019-07-25T00:11:17.044Z,1564013477.044 [profile_station](IMPORTANT): Priority 11: profile_station:J.Buoyancy
2019-07-25T00:11:17.045Z,1564013477.045 [profile_station](IMPORTANT): Priority 12: profile_station:K.SetSpeed
2019-07-25T00:11:17.045Z,1564013477.045 [profile_station](IMPORTANT): Priority 13: profile_station:L.DepthEnvelope
2019-07-25T00:11:17.045Z,1564013477.045 [profile_station](IMPORTANT): Priority 14: profile_station:M.AltitudeEnvelope
2019-07-25T00:11:17.046Z,1564013477.046 [profile_station](IMPORTANT): Priority 15: profile_station:N.YoYo
2019-07-25T00:11:17.672Z,1564013477.672 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346215 psu
2019-07-25T00:11:18.676Z,1564013478.676 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346156 psu
2019-07-25T00:11:19.680Z,1564013479.680 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346156 psu
2019-07-25T00:11:20.684Z,1564013480.684 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346099 psu
2019-07-25T00:11:21.688Z,1564013481.688 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346098 psu
2019-07-25T00:11:22.692Z,1564013482.692 [CTD_Seabird](ERROR): Salinity reading out of range: 0.346041 psu
2019-07-25T00:11:22.795Z,1564013482.795 [CommandLine](IMPORTANT): got command show variable elevatorAngle
2019-07-25T00:11:23.072Z,1564013483.072 [CommandLine](IMPORTANT): VerticalControl.elevatorAngleAction (radian)
2019-07-25T00:11:23.082Z,1564013483.082 [CommandLine](IMPORTANT): VerticalControl.elevatorAngleCmd (radian)
2019-07-25T00:11:23.493Z,1564013483.493 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190725T000219/Courier0010.lzma
2019-07-25T00:11:23.696Z,1564013483.696 [CTD_Seabird](ERROR): Salinity reading out of range: 0.345987 psu
2019-07-25T00:11:24.301Z,1564013484.301 [DataOverHttps](INFO): Moved sent file to Logs/20190725T000219/Courier0010.lzma.bak
2019-07-25T00:11:24.301Z,1564013484.301 [DataOverHttps](INFO): SBD MOMSN=11473398
2019-07-25T00:11:24.700Z,1564013484.700 [CTD_Seabird](ERROR): Salinity reading out of range: 0.345983 psu
2019-07-25T00:11:25.704Z,1564013485.704 [CTD_Seabird](ERROR): Salinity reading out of range: 0.345925 psu
2019-07-25T00:11:26.708Z,1564013486.708 [CTD_Seabird](ERROR): Salinity reading out of range: 0.345863 psu
2019-07-25T00:11:27.712Z,1564013487.712 [CTD_Seabird](ERROR): Salinity reading out of range: 0.345863 psu
2019-07-25T00:11:28.716Z,1564013488.716 [CTD_Seabird](ERROR): Salinity reading out of range: 0.345856 psu
2019-07-25T00:11:29.720Z,1564013489.720 [CTD_Seabird](ERROR): Salinity reading out of range: 0.345805 psu
2019-07-25T00:11:30.724Z,1564013490.724 [CTD_Seabird](ERROR): Salinity reading out of range: 0.345802 psu
2019-07-25T00:11:31.728Z,1564013491.728 [CTD_Seabird](ERROR): Salinity reading out of range: 0.345744 psu
2019-07-25T00:11:32.732Z,1564013492.732 [CTD_Seabird](ERROR): Salinity reading out of range: 0.345687 psu
2019-07-25T00:11:33.032Z,1564013493.032 [CommandLine](IMPORTANT): got command get VerticalControl.elevatorAngleAction degree
2019-07-25T00:11:33.032Z,1564013493.032 [CommandLine](IMPORTANT): VerticalControl.elevatorAngleAction 0.000000 arcdeg
2019-07-25T00:11:33.736Z,1564013493.736 [CTD_Seabird](ERROR): Salinity reading out of range: 0.345631 psu
2019-07-25T00:11:34.741Z,1564013494.741 [CTD_Seabird](ERROR): Salinity reading out of range: 0.345634 psu
2019-07-25T00:11:35.744Z,1564013495.744 [CTD_Seabird](ERROR): Salinity reading out of range: 0.345589 psu
2019-07-25T00:11:36.748Z,1564013496.748 [CTD_Seabird](ERROR): Salinity reading out of range: 0.345580 psu
2019-07-25T00:11:36.901Z,1564013496.901 [CommandLine](IMPORTANT): got command show stack
2019-07-25T00:11:36.901Z,1564013496.901 [CommandLine](IMPORTANT): Behavior Stack:
2019-07-25T00:11:36.902Z,1564013496.902 [profile_station:Science](IMPORTANT): Priority 0: profile_station:Science:A
2019-07-25T00:11:36.902Z,1564013496.902 [profile_station:Science](IMPORTANT): Priority 1: profile_station:Science:C
2019-07-25T00:11:36.903Z,1564013496.903 [profile_station:Science](IMPORTANT): Priority 2: profile_station:Science:D
2019-07-25T00:11:36.903Z,1564013496.903 [profile_station:Science](IMPORTANT): Priority 3: profile_station:Science:G
2019-07-25T00:11:36.903Z,1564013496.903 [profile_station:Science:PeakDetectChl](IMPORTANT): Priority 4: profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth
2019-07-25T00:11:36.904Z,1564013496.904 [profile_station:NeedComms](IMPORTANT): Priority 5: profile_station:NeedComms:B.GoToSurface
2019-07-25T00:11:36.904Z,1564013496.904 [profile_station:NeedComms](IMPORTANT): Priority 6: profile_station:NeedComms:D
2019-07-25T00:11:36.904Z,1564013496.904 [profile_station:StandardEnvelopes](IMPORTANT): Priority 7: profile_station:StandardEnvelopes:A.AltitudeEnvelope
2019-07-25T00:11:36.904Z,1564013496.904 [profile_station:StandardEnvelopes](IMPORTANT): Priority 8: profile_station:StandardEnvelopes:B.DepthEnvelope
2019-07-25T00:11:36.905Z,1564013496.905 [profile_station:StandardEnvelopes](IMPORTANT): Priority 9: profile_station:StandardEnvelopes:C.OffshoreEnvelope
2019-07-25T00:11:36.905Z,1564013496.905 [profile_station](IMPORTANT): Priority 10: profile_station:I.Pitch
2019-07-25T00:11:36.905Z,1564013496.905 [profile_station](IMPORTANT): Priority 11: profile_station:J.Buoyancy
2019-07-25T00:11:36.906Z,1564013496.906 [profile_station](IMPORTANT): Priority 12: profile_station:K.SetSpeed
2019-07-25T00:11:36.906Z,1564013496.906 [profile_station](IMPORTANT): Priority 13: profile_station:L.DepthEnvelope
2019-07-25T00:11:36.906Z,1564013496.906 [profile_station](IMPORTANT): Priority 14: profile_station:M.AltitudeEnvelope
2019-07-25T00:11:36.907Z,1564013496.907 [profile_station](IMPORTANT): Priority 15: profile_station:N.YoYo
2019-07-25T00:11:37.752Z,1564013497.752 [CTD_Seabird](ERROR): Salinity reading out of range: 0.345530 psu
2019-07-25T00:11:38.756Z,1564013498.756 [CTD_Seabird](ERROR): Salinity reading out of range: 0.345469 psu
2019-07-25T00:11:39.363Z,1564013499.363 [DataOverHttps](INFO): Sending 1315 bytes from file Logs/20190725T000219/Express0008.lzma
2019-07-25T00:11:39.760Z,1564013499.760 [CTD_Seabird](ERROR): Salinity reading out of range: 0.345464 psu
2019-07-25T00:11:40.168Z,1564013500.168 [DataOverHttps](INFO): Moved sent file to Logs/20190725T000219/Express0008.lzma.bak
2019-07-25T00:11:40.168Z,1564013500.168 [DataOverHttps](INFO): SBD MOMSN=11473400
2019-07-25T00:11:40.764Z,1564013500.764 [CTD_Seabird](ERROR): Salinity reading out of range: 0.345402 psu
2019-07-25T00:11:41.768Z,1564013501.768 [CTD_Seabird](ERROR): Salinity reading out of range: 0.345344 psu
2019-07-25T00:11:42.773Z,1564013502.773 [CTD_Seabird](ERROR): Salinity reading out of range: 0.345282 psu
2019-07-25T00:11:43.778Z,1564013503.778 [CTD_Seabird](ERROR): Salinity reading out of range: 0.345283 psu
2019-07-25T00:11:44.782Z,1564013504.782 [CTD_Seabird](ERROR): Salinity reading out of range: 0.345231 psu
2019-07-25T00:11:45.785Z,1564013505.785 [CTD_Seabird](ERROR): Salinity reading out of range: 0.345167 psu
2019-07-25T00:11:46.033Z,1564013506.033 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-07-25T00:11:46.033Z,1564013506.033 [DVL_micro] Data Fault, FailCount= 1
2019-07-25T00:11:46.033Z,1564013506.033 [DVL_micro](ERROR): Data Fault
2019-07-25T00:11:46.125Z,1564013506.125 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-07-25T00:11:46.498Z,1564013506.498 [DVL_micro](INFO): uninitialize:Powering down
2019-07-25T00:11:46.789Z,1564013506.789 [CTD_Seabird](ERROR): Salinity reading out of range: 0.345167 psu
2019-07-25T00:11:47.279Z,1564013507.279 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-07-25T00:11:47.279Z,1564013507.279 [DVL_micro] No Fault, FailCount= 1
2019-07-25T00:11:47.646Z,1564013507.646 [DVL_micro](INFO): Initializing
2019-07-25T00:11:47.793Z,1564013507.793 [CTD_Seabird](ERROR): Salinity reading out of range: 0.345166 psu
2019-07-25T00:11:48.798Z,1564013508.798 [CTD_Seabird](ERROR): Salinity reading out of range: 0.345159 psu
2019-07-25T00:11:49.800Z,1564013509.800 [CTD_Seabird](ERROR): Salinity reading out of range: 0.345099 psu
2019-07-25T00:11:50.804Z,1564013510.804 [CTD_Seabird](ERROR): Salinity reading out of range: 0.345048 psu
2019-07-25T00:11:51.808Z,1564013511.808 [CTD_Seabird](ERROR): Salinity reading out of range: 0.344978 psu
2019-07-25T00:11:52.812Z,1564013512.812 [CTD_Seabird](ERROR): Salinity reading out of range: 0.344913 psu
2019-07-25T00:11:53.816Z,1564013513.816 [CTD_Seabird](ERROR): Salinity reading out of range: 0.344909 psu
2019-07-25T00:11:54.419Z,1564013514.419 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1564013440.000000 second
2019-07-25T00:11:54.821Z,1564013514.821 [CTD_Seabird](ERROR): Salinity reading out of range: 0.344852 psu
2019-07-25T00:11:54.865Z,1564013514.865 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20190725T000219/Express0011.lzma
2019-07-25T00:11:55.671Z,1564013515.671 [DataOverHttps](INFO): Moved sent file to Logs/20190725T000219/Express0011.lzma.bak
2019-07-25T00:11:55.672Z,1564013515.672 [DataOverHttps](INFO): SBD MOMSN=11473447
2019-07-25T00:11:55.824Z,1564013515.824 [CTD_Seabird](ERROR): Salinity reading out of range: 0.344846 psu
2019-07-25T00:11:56.156Z,1564013516.156 [profile_station:NeedComms:D] Stopped
2019-07-25T00:11:56.156Z,1564013516.156 [profile_station:NeedComms:E] Running Loop=1
2019-07-25T00:11:56.548Z,1564013516.548 [profile_station:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-07-25T00:11:56.828Z,1564013516.828 [CTD_Seabird](ERROR): Salinity reading out of range: 0.344846 psu
2019-07-25T00:11:57.379Z,1564013517.379 [DVL_micro](ERROR): only read 44 of 46 data items
2019-07-25T00:11:57.379Z,1564013517.379 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 123 134 37 246 2 3 2 2 114.8 -149.5 145.6 2 -135.2 131.4 145.6 2 114 -149 145 2 -135 131.05 -1.55 188.3 -3.0 18.9 0.005 35.0 1489 93
2019-07-25T00:11:57.832Z,1564013517.832 [CTD_Seabird](ERROR): Salinity reading out of range: 0.344795 psu
2019-07-25T00:11:58.836Z,1564013518.836 [CTD_Seabird](ERROR): Salinity reading out of range: 0.344747 psu
2019-07-25T00:11:59.840Z,1564013519.840 [CTD_Seabird](ERROR): Salinity reading out of range: 0.344757 psu
2019-07-25T00:12:00.844Z,1564013520.844 [CTD_Seabird](ERROR): Salinity reading out of range: 0.344698 psu
2019-07-25T00:12:01.848Z,1564013521.848 [CTD_Seabird](ERROR): Salinity reading out of range: 0.344642 psu
2019-07-25T00:12:02.852Z,1564013522.852 [CTD_Seabird](ERROR): Salinity reading out of range: 0.344584 psu
2019-07-25T00:12:03.856Z,1564013523.856 [CTD_Seabird](ERROR): Salinity reading out of range: 0.344577 psu
2019-07-25T00:12:04.860Z,1564013524.860 [CTD_Seabird](ERROR): Salinity reading out of range: 0.344519 psu
2019-07-25T00:12:05.864Z,1564013525.864 [CTD_Seabird](ERROR): Salinity reading out of range: 0.344454 psu
2019-07-25T00:12:06.868Z,1564013526.868 [CTD_Seabird](ERROR): Salinity reading out of range: 0.344446 psu
2019-07-25T00:12:07.087Z,1564013527.087 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2019-07-25T00:12:07.872Z,1564013527.872 [CTD_Seabird](ERROR): Salinity reading out of range: 0.344388 psu
2019-07-25T00:12:08.876Z,1564013528.876 [CTD_Seabird](ERROR): Salinity reading out of range: 0.344330 psu
2019-07-25T00:12:09.880Z,1564013529.880 [CTD_Seabird](ERROR): Salinity reading out of range: 0.344325 psu
2019-07-25T00:12:10.884Z,1564013530.884 [CTD_Seabird](ERROR): Salinity reading out of range: 0.344264 psu
2019-07-25T00:12:11.888Z,1564013531.888 [CTD_Seabird](ERROR): Salinity reading out of range: 0.344258 psu
2019-07-25T00:12:12.892Z,1564013532.892 [CTD_Seabird](ERROR): Salinity reading out of range: 0.344196 psu
2019-07-25T00:12:13.896Z,1564013533.896 [CTD_Seabird](ERROR): Salinity reading out of range: 0.344141 psu
2019-07-25T00:12:14.899Z,1564013534.899 [CTD_Seabird](ERROR): Failed to parse device response: 0
2019-07-25T00:12:14.947Z,1564013534.947 [CommandLine](IMPORTANT): got command show variable elevatorAngle
2019-07-25T00:12:15.070Z,1564013535.070 [CommandLine](IMPORTANT): VerticalControl.elevatorAngleAction (radian)
2019-07-25T00:12:15.071Z,1564013535.071 [CommandLine](IMPORTANT): VerticalControl.elevatorAngleCmd (radian)
2019-07-25T00:12:15.904Z,1564013535.904 [CTD_Seabird](ERROR): Salinity reading out of range: 0.344082 psu
2019-07-25T00:12:16.908Z,1564013536.908 [CTD_Seabird](ERROR): Salinity reading out of range: 0.344079 psu
2019-07-25T00:12:17.912Z,1564013537.912 [CTD_Seabird](ERROR): Salinity reading out of range: 0.344018 psu
2019-07-25T00:12:18.916Z,1564013538.916 [CTD_Seabird](ERROR): Salinity reading out of range: 0.344008 psu
2019-07-25T00:12:19.920Z,1564013539.920 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343953 psu
2019-07-25T00:12:20.924Z,1564013540.924 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343945 psu
2019-07-25T00:12:21.928Z,1564013541.928 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343888 psu
2019-07-25T00:12:22.932Z,1564013542.932 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343885 psu
2019-07-25T00:12:23.936Z,1564013543.936 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343826 psu
2019-07-25T00:12:24.943Z,1564013544.943 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343829 psu
2019-07-25T00:12:25.944Z,1564013545.944 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343769 psu
2019-07-25T00:12:26.948Z,1564013546.948 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343769 psu
2019-07-25T00:12:27.952Z,1564013547.952 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343713 psu
2019-07-25T00:12:28.567Z,1564013548.567 [CommandLine](IMPORTANT): got command get VerticalControl.elevatorAngleAction degree
2019-07-25T00:12:28.568Z,1564013548.568 [CommandLine](IMPORTANT): VerticalControl.elevatorAngleAction 0.000000 arcdeg
2019-07-25T00:12:28.956Z,1564013548.956 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343651 psu
2019-07-25T00:12:29.960Z,1564013549.960 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343594 psu
2019-07-25T00:12:30.964Z,1564013550.964 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343536 psu
2019-07-25T00:12:31.968Z,1564013551.968 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343524 psu
2019-07-25T00:12:32.972Z,1564013552.972 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343523 psu
2019-07-25T00:12:33.976Z,1564013553.976 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343465 psu
2019-07-25T00:12:34.980Z,1564013554.980 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343467 psu
2019-07-25T00:12:35.989Z,1564013555.989 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343464 psu
2019-07-25T00:12:36.988Z,1564013556.988 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343403 psu
2019-07-25T00:12:37.995Z,1564013557.995 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343408 psu
2019-07-25T00:12:38.996Z,1564013558.996 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343465 psu
2019-07-25T00:12:40.002Z,1564013560.002 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343463 psu
2019-07-25T00:12:41.004Z,1564013561.004 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343404 psu
2019-07-25T00:12:42.008Z,1564013562.008 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343404 psu
2019-07-25T00:12:43.012Z,1564013563.012 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343341 psu
2019-07-25T00:12:44.016Z,1564013564.016 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343344 psu
2019-07-25T00:12:45.020Z,1564013565.020 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343284 psu
2019-07-25T00:12:46.025Z,1564013566.025 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343228 psu
2019-07-25T00:12:47.029Z,1564013567.029 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343176 psu
2019-07-25T00:12:47.079Z,1564013567.079 [CommandLine](IMPORTANT): got command show stack
2019-07-25T00:12:47.079Z,1564013567.079 [CommandLine](IMPORTANT): Behavior Stack:
2019-07-25T00:12:47.080Z,1564013567.080 [profile_station:Science](IMPORTANT): Priority 0: profile_station:Science:A
2019-07-25T00:12:47.080Z,1564013567.080 [profile_station:Science](IMPORTANT): Priority 1: profile_station:Science:C
2019-07-25T00:12:47.081Z,1564013567.081 [profile_station:Science](IMPORTANT): Priority 2: profile_station:Science:D
2019-07-25T00:12:47.081Z,1564013567.081 [profile_station:Science](IMPORTANT): Priority 3: profile_station:Science:G
2019-07-25T00:12:47.081Z,1564013567.081 [profile_station:Science:PeakDetectChl](IMPORTANT): Priority 4: profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth
2019-07-25T00:12:47.082Z,1564013567.082 [profile_station:NeedComms](IMPORTANT): Priority 5: profile_station:NeedComms:B.GoToSurface
2019-07-25T00:12:47.083Z,1564013567.083 [profile_station:NeedComms](IMPORTANT): Priority 6: profile_station:NeedComms:E
2019-07-25T00:12:47.083Z,1564013567.083 [profile_station:StandardEnvelopes](IMPORTANT): Priority 7: profile_station:StandardEnvelopes:A.AltitudeEnvelope
2019-07-25T00:12:47.083Z,1564013567.083 [profile_station:StandardEnvelopes](IMPORTANT): Priority 8: profile_station:StandardEnvelopes:B.DepthEnvelope
2019-07-25T00:12:47.084Z,1564013567.084 [profile_station:StandardEnvelopes](IMPORTANT): Priority 9: profile_station:StandardEnvelopes:C.OffshoreEnvelope
2019-07-25T00:12:47.084Z,1564013567.084 [profile_station](IMPORTANT): Priority 10: profile_station:I.Pitch
2019-07-25T00:12:47.084Z,1564013567.084 [profile_station](IMPORTANT): Priority 11: profile_station:J.Buoyancy
2019-07-25T00:12:47.085Z,1564013567.085 [profile_station](IMPORTANT): Priority 12: profile_station:K.SetSpeed
2019-07-25T00:12:47.085Z,1564013567.085 [profile_station](IMPORTANT): Priority 13: profile_station:L.DepthEnvelope
2019-07-25T00:12:47.085Z,1564013567.085 [profile_station](IMPORTANT): Priority 14: profile_station:M.AltitudeEnvelope
2019-07-25T00:12:47.085Z,1564013567.085 [profile_station](IMPORTANT): Priority 15: profile_station:N.YoYo
2019-07-25T00:12:48.033Z,1564013568.033 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343183 psu
2019-07-25T00:12:49.037Z,1564013569.037 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343130 psu
2019-07-25T00:12:50.040Z,1564013570.040 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343073 psu
2019-07-25T00:12:51.045Z,1564013571.045 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343084 psu
2019-07-25T00:12:52.048Z,1564013572.048 [CTD_Seabird](ERROR): Salinity reading out of range: 0.343032 psu
2019-07-25T00:12:53.052Z,1564013573.052 [CTD_Seabird](ERROR): Salinity reading out of range: 0.342980 psu
2019-07-25T00:12:54.056Z,1564013574.056 [CTD_Seabird](ERROR): Salinity reading out of range: 0.342928 psu
2019-07-25T00:12:55.060Z,1564013575.060 [CTD_Seabird](ERROR): Salinity reading out of range: 0.342876 psu
2019-07-25T00:12:55.519Z,1564013575.519 [DVL_micro](ERROR): only read 45 of 46 data items
2019-07-25T00:12:55.519Z,1564013575.519 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 45 248 95 1305 2 3 2 2 -66.7 -1410.7 456.5 2 -137.5 1405.2 -66 -1410 456 2 -137 1405 456 2 0.94 -1.61 188.3 -3.0 18.9 0.005 35.0 1489 93
2019-07-25T00:12:56.064Z,1564013576.064 [CTD_Seabird](ERROR): Salinity reading out of range: 0.342872 psu
2019-07-25T00:12:57.068Z,1564013577.068 [CTD_Seabird](ERROR): Salinity reading out of range: 0.342817 psu
2019-07-25T00:12:58.072Z,1564013578.072 [CTD_Seabird](ERROR): Salinity reading out of range: 0.342759 psu
2019-07-25T00:12:59.076Z,1564013579.076 [CTD_Seabird](ERROR): Salinity reading out of range: 0.342704 psu
2019-07-25T00:13:00.080Z,1564013580.080 [CTD_Seabird](ERROR): Salinity reading out of range: 0.342703 psu
2019-07-25T00:13:01.084Z,1564013581.084 [CTD_Seabird](ERROR): Salinity reading out of range: 0.342648 psu
2019-07-25T00:13:02.088Z,1564013582.088 [CTD_Seabird](ERROR): Salinity reading out of range: 0.342596 psu
2019-07-25T00:13:03.092Z,1564013583.092 [CTD_Seabird](ERROR): Salinity reading out of range: 0.342715 psu
2019-07-25T00:13:04.096Z,1564013584.096 [CTD_Seabird](ERROR): Salinity reading out of range: 0.342612 psu
2019-07-25T00:13:05.100Z,1564013585.100 [CTD_Seabird](ERROR): Salinity reading out of range: 0.342495 psu
2019-07-25T00:13:06.104Z,1564013586.104 [CTD_Seabird](ERROR): Salinity reading out of range: 0.342391 psu
2019-07-25T00:13:07.108Z,1564013587.108 [CTD_Seabird](ERROR): Salinity reading out of range: 0.342393 psu
2019-07-25T00:13:08.112Z,1564013588.112 [CTD_Seabird](ERROR): Salinity reading out of range: 0.342344 psu
2019-07-25T00:13:09.116Z,1564013589.116 [CTD_Seabird](ERROR): Salinity reading out of range: 0.342285 psu
2019-07-25T00:13:10.120Z,1564013590.120 [CTD_Seabird](ERROR): Salinity reading out of range: 0.342236 psu
2019-07-25T00:13:11.124Z,1564013591.124 [CTD_Seabird](ERROR): Salinity reading out of range: 0.342228 psu
2019-07-25T00:13:12.128Z,1564013592.128 [CTD_Seabird](ERROR): Salinity reading out of range: 0.342177 psu
2019-07-25T00:13:13.132Z,1564013593.132 [CTD_Seabird](ERROR): Salinity reading out of range: 0.342183 psu
2019-07-25T00:13:14.136Z,1564013594.136 [CTD_Seabird](ERROR): Salinity reading out of range: 0.342127 psu
2019-07-25T00:13:15.140Z,1564013595.140 [CTD_Seabird](ERROR): Salinity reading out of range: 0.342073 psu
2019-07-25T00:13:16.147Z,1564013596.147 [CTD_Seabird](ERROR): Salinity reading out of range: 0.342016 psu
2019-07-25T00:13:17.150Z,1564013597.150 [CTD_Seabird](ERROR): Salinity reading out of range: 0.341963 psu
2019-07-25T00:13:18.153Z,1564013598.153 [CTD_Seabird](ERROR): Salinity reading out of range: 0.341976 psu
2019-07-25T00:13:19.157Z,1564013599.157 [CTD_Seabird](ERROR): Salinity reading out of range: 0.341925 psu
2019-07-25T00:13:20.161Z,1564013600.161 [CTD_Seabird](ERROR): Salinity reading out of range: 0.341872 psu
2019-07-25T00:13:21.165Z,1564013601.165 [CTD_Seabird](ERROR): Salinity reading out of range: 0.341821 psu
2019-07-25T00:13:22.168Z,1564013602.168 [CTD_Seabird](ERROR): Salinity reading out of range: 0.341827 psu
2019-07-25T00:13:22.711Z,1564013602.711 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2019-07-25T00:13:23.172Z,1564013603.172 [CTD_Seabird](ERROR): Salinity reading out of range: 0.341774 psu
2019-07-25T00:13:24.176Z,1564013604.176 [CTD_Seabird](ERROR): Salinity reading out of range: 0.341721 psu
2019-07-25T00:13:25.180Z,1564013605.180 [CTD_Seabird](ERROR): Salinity reading out of range: 0.341714 psu
2019-07-25T00:13:26.184Z,1564013606.184 [CTD_Seabird](ERROR): Salinity reading out of range: 0.341658 psu
2019-07-25T00:13:27.188Z,1564013607.188 [CTD_Seabird](ERROR): Salinity reading out of range: 0.341604 psu
2019-07-25T00:13:28.192Z,1564013608.192 [CTD_Seabird](ERROR): Salinity reading out of range: 0.341547 psu
2019-07-25T00:13:29.196Z,1564013609.196 [CTD_Seabird](ERROR): Salinity reading out of range: 0.341494 psu
2019-07-25T00:13:30.200Z,1564013610.200 [CTD_Seabird](ERROR): Salinity reading out of range: 0.341491 psu
2019-07-25T00:13:30.837Z,1564013610.837 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 0.000000 bool
2019-07-25T00:13:31.204Z,1564013611.204 [CTD_Seabird](ERROR): Salinity reading out of range: 0.341438 psu
2019-07-25T00:13:32.208Z,1564013612.208 [CTD_Seabird](ERROR): Salinity reading out of range: 0.341375 psu
2019-07-25T00:13:33.212Z,1564013613.212 [CTD_Seabird](ERROR): Salinity reading out of range: 0.341373 psu
2019-07-25T00:13:34.216Z,1564013614.216 [CTD_Seabird](ERROR): Salinity reading out of range: 0.341315 psu
2019-07-25T00:13:35.220Z,1564013615.220 [CTD_Seabird](ERROR): Salinity reading out of range: 0.341259 psu
2019-07-25T00:13:36.224Z,1564013616.224 [CTD_Seabird](ERROR): Salinity reading out of range: 0.341196 psu
2019-07-25T00:13:37.228Z,1564013617.228 [CTD_Seabird](ERROR): Salinity reading out of range: 0.341187 psu
2019-07-25T00:13:38.232Z,1564013618.232 [CTD_Seabird](ERROR): Salinity reading out of range: 0.341127 psu
2019-07-25T00:13:38.941Z,1564013618.941 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2019-07-25T00:13:39.236Z,1564013619.236 [CTD_Seabird](ERROR): Salinity reading out of range: 0.341069 psu
2019-07-25T00:13:40.240Z,1564013620.240 [CTD_Seabird](ERROR): Salinity reading out of range: 0.341063 psu
2019-07-25T00:13:41.244Z,1564013621.244 [CTD_Seabird](ERROR): Salinity reading out of range: 0.341010 psu
2019-07-25T00:13:42.248Z,1564013622.248 [CTD_Seabird](ERROR): Salinity reading out of range: 0.340946 psu
2019-07-25T00:13:43.252Z,1564013623.252 [CTD_Seabird](ERROR): Salinity reading out of range: 0.340943 psu
2019-07-25T00:13:44.256Z,1564013624.256 [CTD_Seabird](ERROR): Salinity reading out of range: 0.340879 psu
2019-07-25T00:13:45.260Z,1564013625.260 [CTD_Seabird](ERROR): Salinity reading out of range: 0.340820 psu
2019-07-25T00:13:46.264Z,1564013626.264 [CTD_Seabird](ERROR): Salinity reading out of range: 0.340808 psu
2019-07-25T00:13:47.268Z,1564013627.268 [CTD_Seabird](ERROR): Salinity reading out of range: 0.340744 psu
2019-07-25T00:13:48.272Z,1564013628.272 [CTD_Seabird](ERROR): Salinity reading out of range: 0.340675 psu
2019-07-25T00:13:49.277Z,1564013629.277 [CTD_Seabird](ERROR): Salinity reading out of range: 0.340666 psu
2019-07-25T00:13:50.281Z,1564013630.281 [CTD_Seabird](ERROR): Salinity reading out of range: 0.340603 psu
2019-07-25T00:13:51.285Z,1564013631.285 [CTD_Seabird](ERROR): Salinity reading out of range: 0.340536 psu
2019-07-25T00:13:52.289Z,1564013632.289 [CTD_Seabird](ERROR): Salinity reading out of range: 0.340530 psu
2019-07-25T00:13:52.796Z,1564013632.796 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1564013568.000000 second
2019-07-25T00:13:52.968Z,1564013632.968 [profile_station:NeedComms:E] Stopped
2019-07-25T00:13:52.969Z,1564013632.969 [profile_station:NeedComms](INFO): Completed profile_station:NeedComms
2019-07-25T00:13:52.969Z,1564013632.969 [profile_station:NeedComms] Stopped
2019-07-25T00:13:52.969Z,1564013632.969 [profile_station:NeedComms](DEBUG): Aggregate::uninitialize profile_station:NeedComms
2019-07-25T00:13:52.969Z,1564013632.969 [profile_station:NeedComms:B.GoToSurface] Stopped
2019-07-25T00:13:52.969Z,1564013632.969 [profile_station:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-07-25T00:13:53.293Z,1564013633.293 [CTD_Seabird](ERROR): Salinity reading out of range: 0.340462 psu
2019-07-25T00:13:53.377Z,1564013633.377 [profile_station:StartingMission](INFO): Completed profile_station:StartingMission
2019-07-25T00:13:53.377Z,1564013633.377 [profile_station:StartingMission] Stopped
2019-07-25T00:13:53.377Z,1564013633.377 [profile_station:StartingMission](DEBUG): Aggregate::uninitialize profile_station:StartingMission
2019-07-25T00:13:53.377Z,1564013633.377 [profile_station:CircleWrapper] Running Loop=1
2019-07-25T00:13:53.377Z,1564013633.377 [profile_station:CircleWrapper](DEBUG): Aggregate::initialize profile_station:CircleWrapper
2019-07-25T00:13:53.377Z,1564013633.377 [profile_station:CircleWrapper:A] Running Loop=1
2019-07-25T00:13:53.377Z,1564013633.377 [profile_station:CircleWrapper:B.Circle] Running Loop=1
2019-07-25T00:13:53.377Z,1564013633.377 [profile_station:CircleWrapper:B.Circle](DEBUG): Initialize CircleComponent.
2019-07-25T00:13:53.391Z,1564013633.391 [Reporter](INFO): SpeedControl.speedCmd 1.000000 m/s
2019-07-25T00:13:53.797Z,1564013633.797 [profile_station:CircleWrapper:A] Running Loop=1
2019-07-25T00:13:54.296Z,1564013634.296 [CTD_Seabird](ERROR): Salinity reading out of range: 0.340450 psu
2019-07-25T00:13:55.300Z,1564013635.300 [CTD_Seabird](ERROR): Salinity reading out of range: 0.340445 psu
2019-07-25T00:13:56.304Z,1564013636.304 [CTD_Seabird](ERROR): Salinity reading out of range: 0.340375 psu
2019-07-25T00:13:57.308Z,1564013637.308 [CTD_Seabird](ERROR): Salinity reading out of range: 0.340312 psu
2019-07-25T00:13:58.337Z,1564013638.337 [CTD_Seabird](ERROR): Salinity reading out of range: 0.340305 psu
2019-07-25T00:13:59.336Z,1564013639.336 [CTD_Seabird](ERROR): Salinity reading out of range: 0.340189 psu
2019-07-25T00:14:00.341Z,1564013640.341 [CTD_Seabird](ERROR): Salinity reading out of range: 0.340236 psu
2019-07-25T00:14:01.344Z,1564013641.344 [CTD_Seabird](ERROR): Salinity reading out of range: 0.340173 psu
2019-07-25T00:14:02.349Z,1564013642.349 [CTD_Seabird](ERROR): Salinity reading out of range: 0.340168 psu
2019-07-25T00:14:03.352Z,1564013643.352 [CTD_Seabird](ERROR): Salinity reading out of range: 0.340054 psu
2019-07-25T00:14:03.419Z,1564013643.419 [DVL_micro](ERROR): only read 45 of 46 data items
2019-07-25T00:14:03.419Z,1564013643.419 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 119 124 102 184 2 3 2 2 22.7 -80.1 142.6 2 -33.6 76.2 142.6 2 22 -80 142 2 -33 76 142 2 1.11 -1.51 188.0 -3.0 19.0 00 1489 108
2019-07-25T00:14:04.356Z,1564013644.356 [CTD_Seabird](ERROR): Salinity reading out of range: 0.339999 psu
2019-07-25T00:14:05.360Z,1564013645.360 [CTD_Seabird](ERROR): Salinity reading out of range: 0.339884 psu
2019-07-25T00:14:06.372Z,1564013646.372 [CTD_Seabird](ERROR): Salinity reading out of range: 0.339878 psu
2019-07-25T00:14:07.368Z,1564013647.368 [CTD_Seabird](ERROR): Salinity reading out of range: 0.339820 psu
2019-07-25T00:14:08.372Z,1564013648.372 [CTD_Seabird](ERROR): Salinity reading out of range: 0.339812 psu
2019-07-25T00:14:08.607Z,1564013648.607 [CommandLine](IMPORTANT): got command get VerticalControl.elevatorAngleAction degree
2019-07-25T00:14:08.607Z,1564013648.607 [CommandLine](IMPORTANT): VerticalControl.elevatorAngleAction 6.784568 arcdeg
2019-07-25T00:14:09.376Z,1564013649.376 [CTD_Seabird](ERROR): Salinity reading out of range: 0.339754 psu
2019-07-25T00:14:10.380Z,1564013650.380 [CTD_Seabird](ERROR): Salinity reading out of range: 0.339691 psu
2019-07-25T00:14:11.384Z,1564013651.384 [CTD_Seabird](ERROR): Salinity reading out of range: 0.339628 psu
2019-07-25T00:14:12.388Z,1564013652.388 [CTD_Seabird](ERROR): Salinity reading out of range: 0.339628 psu
2019-07-25T00:14:13.392Z,1564013653.392 [CTD_Seabird](ERROR): Salinity reading out of range: 0.339569 psu
2019-07-25T00:14:14.396Z,1564013654.396 [CTD_Seabird](ERROR): Salinity reading out of range: 0.339569 psu
2019-07-25T00:14:15.400Z,1564013655.400 [CTD_Seabird](ERROR): Salinity reading out of range: 0.339563 psu
2019-07-25T00:14:16.369Z,1564013656.369 [DVL_micro](ERROR): checksum mismatch: reported96 , calculated:126
2019-07-25T00:14:16.370Z,1564013656.370 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 108 452 174 210 2 3 2 2 -88.154.5 2 131.6 -307.8 254.5 2 -88 323 254 2 131 -307 254 2 1.18 -1.45 187.9 -3.0 19.0 0.005 35.0 1489 96
2019-07-25T00:14:16.404Z,1564013656.404 [CTD_Seabird](ERROR): Salinity reading out of range: 0.339506 psu
2019-07-25T00:14:17.408Z,1564013657.408 [CTD_Seabird](ERROR): Salinity reading out of range: 0.339450 psu
2019-07-25T00:14:18.412Z,1564013658.412 [CTD_Seabird](ERROR): Salinity reading out of range: 0.339447 psu
2019-07-25T00:14:19.416Z,1564013659.416 [CTD_Seabird](ERROR): Salinity reading out of range: 0.339444 psu
2019-07-25T00:14:20.420Z,1564013660.420 [CTD_Seabird](ERROR): Salinity reading out of range: 0.339328 psu
2019-07-25T00:14:21.424Z,1564013661.424 [CTD_Seabird](ERROR): Salinity reading out of range: 0.339322 psu
2019-07-25T00:14:22.428Z,1564013662.428 [CTD_Seabird](ERROR): Salinity reading out of range: 0.339260 psu
2019-07-25T00:14:23.432Z,1564013663.432 [CTD_Seabird](ERROR): Salinity reading out of range: 0.339199 psu
2019-07-25T00:14:24.436Z,1564013664.436 [CTD_Seabird](ERROR): Salinity reading out of range: 0.339248 psu
2019-07-25T00:14:25.441Z,1564013665.441 [CTD_Seabird](ERROR): Salinity reading out of range: 0.339182 psu
2019-07-25T00:14:26.444Z,1564013666.444 [CTD_Seabird](ERROR): Salinity reading out of range: 0.339122 psu
2019-07-25T00:14:27.448Z,1564013667.448 [CTD_Seabird](ERROR): Salinity reading out of range: 0.339123 psu
2019-07-25T00:14:28.477Z,1564013668.477 [CTD_Seabird](ERROR): Salinity reading out of range: 0.339120 psu
2019-07-25T00:14:29.476Z,1564013669.476 [CTD_Seabird](ERROR): Salinity reading out of range: 0.339059 psu
2019-07-25T00:14:30.480Z,1564013670.480 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338998 psu
2019-07-25T00:14:31.485Z,1564013671.485 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338940 psu
2019-07-25T00:14:32.119Z,1564013672.119 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-07-25T00:14:32.488Z,1564013672.488 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338938 psu
2019-07-25T00:14:33.492Z,1564013673.492 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338874 psu
2019-07-25T00:14:34.496Z,1564013674.496 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338817 psu
2019-07-25T00:14:35.500Z,1564013675.500 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338806 psu
2019-07-25T00:14:36.504Z,1564013676.504 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338744 psu
2019-07-25T00:14:37.516Z,1564013677.516 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338743 psu
2019-07-25T00:14:38.512Z,1564013678.512 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338745 psu
2019-07-25T00:14:39.516Z,1564013679.516 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338684 psu
2019-07-25T00:14:40.520Z,1564013680.520 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338692 psu
2019-07-25T00:14:41.527Z,1564013681.527 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338690 psu
2019-07-25T00:14:42.530Z,1564013682.530 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338648 psu
2019-07-25T00:14:43.556Z,1564013683.556 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338649 psu
2019-07-25T00:14:44.505Z,1564013684.505 [CommandLine](IMPORTANT): got command get VerticalControl.elevatorAngleAction degree
2019-07-25T00:14:44.506Z,1564013684.506 [CommandLine](IMPORTANT): VerticalControl.elevatorAngleAction 11.587016 arcdeg
2019-07-25T00:14:44.537Z,1564013684.537 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338599 psu
2019-07-25T00:14:45.465Z,1564013685.465 [DVL_micro](ERROR): only read 45 of 46 data items
2019-07-25T00:14:45.465Z,1564013685.465 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 162 191 75 295 2 3 2 2 116.1 -138.8 194.9 2 -135.5 119.9 194.9 2 116 -138 194 2 -135 119 194 2 0.66 -1.64 188.6 -3.0 19 35.0 1489 90
2019-07-25T00:14:45.541Z,1564013685.541 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338603 psu
2019-07-25T00:14:46.545Z,1564013686.545 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338607 psu
2019-07-25T00:14:47.548Z,1564013687.548 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338555 psu
2019-07-25T00:14:48.552Z,1564013688.552 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338557 psu
2019-07-25T00:14:49.557Z,1564013689.557 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338557 psu
2019-07-25T00:14:50.560Z,1564013690.560 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338496 psu
2019-07-25T00:14:51.564Z,1564013691.564 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338442 psu
2019-07-25T00:14:51.905Z,1564013691.905 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-07-25T00:14:51.905Z,1564013691.905 [DVL_micro] Data Fault, FailCount= 1
2019-07-25T00:14:51.905Z,1564013691.905 [DVL_micro](ERROR): Data Fault
2019-07-25T00:14:51.979Z,1564013691.979 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-07-25T00:14:52.386Z,1564013692.386 [DVL_micro](INFO): uninitialize:Powering down
2019-07-25T00:14:52.568Z,1564013692.568 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338436 psu
2019-07-25T00:14:53.254Z,1564013693.254 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-07-25T00:14:53.254Z,1564013693.254 [DVL_micro] No Fault, FailCount= 1
2019-07-25T00:14:53.541Z,1564013693.541 [DVL_micro](INFO): Initializing
2019-07-25T00:14:53.572Z,1564013693.572 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338379 psu
2019-07-25T00:14:54.577Z,1564013694.577 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338324 psu
2019-07-25T00:14:55.581Z,1564013695.581 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338327 psu
2019-07-25T00:14:56.585Z,1564013696.585 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338278 psu
2019-07-25T00:14:57.589Z,1564013697.589 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338283 psu
2019-07-25T00:14:58.593Z,1564013698.593 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338241 psu
2019-07-25T00:14:59.597Z,1564013699.597 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338243 psu
2019-07-25T00:15:00.601Z,1564013700.601 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338192 psu
2019-07-25T00:15:01.604Z,1564013701.604 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338192 psu
2019-07-25T00:15:02.608Z,1564013702.608 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338080 psu
2019-07-25T00:15:02.818Z,1564013702.818 [NAL9602](INFO): Not Powering down - fast GPS
2019-07-25T00:15:03.612Z,1564013703.612 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338021 psu
2019-07-25T00:15:04.616Z,1564013704.616 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338075 psu
2019-07-25T00:15:05.624Z,1564013705.624 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338017 psu
2019-07-25T00:15:06.628Z,1564013706.628 [CTD_Seabird](ERROR): Salinity reading out of range: 0.338018 psu
2019-07-25T00:15:07.632Z,1564013707.632 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337958 psu
2019-07-25T00:15:08.636Z,1564013708.636 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337962 psu
2019-07-25T00:15:09.640Z,1564013709.640 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337905 psu
2019-07-25T00:15:10.644Z,1564013710.644 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337910 psu
2019-07-25T00:15:11.648Z,1564013711.648 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337906 psu
2019-07-25T00:15:12.652Z,1564013712.652 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337851 psu
2019-07-25T00:15:13.656Z,1564013713.656 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337854 psu
2019-07-25T00:15:14.662Z,1564013714.662 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337795 psu
2019-07-25T00:15:15.669Z,1564013715.669 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337796 psu
2019-07-25T00:15:16.668Z,1564013716.668 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337740 psu
2019-07-25T00:15:17.676Z,1564013717.676 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337794 psu
2019-07-25T00:15:18.676Z,1564013718.676 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337730 psu
2019-07-25T00:15:19.682Z,1564013719.682 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337672 psu
2019-07-25T00:15:20.684Z,1564013720.684 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337665 psu
2019-07-25T00:15:21.689Z,1564013721.689 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337607 psu
2019-07-25T00:15:22.692Z,1564013722.692 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337600 psu
2019-07-25T00:15:23.697Z,1564013723.697 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337594 psu
2019-07-25T00:15:24.700Z,1564013724.700 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337586 psu
2019-07-25T00:15:25.704Z,1564013725.704 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337522 psu
2019-07-25T00:15:26.708Z,1564013726.708 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337521 psu
2019-07-25T00:15:27.712Z,1564013727.712 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337460 psu
2019-07-25T00:15:28.716Z,1564013728.716 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337464 psu
2019-07-25T00:15:29.720Z,1564013729.720 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337469 psu
2019-07-25T00:15:30.724Z,1564013730.724 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337409 psu
2019-07-25T00:15:31.728Z,1564013731.728 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337420 psu
2019-07-25T00:15:32.733Z,1564013732.733 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337354 psu
2019-07-25T00:15:33.736Z,1564013733.736 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337355 psu
2019-07-25T00:15:34.740Z,1564013734.740 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337299 psu
2019-07-25T00:15:35.744Z,1564013735.744 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337297 psu
2019-07-25T00:15:36.748Z,1564013736.748 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337243 psu
2019-07-25T00:15:37.752Z,1564013737.752 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337238 psu
2019-07-25T00:15:38.756Z,1564013738.756 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337179 psu
2019-07-25T00:15:39.760Z,1564013739.760 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337176 psu
2019-07-25T00:15:40.764Z,1564013740.764 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337118 psu
2019-07-25T00:15:41.768Z,1564013741.768 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337109 psu
2019-07-25T00:15:42.046Z,1564013742.046 [DVL_micro](ERROR): only read 45 of 46 data items
2019-07-25T00:15:42.046Z,1564013742.046 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 162 187 251 200 2 3 2 2 -118.8 -17.4 215.7 2 114.9 34.8 215.7 2 -118 -17 215 2 114 34 215 2 0.79 -1.64 18 19.0 0.005 35.0 1489 96
2019-07-25T00:15:42.772Z,1564013742.772 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337104 psu
2019-07-25T00:15:43.776Z,1564013743.776 [CTD_Seabird](ERROR): Salinity reading out of range: 0.337039 psu
2019-07-25T00:15:44.780Z,1564013744.780 [CTD_Seabird](ERROR): Salinity reading out of range: 0.336980 psu
2019-07-25T00:15:45.784Z,1564013745.784 [CTD_Seabird](ERROR): Salinity reading out of range: 0.336910 psu
2019-07-25T00:15:46.788Z,1564013746.788 [CTD_Seabird](ERROR): Salinity reading out of range: 0.336963 psu
2019-07-25T00:15:47.792Z,1564013747.792 [CTD_Seabird](ERROR): Salinity reading out of range: 0.336900 psu
2019-07-25T00:15:48.796Z,1564013748.796 [CTD_Seabird](ERROR): Salinity reading out of range: 0.336892 psu
2019-07-25T00:15:49.800Z,1564013749.800 [CTD_Seabird](ERROR): Salinity reading out of range: 0.336883 psu
2019-07-25T00:15:50.804Z,1564013750.804 [CTD_Seabird](ERROR): Salinity reading out of range: 0.336766 psu
2019-07-25T00:15:51.808Z,1564013751.808 [CTD_Seabird](ERROR): Salinity reading out of range: 0.512599 psu
2019-07-25T00:15:52.812Z,1564013752.812 [CTD_Seabird](ERROR): Salinity reading out of range: 5.048428 psu
2019-07-25T00:15:53.816Z,1564013753.816 [CTD_Seabird](ERROR): Salinity reading out of range: 5.328614 psu
2019-07-25T00:15:54.820Z,1564013754.820 [CTD_Seabird](ERROR): Salinity reading out of range: 3.304351 psu
2019-07-25T00:15:55.824Z,1564013755.824 [CTD_Seabird](ERROR): Salinity reading out of range: 2.739941 psu
2019-07-25T00:15:56.828Z,1564013756.828 [CTD_Seabird](ERROR): Salinity reading out of range: 2.375498 psu
2019-07-25T00:15:57.832Z,1564013757.832 [CTD_Seabird](ERROR): Salinity reading out of range: 2.105053 psu
2019-07-25T00:15:58.836Z,1564013758.836 [CTD_Seabird](ERROR): Salinity reading out of range: 1.830148 psu
2019-07-25T00:15:59.840Z,1564013759.840 [CTD_Seabird](ERROR): Salinity reading out of range: 1.670817 psu
2019-07-25T00:16:00.844Z,1564013760.844 [CTD_Seabird](ERROR): Salinity reading out of range: 3.053103 psu
2019-07-25T00:16:01.849Z,1564013761.849 [CTD_Seabird](ERROR): Salinity reading out of range: 7.663259 psu
2019-07-25T00:16:02.853Z,1564013762.853 [CTD_Seabird](ERROR): Salinity reading out of range: 5.534765 psu
2019-07-25T00:16:03.857Z,1564013763.857 [CTD_Seabird](ERROR): Salinity reading out of range: 5.346126 psu
2019-07-25T00:16:04.861Z,1564013764.861 [CTD_Seabird](ERROR): Salinity reading out of range: 4.547468 psu
2019-07-25T00:16:05.865Z,1564013765.865 [CTD_Seabird](ERROR): Salinity reading out of range: 4.937781 psu
2019-07-25T00:16:06.869Z,1564013766.869 [CTD_Seabird](ERROR): Salinity reading out of range: 3.597866 psu
2019-07-25T00:16:07.872Z,1564013767.872 [CTD_Seabird](ERROR): Salinity reading out of range: 4.354227 psu
2019-07-25T00:16:08.876Z,1564013768.876 [CTD_Seabird](ERROR): Salinity reading out of range: 4.557559 psu
2019-07-25T00:16:09.880Z,1564013769.880 [CTD_Seabird](ERROR): Salinity reading out of range: 3.973872 psu
2019-07-25T00:16:10.884Z,1564013770.884 [CTD_Seabird](ERROR): Salinity reading out of range: 3.784648 psu
2019-07-25T00:16:11.888Z,1564013771.888 [CTD_Seabird](ERROR): Salinity reading out of range: 3.613987 psu
2019-07-25T00:16:12.892Z,1564013772.892 [CTD_Seabird](ERROR): Salinity reading out of range: 3.481140 psu
2019-07-25T00:16:13.898Z,1564013773.898 [CTD_Seabird](ERROR): Salinity reading out of range: 3.451234 psu
2019-07-25T00:16:14.901Z,1564013774.901 [CTD_Seabird](ERROR): Salinity reading out of range: 3.339759 psu
2019-07-25T00:16:15.905Z,1564013775.905 [CTD_Seabird](ERROR): Salinity reading out of range: 3.327499 psu
2019-07-25T00:16:16.909Z,1564013776.909 [CTD_Seabird](ERROR): Salinity reading out of range: 3.326062 psu
2019-07-25T00:16:17.913Z,1564013777.913 [CTD_Seabird](ERROR): Salinity reading out of range: 3.310905 psu
2019-07-25T00:16:18.917Z,1564013778.917 [CTD_Seabird](ERROR): Salinity reading out of range: 3.275481 psu
2019-07-25T00:16:19.920Z,1564013779.920 [CTD_Seabird](ERROR): Salinity reading out of range: 3.221726 psu
2019-07-25T00:16:20.924Z,1564013780.924 [CTD_Seabird](ERROR): Salinity reading out of range: 3.161261 psu
2019-07-25T00:16:21.928Z,1564013781.928 [CTD_Seabird](ERROR): Salinity reading out of range: 3.104678 psu
2019-07-25T00:16:22.932Z,1564013782.932 [CTD_Seabird](ERROR): Salinity reading out of range: 3.054979 psu
2019-07-25T00:16:23.936Z,1564013783.936 [CTD_Seabird](ERROR): Salinity reading out of range: 3.011923 psu
2019-07-25T00:16:24.940Z,1564013784.940 [CTD_Seabird](ERROR): Salinity reading out of range: 2.968627 psu
2019-07-25T00:16:25.944Z,1564013785.944 [CTD_Seabird](ERROR): Salinity reading out of range: 2.928076 psu
2019-07-25T00:16:26.948Z,1564013786.948 [CTD_Seabird](ERROR): Salinity reading out of range: 2.886869 psu
2019-07-25T00:16:27.952Z,1564013787.952 [CTD_Seabird](ERROR): Salinity reading out of range: 2.847469 psu
2019-07-25T00:16:28.956Z,1564013788.956 [CTD_Seabird](ERROR): Salinity reading out of range: 2.809154 psu
2019-07-25T00:16:29.960Z,1564013789.960 [CTD_Seabird](ERROR): Salinity reading out of range: 2.771885 psu
2019-07-25T00:16:30.964Z,1564013790.964 [CTD_Seabird](ERROR): Salinity reading out of range: 2.736649 psu
2019-07-25T00:16:31.968Z,1564013791.968 [CTD_Seabird](ERROR): Salinity reading out of range: 2.702882 psu
2019-07-25T00:16:32.972Z,1564013792.972 [CTD_Seabird](ERROR): Salinity reading out of range: 2.671589 psu
2019-07-25T00:16:33.979Z,1564013793.979 [CTD_Seabird](ERROR): Salinity reading out of range: 2.642855 psu
2019-07-25T00:16:34.980Z,1564013794.980 [CTD_Seabird](ERROR): Salinity reading out of range: 2.616366 psu
2019-07-25T00:16:35.994Z,1564013795.994 [CTD_Seabird](ERROR): Salinity reading out of range: 2.591483 psu
2019-07-25T00:16:36.988Z,1564013796.988 [CTD_Seabird](ERROR): Salinity reading out of range: 2.567431 psu
2019-07-25T00:16:37.992Z,1564013797.992 [CTD_Seabird](ERROR): Salinity reading out of range: 2.545661 psu
2019-07-25T00:16:38.997Z,1564013798.997 [CTD_Seabird](ERROR): Salinity reading out of range: 2.525443 psu
2019-07-25T00:16:39.000Z,1564013800.000 [CTD_Seabird](ERROR): Salinity reading out of range: 2.505175 psu
2019-07-25T00:16:40.008Z,1564013800.008 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 2.000000 meter
2019-07-25T00:16:40.009Z,1564013800.009 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2019-07-25T00:16:40.293Z,1564013800.293 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,Maintain_Depth_Keller.depth,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-07-25T00:16:40.655Z,1564013800.655 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch -20.00 deg, mass-position 30.00 mm (1 active estimators).
2019-07-25T00:16:41.005Z,1564013801.005 [CTD_Seabird](ERROR): Salinity reading out of range: 2.486481 psu
2019-07-25T00:16:42.008Z,1564013802.008 [CTD_Seabird](ERROR): Salinity reading out of range: 2.468899 psu
2019-07-25T00:16:43.012Z,1564013803.012 [CTD_Seabird](ERROR): Salinity reading out of range: 2.451740 psu
2019-07-25T00:16:43.534Z,1564013803.534 [CommandLine](IMPORTANT): got command get depth
2019-07-25T00:16:43.535Z,1564013803.535 [CommandLine](IMPORTANT): depth 2.000000 m
2019-07-25T00:16:44.016Z,1564013804.016 [CTD_Seabird](ERROR): Salinity reading out of range: 2.435808 psu
2019-07-25T00:16:45.020Z,1564013805.020 [CTD_Seabird](ERROR): Salinity reading out of range: 2.419788 psu
2019-07-25T00:16:46.024Z,1564013806.024 [CTD_Seabird](ERROR): Salinity reading out of range: 2.402891 psu
2019-07-25T00:16:47.028Z,1564013807.028 [CTD_Seabird](ERROR): Salinity reading out of range: 2.385849 psu
2019-07-25T00:16:48.032Z,1564013808.032 [CTD_Seabird](ERROR): Salinity reading out of range: 2.369521 psu
2019-07-25T00:16:49.036Z,1564013809.036 [CTD_Seabird](ERROR): Salinity reading out of range: 2.353893 psu
2019-07-25T00:16:50.040Z,1564013810.040 [CTD_Seabird](ERROR): Salinity reading out of range: 2.339133 psu
2019-07-25T00:16:51.044Z,1564013811.044 [CTD_Seabird](ERROR): Salinity reading out of range: 2.324561 psu
2019-07-25T00:16:52.048Z,1564013812.048 [CTD_Seabird](ERROR): Salinity reading out of range: 2.311127 psu
2019-07-25T00:16:53.052Z,1564013813.052 [CTD_Seabird](ERROR): Salinity reading out of range: 2.298143 psu
2019-07-25T00:16:54.064Z,1564013814.064 [CTD_Seabird](ERROR): Salinity reading out of range: 2.286047 psu
2019-07-25T00:16:55.068Z,1564013815.068 [CTD_Seabird](ERROR): Salinity reading out of range: 2.274756 psu
2019-07-25T00:16:56.074Z,1564013816.074 [CTD_Seabird](ERROR): Salinity reading out of range: 2.263860 psu
2019-07-25T00:16:57.076Z,1564013817.076 [CTD_Seabird](ERROR): Salinity reading out of range: 2.253453 psu
2019-07-25T00:16:58.080Z,1564013818.080 [CTD_Seabird](ERROR): Salinity reading out of range: 2.243300 psu
2019-07-25T00:16:59.084Z,1564013819.084 [CTD_Seabird](ERROR): Salinity reading out of range: 2.234078 psu
2019-07-25T00:17:00.089Z,1564013820.089 [CTD_Seabird](ERROR): Salinity reading out of range: 2.323906 psu
2019-07-25T00:17:01.092Z,1564013821.092 [CTD_Seabird](ERROR): Salinity reading out of range: 2.292315 psu
2019-07-25T00:17:02.096Z,1564013822.096 [CTD_Seabird](ERROR): Salinity reading out of range: 2.122121 psu
2019-07-25T00:17:03.100Z,1564013823.100 [CTD_Seabird](ERROR): Salinity reading out of range: 2.030104 psu
2019-07-25T00:17:04.105Z,1564013824.105 [CTD_Seabird](ERROR): Salinity reading out of range: 1.969263 psu
2019-07-25T00:17:05.108Z,1564013825.108 [CTD_Seabird](ERROR): Salinity reading out of range: 1.935569 psu
2019-07-25T00:17:06.112Z,1564013826.112 [CTD_Seabird](ERROR): Salinity reading out of range: 2.045602 psu
2019-07-25T00:17:07.117Z,1564013827.117 [CTD_Seabird](ERROR): Salinity reading out of range: 2.857539 psu
2019-07-25T00:17:08.121Z,1564013828.121 [CTD_Seabird](ERROR): Salinity reading out of range: 3.613652 psu
2019-07-25T00:17:09.125Z,1564013829.125 [CTD_Seabird](ERROR): Salinity reading out of range: 3.542531 psu
2019-07-25T00:17:10.129Z,1564013830.129 [CTD_Seabird](ERROR): Salinity reading out of range: 3.348399 psu
2019-07-25T00:17:11.132Z,1564013831.132 [CTD_Seabird](ERROR): Salinity reading out of range: 3.235981 psu
2019-07-25T00:17:12.136Z,1564013832.136 [CTD_Seabird](ERROR): Salinity reading out of range: 3.135198 psu
2019-07-25T00:17:13.140Z,1564013833.140 [CTD_Seabird](ERROR): Salinity reading out of range: 3.104128 psu
2019-07-25T00:17:14.144Z,1564013834.144 [CTD_Seabird](ERROR): Salinity reading out of range: 3.091763 psu
2019-07-25T00:17:15.148Z,1564013835.148 [CTD_Seabird](ERROR): Salinity reading out of range: 3.081640 psu
2019-07-25T00:17:16.152Z,1564013836.152 [CTD_Seabird](ERROR): Salinity reading out of range: 3.097436 psu
2019-07-25T00:17:17.156Z,1564013837.156 [CTD_Seabird](ERROR): Salinity reading out of range: 3.109784 psu
2019-07-25T00:17:18.160Z,1564013838.160 [CTD_Seabird](ERROR): Salinity reading out of range: 3.151545 psu
2019-07-25T00:17:19.164Z,1564013839.164 [CTD_Seabird](ERROR): Salinity reading out of range: 3.193439 psu
2019-07-25T00:17:20.168Z,1564013840.168 [CTD_Seabird](ERROR): Salinity reading out of range: 3.240831 psu
2019-07-25T00:17:21.172Z,1564013841.172 [CTD_Seabird](ERROR): Salinity reading out of range: 3.275491 psu
2019-07-25T00:17:22.176Z,1564013842.176 [CTD_Seabird](ERROR): Salinity reading out of range: 3.949083 psu
2019-07-25T00:17:23.180Z,1564013843.180 [CTD_Seabird](ERROR): Salinity reading out of range: 2.304496 psu
2019-07-25T00:17:24.223Z,1564013844.223 [CTD_Seabird](ERROR): Salinity reading out of range: 2.457522 psu
2019-07-25T00:17:25.227Z,1564013845.227 [CTD_Seabird](ERROR): Salinity reading out of range: 1.926431 psu
2019-07-25T00:17:26.227Z,1564013846.227 [CTD_Seabird](ERROR): Salinity reading out of range: 1.724439 psu
2019-07-25T00:17:27.232Z,1564013847.232 [CTD_Seabird](ERROR): Salinity reading out of range: 1.570957 psu
2019-07-25T00:17:28.235Z,1564013848.235 [CTD_Seabird](ERROR): Salinity reading out of range: 1.410940 psu
2019-07-25T00:17:29.239Z,1564013849.239 [CTD_Seabird](ERROR): Salinity reading out of range: 1.425587 psu
2019-07-25T00:17:30.231Z,1564013850.231 [CTD_Seabird](ERROR): Salinity reading out of range: 1.216714 psu
2019-07-25T00:17:31.235Z,1564013851.235 [CTD_Seabird](ERROR): Salinity reading out of range: 1.086808 psu
2019-07-25T00:17:32.239Z,1564013852.239 [CTD_Seabird](ERROR): Salinity reading out of range: 0.996863 psu
2019-07-25T00:17:33.247Z,1564013853.247 [CTD_Seabird](ERROR): Salinity reading out of range: 0.934847 psu
2019-07-25T00:17:34.247Z,1564013854.247 [CTD_Seabird](ERROR): Salinity reading out of range: 0.887802 psu
2019-07-25T00:17:35.151Z,1564013855.151 [DVL_micro](ERROR): only read 45 of 46 data items
2019-07-25T00:17:35.151Z,1564013855.151 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 226 156 162 191 2 3 2 2 85.4 -46.7 198.1 2 -94.5 -23.3 198.1 2 85 -46 198 2 -94 -23 198 2 1. 222.5 -3.0 19.2 0.005 35.0 1489 100
2019-07-25T00:17:35.239Z,1564013855.239 [CTD_Seabird](ERROR): Salinity reading out of range: 0.855561 psu
2019-07-25T00:17:36.247Z,1564013856.247 [CTD_Seabird](ERROR): Salinity reading out of range: 0.843054 psu
2019-07-25T00:17:37.251Z,1564013857.251 [CTD_Seabird](ERROR): Salinity reading out of range: 0.841039 psu
2019-07-25T00:17:38.255Z,1564013858.255 [CTD_Seabird](ERROR): Salinity reading out of range: 0.853157 psu
2019-07-25T00:17:39.245Z,1564013859.245 [CTD_Seabird](ERROR): Salinity reading out of range: 0.878651 psu
2019-07-25T00:17:40.248Z,1564013860.248 [CTD_Seabird](ERROR): Salinity reading out of range: 0.908815 psu
2019-07-25T00:17:40.922Z,1564013860.922 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2019-07-25T00:17:41.082Z,1564013861.082 [Radio_Surface](INFO): Powering down
2019-07-25T00:17:41.252Z,1564013861.252 [CTD_Seabird](ERROR): Salinity reading out of range: 0.944112 psu
2019-07-25T00:17:42.256Z,1564013862.256 [CTD_Seabird](ERROR): Salinity reading out of range: 0.989259 psu
2019-07-25T00:17:43.260Z,1564013863.260 [CTD_Seabird](ERROR): Salinity reading out of range: 1.039950 psu
2019-07-25T00:17:44.264Z,1564013864.264 [CTD_Seabird](ERROR): Salinity reading out of range: 1.075529 psu
2019-07-25T00:17:45.268Z,1564013865.268 [CTD_Seabird](ERROR): Salinity reading out of range: 1.107414 psu
2019-07-25T00:17:46.274Z,1564013866.274 [CTD_Seabird](ERROR): Salinity reading out of range: 1.131732 psu
2019-07-25T00:17:47.280Z,1564013867.280 [CTD_Seabird](ERROR): Salinity reading out of range: 1.145311 psu
2019-07-25T00:17:48.281Z,1564013868.281 [CTD_Seabird](ERROR): Salinity reading out of range: 1.150112 psu
2019-07-25T00:17:48.687Z,1564013868.687 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 20.000000 meter
2019-07-25T00:17:49.285Z,1564013869.285 [CTD_Seabird](ERROR): Salinity reading out of range: 1.154005 psu
2019-07-25T00:17:50.290Z,1564013870.290 [CTD_Seabird](ERROR): Salinity reading out of range: 1.159457 psu
2019-07-25T00:17:51.293Z,1564013871.293 [CTD_Seabird](ERROR): Salinity reading out of range: 1.164472 psu
2019-07-25T00:17:52.296Z,1564013872.296 [CTD_Seabird](ERROR): Salinity reading out of range: 1.165289 psu
2019-07-25T00:17:53.300Z,1564013873.300 [CTD_Seabird](ERROR): Salinity reading out of range: 1.162154 psu
2019-07-25T00:17:54.304Z,1564013874.304 [CTD_Seabird](ERROR): Salinity reading out of range: 1.161139 psu
2019-07-25T00:17:55.308Z,1564013875.308 [CTD_Seabird](ERROR): Salinity reading out of range: 1.162068 psu
2019-07-25T00:17:56.312Z,1564013876.312 [CTD_Seabird](ERROR): Salinity reading out of range: 1.160781 psu
2019-07-25T00:17:57.316Z,1564013877.316 [CTD_Seabird](ERROR): Salinity reading out of range: 1.158420 psu
2019-07-25T00:17:57.780Z,1564013877.780 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2019-07-25T00:17:57.780Z,1564013877.780 [DVL_micro] Data Fault, FailCount= 1
2019-07-25T00:17:57.780Z,1564013877.780 [DVL_micro](ERROR): Data Fault
2019-07-25T00:17:57.923Z,1564013877.923 [CBIT](ERROR): Data Fault in component: DVL_micro
2019-07-25T00:17:58.246Z,1564013878.246 [DVL_micro](INFO): uninitialize:Powering down
2019-07-25T00:17:58.348Z,1564013878.348 [CTD_Seabird](ERROR): Salinity reading out of range: 1.156350 psu
2019-07-25T00:17:59.045Z,1564013879.045 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-07-25T00:17:59.046Z,1564013879.046 [DVL_micro] No Fault, FailCount= 1
2019-07-25T00:17:59.352Z,1564013879.352 [CTD_Seabird](ERROR): Salinity reading out of range: 1.153993 psu
2019-07-25T00:17:59.411Z,1564013879.411 [DVL_micro](INFO): Initializing
2019-07-25T00:18:00.361Z,1564013880.361 [CTD_Seabird](ERROR): Salinity reading out of range: 1.152060 psu
2019-07-25T00:18:01.368Z,1564013881.368 [CTD_Seabird](ERROR): Salinity reading out of range: 1.149496 psu
2019-07-25T00:18:01.460Z,1564013881.460 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 25.000000 meter
2019-07-25T00:18:02.368Z,1564013882.368 [CTD_Seabird](ERROR): Salinity reading out of range: 1.147301 psu
2019-07-25T00:18:03.375Z,1564013883.375 [CTD_Seabird](ERROR): Salinity reading out of range: 1.144913 psu
2019-07-25T00:18:04.376Z,1564013884.376 [CTD_Seabird](ERROR): Salinity reading out of range: 1.141632 psu
2019-07-25T00:18:05.381Z,1564013885.381 [CTD_Seabird](ERROR): Salinity reading out of range: 1.137572 psu
2019-07-25T00:18:06.384Z,1564013886.384 [CTD_Seabird](ERROR): Salinity reading out of range: 1.133154 psu
2019-07-25T00:18:07.388Z,1564013887.388 [CTD_Seabird](ERROR): Salinity reading out of range: 1.128980 psu
2019-07-25T00:18:08.392Z,1564013888.392 [CTD_Seabird](ERROR): Salinity reading out of range: 1.125111 psu
2019-07-25T00:18:09.396Z,1564013889.396 [CTD_Seabird](ERROR): Salinity reading out of range: 1.121658 psu
2019-07-25T00:18:10.400Z,1564013890.400 [CTD_Seabird](ERROR): Salinity reading out of range: 1.119722 psu
2019-07-25T00:18:10.404Z,1564013890.404 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 30.000000 meter
2019-07-25T00:18:11.130Z,1564013891.130 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 30.00 mm (2 active estimators).
2019-07-25T00:18:11.404Z,1564013891.404 [CTD_Seabird](ERROR): Salinity reading out of range: 1.117365 psu
2019-07-25T00:18:12.408Z,1564013892.408 [CTD_Seabird](ERROR): Salinity reading out of range: 1.114236 psu
2019-07-25T00:18:13.412Z,1564013893.412 [CTD_Seabird](ERROR): Salinity reading out of range: 1.111827 psu
2019-07-25T00:18:14.416Z,1564013894.416 [CTD_Seabird](ERROR): Salinity reading out of range: 1.110288 psu
2019-07-25T00:18:15.420Z,1564013895.420 [CTD_Seabird](ERROR): Salinity reading out of range: 1.109635 psu
2019-07-25T00:18:16.424Z,1564013896.424 [CTD_Seabird](ERROR): Salinity reading out of range: 1.114818 psu
2019-07-25T00:18:17.428Z,1564013897.428 [CTD_Seabird](ERROR): Salinity reading out of range: 1.122081 psu
2019-07-25T00:18:18.432Z,1564013898.432 [CTD_Seabird](ERROR): Salinity reading out of range: 1.124267 psu
2019-07-25T00:18:19.436Z,1564013899.436 [CTD_Seabird](ERROR): Salinity reading out of range: 1.122867 psu
2019-07-25T00:18:20.441Z,1564013900.441 [CTD_Seabird](ERROR): Salinity reading out of range: 1.120513 psu
2019-07-25T00:18:21.444Z,1564013901.444 [CTD_Seabird](ERROR): Salinity reading out of range: 1.118401 psu
2019-07-25T00:18:22.018Z,1564013902.018 [DVL_micro](ERROR): only read 44 of 46 data items
2019-07-25T00:18:22.019Z,1564013902.019 [DVL_micro](ERROR): Failed to parse:$#NQ.RES 0X0000 0 0 0 0 0 0 0 0 0 0 0 0 213 156 172 128 2 3 2 2 54.7 37.4 180.4 2 -15.2 -64.5 180.4 2 54 37 180 2 -15 -61.16 -1.77 222.4 -3.0 19.2 0.005 35.0 1489 97
2019-07-25T00:18:22.448Z,1564013902.448 [CTD_Seabird](ERROR): Salinity reading out of range: 1.116458 psu
2019-07-25T00:18:23.452Z,1564013903.452 [CTD_Seabird](ERROR): Salinity reading out of range: 1.115117 psu
2019-07-25T00:18:23.951Z,1564013903.951 [CommandLine](IMPORTANT): got command stop
2019-07-25T00:18:23.952Z,1564013903.952 [CommandLine](IMPORTANT): Scheduling is paused
2019-07-25T00:18:23.952Z,1564013903.952 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2019-07-25T00:18:24.088Z,1564013904.088 [MissionManager](INFO): MissionManager is completed.
2019-07-25T00:18:24.088Z,1564013904.088 [MissionManager](INFO): Uninitializing Mission profile_station
2019-07-25T00:18:24.088Z,1564013904.088 [profile_station] Stopped
2019-07-25T00:18:24.089Z,1564013904.089 [profile_station](DEBUG): Aggregate::uninitialize profile_station
2019-07-25T00:18:24.089Z,1564013904.089 [profile_station:Science] Stopped
2019-07-25T00:18:24.089Z,1564013904.089 [profile_station:Science](DEBUG): Aggregate::uninitialize profile_station:Science
2019-07-25T00:18:24.089Z,1564013904.089 [profile_station:Science:A] Stopped
2019-07-25T00:18:24.089Z,1564013904.089 [profile_station:Science:C] Stopped
2019-07-25T00:18:24.089Z,1564013904.089 [profile_station:Science:D] Stopped
2019-07-25T00:18:24.089Z,1564013904.089 [profile_station:Science:G] Stopped
2019-07-25T00:18:24.089Z,1564013904.089 [profile_station:Science:PeakDetectChl] Stopped
2019-07-25T00:18:24.089Z,1564013904.089 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectChl
2019-07-25T00:18:24.089Z,1564013904.089 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2019-07-25T00:18:24.089Z,1564013904.089 [profile_station:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-07-25T00:18:24.089Z,1564013904.089 [profile_station:StandardEnvelopes] Stopped
2019-07-25T00:18:24.089Z,1564013904.089 [profile_station:StandardEnvelopes](DEBUG): Aggregate::uninitialize profile_station:StandardEnvelopes
2019-07-25T00:18:24.090Z,1564013904.090 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2019-07-25T00:18:24.094Z,1564013904.094 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2019-07-25T00:18:24.094Z,1564013904.094 [profile_station:StandardEnvelopes:B.DepthEnvelope] Stopped
2019-07-25T00:18:24.094Z,1564013904.094 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2019-07-25T00:18:24.094Z,1564013904.094 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2019-07-25T00:18:24.094Z,1564013904.094 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2019-07-25T00:18:24.094Z,1564013904.094 [profile_station:I.Pitch] Stopped
2019-07-25T00:18:24.094Z,1564013904.094 [profile_station:J.Buoyancy] Stopped
2019-07-25T00:18:24.094Z,1564013904.094 [profile_station:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2019-07-25T00:18:24.094Z,1564013904.094 [profile_station:K.SetSpeed] Stopped
2019-07-25T00:18:24.094Z,1564013904.094 [profile_station:K.SetSpeed](DEBUG): Uninitialize.
2019-07-25T00:18:24.095Z,1564013904.095 [profile_station:L.DepthEnvelope] Stopped
2019-07-25T00:18:24.095Z,1564013904.095 [profile_station:L.DepthEnvelope](DEBUG): Uninitialize.
2019-07-25T00:18:24.095Z,1564013904.095 [profile_station:M.AltitudeEnvelope] Stopped
2019-07-25T00:18:24.095Z,1564013904.095 [profile_station:M.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2019-07-25T00:18:24.095Z,1564013904.095 [profile_station:N.YoYo] Stopped
2019-07-25T00:18:24.095Z,1564013904.095 [profile_station:N.YoYo](DEBUG): Uninitialize YoYoComponent.
2019-07-25T00:18:24.095Z,1564013904.095 [profile_station:CircleWrapper] Stopped
2019-07-25T00:18:24.095Z,1564013904.095 [profile_station:CircleWrapper](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper
2019-07-25T00:18:24.095Z,1564013904.095 [profile_station:CircleWrapper:A] Stopped
2019-07-25T00:18:24.095Z,1564013904.095 [profile_station:CircleWrapper:B.Circle] Stopped
2019-07-25T00:18:24.095Z,1564013904.095 [profile_station:CircleWrapper:B.Circle](DEBUG): Uninitialize.
2019-07-25T00:18:24.459Z,1564013904.459 [MissionManager](IMPORTANT): Started mission Default
2019-07-25T00:18:24.459Z,1564013904.459 [Default] Running Loop=1
2019-07-25T00:18:24.459Z,1564013904.459 [Default](DEBUG): Aggregate::initialize Default
2019-07-25T00:18:24.459Z,1564013904.459 [Default:B.GoToSurface] Running Loop=1
2019-07-25T00:18:24.459Z,1564013904.459 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-07-25T00:18:24.459Z,1564013904.459 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-07-25T00:18:24.460Z,1564013904.460 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-07-25T00:18:24.460Z,1564013904.460 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-07-25T00:18:24.460Z,1564013904.460 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-07-25T00:18:24.461Z,1564013904.461 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-07-25T00:18:24.461Z,1564013904.461 [Default:A.Wait] Running Loop=1
2019-07-25T00:18:24.461Z,1564013904.461 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-07-25T00:18:24.462Z,1564013904.462 [WetLabsBB2FL](INFO): Powering down
2019-07-25T00:18:24.870Z,1564013904.870 [WetLabsBB2FL](FAULT): LCB fault: Software Overcurrent.
2019-07-25T00:18:24.870Z,1564013904.870 [WetLabsBB2FL] Hardware Fault, FailCount= 1
2019-07-25T00:18:24.870Z,1564013904.870 [WetLabsBB2FL](ERROR): Hardware Fault
2019-07-25T00:18:24.938Z,1564013904.938 [Aanderaa_O2](INFO): Powering down
2019-07-25T00:18:25.111Z,1564013905.111 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -13.00 mm (3 active estimators).
2019-07-25T00:18:25.118Z,1564013905.118 [Radio_Surface](INFO): Powering up
2019-07-25T00:18:25.241Z,1564013905.241 [CBIT](ERROR): Hardware Fault in component: WetLabsBB2FL
2019-07-25T00:18:25.385Z,1564013905.385 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-07-25T00:18:25.386Z,1564013905.386 [WetLabsBB2FL] No Fault, FailCount= 1
2019-07-25T00:18:25.679Z,1564013905.679 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-07-25T00:18:25.679Z,1564013905.679 [WetLabsBB2FL](INFO): Powering up
2019-07-25T00:18:25.820Z,1564013905.820 [CommandLine](IMPORTANT): got command maintain clear
2019-07-25T00:18:25.889Z,1564013905.889 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-07-25T00:18:26.318Z,1564013906.318 [Reporter](INFO): SpeedControl.speedCmd 0.000000 m/s
2019-07-25T00:18:27.790Z,1564013907.790 [WetLabsBB2FL](INFO): Powering down
2019-07-25T00:18:30.626Z,1564013910.626 [DataOverHttps](INFO): Radio surface powered ON.
2019-07-25T00:18:36.403Z,1564013916.403 [CommandLine](IMPORTANT): got command get platform_mass_position centimeter
2019-07-25T00:18:36.403Z,1564013916.403 [CommandLine](IMPORTANT): platform_mass_position 2.999997 cm
2019-07-25T00:18:37.635Z,1564013917.635 [Default:A.Wait](INFO): Done Waiting.
2019-07-25T00:18:37.635Z,1564013917.635 [Default:A.Wait] Stopped
2019-07-25T00:18:37.635Z,1564013917.635 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-07-25T00:18:38.029Z,1564013918.029 [Default:CheckIn] Running Loop=1
2019-07-25T00:18:38.030Z,1564013918.030 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-25T00:18:38.030Z,1564013918.030 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-25T00:18:39.610Z,1564013919.610 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-07-25T00:18:39.610Z,1564013919.610 [NAL9602] Data Fault, FailCount= 2
2019-07-25T00:18:39.610Z,1564013919.610 [NAL9602](ERROR): Data Fault
2019-07-25T00:18:39.691Z,1564013919.691 [CBIT](ERROR): Data Fault in component: NAL9602
2019-07-25T00:18:40.021Z,1564013920.021 [NAL9602](INFO): Powering down
2019-07-25T00:18:40.938Z,1564013920.938 [CBIT](INFO): Clearing failed state for component NAL9602
2019-07-25T00:18:40.938Z,1564013920.938 [NAL9602] No Fault, FailCount= 2
2019-07-25T00:19:00.926Z,1564013940.926 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-07-25T00:19:10.324Z,1564013950.324 [NAL9602](INFO): Powering up NAL9602
2019-07-25T00:19:10.807Z,1564013950.807 [CommandLine](IMPORTANT): got command quit
2019-07-25T00:19:11.814Z,1564013951.814 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-25T00:19:11.814Z,1564013951.814 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-07-25T00:19:11.902Z,1564013951.902 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2019-07-25T00:19:11.902Z,1564013951.902 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-07-25T00:19:11.903Z,1564013951.903 [CommandLine](INFO): Join timeout helper Thread ID is 7690
2019-07-25T00:19:11.903Z,1564013951.903 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-07-25T00:19:11.903Z,1564013951.903 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-07-25T00:19:11.904Z,1564013951.904 [NavChartDb](INFO): Join timeout helper Thread ID is 7691
2019-07-25T00:19:12.078Z,1564013952.078 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-25T00:19:12.078Z,1564013952.078 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-07-25T00:19:12.094Z,1564013952.094 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-07-25T00:19:12.094Z,1564013952.094 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-07-25T00:19:12.094Z,1564013952.094 [Radio_Surface](INFO): Join timeout helper Thread ID is 7692
2019-07-25T00:19:12.410Z,1564013952.410 [Radio_Surface](INFO): Powering down
2019-07-25T00:19:12.411Z,1564013952.411 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-25T00:19:12.411Z,1564013952.411 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-07-25T00:19:12.411Z,1564013952.411 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-07-25T00:19:12.412Z,1564013952.412 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-07-25T00:19:12.412Z,1564013952.412 [DataOverHttps](INFO): Join timeout helper Thread ID is 7693
2019-07-25T00:19:12.642Z,1564013952.642 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-25T00:19:12.642Z,1564013952.642 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-07-25T00:19:12.662Z,1564013952.662 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-07-25T00:19:12.662Z,1564013952.662 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-07-25T00:19:12.662Z,1564013952.662 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7694
2019-07-25T00:19:13.090Z,1564013953.090 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-25T00:19:13.091Z,1564013953.091 [WetLabsBB2FL](INFO): Powering down
2019-07-25T00:19:13.091Z,1564013953.091 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-07-25T00:19:13.111Z,1564013953.111 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-07-25T00:19:13.111Z,1564013953.111 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-07-25T00:19:13.111Z,1564013953.111 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7695
2019-07-25T00:19:13.674Z,1564013953.674 [CTD_Seabird](INFO): Powering down
2019-07-25T00:19:13.686Z,1564013953.686 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-25T00:19:13.686Z,1564013953.686 [CTD_Seabird](INFO): Powering down
2019-07-25T00:19:13.698Z,1564013953.698 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-07-25T00:19:13.703Z,1564013953.703 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-07-25T00:19:13.703Z,1564013953.703 [logger ThreadHandler](INFO): Thread cancelled.
2019-07-25T00:19:13.703Z,1564013953.703 [logger](INFO): Join timeout helper Thread ID is 7696
2019-07-25T00:19:13.750Z,1564013953.750 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-25T00:19:13.750Z,1564013953.750 [logger ThreadHandler](INFO): Thread cancelled.
2019-07-25T00:19:13.763Z,1564013953.763 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-07-25T00:19:13.763Z,1564013953.763 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-07-25T00:19:13.763Z,1564013953.763 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-07-25T00:19:13.763Z,1564013953.763 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-07-25T00:19:13.763Z,1564013953.763 [controlThread](INFO): Join timeout helper Thread ID is 7697
2019-07-25T00:19:13.926Z,1564013953.926 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-07-25T00:19:13.926Z,1564013953.926 [controlThread](DEBUG): Uninitializing ControlThread
2019-07-25T00:19:13.927Z,1564013953.927 [Aanderaa_O2](INFO): Powering down
2019-07-25T00:19:13.948Z,1564013953.948 [AHRS_M2](INFO): Powering down
2019-07-25T00:19:14.090Z,1564013954.090 [DVL_micro](INFO): uninitialize:Powering down
2019-07-25T00:19:14.091Z,1564013954.091 [NAL9602](INFO): Powering down
2019-07-25T00:19:14.092Z,1564013954.092 [DAT](INFO): Powering down
2019-07-25T00:19:14.211Z,1564013954.211 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-07-25T00:19:14.212Z,1564013954.212 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-07-25T00:19:14.212Z,1564013954.212 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-07-25T00:19:14.212Z,1564013954.212 [MissionManager](INFO): Uninitializing Mission Default
2019-07-25T00:19:14.213Z,1564013954.213 [Default] Stopped
2019-07-25T00:19:14.213Z,1564013954.213 [Default](DEBUG): Aggregate::uninitialize Default
2019-07-25T00:19:14.213Z,1564013954.213 [Default:B.GoToSurface] Stopped
2019-07-25T00:19:14.213Z,1564013954.213 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-07-25T00:19:14.213Z,1564013954.213 [Default:CheckIn] Stopped
2019-07-25T00:19:14.213Z,1564013954.213 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-25T00:19:14.213Z,1564013954.213 [Default:CheckIn:Read_GPS] Stopped
2019-07-25T00:19:14.215Z,1564013954.215 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-07-25T00:19:14.216Z,1564013954.216 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-07-25T00:19:14.216Z,1564013954.216 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-07-25T00:19:14.216Z,1564013954.216 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-07-25T00:19:14.216Z,1564013954.216 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-07-25T00:19:14.217Z,1564013954.217 [BuoyancyServo](INFO): Powering down
2019-07-25T00:19:14.346Z,1564013954.346 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-07-25T00:19:14.346Z,1564013954.346 [ElevatorServo](INFO): Powering down
2019-07-25T00:19:14.347Z,1564013954.347 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-07-25T00:19:14.347Z,1564013954.347 [MassServo](INFO): Powering down
2019-07-25T00:19:14.348Z,1564013954.348 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-07-25T00:19:14.348Z,1564013954.348 [RudderServo](INFO): Powering down
2019-07-25T00:19:14.349Z,1564013954.349 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-07-25T00:19:14.349Z,1564013954.349 [ThrusterServo](INFO): Powering down
2019-07-25T00:19:14.350Z,1564013954.350 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-07-25T00:19:14.350Z,1564013954.350 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-07-25T00:19:14.350Z,1564013954.350 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-07-25T00:19:14.350Z,1564013954.350 [CBIT](DEBUG): Powering off loads.
2019-07-25T00:19:14.362Z,1564013954.362 [CBIT](DEBUG): Disabling WDT.
2019-07-25T00:19:14.374Z,1564013954.374 [CBIT](DEBUG): Opening all GF detection circuits.
2019-07-25T00:19:14.375Z,1564013954.375 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-07-25T00:19:14.410Z,1564013954.410 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-07-25T00:19:14.415Z,1564013954.415 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-07-25T00:19:14.477Z,1564013954.477 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-07-25T00:19:14.484Z,1564013954.484 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-07-25T00:19:14.533Z,1564013954.533 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-07-25T00:19:14.599Z,1564013954.599 [logger ThreadHandler](INFO): Thread cancelled.