2019-08-28T04:37:21.128Z,1566967041.128 [Supervisor](DEBUG): Initializing supervisor.
2019-08-28T04:37:21.157Z,1566967041.157 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-08-28T04:37:21.157Z,1566967041.157 [SyncHandler](INFO): Protected caller Thread ID is 2968
2019-08-28T04:37:21.158Z,1566967041.158 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-08-28T04:37:21.161Z,1566967041.161 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-08-28T04:37:21.161Z,1566967041.161 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2969
2019-08-28T04:37:21.195Z,1566967041.195 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-08-28T04:37:21.246Z,1566967041.246 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-08-28T04:37:21.247Z,1566967041.247 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-08-28T04:37:21.247Z,1566967041.247 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2970
2019-08-28T04:37:21.248Z,1566967041.248 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-08-28T04:37:21.249Z,1566967041.249 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-08-28T04:37:21.249Z,1566967041.249 [logger ThreadHandler](INFO): Protected caller Thread ID is 2971
2019-08-28T04:37:21.251Z,1566967041.251 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-08-28T04:37:21.251Z,1566967041.251 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-08-28T04:37:21.253Z,1566967041.253 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-08-28T04:37:21.371Z,1566967041.371 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-08-28T04:37:21.371Z,1566967041.371 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-08-28T04:37:21.937Z,1566967041.937 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-08-28T04:37:21.937Z,1566967041.937 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-08-28T04:37:22.038Z,1566967042.038 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-08-28T04:37:22.038Z,1566967042.038 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-08-28T04:37:22.142Z,1566967042.142 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-08-28T04:37:22.143Z,1566967042.143 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-08-28T04:37:22.225Z,1566967042.225 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-08-28T04:37:22.365Z,1566967042.365 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-08-28T04:37:22.366Z,1566967042.366 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-08-28T04:37:22.663Z,1566967042.663 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-08-28T04:37:22.664Z,1566967042.664 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-08-28T04:37:23.130Z,1566967043.130 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-08-28T04:37:23.130Z,1566967043.130 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-08-28T04:37:23.278Z,1566967043.278 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-08-28T04:37:23.278Z,1566967043.278 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-08-28T04:37:23.485Z,1566967043.485 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-08-28T04:37:23.485Z,1566967043.485 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-08-28T04:37:23.945Z,1566967043.945 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-08-28T04:37:23.946Z,1566967043.946 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-08-28T04:37:24.161Z,1566967044.161 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-08-28T04:37:24.162Z,1566967044.162 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-08-28T04:37:24.366Z,1566967044.366 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-08-28T04:37:24.366Z,1566967044.366 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-08-28T04:37:24.761Z,1566967044.761 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-08-28T04:37:24.762Z,1566967044.762 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-08-28T04:37:25.098Z,1566967045.098 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-08-28T04:37:25.100Z,1566967045.100 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2019-08-28T04:37:25.101Z,1566967045.101 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2019-08-28T04:37:25.186Z,1566967045.186 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2019-08-28T04:37:25.346Z,1566967045.346 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2019-08-28T04:37:25.456Z,1566967045.456 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2019-08-28T04:37:25.543Z,1566967045.543 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2019-08-28T04:37:25.640Z,1566967045.640 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2019-08-28T04:37:25.840Z,1566967045.840 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2019-08-28T04:37:26.119Z,1566967046.119 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-08-28T04:37:26.119Z,1566967046.119 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2019-08-28T04:37:26.220Z,1566967046.220 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2019-08-28T04:37:26.423Z,1566967046.423 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2019-08-28T04:37:26.726Z,1566967046.726 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2019-08-28T04:37:26.891Z,1566967046.891 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2019-08-28T04:37:26.891Z,1566967046.891 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-08-28T04:37:27.094Z,1566967047.094 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-08-28T04:37:27.164Z,1566967047.164 [VerticalControl](DEBUG): Construct VerticalControl.
2019-08-28T04:37:27.276Z,1566967047.276 [VerticalControl] Loaded
2019-08-28T04:37:27.277Z,1566967047.277 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-08-28T04:37:27.278Z,1566967047.278 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-08-28T04:37:27.346Z,1566967047.346 [HorizontalControl] Loaded
2019-08-28T04:37:27.347Z,1566967047.347 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-08-28T04:37:27.347Z,1566967047.347 [SpeedControl](DEBUG): Construct SpeedControl.
2019-08-28T04:37:27.353Z,1566967047.353 [SpeedControl] Loaded
2019-08-28T04:37:27.353Z,1566967047.353 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-08-28T04:37:27.354Z,1566967047.354 [LoopControl](DEBUG): Construct LoopControl.
2019-08-28T04:37:27.355Z,1566967047.355 [LoopControl] Loaded
2019-08-28T04:37:27.355Z,1566967047.355 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-08-28T04:37:27.355Z,1566967047.355 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-08-28T04:37:27.356Z,1566967047.356 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-08-28T04:37:27.396Z,1566967047.396 [DepthRateCalculator] Loaded
2019-08-28T04:37:27.396Z,1566967047.396 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-08-28T04:37:27.401Z,1566967047.401 [PitchRateCalculator] Loaded
2019-08-28T04:37:27.402Z,1566967047.402 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-08-28T04:37:27.417Z,1566967047.417 [SpeedCalculator] Loaded
2019-08-28T04:37:27.417Z,1566967047.417 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-08-28T04:37:27.438Z,1566967047.438 [TempGradientCalculator] Loaded
2019-08-28T04:37:27.438Z,1566967047.438 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-08-28T04:37:27.443Z,1566967047.443 [YawRateCalculator] Loaded
2019-08-28T04:37:27.444Z,1566967047.444 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-08-28T04:37:27.472Z,1566967047.472 [ElevatorOffsetCalculator] Loaded
2019-08-28T04:37:27.473Z,1566967047.473 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-08-28T04:37:27.473Z,1566967047.473 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-08-28T04:37:27.474Z,1566967047.474 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-08-28T04:37:27.505Z,1566967047.505 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-08-28T04:37:27.506Z,1566967047.506 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-08-28T04:37:27.576Z,1566967047.576 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-08-28T04:37:27.576Z,1566967047.576 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-08-28T04:37:27.861Z,1566967047.861 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-08-28T04:37:27.861Z,1566967047.861 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-08-28T04:37:27.959Z,1566967047.959 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-08-28T04:37:27.959Z,1566967047.959 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-08-28T04:37:28.341Z,1566967048.341 [AHRS_M2] Loaded
2019-08-28T04:37:28.342Z,1566967048.342 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-08-28T04:37:28.415Z,1566967048.415 [DataOverHttps] Loaded
2019-08-28T04:37:28.415Z,1566967048.415 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-08-28T04:37:28.416Z,1566967048.416 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408CA4E0
2019-08-28T04:37:28.417Z,1566967048.417 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3050
2019-08-28T04:37:28.430Z,1566967048.430 [Depth_Keller] Loaded
2019-08-28T04:37:28.430Z,1566967048.430 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-08-28T04:37:28.435Z,1566967048.435 [DropWeight] Loaded
2019-08-28T04:37:28.435Z,1566967048.435 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-08-28T04:37:28.532Z,1566967048.532 [NAL9602] Loaded
2019-08-28T04:37:28.532Z,1566967048.532 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-08-28T04:37:28.548Z,1566967048.548 [Onboard] Loaded
2019-08-28T04:37:28.549Z,1566967048.549 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-08-28T04:37:28.552Z,1566967048.552 [Radio_Surface] Loaded
2019-08-28T04:37:28.552Z,1566967048.552 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-08-28T04:37:28.553Z,1566967048.553 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408FA4E0
2019-08-28T04:37:28.554Z,1566967048.554 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3051
2019-08-28T04:37:28.732Z,1566967048.732 [Rowe_600] Loaded
2019-08-28T04:37:28.732Z,1566967048.732 [ComponentRegistry](DEBUG): Component "Rowe_600" handled in its own thread.
2019-08-28T04:37:28.733Z,1566967048.733 [Rowe_600 ThreadHandler](DEBUG): Created PCaller Thread at 4092A4E0
2019-08-28T04:37:28.733Z,1566967048.733 [Rowe_600 ThreadHandler](INFO): Protected caller Thread ID is 3052
2019-08-28T04:37:30.212Z,1566967050.212 [BPC1] Loaded
2019-08-28T04:37:30.213Z,1566967050.213 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-08-28T04:37:30.213Z,1566967050.213 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-08-28T04:37:30.214Z,1566967050.214 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-08-28T04:37:30.315Z,1566967050.315 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-08-28T04:37:30.316Z,1566967050.316 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-08-28T04:37:30.336Z,1566967050.336 [NavChart] Loaded
2019-08-28T04:37:30.336Z,1566967050.336 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-08-28T04:37:30.340Z,1566967050.340 [UniversalFixResidualReporter] Loaded
2019-08-28T04:37:30.341Z,1566967050.341 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-08-28T04:37:30.341Z,1566967050.341 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-08-28T04:37:30.342Z,1566967050.342 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-08-28T04:37:30.477Z,1566967050.477 [SBIT](DEBUG): Construct Startup Built In Test.
2019-08-28T04:37:30.488Z,1566967050.488 [SBIT] Loaded
2019-08-28T04:37:30.489Z,1566967050.489 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-08-28T04:37:30.489Z,1566967050.489 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-08-28T04:37:30.501Z,1566967050.501 [IBIT] Loaded
2019-08-28T04:37:30.501Z,1566967050.501 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-08-28T04:37:30.504Z,1566967050.504 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-08-28T04:37:30.642Z,1566967050.642 [CBIT] Loaded
2019-08-28T04:37:30.642Z,1566967050.642 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-08-28T04:37:30.642Z,1566967050.642 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-08-28T04:37:30.643Z,1566967050.643 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-08-28T04:37:30.745Z,1566967050.745 [BuoyancyServo] Loaded
2019-08-28T04:37:30.745Z,1566967050.745 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-08-28T04:37:30.757Z,1566967050.757 [ElevatorServo] Loaded
2019-08-28T04:37:30.757Z,1566967050.757 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-08-28T04:37:30.769Z,1566967050.769 [MassServo] Loaded
2019-08-28T04:37:30.769Z,1566967050.769 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-08-28T04:37:30.780Z,1566967050.780 [RudderServo] Loaded
2019-08-28T04:37:30.780Z,1566967050.780 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-08-28T04:37:30.792Z,1566967050.792 [ThrusterServo] Loaded
2019-08-28T04:37:30.792Z,1566967050.792 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-08-28T04:37:30.792Z,1566967050.792 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-08-28T04:37:30.793Z,1566967050.793 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-08-28T04:37:30.806Z,1566967050.806 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-08-28T04:37:30.807Z,1566967050.807 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-08-28T04:37:30.976Z,1566967050.976 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-08-28T04:37:30.982Z,1566967050.982 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-08-28T04:37:30.983Z,1566967050.983 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-08-28T04:37:30.988Z,1566967050.988 [CTD_Seabird](INFO): created writer for : depth
2019-08-28T04:37:30.989Z,1566967050.989 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-08-28T04:37:30.994Z,1566967050.994 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-08-28T04:37:30.995Z,1566967050.995 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-08-28T04:37:30.000Z,1566967051.000 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-08-28T04:37:31.000Z,1566967051.000 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-08-28T04:37:31.006Z,1566967051.006 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-08-28T04:37:31.006Z,1566967051.006 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-08-28T04:37:31.012Z,1566967051.012 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-08-28T04:37:31.012Z,1566967051.012 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-08-28T04:37:31.017Z,1566967051.017 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-08-28T04:37:31.045Z,1566967051.045 [CTD_Seabird] Loaded
2019-08-28T04:37:31.045Z,1566967051.045 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-08-28T04:37:31.046Z,1566967051.046 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40AAF4E0
2019-08-28T04:37:31.047Z,1566967051.047 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3053
2019-08-28T04:37:31.096Z,1566967051.096 [WetLabsBB2FL] Loaded
2019-08-28T04:37:31.096Z,1566967051.096 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-08-28T04:37:31.097Z,1566967051.097 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40ADF4E0
2019-08-28T04:37:31.098Z,1566967051.098 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3054
2019-08-28T04:37:31.099Z,1566967051.099 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-08-28T04:37:31.149Z,1566967051.149 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-08-28T04:37:31.150Z,1566967051.150 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-08-28T04:37:31.157Z,1566967051.157 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-08-28T04:37:31.158Z,1566967051.158 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B0F4E0
2019-08-28T04:37:31.158Z,1566967051.158 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3055
2019-08-28T04:37:31.164Z,1566967051.164 [Supervisor](INFO): Main Thread ID is 2133
2019-08-28T04:37:31.164Z,1566967051.164 [Supervisor](DEBUG): Running supervisor.
2019-08-28T04:37:31.164Z,1566967051.164 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3056
2019-08-28T04:37:31.167Z,1566967051.167 [controlThread ThreadHandler](INFO): Handler Thread ID is 3057
2019-08-28T04:37:31.167Z,1566967051.167 [controlThread](DEBUG): Initializing ControlThread
2019-08-28T04:37:31.168Z,1566967051.168 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-08-28T04:37:31.170Z,1566967051.170 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-08-28T04:37:31.170Z,1566967051.170 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-08-28T04:37:31.171Z,1566967051.171 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-08-28T04:37:31.171Z,1566967051.171 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-08-28T04:37:31.171Z,1566967051.171 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-08-28T04:37:31.172Z,1566967051.172 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-08-28T04:37:31.172Z,1566967051.172 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-08-28T04:37:31.173Z,1566967051.173 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-08-28T04:37:31.173Z,1566967051.173 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-08-28T04:37:31.180Z,1566967051.180 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-08-28T04:37:31.180Z,1566967051.180 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-08-28T04:37:31.180Z,1566967051.180 [SBIT](INFO): Initialize SBIT Component.
2019-08-28T04:37:31.181Z,1566967051.181 [SBIT](IMPORTANT): git: 2019-08-13-6-g4aae551
2019-08-28T04:37:31.181Z,1566967051.181 [SBIT](INFO): git hash: 4aae551959fa3237669caaf032899fc457a0fca0
2019-08-28T04:37:31.181Z,1566967051.181 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-08-28T04:37:31.182Z,1566967051.182 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-08-28T04:37:31.183Z,1566967051.183 [SBIT](INFO): Beginning SBIT in 59.000000 seconds.
2019-08-28T04:37:31.183Z,1566967051.183 [IBIT](INFO): Initialize IBIT Component.
2019-08-28T04:37:31.184Z,1566967051.184 [CBIT](DEBUG): Initialize CBIT Component.
2019-08-28T04:37:31.188Z,1566967051.188 [logger ThreadHandler](INFO): Handler Thread ID is 3058
2019-08-28T04:37:31.197Z,1566967051.197 [CBIT](DEBUG): Initialized mux pins.
2019-08-28T04:37:31.197Z,1566967051.197 [CBIT](DEBUG): Initializing the watchdog timer.
2019-08-28T04:37:31.201Z,1566967051.201 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3059
2019-08-28T04:37:31.203Z,1566967051.203 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-08-28T04:37:31.209Z,1566967051.209 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3060
2019-08-28T04:37:31.211Z,1566967051.211 [Rowe_600 ThreadHandler](INFO): Handler Thread ID is 3061
2019-08-28T04:37:31.217Z,1566967051.217 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3062
2019-08-28T04:37:31.218Z,1566967051.218 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-08-28T04:37:31.221Z,1566967051.221 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2019-08-28T04:37:31.221Z,1566967051.221 [CBIT](DEBUG): Initializing heartbeat.
2019-08-28T04:37:31.222Z,1566967051.222 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3064
2019-08-28T04:37:31.223Z,1566967051.223 [WetLabsBB2FL](INFO): Powering down
2019-08-28T04:37:31.244Z,1566967051.244 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3065
2019-08-28T04:37:31.248Z,1566967051.248 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-08-28T04:37:31.248Z,1566967051.248 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-08-28T04:37:31.248Z,1566967051.248 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-08-28T04:37:31.248Z,1566967051.248 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-08-28T04:37:31.248Z,1566967051.248 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-08-28T04:37:31.249Z,1566967051.249 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-08-28T04:37:31.249Z,1566967051.249 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-08-28T04:37:31.249Z,1566967051.249 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-08-28T04:37:31.249Z,1566967051.249 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-08-28T04:37:31.250Z,1566967051.250 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-08-28T04:37:31.250Z,1566967051.250 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-08-28T04:37:31.250Z,1566967051.250 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-08-28T04:37:31.250Z,1566967051.250 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-08-28T04:37:31.250Z,1566967051.250 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-08-28T04:37:31.250Z,1566967051.250 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-08-28T04:37:31.251Z,1566967051.251 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-08-28T04:37:31.293Z,1566967051.293 [CBIT](DEBUG): Deactivating GF circuits.
2019-08-28T04:37:31.293Z,1566967051.293 [CBIT](DEBUG): Deactivating emergency mode.
2019-08-28T04:37:31.329Z,1566967051.329 [CBIT](DEBUG): Backplane powered.
2019-08-28T04:37:31.331Z,1566967051.331 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-08-28T04:37:31.341Z,1566967051.341 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-08-28T04:37:31.376Z,1566967051.376 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2019-08-28T04:37:31.377Z,1566967051.377 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-08-28T04:37:31.385Z,1566967051.385 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-08-28T04:37:31.447Z,1566967051.447 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-08-28T04:37:31.448Z,1566967051.448 [Default:A.Wait](DEBUG): Construct Wait.
2019-08-28T04:37:31.450Z,1566967051.450 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-08-28T04:37:31.493Z,1566967051.493 [Rowe_600](INFO): Powering down
2019-08-28T04:37:31.509Z,1566967051.509 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-08-28T04:37:31.514Z,1566967051.514 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-08-28T04:37:31.543Z,1566967051.543 [Default:E.Execute](DEBUG): Construct Execute.
2019-08-28T04:37:31.547Z,1566967051.547 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2019-08-28T04:37:31.551Z,1566967051.551 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,BPC1,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-08-28T04:37:31.565Z,1566967051.565 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-08-28T04:37:31.613Z,1566967051.613 [Rowe_600](FAULT): LCB fault:  Software Overcurrent.
2019-08-28T04:37:31.613Z,1566967051.613 [Rowe_600] Hardware Fault, FailCount= 1
2019-08-28T04:37:31.613Z,1566967051.613 [Rowe_600](ERROR): Hardware Fault
2019-08-28T04:37:31.614Z,1566967051.614 [Radio_Surface](INFO): Powering up
2019-08-28T04:37:31.699Z,1566967051.699 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-08-28T04:37:31.706Z,1566967051.706 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-08-28T04:37:31.707Z,1566967051.707 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-08-28T04:37:31.721Z,1566967051.721 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-08-28T04:37:31.722Z,1566967051.722 [MassServo](DEBUG): Initializing EZServoServo.
2019-08-28T04:37:31.729Z,1566967051.729 [MassServo](DEBUG): Initializing MassServo.
2019-08-28T04:37:31.730Z,1566967051.730 [RudderServo](DEBUG): Initializing EZServoServo.
2019-08-28T04:37:31.737Z,1566967051.737 [RudderServo](DEBUG): Initializing RudderServo.
2019-08-28T04:37:31.738Z,1566967051.738 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-08-28T04:37:31.745Z,1566967051.745 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-08-28T04:37:31.747Z,1566967051.747 [CommandLine](FAULT): Scheduling is paused
2019-08-28T04:37:31.747Z,1566967051.747 [CBIT](INFO): Critical error at 20190828T043731
2019-08-28T04:37:31.748Z,1566967051.748 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-08-28T04:37:31.752Z,1566967051.752 [CBIT](ERROR): Hardware Fault in component: Rowe_600
2019-08-28T04:37:32.121Z,1566967052.121 [Rowe_600](INFO): Data requested. STOPPED ==> START
2019-08-28T04:37:32.693Z,1566967052.693 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-08-28T04:37:32.693Z,1566967052.693 [RudderServo](FAULT): Rudder failed to initialize
2019-08-28T04:37:32.693Z,1566967052.693 [RudderServo] Communications Fault, FailCount= 1
2019-08-28T04:37:32.693Z,1566967052.693 [RudderServo](ERROR): Communications Fault
2019-08-28T04:37:32.804Z,1566967052.804 [CBIT](INFO): Clearing failed state for component Rowe_600
2019-08-28T04:37:32.804Z,1566967052.804 [Rowe_600] No Fault, FailCount= 1
2019-08-28T04:37:32.804Z,1566967052.804 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-08-28T04:37:32.930Z,1566967052.930 [Rowe_600](INFO): Initializing
2019-08-28T04:37:32.953Z,1566967052.953 [Rowe_600](INFO): Powering up
2019-08-28T04:37:32.966Z,1566967052.966 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-08-28T04:37:32.966Z,1566967052.966 [RudderServo](INFO): Powering down
2019-08-28T04:37:33.656Z,1566967053.656 [RudderServo](DEBUG): Initializing EZServoServo.
2019-08-28T04:37:33.774Z,1566967053.774 [RudderServo](DEBUG): Initializing RudderServo.
2019-08-28T04:37:33.778Z,1566967053.778 [CBIT](INFO): Clearing failed state for component RudderServo
2019-08-28T04:37:33.778Z,1566967053.778 [RudderServo] No Fault, FailCount= 1
2019-08-28T04:37:39.253Z,1566967059.253 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2019-08-28T04:37:58.706Z,1566967078.706 [NAL9602](INFO): Powering up NAL9602
2019-08-28T04:38:09.613Z,1566967089.613 [NAL9602](INFO): NAL9602 initialized
2019-08-28T04:38:10.433Z,1566967090.433 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:38:30.675Z,1566967110.675 [SBIT](IMPORTANT): Beginning Startup BIT
2019-08-28T04:38:30.679Z,1566967110.679 [CBIT](IMPORTANT): Beginning ground fault scan
2019-08-28T04:38:41.706Z,1566967121.706 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007352
CHAN A1 (24V): 0.006270
CHAN A2 (12V): -0.007061
CHAN A3 (5V): -0.001574
CHAN B0 (3.3V): 0.001123
CHAN B1 (3.15aV): 0.000502
CHAN B2 (3.15bV): 0.000299
CHAN B3 (GND): 0.002313
OPEN: 0.006975
Full Scale Calc: 4.765 mA, -1.589 mA
2019-08-28T04:38:57.467Z,1566967137.467 [NAL9602](INFO): SBD MO Status=2, MOMSN=179, MT Status=2, MTMSN=0
2019-08-28T04:38:57.467Z,1566967137.467 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-08-28T04:39:24.243Z,1566967164.243 [SBIT](IMPORTANT): SBIT PASSED
2019-08-28T04:39:24.261Z,1566967164.261 [CommandLine](IMPORTANT): got command configSet list
2019-08-28T04:39:24.262Z,1566967164.262 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-08-28T04:39:24.262Z,1566967164.262 [CommandLine](IMPORTANT): Micromodem.loadAtStartup=0 bool;

2019-08-28T04:39:24.263Z,1566967164.263 [CommandLine](IMPORTANT): VerticalControl.massDefault=10 millimeter;

2019-08-28T04:39:24.646Z,1566967164.646 [MissionManager](IMPORTANT): Started mission Startup
2019-08-28T04:39:24.646Z,1566967164.646 [Startup] Running Loop=1
2019-08-28T04:39:24.646Z,1566967164.646 [Startup](DEBUG): Aggregate::initialize Startup
2019-08-28T04:39:24.647Z,1566967164.647 [Startup:A.GoToSurface] Running Loop=1
2019-08-28T04:39:24.647Z,1566967164.647 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-08-28T04:39:24.647Z,1566967164.647 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-08-28T04:39:24.648Z,1566967164.648 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-08-28T04:39:24.648Z,1566967164.648 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-08-28T04:39:24.648Z,1566967164.648 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-08-28T04:39:24.649Z,1566967164.649 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-08-28T04:39:24.650Z,1566967164.650 [Startup:StartupSatComms] Running Loop=1
2019-08-28T04:39:24.650Z,1566967164.650 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-08-28T04:39:24.650Z,1566967164.650 [Startup:StartupSatComms:A] Running Loop=1
2019-08-28T04:39:25.048Z,1566967165.048 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-08-28T04:39:39.163Z,1566967179.163 [NAL9602](INFO): SBD MO Status=2, MOMSN=179, MT Status=2, MTMSN=0
2019-08-28T04:39:39.163Z,1566967179.163 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-08-28T04:40:03.116Z,1566967203.116 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004911
2019-08-28T04:40:24.999Z,1566967224.999 [Startup:StartupSatComms:A](INFO): Timed out from 2019-08-28T04:39:24.7Z
2019-08-28T04:40:24.999Z,1566967224.999 [Startup:StartupSatComms:A] Stopped
2019-08-28T04:40:24.999Z,1566967224.999 [Startup:StartupSatComms:B] Running Loop=1
2019-08-28T04:40:25.362Z,1566967225.362 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-08-28T04:40:26.213Z,1566967226.213 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Courier0004
2019-08-28T04:40:31.393Z,1566967231.393 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-08-28T04:40:31.393Z,1566967231.393 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-28T04:40:31.407Z,1566967231.407 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-28T04:40:31.821Z,1566967231.821 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-28T04:40:31.822Z,1566967231.822 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-08-28T04:40:33.768Z,1566967233.768 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190828T042457/Courier0006.lzma
2019-08-28T04:40:35.774Z,1566967235.774 [DataOverHttps](INFO): Moved sent file to Logs/20190828T042457/Courier0006.lzma.bak
2019-08-28T04:40:35.775Z,1566967235.775 [DataOverHttps](INFO): SBD MOMSN=11706571
2019-08-28T04:40:36.782Z,1566967236.782 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Courier0004
2019-08-28T04:40:37.904Z,1566967237.904 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Courier0004 (1/1)
2019-08-28T04:40:48.344Z,1566967248.344 [NAL9602](INFO): SBD MO Status=0, MOMSN=179, MT Status=0, MTMSN=0
2019-08-28T04:40:48.344Z,1566967248.344 [NAL9602](INFO): No messages in MT queue
2019-08-28T04:40:49.161Z,1566967249.161 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:40:51.585Z,1566967251.585 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:40:54.112Z,1566967254.112 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20190828T043721/Courier0000.lzma
2019-08-28T04:40:54.413Z,1566967254.413 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:40:56.572Z,1566967256.572 [DataOverHttps](INFO): Moved sent file to Logs/20190828T043721/Courier0000.lzma.bak
2019-08-28T04:40:56.572Z,1566967256.572 [DataOverHttps](INFO): SBD MOMSN=11706573
2019-08-28T04:40:57.129Z,1566967257.129 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Courier0004
2019-08-28T04:40:57.241Z,1566967257.241 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:40:58.333Z,1566967258.333 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Courier0004 (1/1)
2019-08-28T04:40:58.337Z,1566967258.337 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Express0005
2019-08-28T04:41:00.473Z,1566967260.473 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:41:03.305Z,1566967263.305 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:41:06.641Z,1566967266.641 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:41:09.476Z,1566967269.476 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:41:12.305Z,1566967272.305 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:41:15.537Z,1566967275.537 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:41:17.148Z,1566967277.148 [DataOverHttps](INFO): Sending 127 bytes from file Logs/20190828T042457/Express0007.lzma
2019-08-28T04:41:18.357Z,1566967278.357 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:41:19.154Z,1566967279.154 [DataOverHttps](INFO): Moved sent file to Logs/20190828T042457/Express0007.lzma.bak
2019-08-28T04:41:19.154Z,1566967279.154 [DataOverHttps](INFO): SBD MOMSN=11706577
2019-08-28T04:41:20.161Z,1566967280.161 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Courier0004
2019-08-28T04:41:21.303Z,1566967281.303 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Courier0004 (1/1)
2019-08-28T04:41:21.308Z,1566967281.308 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Express0005
2019-08-28T04:41:21.589Z,1566967281.589 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:41:22.403Z,1566967282.403 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Express0005 (1/1)
2019-08-28T04:41:24.425Z,1566967284.425 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:41:25.254Z,1566967285.254 [Startup:StartupSatComms:B](INFO): Timed out from 2019-08-28T04:40:24.0Z
2019-08-28T04:41:25.254Z,1566967285.254 [Startup:StartupSatComms:B] Stopped
2019-08-28T04:41:25.255Z,1566967285.255 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-08-28T04:41:25.255Z,1566967285.255 [Startup:StartupSatComms] Stopped
2019-08-28T04:41:25.255Z,1566967285.255 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-08-28T04:41:25.255Z,1566967285.255 [Startup](INFO): Completed Startup
2019-08-28T04:41:25.256Z,1566967285.256 [MissionManager](INFO): Startup is completed.
2019-08-28T04:41:25.256Z,1566967285.256 [MissionManager](INFO): Uninitializing Mission Startup
2019-08-28T04:41:25.256Z,1566967285.256 [Startup] Stopped
2019-08-28T04:41:25.256Z,1566967285.256 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-08-28T04:41:25.256Z,1566967285.256 [Startup:A.GoToSurface] Stopped
2019-08-28T04:41:25.256Z,1566967285.256 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-08-28T04:41:25.643Z,1566967285.643 [MissionManager](IMPORTANT): Started mission Default
2019-08-28T04:41:25.643Z,1566967285.643 [Default] Running Loop=1
2019-08-28T04:41:25.643Z,1566967285.643 [Default](DEBUG): Aggregate::initialize Default
2019-08-28T04:41:25.643Z,1566967285.643 [Default:B.GoToSurface] Running Loop=1
2019-08-28T04:41:25.643Z,1566967285.643 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-08-28T04:41:25.644Z,1566967285.644 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-08-28T04:41:25.644Z,1566967285.644 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-08-28T04:41:25.644Z,1566967285.644 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-08-28T04:41:25.645Z,1566967285.645 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-08-28T04:41:25.645Z,1566967285.645 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-08-28T04:41:25.646Z,1566967285.646 [Default:A.Wait] Running Loop=1
2019-08-28T04:41:25.646Z,1566967285.646 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-08-28T04:41:27.245Z,1566967287.245 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:41:30.477Z,1566967290.477 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:41:33.305Z,1566967293.305 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:41:36.537Z,1566967296.537 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:41:38.846Z,1566967298.846 [DataOverHttps](INFO): Sending 776 bytes from file Logs/20190828T043721/Express0001.lzma
2019-08-28T04:41:38.981Z,1566967298.981 [Default:A.Wait](INFO): Done Waiting.
2019-08-28T04:41:38.981Z,1566967298.981 [Default:A.Wait] Stopped
2019-08-28T04:41:38.981Z,1566967298.981 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-08-28T04:41:39.365Z,1566967299.365 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:41:39.394Z,1566967299.394 [Default:CheckIn] Running Loop=1
2019-08-28T04:41:39.394Z,1566967299.394 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-28T04:41:39.395Z,1566967299.395 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-28T04:41:39.814Z,1566967299.814 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-08-28T04:41:40.850Z,1566967300.850 [DataOverHttps](INFO): Moved sent file to Logs/20190828T043721/Express0001.lzma.bak
2019-08-28T04:41:40.850Z,1566967300.850 [DataOverHttps](INFO): SBD MOMSN=11706581
2019-08-28T04:41:42.601Z,1566967302.601 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:41:45.429Z,1566967305.429 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:41:48.257Z,1566967308.257 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:41:51.489Z,1566967311.489 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:41:54.317Z,1566967314.317 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:41:57.557Z,1566967317.557 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:42:00.377Z,1566967320.377 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:42:00.768Z,1566967320.768 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,044107.00,A,3648.18002,N,12147.23977,W,1.633,239.96,280819,,,A*7B
2019-08-28T04:42:00.772Z,1566967320.772 [NAL9602](INFO): GPS fix at 20190828T044107: (36.803000, -121.787329)
2019-08-28T04:42:00.812Z,1566967320.812 [Default:CheckIn:Read_GPS] Stopped
2019-08-28T04:42:00.812Z,1566967320.812 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-28T04:42:01.232Z,1566967321.232 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-08-28T04:42:01.938Z,1566967321.938 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Courier0007
2019-08-28T04:42:03.545Z,1566967323.545 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Courier0007 (1/1)
2019-08-28T04:42:26.537Z,1566967346.537 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20190828T043721/Courier0004.lzma
2019-08-28T04:42:28.542Z,1566967348.542 [DataOverHttps](INFO): Moved sent file to Logs/20190828T043721/Courier0004.lzma.bak
2019-08-28T04:42:28.542Z,1566967348.542 [DataOverHttps](INFO): SBD MOMSN=11706599
2019-08-28T04:42:29.550Z,1566967349.550 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Courier0007
2019-08-28T04:42:30.835Z,1566967350.835 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Courier0007 (1/1)
2019-08-28T04:42:30.839Z,1566967350.839 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Express0008
2019-08-28T04:42:32.176Z,1566967352.176 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190827T223724/Express0001 (1/2)
2019-08-28T04:42:32.176Z,1566967352.176 [DataOverHttps](INFO): GetToShoreFilename: encoding Logs/20190827T223724/Express0001
2019-08-28T04:42:32.366Z,1566967352.366 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190827T223724/Express0005 (2/2)
2019-08-28T04:42:32.367Z,1566967352.367 [DataOverHttps](INFO): GetToShoreFilename: encoding Logs/20190827T223724/Express0005
2019-08-28T04:42:33.526Z,1566967353.526 [NAL9602](INFO): Not Powering down - fast GPS
2019-08-28T04:43:00.981Z,1566967380.981 [DataOverHttps](INFO): Sending 769 bytes from file Logs/20190827T223724/Express0001.lzma
2019-08-28T04:43:02.987Z,1566967382.987 [DataOverHttps](INFO): Moved sent file to Logs/20190827T223724/Express0001.lzma.bak
2019-08-28T04:43:02.987Z,1566967382.987 [DataOverHttps](INFO): SBD MOMSN=11704926
2019-08-28T04:43:03.994Z,1566967383.994 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Courier0007
2019-08-28T04:43:05.193Z,1566967385.193 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Courier0007 (1/1)
2019-08-28T04:43:05.197Z,1566967385.197 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Express0008
2019-08-28T04:43:22.810Z,1566967402.810 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190827T223724/Express0005.lzma
2019-08-28T04:43:24.815Z,1566967404.815 [DataOverHttps](INFO): Moved sent file to Logs/20190827T223724/Express0005.lzma.bak
2019-08-28T04:43:24.815Z,1566967404.815 [DataOverHttps](INFO): SBD MOMSN=11704950
2019-08-28T04:43:25.822Z,1566967405.822 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Courier0007
2019-08-28T04:43:27.185Z,1566967407.185 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190827T223724/Courier0000 (1/2)
2019-08-28T04:43:27.185Z,1566967407.185 [DataOverHttps](INFO): GetToShoreFilename: encoding Logs/20190827T223724/Courier0000
2019-08-28T04:43:27.372Z,1566967407.372 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190827T223724/Courier0004 (2/2)
2019-08-28T04:43:27.373Z,1566967407.373 [DataOverHttps](INFO): GetToShoreFilename: encoding Logs/20190827T223724/Courier0004
2019-08-28T04:43:44.161Z,1566967424.161 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20190827T223724/Courier0000.lzma
2019-08-28T04:43:46.167Z,1566967426.167 [DataOverHttps](INFO): Moved sent file to Logs/20190827T223724/Courier0000.lzma.bak
2019-08-28T04:43:46.167Z,1566967426.167 [DataOverHttps](INFO): SBD MOMSN=11704808
2019-08-28T04:43:47.174Z,1566967427.174 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Courier0007
2019-08-28T04:44:04.732Z,1566967444.732 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190827T223724/Courier0004.lzma
2019-08-28T04:44:06.739Z,1566967446.739 [DataOverHttps](INFO): Moved sent file to Logs/20190827T223724/Courier0004.lzma.bak
2019-08-28T04:44:06.739Z,1566967446.739 [DataOverHttps](INFO): SBD MOMSN=11704812
2019-08-28T04:44:07.746Z,1566967447.746 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Courier0007
2019-08-28T04:44:08.948Z,1566967448.948 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Courier0007 (1/1)
2019-08-28T04:44:08.953Z,1566967448.953 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Express0008
2019-08-28T04:44:10.140Z,1566967450.140 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Express0008 (1/1)
2019-08-28T04:44:26.469Z,1566967466.469 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20190828T043721/Express0005.lzma
2019-08-28T04:44:28.474Z,1566967468.474 [DataOverHttps](INFO): Moved sent file to Logs/20190828T043721/Express0005.lzma.bak
2019-08-28T04:44:28.475Z,1566967468.475 [DataOverHttps](INFO): SBD MOMSN=11706622
2019-08-28T04:44:29.482Z,1566967469.482 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Courier0007
2019-08-28T04:44:30.738Z,1566967470.738 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Courier0007 (1/1)
2019-08-28T04:44:30.743Z,1566967470.743 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Express0008
2019-08-28T04:44:32.136Z,1566967472.136 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Express0008 (1/1)
2019-08-28T04:44:32.142Z,1566967472.142 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Courier0007
2019-08-28T04:44:32.320Z,1566967472.320 [Default:CheckIn:Read_Iridium] Stopped
2019-08-28T04:44:32.320Z,1566967472.320 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-28T04:44:32.320Z,1566967472.320 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-28T04:44:33.352Z,1566967473.352 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Courier0007 (1/1)
2019-08-28T04:44:33.357Z,1566967473.357 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Express0008
2019-08-28T04:44:34.566Z,1566967474.566 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Express0008 (1/1)
2019-08-28T04:44:52.445Z,1566967492.445 [CommandLine](INFO): End of History
2019-08-28T04:44:52.488Z,1566967492.488 [CommandLine](INFO): End of History
2019-08-28T04:44:52.540Z,1566967492.540 [CommandLine](INFO): End of History
2019-08-28T04:44:52.596Z,1566967492.596 [CommandLine](INFO): End of History
2019-08-28T04:44:52.647Z,1566967492.647 [CommandLine](INFO): End of History
2019-08-28T04:45:01.398Z,1566967501.398 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-08-28T04:45:01.401Z,1566967501.401 [BPC1](INFO): Received data from all battery sticks.
2019-08-28T04:47:35.735Z,1566967655.735 [CommandLine](IMPORTANT): got command run ./Missions/Insert/Science.xml
2019-08-28T04:47:35.735Z,1566967655.735 [MissionManager](INFO): Loading Mission: ./Missions/Insert/Science.xml
2019-08-28T04:47:36.245Z,1566967656.245 [MissionManager](INFO): DefineArg Science.SampleISUS = 0 bool
2019-08-28T04:47:36.248Z,1566967656.248 [MissionManager](INFO): DefineArg Science.SampleISUSmaxPitch = 60.000000 arcdeg
2019-08-28T04:47:36.252Z,1566967656.252 [MissionManager](INFO): DefineArg Science.SampleISUSminPitch = -60.000000 arcdeg
2019-08-28T04:47:36.255Z,1566967656.255 [MissionManager](INFO): DefineArg Science.PeakDetectChlActive = 0 bool
2019-08-28T04:47:36.258Z,1566967656.258 [MissionManager](INFO): DefineArg Science.TimeWindowPeakReport = nan min
2019-08-28T04:47:36.260Z,1566967656.260 [MissionManager](INFO): DefineArg Science.HighestChlPeakReportActive = 0 bool
2019-08-28T04:47:36.268Z,1566967656.268 [MissionManager](INFO): DefineArg Science.PatchTracking = 0 bool
2019-08-28T04:47:36.271Z,1566967656.271 [MissionManager](INFO): DefineArg Science.FilterWidthHorizontal = 3.000000 count
2019-08-28T04:47:36.299Z,1566967656.299 [MissionManager](INFO): DefineArg Science.NumProfilesSlidingwindow = 100.000000 count
2019-08-28T04:47:36.315Z,1566967656.315 [MissionManager](INFO): DefineArg Science.OffPeakFractionHorizontal = 80.000000 %
2019-08-28T04:47:36.319Z,1566967656.319 [MissionManager](INFO): DefineArg Science.PeakDetectNO3Active = 0 bool
2019-08-28T04:47:36.344Z,1566967656.344 [MissionManager](INFO): DefineArg Science.PeakDetectOilActive = 0 bool
2019-08-28T04:47:36.351Z,1566967656.351 [MissionManager](INFO): DefineArg Science.PeakDetectFDOMActive = 0 bool
2019-08-28T04:47:36.354Z,1566967656.354 [MissionManager](INFO): DefineArg Science.UpwardDerivativeOfTemperatureActive = 0 bool
2019-08-28T04:47:36.379Z,1566967656.379 [MissionManager](INFO): DefineArg Science.EnabledAanderaaO2 = 0.000000 bool
2019-08-28T04:47:36.387Z,1566967656.387 [MissionManager](INFO): DefineArg Science.EnabledNeilBrown = 0.000000 bool
2019-08-28T04:47:36.391Z,1566967656.391 [MissionManager](INFO): DefineArg Science.EnabledSeabird = 1.000000 bool
2019-08-28T04:47:36.421Z,1566967656.421 [MissionManager](INFO): DefineArg Science.EnabledTurbulence_NPS = 0.000000 bool
2019-08-28T04:47:36.424Z,1566967656.424 [MissionManager](INFO): DefineArg Science.EnabledWetLabsBB2FL = 1.000000 bool
2019-08-28T04:47:36.428Z,1566967656.428 [MissionManager](INFO): DefineArg Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2019-08-28T04:47:36.444Z,1566967656.444 [MissionManager](INFO): DefineArg Science.EnabledWetLabsUBAT = 0.000000 bool
2019-08-28T04:47:36.448Z,1566967656.448 [MissionManager](INFO): DefineArg Science.EnabledISUS = 0.000000 bool
2019-08-28T04:47:36.451Z,1566967656.451 [MissionManager](INFO): DefineArg Science.LowPassWindowLength = 20.000000 count
2019-08-28T04:47:36.525Z,1566967656.525 [MissionManager](INFO): DefineArg Science.MedianFilterLen = 5.000000 count
2019-08-28T04:47:36.535Z,1566967656.535 [MissionManager](INFO): DefineArg Science.PeakChlShallowBound = nan m
2019-08-28T04:47:36.538Z,1566967656.538 [MissionManager](INFO): DefineArg Science.PeakChlDeepBound = nan m
2019-08-28T04:47:36.565Z,1566967656.565 [MissionManager](INFO): DefineArg Science.PeakShallowBound = nan m
2019-08-28T04:47:36.568Z,1566967656.568 [MissionManager](INFO): DefineArg Science.PeakDeepBound = nan m
2019-08-28T04:47:36.571Z,1566967656.571 [MissionManager](INFO): DefineOutput Science.PeakChl = 0.000000 ug/l
2019-08-28T04:47:36.614Z,1566967656.614 [MissionManager](INFO): DefineOutput Science.PeakChlDepth = 0.000000 m
2019-08-28T04:47:36.617Z,1566967656.617 [MissionManager](INFO): DefineOutput Science.PeakChlTemperature = 0.000000 degC
2019-08-28T04:47:36.620Z,1566967656.620 [MissionManager](INFO): DefineOutput Science.PeakChlLatitude = nan arcdeg
2019-08-28T04:47:36.623Z,1566967656.623 [MissionManager](INFO): DefineOutput Science.PeakChlLongitude = nan arcdeg
2019-08-28T04:47:36.635Z,1566967656.635 [MissionManager](INFO): DefineOutput Science.PeakNO3 = 0.000000 umol/l
2019-08-28T04:47:36.645Z,1566967656.645 [MissionManager](INFO): DefineOutput Science.PeakNO3Depth = 0.000000 m
2019-08-28T04:47:36.648Z,1566967656.648 [MissionManager](INFO): DefineOutput Science.PeakNO3Latitude = nan arcdeg
2019-08-28T04:47:36.674Z,1566967656.674 [MissionManager](INFO): DefineOutput Science.PeakNO3Longitude = nan arcdeg
2019-08-28T04:47:36.688Z,1566967656.688 [MissionManager](INFO): DefineOutput Science.PatchChl = 0.000000 m
2019-08-28T04:47:36.705Z,1566967656.705 [MissionManager](INFO): DefineOutput Science.PatchChlDepth = 0.000000 m
2019-08-28T04:47:36.708Z,1566967656.708 [MissionManager](INFO): DefineOutput Science.PatchChlLatitude = nan arcdeg
2019-08-28T04:47:36.711Z,1566967656.711 [MissionManager](INFO): DefineOutput Science.PatchChlLongitude = nan arcdeg
2019-08-28T04:47:36.744Z,1566967656.744 [MissionManager](INFO): DefineOutput Science.PatchChlDistance = nan m
2019-08-28T04:47:36.748Z,1566967656.748 [MissionManager](INFO): DefineOutput Science.PeakOil = 0.000000 kg/m3
2019-08-28T04:47:36.771Z,1566967656.771 [MissionManager](INFO): DefineOutput Science.PeakOilDepth = 0.000000 m
2019-08-28T04:47:36.775Z,1566967656.775 [MissionManager](INFO): DefineOutput Science.PeakOilLatitude = nan arcdeg
2019-08-28T04:47:36.778Z,1566967656.778 [MissionManager](INFO): DefineOutput Science.PeakOilLongitude = nan arcdeg
2019-08-28T04:47:36.781Z,1566967656.781 [MissionManager](INFO): DefineOutput Science.PeakFDOM = 0.000000 ppb
2019-08-28T04:47:36.784Z,1566967656.784 [MissionManager](INFO): DefineOutput Science.PeakFDOMDepth = 0.000000 m
2019-08-28T04:47:36.815Z,1566967656.815 [MissionManager](INFO): DefineOutput Science.PeakFDOMLatitude = nan arcdeg
2019-08-28T04:47:36.818Z,1566967656.818 [MissionManager](INFO): DefineOutput Science.PeakFDOMLongitude = nan arcdeg
2019-08-28T04:47:36.846Z,1566967656.846 [MissionManager](INFO): DefineOutput Science.PatchFDOMDistance = nan m
2019-08-28T04:47:36.876Z,1566967656.876 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-08-28T04:47:36.956Z,1566967656.956 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2019-08-28T04:47:36.987Z,1566967656.987 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-08-28T04:47:37.018Z,1566967657.018 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-08-28T04:47:37.036Z,1566967657.036 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-08-28T04:47:37.126Z,1566967657.126 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Aggregate xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
           xmlns="Tethys"
           xmlns:Units="Tethys/Units"
           xmlns:Science="Tethys/Science"
           xmlns:Universal="Tethys/Universal"
           xmlns:Trigger="Tethys/Trigger"
           xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                               Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                               Tethys/Science http://okeanids.mbari.org/tethys/Xml/Science.xsd
                               Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd
                               Tethys/Trigger http://okeanids.mbari.org/tethys/Xml/Trigger.xsd"
       Id="Science">

    <DefineArg Name="SampleISUS"><Description>
        Set either this or PeakDetectNO3Active to True in order to sample
        Nitrate. EnabledISUS must also be true.
    </Description><False/></DefineArg>

    <DefineArg Name="SampleISUSmaxPitch"><Description>
        Maximum pitch to sample ISUS. (EnabledISUS must also be true.)
    </Description><Units:degree/><Value>60.0</Value></DefineArg>

    <DefineArg Name="SampleISUSminPitch"><Description>
        Minimum pitch to sample ISUS. (EnabledISUS must also be true.)
    </Description><Units:degree/><Value>-60.0</Value></DefineArg>

    <DefineArg Name="PeakDetectChlActive"><Description>
        Turns on peak detection of Cholorphyll.
    </Description><False/></DefineArg>

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

    <DefineArg Name="HighestChlPeakReportActive"><Description>
        Turns on reporting of the highest peak value on yo-yo profiles in a
        horizontal sliding window (of length numProfilesSlidingwindow)
    </Description><False/></DefineArg>

    <DefineArg Name="PatchTracking"><Description>
        If tracking a patch
    </Description><False/></DefineArg>

<!--debug
    </Description><True/></DefineArg>
-->

    <DefineArg Name="FilterWidthHorizontal"><Description>
        Width of boxcar filter applied to yoyo-wise chl peaks to pick out the
        highest peak.
    </Description><Units:count/><Value>3</Value></DefineArg>

    <DefineArg Name="NumProfilesSlidingwindow"><Description>
        Length of horizontal sliding window. The highest yoyo-wise chl peak
        (after low-pass filtering by a filter of length FilterWidthHorizontal)
        within this window.
    </Description><Units:count/><Value>100</Value></DefineArg>

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

    <DefineArg Name="PeakDetectNO3Active"><Description>
        Turns on peak detection of Nitrate (and turns on ISUS).
    </Description><False/></DefineArg>

    <DefineArg Name="PeakDetectOilActive"><Description>
        Turns on peak detection of Dissolved Oil.
    </Description><False/></DefineArg>

    <DefineArg Name="PeakDetectFDOMActive"><Description>
        Turns on peak detection of concentration_of_chromophoric_dissolved_organic_matter_in_sea_water from FDOM sensor.
    </Description><False/></DefineArg>
    
    <DefineArg Name="UpwardDerivativeOfTemperatureActive"><Description>
        Turns on seawater temperature derivative.
    </Description><False/></DefineArg>

    <DefineArg Name="EnabledAanderaaO2"><Description>
        Automatically set to True if the Aanderaa O2 sensor is installed. Set to
        false to disable reading Aandera O2.
    </Description><Science:Aanderaa_O2.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledNeilBrown"><Description>
        Automatically set to true if the Neil Brown CTD is enabled. Set to false
        to disable reading from the Neil Brown.
    </Description><Science:CTD_NeilBrown.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledSeabird"><Description>
        Automatically set to true if the CTD is enabled. Set to false to disable
        reading from the CTD.
    </Description><Science:CTD_Seabird.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledTurbulence_NPS"><Description>
        Automatically set to true if the NPS Turbulence sensor is enabled. Set
        to false to disable reading from the NPS Turbulence sensor.
    </Description><Science:Turbulence_NPS.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledWetLabsBB2FL"><Description>
        Automatically set to true if the the WetLabs BB2FL is enabled. Set to
        false to disable the WetLabs BB2FL.
    </Description><Science:WetLabsBB2FL.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledWetLabsSeaOWL_UV_A"><Description>
        Automatically set to true if the the WetLabs SeaOWL UV-A is enabled. Set
        to false to disable the WetLabs SeaOWL UV-A.
    </Description><Science:WetLabsSeaOWL_UV_A.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledWetLabsUBAT"><Description>
        Automatically set to true if the the WetLabs UBAT is enabled. Set
        to false to disable the WetLabs UBAT.
    </Description><Science:WetLabsUBAT.loadAtStartup/></DefineArg>    

    <DefineArg Name="EnabledISUS"><Description>
        Automatically set to True if ISUS is enabled. Set to false to disable
        ISUS.
    </Description><Science:ISUS.loadAtStartup/></DefineArg>

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

    <DefineArg Name="MedianFilterLen"><Description>
        Median filter length (only for chlorophyll fluorescence which tends to have spikes)
    </Description><Units:count/><Value>5</Value></DefineArg>

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

<!--debug
    </Description><Units:meter/><Value>10</Value></DefineArg>
-->

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

<!--debug
    </Description><Units:meter/><Value>20</Value></DefineArg>
-->

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

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

    <DefineOutput Name="PeakChl"><Units:microgram_per_liter/><Value>0</Value></DefineOutput>

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

    <DefineOutput Name="PeakChlTemperature"><Units:celsius/><Value>0</Value></DefineOutput>

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

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

    <DefineOutput Name="PeakNO3"><Units:micromole_per_liter/><Value>0</Value></DefineOutput>

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

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

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

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

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

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

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

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

    <DefineOutput Name="PeakOil"><Units:kilogram_per_cubic_meter/><Value>0</Value></DefineOutput>

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

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

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

    <DefineOutput Name="PeakFDOM"><Units:part_per_billion/><Value>0</Value></DefineOutput>

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

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

    <DefineOutput Name="PeakFDOMLongitude"><Units:degree/><Value>NaN</Value></DefineOutput>
    
    <DefineOutput Name="PatchFDOMDistance"><Units:meter/><Value>NaN</Value></DefineOutput>    
    
    <Parallel/>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledAanderaaO2"/>
        </While>
        <Universal:mass_concentration_of_oxygen_in_sea_water/>
    </ReadData>

    <ReadData>
        <While>
            <Arg Name="EnabledISUS"/>
            <And><Universal:platform_pitch_angle/><Lt><Arg Name="SampleISUSmaxPitch"/></Lt><And><Universal:platform_pitch_angle/><Gt><Arg Name="SampleISUSminPitch"/></Gt><And><Arg Name="SampleISUS"/><Or><Arg Name="PeakDetectNO3Active"/></Or></And></And></And>
        </While>
        <Universal:mole_concentration_of_nitrate_in_sea_water/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledNeilBrown"/>
            <Or><Arg Name="EnabledSeabird"/></Or>
        </While>
        <Universal:sea_water_temperature/>
        <Universal:sea_water_salinity/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledSeabird"/>
        </While>
        <Science:CTD_Seabird.sea_water_temperature/>
        <Science:CTD_Seabird.sea_water_salinity/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledNeilBrown"/>
            <Or><Arg Name="EnabledSeabird"/></Or>
            <And><Arg Name="UpwardDerivativeOfTemperatureActive"/></And>
        </While>
        <Universal:upward_derivative_of_sea_water_temperature/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledTurbulence_NPS"/>
        </While>
        <Science:Turbulence_NPS.MicroCond/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledWetLabsBB2FL"/>
            <Or><Arg Name="PeakDetectChlActive"/></Or>
        </While>
        <Universal:mass_concentration_of_chlorophyll_in_sea_water/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledWetLabsUBAT"/>
        </While>
        <Science:WetLabsUBAT.biolum_potential/>
    </ReadData>

<!--debug-->

    <ReadData Id="Read_Oil" Strategy="MinError">
        <While>
            <Arg Name="EnabledWetLabsSeaOWL_UV_A"/>
            <Or><Arg Name="PeakDetectOilActive"/></Or>
        </While>
        <Universal:mass_concentration_of_chlorophyll_in_sea_water/>
    </ReadData>

    <Aggregate Id="PeakDetectChl">

        <While>
            <Arg Name="PeakDetectChlActive"/>
        </While>

        <Trigger:PeakDetectVsDepth>
            <Sequence/>
            <Setting><Trigger:PeakDetectVsDepth.detect/><Universal:mass_concentration_of_chlorophyll_in_sea_water/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.timeWindowPeakReport/><Arg Name="TimeWindowPeakReport"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.windowLength/><Arg Name="LowPassWindowLength"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.medianFilterLength/><Arg Name="MedianFilterLen"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.shallowBound/><Arg Name="PeakChlShallowBound"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.deepBound/><Arg Name="PeakChlDeepBound"/></Setting>
            <OutputArg Name="PeakChl"><Trigger:PeakDetectVsDepth.peakDetect/></OutputArg>
            <OutputArg Name="PeakChlDepth"><Trigger:PeakDetectVsDepth.peakDepth/></OutputArg>
            <OutputArg Name="PeakChlTemperature"><Trigger:PeakDetectVsDepth.peakTemperature/></OutputArg>
            <OutputArg Name="PeakChlLatitude"><Trigger:PeakDetectVsDepth.peakLatitude/></OutputArg>
            <OutputArg Name="PeakChlLongitude"><Trigger:PeakDetectVsDepth.peakLongitude/></OutputArg>
        </Trigger:PeakDetectVsDepth>

<!--debug
        <Syslog Severity="Important">PeakChl, PeakChlLatitude, PeakChlLongitude = <Arg Name="PeakChl"/><Units:microgram_per_liter/>, <Arg Name="PeakChlLatitude"/><Units:degree/>, <Arg Name="PeakChlLongitude"/><Units:degree/></Syslog>
-->

        <SendData Service="Express"><Arg Name="PeakChl"/><Arg Name="PeakChlDepth"/>
        </SendData>

    </Aggregate>

    <Aggregate Id="HighestChlPeakReport">

        <While>
            <Arg Name="HighestChlPeakReportActive"/>
        </While>

        <Trigger:PeakDetectHorizontal>
            <Sequence/>
            <Setting><Trigger:PeakDetectHorizontal.detectFrom/><Arg Name="PeakChl"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.depthFrom/><Arg Name="PeakChlDepth"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.latitudeFrom/><Arg Name="PeakChlLatitude"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.longitudeFrom/><Arg Name="PeakChlLongitude"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.patchTracking/><Arg Name="PatchTracking"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.filterWidth/><Arg Name="FilterWidthHorizontal"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.numProfilesSlidingwindow/><Arg Name="NumProfilesSlidingwindow"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.offPeakFraction/><Arg Name="OffPeakFractionHorizontal"/></Setting>
            <OutputArg Name="PatchChl"><Trigger:PeakDetectHorizontal.peakDetect/></OutputArg>
            <OutputArg Name="PatchChlDepth"><Trigger:PeakDetectHorizontal.peakDepth/></OutputArg>
            <OutputArg Name="PatchChlLatitude"><Trigger:PeakDetectHorizontal.peakLatitude/></OutputArg>
            <OutputArg Name="PatchChlLongitude"><Trigger:PeakDetectHorizontal.peakLongitude/></OutputArg>
            <OutputArg Name="PatchChlDistance"><Trigger:PeakDetectHorizontal.peakDistance/></OutputArg>
        </Trigger:PeakDetectHorizontal>

    </Aggregate>

    <Aggregate Id="PeakDetectNO3">

        <While>
            <Arg Name="PeakDetectNO3Active"/>
        </While>

        <Trigger:PeakDetectVsDepth>
            <Sequence/>
            <Setting><Trigger:PeakDetectVsDepth.detect/><Universal:mole_concentration_of_nitrate_in_sea_water/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.timeWindowPeakReport/><Arg Name="TimeWindowPeakReport"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.windowLength/><Arg Name="LowPassWindowLength"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.shallowBound/><Arg Name="PeakShallowBound"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.deepBound/><Arg Name="PeakDeepBound"/></Setting>
            <OutputArg Name="PeakNO3"><Trigger:PeakDetectVsDepth.peakDetect/></OutputArg>
            <OutputArg Name="PeakNO3Depth"><Trigger:PeakDetectVsDepth.peakDepth/></OutputArg>
            <OutputArg Name="PeakNO3Latitude"><Trigger:PeakDetectVsDepth.peakLatitude/></OutputArg>
            <OutputArg Name="PeakNO3Longitude"><Trigger:PeakDetectVsDepth.peakLongitude/></OutputArg>
        </Trigger:PeakDetectVsDepth>

        <SendData Service="Express"><Arg Name="PeakNO3"/><Arg Name="PeakNO3Depth"/>
        </SendData>

    </Aggregate>

    <Aggregate Id="PeakDetectOil">

        <While>
            <Arg Name="PeakDetectOilActive"/>
        </While>

        <Trigger:PeakDetectVsDepth>
            <Sequence/>
            <Setting><Trigger:PeakDetectVsDepth.detect/><Universal:mass_concentration_of_petroleum_hydrocarbons_in_sea_water/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.timeWindowPeakReport/><Arg Name="TimeWindowPeakReport"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.windowLength/><Arg Name="LowPassWindowLength"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.shallowBound/><Arg Name="PeakShallowBound"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.deepBound/><Arg Name="PeakDeepBound"/></Setting>
            <OutputArg Name="PeakOil"><Trigger:PeakDetectVsDepth.peakDetect/></OutputArg>
            <OutputArg Name="PeakOilDepth"><Trigger:PeakDetectVsDepth.peakDepth/></OutputArg>
            <OutputArg Name="PeakOilLatitude"><Trigger:PeakDetectVsDepth.peakLatitude/></OutputArg>
            <OutputArg Name="PeakOilLongitude"><Trigger:PeakDetectVsDepth.peakLongitude/></OutputArg>
        </Trigger:PeakDetectVsDepth>

        <SendData Service="Express"><Arg Name="PeakOil"/><Arg Name="PeakOilDepth"/>
        </SendData>

    </Aggregate>

    <Aggregate Id="PeakDetectFDOM">

        <While>
            <Arg Name="PeakDetectFDOMActive"/>
        </While>

        <Trigger:PeakDetectVsDepth>
            <Sequence/>
            <Setting><Trigger:PeakDetectVsDepth.detect/><Universal:concentration_of_chromophoric_dissolved_organic_matter_in_sea_water/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.timeWindowPeakReport/><Arg Name="TimeWindowPeakReport"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.windowLength/><Arg Name="LowPassWindowLength"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.shallowBound/><Arg Name="PeakShallowBound"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.deepBound/><Arg Name="PeakDeepBound"/></Setting>
            <OutputArg Name="PeakFDOM"><Trigger:PeakDetectVsDepth.peakDetect/></OutputArg>
            <OutputArg Name="PeakFDOMDepth"><Trigger:PeakDetectVsDepth.peakDepth/></OutputArg>
            <OutputArg Name="PeakFDOMTemperature"><Trigger:PeakDetectVsDepth.peakTemperature/></OutputArg>
            <OutputArg Name="PeakFDOMLatitude"><Trigger:PeakDetectVsDepth.peakLatitude/></OutputArg>
            <OutputArg Name="PeakFDOMLongitude"><Trigger:PeakDetectVsDepth.peakLongitude/></OutputArg>
        </Trigger:PeakDetectVsDepth>

        <SendData Service="Express"><Arg Name="PeakFDOM"/><Arg Name="PeakFDOMDepth"/>
        </SendData>

    </Aggregate>

</Aggregate>


2019-08-28T04:47:37.129Z,1566967657.129 [CommandLine](IMPORTANT): Running ./Missions/Insert/Science.xml
2019-08-28T04:47:37.332Z,1566967657.332 [Default] Stopped
2019-08-28T04:47:37.332Z,1566967657.332 [Default](DEBUG): Aggregate::uninitialize Default
2019-08-28T04:47:37.332Z,1566967657.332 [Default:B.GoToSurface] Stopped
2019-08-28T04:47:37.332Z,1566967657.332 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-08-28T04:47:37.332Z,1566967657.332 [Default:CheckIn] Stopped
2019-08-28T04:47:37.332Z,1566967657.332 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-28T04:47:37.332Z,1566967657.332 [Default:CheckIn:C.Wait] Stopped
2019-08-28T04:47:37.332Z,1566967657.332 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-28T04:47:37.332Z,1566967657.332 [MissionManager](IMPORTANT): Started mission Science
2019-08-28T04:47:37.333Z,1566967657.333 [Science] Running Loop=1
2019-08-28T04:47:37.334Z,1566967657.334 [Science](DEBUG): Aggregate::initialize Science
2019-08-28T04:47:37.334Z,1566967657.334 [Science:A] Running Loop=1
2019-08-28T04:47:37.334Z,1566967657.334 [Science:B] Running Loop=1
2019-08-28T04:47:37.334Z,1566967657.334 [Science:C] Running Loop=1
2019-08-28T04:47:37.334Z,1566967657.334 [Science:D] Running Loop=1
2019-08-28T04:47:37.334Z,1566967657.334 [Science:E] Running Loop=1
2019-08-28T04:47:37.334Z,1566967657.334 [Science:F] Running Loop=1
2019-08-28T04:47:37.334Z,1566967657.334 [Science:G] Running Loop=1
2019-08-28T04:47:37.334Z,1566967657.334 [Science:H] Running Loop=1
2019-08-28T04:47:37.334Z,1566967657.334 [Science:Read_Oil] Running Loop=1
2019-08-28T04:47:37.334Z,1566967657.334 [Science:PeakDetectChl] Running Loop=1
2019-08-28T04:47:37.334Z,1566967657.334 [Science:PeakDetectChl](DEBUG): Aggregate::initialize Science:PeakDetectChl
2019-08-28T04:47:37.335Z,1566967657.335 [Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2019-08-28T04:47:37.335Z,1566967657.335 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-08-28T04:47:37.336Z,1566967657.336 [Science:HighestChlPeakReport] Running Loop=1
2019-08-28T04:47:37.337Z,1566967657.337 [Science:HighestChlPeakReport](DEBUG): Aggregate::initialize Science:HighestChlPeakReport
2019-08-28T04:47:37.353Z,1566967657.353 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2019-08-28T04:47:37.353Z,1566967657.353 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2019-08-28T04:47:37.353Z,1566967657.353 [Science:PeakDetectNO3] Running Loop=1
2019-08-28T04:47:37.354Z,1566967657.354 [Science:PeakDetectNO3](DEBUG): Aggregate::initialize Science:PeakDetectNO3
2019-08-28T04:47:37.354Z,1566967657.354 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2019-08-28T04:47:37.354Z,1566967657.354 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-08-28T04:47:37.356Z,1566967657.356 [Science:PeakDetectOil] Running Loop=1
2019-08-28T04:47:37.356Z,1566967657.356 [Science:PeakDetectOil](DEBUG): Aggregate::initialize Science:PeakDetectOil
2019-08-28T04:47:37.356Z,1566967657.356 [Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2019-08-28T04:47:37.356Z,1566967657.356 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-08-28T04:47:37.362Z,1566967657.362 [Science:PeakDetectFDOM] Running Loop=1
2019-08-28T04:47:37.362Z,1566967657.362 [Science:PeakDetectFDOM](DEBUG): Aggregate::initialize Science:PeakDetectFDOM
2019-08-28T04:47:37.363Z,1566967657.363 [Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2019-08-28T04:47:37.363Z,1566967657.363 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-08-28T04:47:37.382Z,1566967657.382 [Science:PeakDetectFDOM] Stopped
2019-08-28T04:47:37.382Z,1566967657.382 [Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize Science:PeakDetectFDOM
2019-08-28T04:47:37.382Z,1566967657.382 [Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2019-08-28T04:47:37.382Z,1566967657.382 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-08-28T04:47:37.382Z,1566967657.382 [Science:PeakDetectOil] Stopped
2019-08-28T04:47:37.382Z,1566967657.382 [Science:PeakDetectOil](DEBUG): Aggregate::uninitialize Science:PeakDetectOil
2019-08-28T04:47:37.382Z,1566967657.382 [Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2019-08-28T04:47:37.382Z,1566967657.382 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-08-28T04:47:37.382Z,1566967657.382 [Science:PeakDetectNO3] Stopped
2019-08-28T04:47:37.382Z,1566967657.382 [Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize Science:PeakDetectNO3
2019-08-28T04:47:37.382Z,1566967657.382 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2019-08-28T04:47:37.383Z,1566967657.383 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-08-28T04:47:37.383Z,1566967657.383 [Science:HighestChlPeakReport] Stopped
2019-08-28T04:47:37.383Z,1566967657.383 [Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize Science:HighestChlPeakReport
2019-08-28T04:47:37.383Z,1566967657.383 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2019-08-28T04:47:37.383Z,1566967657.383 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2019-08-28T04:47:37.383Z,1566967657.383 [Science:PeakDetectChl] Stopped
2019-08-28T04:47:37.383Z,1566967657.383 [Science:PeakDetectChl](DEBUG): Aggregate::uninitialize Science:PeakDetectChl
2019-08-28T04:47:37.383Z,1566967657.383 [Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2019-08-28T04:47:37.383Z,1566967657.383 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-08-28T04:47:37.383Z,1566967657.383 [Science:Read_Oil] Stopped
2019-08-28T04:47:37.383Z,1566967657.383 [Science:H] Stopped
2019-08-28T04:47:37.383Z,1566967657.383 [Science:G] Running Loop=1
2019-08-28T04:47:37.384Z,1566967657.384 [Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2019-08-28T04:47:37.384Z,1566967657.384 [Science:F] Stopped
2019-08-28T04:47:37.384Z,1566967657.384 [Science:E] Stopped
2019-08-28T04:47:37.384Z,1566967657.384 [Science:D] Running Loop=1
2019-08-28T04:47:37.385Z,1566967657.385 [Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2019-08-28T04:47:37.386Z,1566967657.386 [Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2019-08-28T04:47:37.386Z,1566967657.386 [Science:C] Running Loop=1
2019-08-28T04:47:37.387Z,1566967657.387 [Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2019-08-28T04:47:37.387Z,1566967657.387 [Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2019-08-28T04:47:37.387Z,1566967657.387 [Science:B] Stopped
2019-08-28T04:47:37.387Z,1566967657.387 [Science:A] Stopped
2019-08-28T04:47:37.677Z,1566967657.677 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-08-28T04:47:37.677Z,1566967657.677 [WetLabsBB2FL](INFO): Powering up
2019-08-28T04:47:37.774Z,1566967657.774 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-08-28T04:47:40.786Z,1566967660.786 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2019-08-28T04:47:46.081Z,1566967666.081 [CommandLine](IMPORTANT): got command stop
2019-08-28T04:47:46.082Z,1566967666.082 [CommandLine](IMPORTANT): Scheduling is paused
2019-08-28T04:47:46.082Z,1566967666.082 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2019-08-28T04:47:46.221Z,1566967666.221 [MissionManager](INFO): MissionManager is completed.
2019-08-28T04:47:46.221Z,1566967666.221 [MissionManager](INFO): Uninitializing Mission Science
2019-08-28T04:47:46.222Z,1566967666.222 [Science] Stopped
2019-08-28T04:47:46.222Z,1566967666.222 [Science](DEBUG): Aggregate::uninitialize Science
2019-08-28T04:47:46.222Z,1566967666.222 [Science:C] Stopped
2019-08-28T04:47:46.222Z,1566967666.222 [Science:D] Stopped
2019-08-28T04:47:46.222Z,1566967666.222 [Science:G] Stopped
2019-08-28T04:47:46.333Z,1566967666.333 [WetLabsBB2FL](INFO): Powering down
2019-08-28T04:47:46.629Z,1566967666.629 [MissionManager](IMPORTANT): Started mission Default
2019-08-28T04:47:46.629Z,1566967666.629 [Default] Running Loop=1
2019-08-28T04:47:46.629Z,1566967666.629 [Default](DEBUG): Aggregate::initialize Default
2019-08-28T04:47:46.629Z,1566967666.629 [Default:B.GoToSurface] Running Loop=1
2019-08-28T04:47:46.629Z,1566967666.629 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-08-28T04:47:46.630Z,1566967666.630 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-08-28T04:47:46.630Z,1566967666.630 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-08-28T04:47:46.630Z,1566967666.630 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-08-28T04:47:46.631Z,1566967666.631 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-08-28T04:47:46.631Z,1566967666.631 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-08-28T04:47:46.631Z,1566967666.631 [Default:A.Wait] Running Loop=1
2019-08-28T04:47:46.631Z,1566967666.631 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-08-28T04:47:59.959Z,1566967679.959 [Default:A.Wait](INFO): Done Waiting.
2019-08-28T04:47:59.959Z,1566967679.959 [Default:A.Wait] Stopped
2019-08-28T04:47:59.959Z,1566967679.959 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-08-28T04:48:00.365Z,1566967680.365 [Default:CheckIn] Running Loop=1
2019-08-28T04:48:00.365Z,1566967680.365 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-28T04:48:00.365Z,1566967680.365 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-28T04:48:01.969Z,1566967681.969 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:48:02.357Z,1566967682.357 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,044708.00,A,3648.16365,N,12147.27962,W,0.214,239.96,280819,,,A*7E
2019-08-28T04:48:02.359Z,1566967682.359 [NAL9602](INFO): GPS fix at 20190828T044708: (36.802728, -121.787994)
2019-08-28T04:48:02.383Z,1566967682.383 [Default:CheckIn:Read_GPS] Stopped
2019-08-28T04:48:02.383Z,1566967682.383 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-28T04:48:02.841Z,1566967682.841 [DataOverHttps](INFO): GetToShoreFilename: active shore file is 
2019-08-28T04:48:04.676Z,1566967684.676 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Courier0010 (1/1)
2019-08-28T04:48:11.297Z,1566967691.297 [DataOverHttps](INFO): Sending 221 bytes from file Logs/20190828T043721/Courier0007.lzma
2019-08-28T04:48:13.302Z,1566967693.302 [DataOverHttps](INFO): Moved sent file to Logs/20190828T043721/Courier0007.lzma.bak
2019-08-28T04:48:13.302Z,1566967693.302 [DataOverHttps](INFO): SBD MOMSN=11706634
2019-08-28T04:48:14.309Z,1566967694.309 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Courier0010
2019-08-28T04:48:15.487Z,1566967695.487 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Courier0010 (1/1)
2019-08-28T04:48:15.491Z,1566967695.491 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Express0011
2019-08-28T04:48:16.745Z,1566967696.745 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Express0011 (1/1)
2019-08-28T04:48:27.107Z,1566967707.107 [NAL9602](INFO): SBD MO Status=2, MOMSN=180, MT Status=2, MTMSN=0
2019-08-28T04:48:27.108Z,1566967707.108 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-08-28T04:48:33.293Z,1566967713.293 [DataOverHttps](INFO): Sending 373 bytes from file Logs/20190828T043721/Express0008.lzma
2019-08-28T04:48:35.298Z,1566967715.298 [DataOverHttps](INFO): Moved sent file to Logs/20190828T043721/Express0008.lzma.bak
2019-08-28T04:48:35.299Z,1566967715.299 [DataOverHttps](INFO): SBD MOMSN=11706638
2019-08-28T04:48:36.305Z,1566967716.305 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Courier0010
2019-08-28T04:48:37.555Z,1566967717.555 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Courier0010 (1/1)
2019-08-28T04:48:37.559Z,1566967717.559 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Express0011
2019-08-28T04:48:38.783Z,1566967718.783 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Express0011 (1/1)
2019-08-28T04:48:38.789Z,1566967718.789 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Courier0010
2019-08-28T04:48:38.840Z,1566967718.840 [Default:CheckIn:Read_Iridium] Stopped
2019-08-28T04:48:38.840Z,1566967718.840 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-28T04:48:38.840Z,1566967718.840 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-28T04:48:40.130Z,1566967720.130 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Courier0010 (1/1)
2019-08-28T04:48:40.139Z,1566967720.139 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Express0011
2019-08-28T04:48:41.354Z,1566967721.354 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Express0011 (1/1)
2019-08-28T04:48:44.475Z,1566967724.475 [NAL9602](INFO): SBD MO Status=0, MOMSN=180, MT Status=0, MTMSN=0
2019-08-28T04:48:44.475Z,1566967724.475 [NAL9602](INFO): No messages in MT queue
2019-08-28T04:49:15.186Z,1566967755.186 [NAL9602](INFO): Not Powering down - fast GPS
2019-08-28T04:53:39.424Z,1566968019.424 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-08-28T04:53:39.424Z,1566968019.424 [Default:CheckIn:C.Wait] Stopped
2019-08-28T04:53:39.424Z,1566968019.424 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-28T04:53:39.425Z,1566968019.425 [Default:CheckIn:D] Running Loop=1
2019-08-28T04:53:39.830Z,1566968019.830 [Default:CheckIn:D] Stopped
2019-08-28T04:53:39.830Z,1566968019.830 [Default:CheckIn:E] Running Loop=1
2019-08-28T04:53:40.239Z,1566968020.239 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.886677 min 
2019-08-28T04:53:40.239Z,1566968020.239 [Default:CheckIn:E] Stopped
2019-08-28T04:53:40.239Z,1566968020.239 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-08-28T04:53:40.240Z,1566968020.240 [Default:CheckIn] Stopped
2019-08-28T04:53:40.240Z,1566968020.240 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-28T04:53:40.240Z,1566968020.240 [Default:CheckIn](INFO): Running loop #2
2019-08-28T04:53:40.240Z,1566968020.240 [Default:CheckIn] Running Loop=2
2019-08-28T04:53:40.240Z,1566968020.240 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-28T04:53:40.240Z,1566968020.240 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-28T04:53:41.837Z,1566968021.837 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:53:42.249Z,1566968022.249 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,045249.00,A,3648.16204,N,12147.28094,W,0.117,46.82,280819,,,A*49
2019-08-28T04:53:42.251Z,1566968022.251 [NAL9602](INFO): GPS fix at 20190828T045249: (36.802701, -121.788016)
2019-08-28T04:53:42.280Z,1566968022.280 [Default:CheckIn:Read_GPS] Stopped
2019-08-28T04:53:42.280Z,1566968022.280 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-28T04:53:42.681Z,1566968022.681 [DataOverHttps](INFO): GetToShoreFilename: active shore file is 
2019-08-28T04:53:44.261Z,1566968024.261 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Courier0013 (1/1)
2019-08-28T04:53:50.876Z,1566968030.876 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190828T043721/Courier0010.lzma
2019-08-28T04:53:52.882Z,1566968032.882 [DataOverHttps](INFO): Moved sent file to Logs/20190828T043721/Courier0010.lzma.bak
2019-08-28T04:53:52.883Z,1566968032.883 [DataOverHttps](INFO): SBD MOMSN=11706663
2019-08-28T04:53:53.889Z,1566968033.889 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Courier0013
2019-08-28T04:53:55.069Z,1566968035.069 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Courier0013 (1/1)
2019-08-28T04:53:55.075Z,1566968035.075 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Express0014
2019-08-28T04:53:56.291Z,1566968036.291 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Express0014 (1/1)
2019-08-28T04:54:12.680Z,1566968052.680 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20190828T043721/Express0011.lzma
2019-08-28T04:54:14.686Z,1566968054.686 [DataOverHttps](INFO): Moved sent file to Logs/20190828T043721/Express0011.lzma.bak
2019-08-28T04:54:14.687Z,1566968054.687 [DataOverHttps](INFO): SBD MOMSN=11706666
2019-08-28T04:54:15.693Z,1566968055.693 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Courier0013
2019-08-28T04:54:17.614Z,1566968057.614 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Courier0013 (1/1)
2019-08-28T04:54:17.619Z,1566968057.619 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Express0014
2019-08-28T04:54:18.828Z,1566968058.828 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Express0014 (1/1)
2019-08-28T04:54:18.834Z,1566968058.834 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Courier0013
2019-08-28T04:54:19.116Z,1566968059.116 [Default:CheckIn:Read_Iridium] Stopped
2019-08-28T04:54:19.116Z,1566968059.116 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-28T04:54:19.116Z,1566968059.116 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-28T04:54:20.067Z,1566968060.067 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Courier0013 (1/1)
2019-08-28T04:54:20.072Z,1566968060.072 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Express0014
2019-08-28T04:54:21.376Z,1566968061.376 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Express0014 (1/1)
2019-08-28T04:56:39.679Z,1566968199.679 [NAL9602](INFO): SBD MO Status=2, MOMSN=181, MT Status=2, MTMSN=0
2019-08-28T04:56:39.679Z,1566968199.679 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-08-28T04:58:04.120Z,1566968284.120 [NAL9602](INFO): SBD MO Status=2, MOMSN=181, MT Status=2, MTMSN=0
2019-08-28T04:58:04.120Z,1566968284.120 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-08-28T04:58:36.043Z,1566968316.043 [NAL9602](INFO): SBD MO Status=2, MOMSN=181, MT Status=2, MTMSN=0
2019-08-28T04:58:36.043Z,1566968316.043 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-08-28T04:58:48.560Z,1566968328.560 [NAL9602](INFO): SBD MO Status=0, MOMSN=181, MT Status=0, MTMSN=0
2019-08-28T04:58:48.561Z,1566968328.561 [NAL9602](INFO): No messages in MT queue
2019-08-28T04:59:19.266Z,1566968359.266 [NAL9602](INFO): Not Powering down - fast GPS
2019-08-28T04:59:19.696Z,1566968359.696 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-08-28T04:59:19.696Z,1566968359.696 [Default:CheckIn:C.Wait] Stopped
2019-08-28T04:59:19.696Z,1566968359.696 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-28T04:59:19.696Z,1566968359.696 [Default:CheckIn:D] Running Loop=1
2019-08-28T04:59:20.108Z,1566968360.108 [Default:CheckIn:D] Stopped
2019-08-28T04:59:20.108Z,1566968360.108 [Default:CheckIn:E] Running Loop=1
2019-08-28T04:59:20.516Z,1566968360.516 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.557981 min 
2019-08-28T04:59:20.516Z,1566968360.516 [Default:CheckIn:E] Stopped
2019-08-28T04:59:20.516Z,1566968360.516 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-08-28T04:59:20.516Z,1566968360.516 [Default:CheckIn] Stopped
2019-08-28T04:59:20.516Z,1566968360.516 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-28T04:59:20.517Z,1566968360.517 [Default:CheckIn](INFO): Running loop #3
2019-08-28T04:59:20.517Z,1566968360.517 [Default:CheckIn] Running Loop=3
2019-08-28T04:59:20.517Z,1566968360.517 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-28T04:59:20.517Z,1566968360.517 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-28T04:59:22.105Z,1566968362.105 [NAL9602](DEBUG): Fix Requested
2019-08-28T04:59:22.504Z,1566968362.504 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,045829.00,A,3648.16240,N,12147.28208,W,0.117,46.82,280819,,,A*42
2019-08-28T04:59:22.506Z,1566968362.506 [NAL9602](INFO): GPS fix at 20190828T045829: (36.802707, -121.788035)
2019-08-28T04:59:22.529Z,1566968362.529 [Default:CheckIn:Read_GPS] Stopped
2019-08-28T04:59:22.529Z,1566968362.529 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-28T04:59:23.469Z,1566968363.469 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Courier0016
2019-08-28T04:59:24.866Z,1566968364.866 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Courier0016 (1/1)
2019-08-28T04:59:31.472Z,1566968371.472 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190828T043721/Courier0013.lzma
2019-08-28T04:59:33.478Z,1566968373.478 [DataOverHttps](INFO): Moved sent file to Logs/20190828T043721/Courier0013.lzma.bak
2019-08-28T04:59:33.479Z,1566968373.479 [DataOverHttps](INFO): SBD MOMSN=11706683
2019-08-28T04:59:34.485Z,1566968374.485 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Courier0016
2019-08-28T04:59:35.654Z,1566968375.654 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Courier0016 (1/1)
2019-08-28T04:59:35.658Z,1566968375.658 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Express0017
2019-08-28T04:59:36.886Z,1566968376.886 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Express0017 (1/1)
2019-08-28T04:59:48.359Z,1566968388.359 [NAL9602](INFO): SBD MO Status=0, MOMSN=182, MT Status=0, MTMSN=0
2019-08-28T04:59:48.359Z,1566968388.359 [NAL9602](INFO): No messages in MT queue
2019-08-28T05:00:02.968Z,1566968402.968 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20190828T043721/Express0014.lzma
2019-08-28T05:00:04.975Z,1566968404.975 [DataOverHttps](INFO): Moved sent file to Logs/20190828T043721/Express0014.lzma.bak
2019-08-28T05:00:04.975Z,1566968404.975 [DataOverHttps](INFO): SBD MOMSN=11706692
2019-08-28T05:00:05.981Z,1566968405.981 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Courier0016
2019-08-28T05:00:07.159Z,1566968407.159 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Courier0016 (1/1)
2019-08-28T05:00:07.164Z,1566968407.164 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Express0017
2019-08-28T05:00:08.430Z,1566968408.430 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Express0017 (1/1)
2019-08-28T05:00:08.436Z,1566968408.436 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Courier0016
2019-08-28T05:00:08.601Z,1566968408.601 [Default:CheckIn:Read_Iridium] Stopped
2019-08-28T05:00:08.601Z,1566968408.601 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-28T05:00:08.601Z,1566968408.601 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-28T05:00:09.635Z,1566968409.635 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Courier0016 (1/1)
2019-08-28T05:00:09.640Z,1566968409.640 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Express0017
2019-08-28T05:00:10.846Z,1566968410.846 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Express0017 (1/1)
2019-08-28T05:00:19.059Z,1566968419.059 [NAL9602](INFO): Not Powering down - fast GPS
2019-08-28T05:05:09.160Z,1566968709.160 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-08-28T05:05:09.160Z,1566968709.160 [Default:CheckIn:C.Wait] Stopped
2019-08-28T05:05:09.160Z,1566968709.160 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-28T05:05:09.160Z,1566968709.160 [Default:CheckIn:D] Running Loop=1
2019-08-28T05:05:09.564Z,1566968709.564 [Default:CheckIn:D] Stopped
2019-08-28T05:05:09.564Z,1566968709.564 [Default:CheckIn:E] Running Loop=1
2019-08-28T05:05:09.960Z,1566968709.960 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.382245 min 
2019-08-28T05:05:09.960Z,1566968709.960 [Default:CheckIn:E] Stopped
2019-08-28T05:05:09.960Z,1566968709.960 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-08-28T05:05:09.960Z,1566968709.960 [Default:CheckIn] Stopped
2019-08-28T05:05:10.005Z,1566968710.005 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-28T05:05:10.005Z,1566968710.005 [Default:CheckIn](INFO): Running loop #4
2019-08-28T05:05:10.005Z,1566968710.005 [Default:CheckIn] Running Loop=4
2019-08-28T05:05:10.005Z,1566968710.005 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-28T05:05:10.005Z,1566968710.005 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-28T05:05:11.565Z,1566968711.565 [NAL9602](DEBUG): Fix Requested
2019-08-28T05:05:11.968Z,1566968711.968 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,050419.00,A,3648.16318,N,12147.28219,W,0.836,0.00,280819,,,A*77
2019-08-28T05:05:11.970Z,1566968711.970 [NAL9602](INFO): GPS fix at 20190828T050419: (36.802720, -121.788037)
2019-08-28T05:05:11.992Z,1566968711.992 [Default:CheckIn:Read_GPS] Stopped
2019-08-28T05:05:11.992Z,1566968711.992 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-28T05:05:13.121Z,1566968713.121 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Courier0019
2019-08-28T05:05:14.795Z,1566968714.795 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Courier0019 (1/1)
2019-08-28T05:05:21.360Z,1566968721.360 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190828T043721/Courier0016.lzma
2019-08-28T05:05:23.366Z,1566968723.366 [DataOverHttps](INFO): Moved sent file to Logs/20190828T043721/Courier0016.lzma.bak
2019-08-28T05:05:23.366Z,1566968723.366 [DataOverHttps](INFO): SBD MOMSN=11706750
2019-08-28T05:05:24.373Z,1566968724.373 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Courier0019
2019-08-28T05:05:25.543Z,1566968725.543 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Courier0019 (1/1)
2019-08-28T05:05:25.548Z,1566968725.548 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Express0020
2019-08-28T05:05:26.745Z,1566968726.745 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Express0020 (1/1)
2019-08-28T05:05:43.124Z,1566968743.124 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20190828T043721/Express0017.lzma
2019-08-28T05:05:45.131Z,1566968745.131 [DataOverHttps](INFO): Moved sent file to Logs/20190828T043721/Express0017.lzma.bak
2019-08-28T05:05:45.131Z,1566968745.131 [DataOverHttps](INFO): SBD MOMSN=11706753
2019-08-28T05:05:46.137Z,1566968746.137 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Courier0019
2019-08-28T05:05:47.425Z,1566968747.425 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Courier0019 (1/1)
2019-08-28T05:05:47.431Z,1566968747.431 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Express0020
2019-08-28T05:05:48.628Z,1566968748.628 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Express0020 (1/1)
2019-08-28T05:05:48.635Z,1566968748.635 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Courier0019
2019-08-28T05:05:48.748Z,1566968748.748 [Default:CheckIn:Read_Iridium] Stopped
2019-08-28T05:05:48.748Z,1566968748.748 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-28T05:05:48.748Z,1566968748.748 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-28T05:05:49.876Z,1566968749.876 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Courier0019 (1/1)
2019-08-28T05:05:49.881Z,1566968749.881 [DataOverHttps](INFO): GetToShoreFilename: active shore file is Logs/20190828T043721/Express0020
2019-08-28T05:05:51.427Z,1566968751.427 [DataOverHttps](INFO): GetToShoreFilename: checking Logs/20190828T043721/Express0020 (1/1)
2019-08-28T05:05:56.002Z,1566968756.002 [NAL9602](INFO): SBD MO Status=2, MOMSN=183, MT Status=2, MTMSN=0
2019-08-28T05:05:56.002Z,1566968756.002 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-08-28T05:06:47.300Z,1566968807.300 [NAL9602](INFO): SBD MO Status=2, MOMSN=183, MT Status=2, MTMSN=0
2019-08-28T05:06:47.300Z,1566968807.300 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2