2020-03-06T00:04:51.747Z,1583453091.747 [Supervisor](DEBUG): Initializing supervisor.
2020-03-06T00:04:51.750Z,1583453091.750 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-03-06T00:04:51.750Z,1583453091.750 [SyncHandler](INFO): Protected caller Thread ID is 7085
2020-03-06T00:04:51.751Z,1583453091.751 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-03-06T00:04:51.752Z,1583453091.752 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-03-06T00:04:51.752Z,1583453091.752 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7086
2020-03-06T00:04:51.755Z,1583453091.755 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-03-06T00:04:51.768Z,1583453091.768 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-03-06T00:04:51.769Z,1583453091.769 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-03-06T00:04:51.769Z,1583453091.769 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7087
2020-03-06T00:04:51.770Z,1583453091.770 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-03-06T00:04:51.771Z,1583453091.771 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-03-06T00:04:51.771Z,1583453091.771 [logger ThreadHandler](INFO): Protected caller Thread ID is 7088
2020-03-06T00:04:51.773Z,1583453091.773 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-03-06T00:04:51.773Z,1583453091.773 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-03-06T00:04:51.775Z,1583453091.775 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-03-06T00:04:51.868Z,1583453091.868 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-03-06T00:04:51.869Z,1583453091.869 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-03-06T00:04:52.471Z,1583453092.471 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-03-06T00:04:52.471Z,1583453092.471 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-03-06T00:04:52.567Z,1583453092.567 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-03-06T00:04:52.567Z,1583453092.567 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-03-06T00:04:52.667Z,1583453092.667 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-03-06T00:04:52.667Z,1583453092.667 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-03-06T00:04:52.746Z,1583453092.746 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-03-06T00:04:52.881Z,1583453092.881 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-03-06T00:04:52.882Z,1583453092.882 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-03-06T00:04:53.168Z,1583453093.168 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-03-06T00:04:53.169Z,1583453093.169 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-03-06T00:04:53.619Z,1583453093.619 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-03-06T00:04:53.619Z,1583453093.619 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-03-06T00:04:54.106Z,1583453094.106 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-03-06T00:04:54.106Z,1583453094.106 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-03-06T00:04:54.294Z,1583453094.294 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-03-06T00:04:54.295Z,1583453094.295 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-03-06T00:04:54.756Z,1583453094.756 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-03-06T00:04:54.756Z,1583453094.756 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-03-06T00:04:54.970Z,1583453094.970 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-03-06T00:04:54.971Z,1583453094.971 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-03-06T00:04:55.167Z,1583453095.167 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-03-06T00:04:55.167Z,1583453095.167 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-03-06T00:04:55.577Z,1583453095.577 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-03-06T00:04:55.577Z,1583453095.577 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-03-06T00:04:55.899Z,1583453095.899 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-03-06T00:04:55.901Z,1583453095.901 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2020-03-06T00:04:55.902Z,1583453095.902 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2020-03-06T00:04:55.983Z,1583453095.983 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2020-03-06T00:04:56.139Z,1583453096.139 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2020-03-06T00:04:56.245Z,1583453096.245 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2020-03-06T00:04:56.328Z,1583453096.328 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2020-03-06T00:04:56.420Z,1583453096.420 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2020-03-06T00:04:56.619Z,1583453096.619 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2020-03-06T00:04:56.843Z,1583453096.843 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-03-06T00:04:56.844Z,1583453096.844 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2020-03-06T00:04:56.937Z,1583453096.937 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2020-03-06T00:04:57.032Z,1583453097.032 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2020-03-06T00:04:57.169Z,1583453097.169 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2020-03-06T00:04:57.266Z,1583453097.266 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-03-06T00:04:57.274Z,1583453097.274 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-03-06T00:04:57.324Z,1583453097.324 [VerticalControl](DEBUG): Construct VerticalControl.
2020-03-06T00:04:57.436Z,1583453097.436 [VerticalControl] Loaded
2020-03-06T00:04:57.436Z,1583453097.436 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-03-06T00:04:57.437Z,1583453097.437 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-03-06T00:04:57.504Z,1583453097.504 [HorizontalControl] Loaded
2020-03-06T00:04:57.505Z,1583453097.505 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-03-06T00:04:57.505Z,1583453097.505 [SpeedControl](DEBUG): Construct SpeedControl.
2020-03-06T00:04:57.511Z,1583453097.511 [SpeedControl] Loaded
2020-03-06T00:04:57.511Z,1583453097.511 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-03-06T00:04:57.511Z,1583453097.511 [LoopControl](DEBUG): Construct LoopControl.
2020-03-06T00:04:57.512Z,1583453097.512 [LoopControl] Loaded
2020-03-06T00:04:57.512Z,1583453097.512 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-03-06T00:04:57.513Z,1583453097.513 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-03-06T00:04:57.513Z,1583453097.513 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-03-06T00:04:57.555Z,1583453097.555 [DepthRateCalculator] Loaded
2020-03-06T00:04:57.555Z,1583453097.555 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-03-06T00:04:57.560Z,1583453097.560 [PitchRateCalculator] Loaded
2020-03-06T00:04:57.561Z,1583453097.561 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-03-06T00:04:57.576Z,1583453097.576 [SpeedCalculator] Loaded
2020-03-06T00:04:57.576Z,1583453097.576 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-03-06T00:04:57.597Z,1583453097.597 [TempGradientCalculator] Loaded
2020-03-06T00:04:57.597Z,1583453097.597 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-03-06T00:04:57.602Z,1583453097.602 [YawRateCalculator] Loaded
2020-03-06T00:04:57.603Z,1583453097.603 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-03-06T00:04:57.631Z,1583453097.631 [ElevatorOffsetCalculator] Loaded
2020-03-06T00:04:57.632Z,1583453097.632 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-03-06T00:04:57.632Z,1583453097.632 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-03-06T00:04:57.633Z,1583453097.633 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-03-06T00:04:57.667Z,1583453097.667 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-03-06T00:04:57.668Z,1583453097.668 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-03-06T00:04:57.742Z,1583453097.742 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-03-06T00:04:57.743Z,1583453097.743 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-03-06T00:04:58.039Z,1583453098.039 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-03-06T00:04:58.039Z,1583453098.039 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-03-06T00:04:58.139Z,1583453098.139 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-03-06T00:04:58.139Z,1583453098.139 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-03-06T00:04:58.415Z,1583453098.415 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-03-06T00:04:58.420Z,1583453098.420 [AHRS_M2](INFO): created writer for : platform_orientation
2020-03-06T00:04:58.422Z,1583453098.422 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-03-06T00:04:58.427Z,1583453098.427 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-03-06T00:04:58.428Z,1583453098.428 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-03-06T00:04:58.433Z,1583453098.433 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-03-06T00:04:58.433Z,1583453098.433 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-03-06T00:04:58.438Z,1583453098.438 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-03-06T00:04:58.510Z,1583453098.510 [AHRS_M2] Loaded
2020-03-06T00:04:58.510Z,1583453098.510 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-03-06T00:04:58.586Z,1583453098.586 [DataOverHttps] Loaded
2020-03-06T00:04:58.587Z,1583453098.587 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-03-06T00:04:58.588Z,1583453098.588 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408B14E0
2020-03-06T00:04:58.588Z,1583453098.588 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7167
2020-03-06T00:04:58.601Z,1583453098.601 [Depth_Keller] Loaded
2020-03-06T00:04:58.602Z,1583453098.602 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-03-06T00:04:58.606Z,1583453098.606 [DropWeight] Loaded
2020-03-06T00:04:58.607Z,1583453098.607 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-03-06T00:04:58.704Z,1583453098.704 [NAL9602] Loaded
2020-03-06T00:04:58.704Z,1583453098.704 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-03-06T00:04:58.720Z,1583453098.720 [Onboard] Loaded
2020-03-06T00:04:58.720Z,1583453098.720 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-03-06T00:04:58.723Z,1583453098.723 [Radio_Surface] Loaded
2020-03-06T00:04:58.723Z,1583453098.723 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-03-06T00:04:58.724Z,1583453098.724 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408E14E0
2020-03-06T00:04:58.725Z,1583453098.725 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7168
2020-03-06T00:04:58.768Z,1583453098.768 [RDI_Pathfinder] Loaded
2020-03-06T00:04:58.768Z,1583453098.768 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-03-06T00:04:58.893Z,1583453098.893 [DAT] Loaded
2020-03-06T00:04:58.893Z,1583453098.893 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-03-06T00:05:01.176Z,1583453101.176 [BPC1] Loaded
2020-03-06T00:05:01.176Z,1583453101.176 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-03-06T00:05:01.176Z,1583453101.176 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-03-06T00:05:01.177Z,1583453101.177 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-03-06T00:05:01.280Z,1583453101.280 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-03-06T00:05:01.280Z,1583453101.280 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-03-06T00:05:01.301Z,1583453101.301 [NavChart] Loaded
2020-03-06T00:05:01.302Z,1583453101.302 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-03-06T00:05:01.306Z,1583453101.306 [UniversalFixResidualReporter] Loaded
2020-03-06T00:05:01.306Z,1583453101.306 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-03-06T00:05:01.306Z,1583453101.306 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-03-06T00:05:01.307Z,1583453101.307 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-03-06T00:05:01.443Z,1583453101.443 [SBIT](DEBUG): Construct Startup Built In Test.
2020-03-06T00:05:01.454Z,1583453101.454 [SBIT] Loaded
2020-03-06T00:05:01.454Z,1583453101.454 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-03-06T00:05:01.455Z,1583453101.455 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-03-06T00:05:01.467Z,1583453101.467 [IBIT] Loaded
2020-03-06T00:05:01.468Z,1583453101.468 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-03-06T00:05:01.471Z,1583453101.471 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-03-06T00:05:01.608Z,1583453101.608 [CBIT] Loaded
2020-03-06T00:05:01.608Z,1583453101.608 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-03-06T00:05:01.608Z,1583453101.608 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-03-06T00:05:01.609Z,1583453101.609 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-03-06T00:05:01.714Z,1583453101.714 [BuoyancyServo] Loaded
2020-03-06T00:05:01.714Z,1583453101.714 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-03-06T00:05:01.725Z,1583453101.725 [ElevatorServo] Loaded
2020-03-06T00:05:01.726Z,1583453101.726 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-03-06T00:05:01.736Z,1583453101.736 [MassServo] Loaded
2020-03-06T00:05:01.737Z,1583453101.737 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-03-06T00:05:01.748Z,1583453101.748 [RudderServo] Loaded
2020-03-06T00:05:01.748Z,1583453101.748 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-03-06T00:05:01.759Z,1583453101.759 [ThrusterServo] Loaded
2020-03-06T00:05:01.759Z,1583453101.759 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-03-06T00:05:01.760Z,1583453101.760 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-03-06T00:05:01.760Z,1583453101.760 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-03-06T00:05:01.774Z,1583453101.774 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-03-06T00:05:01.775Z,1583453101.775 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-03-06T00:05:02.030Z,1583453102.030 [BackSeatDriver] Loaded
2020-03-06T00:05:02.030Z,1583453102.030 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread.
2020-03-06T00:05:02.031Z,1583453102.031 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40A664E0
2020-03-06T00:05:02.031Z,1583453102.031 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 7169
2020-03-06T00:05:02.041Z,1583453102.041 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-03-06T00:05:02.046Z,1583453102.046 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-03-06T00:05:02.046Z,1583453102.046 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-03-06T00:05:02.051Z,1583453102.051 [CTD_Seabird](INFO): created writer for : depth
2020-03-06T00:05:02.052Z,1583453102.052 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-03-06T00:05:02.057Z,1583453102.057 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-03-06T00:05:02.057Z,1583453102.057 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-03-06T00:05:02.062Z,1583453102.062 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-03-06T00:05:02.063Z,1583453102.063 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-03-06T00:05:02.068Z,1583453102.068 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-03-06T00:05:02.069Z,1583453102.069 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-03-06T00:05:02.074Z,1583453102.074 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-03-06T00:05:02.075Z,1583453102.075 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-03-06T00:05:02.080Z,1583453102.080 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-03-06T00:05:02.107Z,1583453102.107 [CTD_Seabird] Loaded
2020-03-06T00:05:02.107Z,1583453102.107 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-03-06T00:05:02.108Z,1583453102.108 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A964E0
2020-03-06T00:05:02.108Z,1583453102.108 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7170
2020-03-06T00:05:02.115Z,1583453102.115 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-03-06T00:05:02.115Z,1583453102.115 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-03-06T00:05:02.120Z,1583453102.120 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-03-06T00:05:02.120Z,1583453102.120 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-03-06T00:05:02.124Z,1583453102.124 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-03-06T00:05:02.124Z,1583453102.124 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-03-06T00:05:02.129Z,1583453102.129 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-03-06T00:05:02.129Z,1583453102.129 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-03-06T00:05:02.133Z,1583453102.133 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-03-06T00:05:02.133Z,1583453102.133 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-03-06T00:05:02.138Z,1583453102.138 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-03-06T00:05:02.138Z,1583453102.138 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-03-06T00:05:02.142Z,1583453102.142 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-03-06T00:05:02.142Z,1583453102.142 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-03-06T00:05:02.147Z,1583453102.147 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-03-06T00:05:02.151Z,1583453102.151 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-03-06T00:05:02.152Z,1583453102.152 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-03-06T00:05:02.152Z,1583453102.152 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-03-06T00:05:02.156Z,1583453102.156 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-03-06T00:05:02.157Z,1583453102.157 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-03-06T00:05:02.161Z,1583453102.161 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-03-06T00:05:02.161Z,1583453102.161 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-03-06T00:05:02.165Z,1583453102.165 [WetLabsBB2FL] Loaded
2020-03-06T00:05:02.166Z,1583453102.166 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-03-06T00:05:02.167Z,1583453102.167 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AC64E0
2020-03-06T00:05:02.167Z,1583453102.167 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7171
2020-03-06T00:05:02.181Z,1583453102.181 [WetLabsUBAT..SerialDriver](INFO): Created logger
2020-03-06T00:05:02.182Z,1583453102.182 [WetLabsUBAT..SerialDriver](INFO): publishing on LCM channel WetLabsUBAT
2020-03-06T00:05:02.182Z,1583453102.182 [WetLabsUBAT..StreamSerialDriver](INFO): Created logger
2020-03-06T00:05:02.187Z,1583453102.187 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: -ldir /mnt/mmc/LRAUV/Logs/latest
2020-03-06T00:05:02.187Z,1583453102.187 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.biolum_potential
2020-03-06T00:05:02.187Z,1583453102.187 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: biolum_potential
2020-03-06T00:05:02.192Z,1583453102.192 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.flow_rate
2020-03-06T00:05:02.192Z,1583453102.192 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: flow_rate
2020-03-06T00:05:02.197Z,1583453102.197 [WetLabsUBAT] Loaded
2020-03-06T00:05:02.198Z,1583453102.198 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2020-03-06T00:05:02.199Z,1583453102.199 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40AF64E0
2020-03-06T00:05:02.199Z,1583453102.199 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 7172
2020-03-06T00:05:02.199Z,1583453102.199 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-03-06T00:05:02.203Z,1583453102.203 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-03-06T00:05:02.204Z,1583453102.204 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-03-06T00:05:02.211Z,1583453102.211 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-03-06T00:05:02.212Z,1583453102.212 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B264E0
2020-03-06T00:05:02.212Z,1583453102.212 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7173
2020-03-06T00:05:02.217Z,1583453102.217 [Supervisor](INFO): Main Thread ID is 4000
2020-03-06T00:05:02.217Z,1583453102.217 [Supervisor](DEBUG): Running supervisor.
2020-03-06T00:05:02.218Z,1583453102.218 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7174
2020-03-06T00:05:02.221Z,1583453102.221 [controlThread ThreadHandler](INFO): Handler Thread ID is 7175
2020-03-06T00:05:02.221Z,1583453102.221 [controlThread](DEBUG): Initializing ControlThread
2020-03-06T00:05:02.222Z,1583453102.222 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-03-06T00:05:02.224Z,1583453102.224 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-03-06T00:05:02.224Z,1583453102.224 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-03-06T00:05:02.225Z,1583453102.225 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-03-06T00:05:02.225Z,1583453102.225 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-03-06T00:05:02.225Z,1583453102.225 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-03-06T00:05:02.226Z,1583453102.226 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-03-06T00:05:02.226Z,1583453102.226 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-03-06T00:05:02.226Z,1583453102.226 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-03-06T00:05:02.227Z,1583453102.227 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-03-06T00:05:02.232Z,1583453102.232 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-03-06T00:05:02.232Z,1583453102.232 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-03-06T00:05:02.233Z,1583453102.233 [SBIT](INFO): Initialize SBIT Component.
2020-03-06T00:05:02.233Z,1583453102.233 [SBIT](IMPORTANT): git: 2020-01-30-9-ge326a2b
2020-03-06T00:05:02.233Z,1583453102.233 [SBIT](INFO): git hash: e326a2bb2c4829638d886cff235991fc3e02e89f
2020-03-06T00:05:02.234Z,1583453102.234 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-03-06T00:05:02.234Z,1583453102.234 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2020-03-06T00:05:02.235Z,1583453102.235 [SBIT](INFO): Beginning SBIT in 46.000000 seconds.
2020-03-06T00:05:02.235Z,1583453102.235 [IBIT](INFO): Initialize IBIT Component.
2020-03-06T00:05:02.236Z,1583453102.236 [CBIT](DEBUG): Initialize CBIT Component.
2020-03-06T00:05:02.237Z,1583453102.237 [logger ThreadHandler](INFO): Handler Thread ID is 7176
2020-03-06T00:05:02.249Z,1583453102.249 [CBIT](DEBUG): Initialized mux pins.
2020-03-06T00:05:02.249Z,1583453102.249 [CBIT](DEBUG): Initializing the watchdog timer.
2020-03-06T00:05:02.257Z,1583453102.257 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7177
2020-03-06T00:05:02.258Z,1583453102.258 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-03-06T00:05:02.269Z,1583453102.269 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7178
2020-03-06T00:05:02.273Z,1583453102.273 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-03-06T00:05:02.273Z,1583453102.273 [CBIT](DEBUG): Initializing heartbeat.
2020-03-06T00:05:02.281Z,1583453102.281 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 7179
2020-03-06T00:05:02.289Z,1583453102.289 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7180
2020-03-06T00:05:02.290Z,1583453102.290 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-03-06T00:05:02.293Z,1583453102.293 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2020-03-06T00:05:02.295Z,1583453102.295 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7182
2020-03-06T00:05:02.296Z,1583453102.296 [WetLabsBB2FL](INFO): Powering down
2020-03-06T00:05:02.325Z,1583453102.325 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 7183
2020-03-06T00:05:02.333Z,1583453102.333 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7184
2020-03-06T00:05:02.336Z,1583453102.336 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-03-06T00:05:02.336Z,1583453102.336 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-03-06T00:05:02.337Z,1583453102.337 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-03-06T00:05:02.337Z,1583453102.337 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-03-06T00:05:02.337Z,1583453102.337 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-03-06T00:05:02.337Z,1583453102.337 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-03-06T00:05:02.338Z,1583453102.338 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-03-06T00:05:02.338Z,1583453102.338 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-03-06T00:05:02.338Z,1583453102.338 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-03-06T00:05:02.338Z,1583453102.338 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-03-06T00:05:02.338Z,1583453102.338 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-03-06T00:05:02.338Z,1583453102.338 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-03-06T00:05:02.339Z,1583453102.339 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-03-06T00:05:02.339Z,1583453102.339 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-03-06T00:05:02.339Z,1583453102.339 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-03-06T00:05:02.339Z,1583453102.339 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-03-06T00:05:02.345Z,1583453102.345 [CBIT](DEBUG): Deactivating GF circuits.
2020-03-06T00:05:02.345Z,1583453102.345 [CBIT](DEBUG): Deactivating emergency mode.
2020-03-06T00:05:02.381Z,1583453102.381 [CBIT](DEBUG): Backplane powered.
2020-03-06T00:05:02.383Z,1583453102.383 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-03-06T00:05:02.394Z,1583453102.394 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-03-06T00:05:02.418Z,1583453102.418 [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>


2020-03-06T00:05:02.418Z,1583453102.418 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-03-06T00:05:02.492Z,1583453102.492 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-03-06T00:05:02.493Z,1583453102.493 [Default:A.Wait](DEBUG): Construct Wait.
2020-03-06T00:05:02.495Z,1583453102.495 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-03-06T00:05:02.534Z,1583453102.534 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-03-06T00:05:02.536Z,1583453102.536 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-03-06T00:05:02.574Z,1583453102.574 [Default:E.Execute](DEBUG): Construct Execute.
2020-03-06T00:05:02.593Z,1583453102.593 [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>


2020-03-06T00:05:02.598Z,1583453102.598 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,DAT,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,
2020-03-06T00:05:02.604Z,1583453102.604 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-03-06T00:05:02.635Z,1583453102.635 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2020-03-06T00:05:02.672Z,1583453102.672 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-03-06T00:05:02.672Z,1583453102.672 [DAT](INFO): Powering up
2020-03-06T00:05:02.673Z,1583453102.673 [Radio_Surface](INFO): Powering up
2020-03-06T00:05:02.673Z,1583453102.673 [DAT](DEBUG): Initializing DAT.
2020-03-06T00:05:02.721Z,1583453102.721 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-03-06T00:05:02.745Z,1583453102.745 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-03-06T00:05:02.775Z,1583453102.775 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-03-06T00:05:02.785Z,1583453102.785 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-03-06T00:05:02.787Z,1583453102.787 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-03-06T00:05:02.798Z,1583453102.798 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-03-06T00:05:02.799Z,1583453102.799 [MassServo](DEBUG): Initializing EZServoServo.
2020-03-06T00:05:02.809Z,1583453102.809 [MassServo](DEBUG): Initializing MassServo.
2020-03-06T00:05:02.810Z,1583453102.810 [RudderServo](DEBUG): Initializing EZServoServo.
2020-03-06T00:05:02.821Z,1583453102.821 [RudderServo](DEBUG): Initializing RudderServo.
2020-03-06T00:05:02.822Z,1583453102.822 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-03-06T00:05:02.834Z,1583453102.834 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-03-06T00:05:03.049Z,1583453103.049 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-03-06T00:05:03.769Z,1583453103.769 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-03-06T00:05:03.769Z,1583453103.769 [RudderServo](FAULT): Rudder failed to initialize
2020-03-06T00:05:03.769Z,1583453103.769 [RudderServo] Communications Fault, FailCount= 1
2020-03-06T00:05:03.769Z,1583453103.769 [RudderServo](ERROR): Communications Fault
2020-03-06T00:05:03.880Z,1583453103.880 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-03-06T00:05:04.042Z,1583453104.042 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-03-06T00:05:04.042Z,1583453104.042 [RudderServo](INFO): Powering down
2020-03-06T00:05:04.724Z,1583453104.724 [RudderServo](DEBUG): Initializing EZServoServo.
2020-03-06T00:05:04.854Z,1583453104.854 [RudderServo](DEBUG): Initializing RudderServo.
2020-03-06T00:05:04.858Z,1583453104.858 [CBIT](INFO): Clearing failed state for component RudderServo
2020-03-06T00:05:04.858Z,1583453104.858 [RudderServo] No Fault, FailCount= 1
2020-03-06T00:05:15.252Z,1583453115.252 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600
2020-03-06T00:05:16.462Z,1583453116.462 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:05:16.864Z,1583453116.864 [DAT](INFO): setting local address to 9
2020-03-06T00:05:17.280Z,1583453117.280 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:05:17.289Z,1583453117.289 [DAT](INFO): set local address to 9
2020-03-06T00:05:18.483Z,1583453118.483 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:05:19.290Z,1583453119.290 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:05:20.503Z,1583453120.503 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:05:21.310Z,1583453121.310 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:05:22.522Z,1583453122.522 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:05:23.330Z,1583453123.330 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:05:24.542Z,1583453124.542 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:05:24.945Z,1583453124.945 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-03-06T00:05:24.945Z,1583453124.945 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-03-06T00:05:24.945Z,1583453124.945 [RDI_Pathfinder](ERROR): Communications Fault
2020-03-06T00:05:24.978Z,1583453124.978 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:05:25.429Z,1583453125.429 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:05:26.199Z,1583453126.199 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:05:26.200Z,1583453126.200 [RDI_Pathfinder] No Fault, FailCount= 1
2020-03-06T00:05:28.983Z,1583453128.983 [NAL9602](INFO): Powering up NAL9602
2020-03-06T00:05:39.087Z,1583453139.087 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600
2020-03-06T00:05:39.878Z,1583453139.878 [NAL9602](INFO): NAL9602 initialized
2020-03-06T00:05:40.318Z,1583453140.318 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:05:41.132Z,1583453141.132 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:05:42.326Z,1583453142.326 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:05:43.138Z,1583453143.138 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:05:43.973Z,1583453143.973 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:05:45.156Z,1583453145.156 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:05:45.987Z,1583453145.987 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:05:47.175Z,1583453147.175 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:05:48.000Z,1583453148.000 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:05:48.796Z,1583453148.796 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-03-06T00:05:48.796Z,1583453148.796 [RDI_Pathfinder] Communications Fault, FailCount= 2
2020-03-06T00:05:48.796Z,1583453148.796 [RDI_Pathfinder](ERROR): Communications Fault
2020-03-06T00:05:48.815Z,1583453148.815 [SBIT](IMPORTANT): Beginning Startup BIT
2020-03-06T00:05:48.843Z,1583453148.843 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:05:48.843Z,1583453148.843 [CBIT](IMPORTANT): Beginning ground fault scan
2020-03-06T00:05:49.273Z,1583453149.273 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:05:50.086Z,1583453150.086 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:05:50.086Z,1583453150.086 [RDI_Pathfinder] No Fault, FailCount= 2
2020-03-06T00:05:59.866Z,1583453159.866 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.122716
CHAN A1 (24V): 0.287975
CHAN A2 (12V): 0.116637
CHAN A3 (5V): 0.046510
CHAN B0 (3.3V): 0.034442
CHAN B1 (3.15aV): 0.033513
CHAN B2 (3.15bV): 0.033538
CHAN B3 (GND): 0.003454
OPEN: 0.014484
Full Scale Calc: 4.765 mA, -1.589 mA
2020-03-06T00:06:03.015Z,1583453163.015 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600
2020-03-06T00:06:03.815Z,1583453163.815 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:06:05.116Z,1583453165.116 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:06:05.918Z,1583453165.918 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:06:07.127Z,1583453167.127 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:06:07.943Z,1583453167.943 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:06:09.164Z,1583453169.164 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:06:09.954Z,1583453169.954 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:06:10.779Z,1583453170.779 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:06:11.975Z,1583453171.975 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:06:12.804Z,1583453172.804 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-03-06T00:06:12.804Z,1583453172.804 [RDI_Pathfinder] Communications Fault, FailCount= 3
2020-03-06T00:06:12.804Z,1583453172.804 [RDI_Pathfinder](ERROR): Communications Fault
2020-03-06T00:06:12.874Z,1583453172.874 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:06:12.875Z,1583453172.875 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:06:13.253Z,1583453173.253 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:06:13.312Z,1583453173.312 [CommandLine](FAULT): Scheduling is paused
2020-03-06T00:06:13.312Z,1583453173.312 [CBIT](INFO): Critical error at 20200306T000612
2020-03-06T00:06:13.312Z,1583453173.312 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-03-06T00:06:42.409Z,1583453202.409 [SBIT](IMPORTANT): SBIT PASSED
2020-03-06T00:06:42.409Z,1583453202.409 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-03-06T00:06:42.410Z,1583453202.410 [SBIT](IMPORTANT): Express none ThrusterServo.component_avgCurrent;

2020-03-06T00:06:42.410Z,1583453202.410 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 1.000000 liter_per_second;

2020-03-06T00:06:42.410Z,1583453202.410 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2020-03-06T00:06:42.410Z,1583453202.410 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=15 ampere_hour;

2020-03-06T00:06:42.410Z,1583453202.410 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=13 volt;

2020-03-06T00:06:42.410Z,1583453202.410 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=187 cubic_centimeter;

2020-03-06T00:06:42.410Z,1583453202.410 [SBIT](IMPORTANT): VerticalControl.massDefault=6.5 millimeter;

2020-03-06T00:06:42.411Z,1583453202.411 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=1 bool;

2020-03-06T00:06:42.815Z,1583453202.815 [MissionManager](IMPORTANT): Started mission Startup
2020-03-06T00:06:42.816Z,1583453202.816 [Startup] Running Loop=1
2020-03-06T00:06:42.816Z,1583453202.816 [Startup](DEBUG): Aggregate::initialize Startup
2020-03-06T00:06:42.816Z,1583453202.816 [Startup:A.GoToSurface] Running Loop=1
2020-03-06T00:06:42.816Z,1583453202.816 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-03-06T00:06:42.817Z,1583453202.817 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-03-06T00:06:42.818Z,1583453202.818 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-03-06T00:06:42.818Z,1583453202.818 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-03-06T00:06:42.818Z,1583453202.818 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-03-06T00:06:42.819Z,1583453202.819 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-03-06T00:06:42.820Z,1583453202.820 [Startup:StartupSatComms] Running Loop=1
2020-03-06T00:06:42.820Z,1583453202.820 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-03-06T00:06:42.820Z,1583453202.820 [Startup:StartupSatComms:A] Running Loop=1
2020-03-06T00:06:43.229Z,1583453203.229 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-03-06T00:07:43.021Z,1583453263.021 [Startup:StartupSatComms:A](INFO): Timed out from 2020-03-06T00:06:42.8Z
2020-03-06T00:07:43.021Z,1583453263.021 [Startup:StartupSatComms:A] Stopped
2020-03-06T00:07:43.021Z,1583453263.021 [Startup:StartupSatComms:B] Running Loop=1
2020-03-06T00:07:43.414Z,1583453263.414 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-03-06T00:08:02.397Z,1583453282.397 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-03-06T00:08:02.397Z,1583453282.397 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-03-06T00:08:02.407Z,1583453282.407 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-03-06T00:08:02.806Z,1583453282.806 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-03-06T00:08:02.806Z,1583453282.806 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-03-06T00:08:26.451Z,1583453306.451 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004992
2020-03-06T00:08:33.737Z,1583453313.737 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20200305T235139/Courier0007.lzma
2020-03-06T00:08:35.743Z,1583453315.743 [DataOverHttps](INFO): Moved sent file to Logs/20200305T235139/Courier0007.lzma.bak
2020-03-06T00:08:35.743Z,1583453315.743 [DataOverHttps](INFO): SBD MOMSN=12298464
2020-03-06T00:08:43.226Z,1583453323.226 [Startup:StartupSatComms:B](INFO): Timed out from 2020-03-06T00:07:43.0Z
2020-03-06T00:08:43.226Z,1583453323.226 [Startup:StartupSatComms:B] Stopped
2020-03-06T00:08:43.226Z,1583453323.226 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-03-06T00:08:43.226Z,1583453323.226 [Startup:StartupSatComms] Stopped
2020-03-06T00:08:43.227Z,1583453323.227 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-03-06T00:08:43.227Z,1583453323.227 [Startup](INFO): Completed Startup
2020-03-06T00:08:43.227Z,1583453323.227 [MissionManager](INFO): Startup is completed.
2020-03-06T00:08:43.228Z,1583453323.228 [MissionManager](INFO): Uninitializing Mission Startup
2020-03-06T00:08:43.228Z,1583453323.228 [Startup] Stopped
2020-03-06T00:08:43.228Z,1583453323.228 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-03-06T00:08:43.228Z,1583453323.228 [Startup:A.GoToSurface] Stopped
2020-03-06T00:08:43.228Z,1583453323.228 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-03-06T00:08:43.622Z,1583453323.622 [MissionManager](IMPORTANT): Started mission Default
2020-03-06T00:08:43.622Z,1583453323.622 [Default] Running Loop=1
2020-03-06T00:08:43.622Z,1583453323.622 [Default](DEBUG): Aggregate::initialize Default
2020-03-06T00:08:43.622Z,1583453323.622 [Default:B.GoToSurface] Running Loop=1
2020-03-06T00:08:43.622Z,1583453323.622 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-03-06T00:08:43.623Z,1583453323.623 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-03-06T00:08:43.623Z,1583453323.623 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-03-06T00:08:43.623Z,1583453323.623 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-03-06T00:08:43.623Z,1583453323.623 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-03-06T00:08:43.624Z,1583453323.624 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-03-06T00:08:43.624Z,1583453323.624 [Default:A.Wait] Running Loop=1
2020-03-06T00:08:43.624Z,1583453323.624 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-03-06T00:08:53.359Z,1583453333.359 [CommandLine](IMPORTANT): got command ubat on
2020-03-06T00:08:53.360Z,1583453333.360 [CommandLine](IMPORTANT): Enabling UBAT.
2020-03-06T00:08:55.632Z,1583453335.632 [DataOverHttps](INFO): Sending 107 bytes from file Logs/20200306T000451/Courier0000.lzma
2020-03-06T00:08:56.950Z,1583453336.950 [Default:A.Wait](INFO): Done Waiting.
2020-03-06T00:08:56.950Z,1583453336.950 [Default:A.Wait] Stopped
2020-03-06T00:08:56.951Z,1583453336.951 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-03-06T00:08:57.358Z,1583453337.358 [Default:CheckIn] Running Loop=1
2020-03-06T00:08:57.358Z,1583453337.358 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-03-06T00:08:57.358Z,1583453337.358 [Default:CheckIn:Read_GPS] Running Loop=1
2020-03-06T00:08:57.638Z,1583453337.638 [DataOverHttps](INFO): Moved sent file to Logs/20200306T000451/Courier0000.lzma.bak
2020-03-06T00:08:57.638Z,1583453337.638 [DataOverHttps](INFO): SBD MOMSN=12298467
2020-03-06T00:08:57.764Z,1583453337.764 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-03-06T00:09:08.438Z,1583453348.438 [CommandLine](IMPORTANT): got command run ./Missions/Insert/Science.xml
2020-03-06T00:09:08.439Z,1583453348.439 [MissionManager](INFO): Loading Mission: ./Missions/Insert/Science.xml
2020-03-06T00:09:08.611Z,1583453348.611 [MissionManager](INFO): DefineArg Science.SampleISUS = 0 bool
2020-03-06T00:09:08.614Z,1583453348.614 [MissionManager](INFO): DefineArg Science.SampleISUSmaxPitch = 60.000000 arcdeg
2020-03-06T00:09:08.618Z,1583453348.618 [MissionManager](INFO): DefineArg Science.SampleISUSminPitch = -60.000000 arcdeg
2020-03-06T00:09:08.620Z,1583453348.620 [MissionManager](INFO): DefineArg Science.PeakDetectChlActive = 0 bool
2020-03-06T00:09:08.700Z,1583453348.700 [MissionManager](INFO): DefineArg Science.TimeWindowPeakReport = nan min
2020-03-06T00:09:08.713Z,1583453348.713 [MissionManager](INFO): DefineArg Science.HighestChlPeakReportActive = 0 bool
2020-03-06T00:09:08.716Z,1583453348.716 [MissionManager](INFO): DefineArg Science.PatchTracking = 0 bool
2020-03-06T00:09:08.737Z,1583453348.737 [MissionManager](INFO): DefineArg Science.FilterWidthHorizontal = 3.000000 count
2020-03-06T00:09:08.740Z,1583453348.740 [MissionManager](INFO): DefineArg Science.NumProfilesSlidingwindow = 100.000000 count
2020-03-06T00:09:08.755Z,1583453348.755 [MissionManager](INFO): DefineArg Science.OffPeakFractionHorizontal = 80.000000 %
2020-03-06T00:09:08.759Z,1583453348.759 [MissionManager](INFO): DefineArg Science.PeakDetectNO3Active = 0 bool
2020-03-06T00:09:08.769Z,1583453348.769 [MissionManager](INFO): DefineArg Science.PeakDetectOilActive = 0 bool
2020-03-06T00:09:08.772Z,1583453348.772 [MissionManager](INFO): DefineArg Science.PeakDetectFDOMActive = 0 bool
2020-03-06T00:09:08.780Z,1583453348.780 [MissionManager](INFO): DefineArg Science.UpwardDerivativeOfTemperatureActive = 0 bool
2020-03-06T00:09:08.788Z,1583453348.788 [MissionManager](INFO): DefineArg Science.EnabledAanderaaO2 = 0.000000 bool
2020-03-06T00:09:08.804Z,1583453348.804 [MissionManager](INFO): DefineArg Science.EnabledNeilBrown = 0.000000 bool
2020-03-06T00:09:08.807Z,1583453348.807 [MissionManager](INFO): DefineArg Science.EnabledSeabird = 1.000000 bool
2020-03-06T00:09:08.811Z,1583453348.811 [MissionManager](INFO): DefineArg Science.EnabledTurbulence_NPS = 0.000000 bool
2020-03-06T00:09:08.824Z,1583453348.824 [MissionManager](INFO): DefineArg Science.EnabledWetLabsBB2FL = 1.000000 bool
2020-03-06T00:09:08.828Z,1583453348.828 [MissionManager](INFO): DefineArg Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2020-03-06T00:09:08.832Z,1583453348.832 [MissionManager](INFO): DefineArg Science.EnabledWetLabsUBAT = 1.000000 bool
2020-03-06T00:09:08.843Z,1583453348.843 [MissionManager](INFO): DefineArg Science.EnabledISUS = 0.000000 bool
2020-03-06T00:09:08.861Z,1583453348.861 [MissionManager](INFO): DefineArg Science.LowPassWindowLength = 20.000000 count
2020-03-06T00:09:08.864Z,1583453348.864 [MissionManager](INFO): DefineArg Science.MedianFilterLen = 5.000000 count
2020-03-06T00:09:08.867Z,1583453348.867 [MissionManager](INFO): DefineArg Science.PeakChlShallowBound = nan m
2020-03-06T00:09:08.900Z,1583453348.900 [MissionManager](INFO): DefineArg Science.PeakChlDeepBound = nan m
2020-03-06T00:09:08.903Z,1583453348.903 [MissionManager](INFO): DefineArg Science.PeakShallowBound = nan m
2020-03-06T00:09:08.906Z,1583453348.906 [MissionManager](INFO): DefineArg Science.PeakDeepBound = nan m
2020-03-06T00:09:08.926Z,1583453348.926 [MissionManager](INFO): DefineOutput Science.PeakChl = 0.000000 ug/l
2020-03-06T00:09:08.929Z,1583453348.929 [MissionManager](INFO): DefineOutput Science.PeakChlDepth = 0.000000 m
2020-03-06T00:09:08.933Z,1583453348.933 [MissionManager](INFO): DefineOutput Science.PeakChlTemperature = 0.000000 degC
2020-03-06T00:09:08.936Z,1583453348.936 [MissionManager](INFO): DefineOutput Science.PeakChlLatitude = nan arcdeg
2020-03-06T00:09:08.948Z,1583453348.948 [MissionManager](INFO): DefineOutput Science.PeakChlLongitude = nan arcdeg
2020-03-06T00:09:08.951Z,1583453348.951 [MissionManager](INFO): DefineOutput Science.PeakNO3 = 0.000000 umol/l
2020-03-06T00:09:08.955Z,1583453348.955 [MissionManager](INFO): DefineOutput Science.PeakNO3Depth = 0.000000 m
2020-03-06T00:09:08.984Z,1583453348.984 [MissionManager](INFO): DefineOutput Science.PeakNO3Latitude = nan arcdeg
2020-03-06T00:09:08.987Z,1583453348.987 [MissionManager](INFO): DefineOutput Science.PeakNO3Longitude = nan arcdeg
2020-03-06T00:09:08.991Z,1583453348.991 [MissionManager](INFO): DefineOutput Science.PatchChl = 0.000000 m
2020-03-06T00:09:09.070Z,1583453349.070 [MissionManager](INFO): DefineOutput Science.PatchChlDepth = 0.000000 m
2020-03-06T00:09:09.085Z,1583453349.085 [MissionManager](INFO): DefineOutput Science.PatchChlLatitude = nan arcdeg
2020-03-06T00:09:09.093Z,1583453349.093 [MissionManager](INFO): DefineOutput Science.PatchChlLongitude = nan arcdeg
2020-03-06T00:09:09.096Z,1583453349.096 [MissionManager](INFO): DefineOutput Science.PatchChlDistance = nan m
2020-03-06T00:09:09.112Z,1583453349.112 [MissionManager](INFO): DefineOutput Science.PeakOil = 0.000000 kg/m3
2020-03-06T00:09:09.116Z,1583453349.116 [MissionManager](INFO): DefineOutput Science.PeakOilDepth = 0.000000 m
2020-03-06T00:09:09.135Z,1583453349.135 [MissionManager](INFO): DefineOutput Science.PeakOilLatitude = nan arcdeg
2020-03-06T00:09:09.149Z,1583453349.149 [MissionManager](INFO): DefineOutput Science.PeakOilLongitude = nan arcdeg
2020-03-06T00:09:09.152Z,1583453349.152 [MissionManager](INFO): DefineOutput Science.PeakFDOM = 0.000000 ppb
2020-03-06T00:09:09.168Z,1583453349.168 [MissionManager](INFO): DefineOutput Science.PeakFDOMDepth = 0.000000 m
2020-03-06T00:09:09.175Z,1583453349.175 [MissionManager](INFO): DefineOutput Science.PeakFDOMLatitude = nan arcdeg
2020-03-06T00:09:09.180Z,1583453349.180 [MissionManager](INFO): DefineOutput Science.PeakFDOMLongitude = nan arcdeg
2020-03-06T00:09:09.191Z,1583453349.191 [MissionManager](INFO): DefineOutput Science.PatchFDOMDistance = nan m
2020-03-06T00:09:09.227Z,1583453349.227 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-03-06T00:09:09.282Z,1583453349.282 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2020-03-06T00:09:09.311Z,1583453349.311 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-03-06T00:09:09.347Z,1583453349.347 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-03-06T00:09:09.369Z,1583453349.369 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-03-06T00:09:09.489Z,1583453349.489 [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>


2020-03-06T00:09:09.492Z,1583453349.492 [CommandLine](IMPORTANT): Running ./Missions/Insert/Science.xml
2020-03-06T00:09:09.888Z,1583453349.888 [Default] Stopped
2020-03-06T00:09:09.888Z,1583453349.888 [Default](DEBUG): Aggregate::uninitialize Default
2020-03-06T00:09:09.888Z,1583453349.888 [Default:B.GoToSurface] Stopped
2020-03-06T00:09:09.888Z,1583453349.888 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-03-06T00:09:09.888Z,1583453349.888 [Default:CheckIn] Stopped
2020-03-06T00:09:09.888Z,1583453349.888 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-03-06T00:09:09.888Z,1583453349.888 [Default:CheckIn:Read_GPS] Stopped
2020-03-06T00:09:09.901Z,1583453349.901 [MissionManager](IMPORTANT): Started mission Science
2020-03-06T00:09:09.901Z,1583453349.901 [Science] Running Loop=1
2020-03-06T00:09:09.901Z,1583453349.901 [Science](DEBUG): Aggregate::initialize Science
2020-03-06T00:09:09.901Z,1583453349.901 [Science:A] Running Loop=1
2020-03-06T00:09:09.901Z,1583453349.901 [Science:B] Running Loop=1
2020-03-06T00:09:09.901Z,1583453349.901 [Science:C] Running Loop=1
2020-03-06T00:09:09.901Z,1583453349.901 [Science:D] Running Loop=1
2020-03-06T00:09:09.902Z,1583453349.902 [Science:E] Running Loop=1
2020-03-06T00:09:09.902Z,1583453349.902 [Science:F] Running Loop=1
2020-03-06T00:09:09.902Z,1583453349.902 [Science:G] Running Loop=1
2020-03-06T00:09:09.902Z,1583453349.902 [Science:H] Running Loop=1
2020-03-06T00:09:09.902Z,1583453349.902 [Science:Read_Oil] Running Loop=1
2020-03-06T00:09:09.902Z,1583453349.902 [Science:PeakDetectChl] Running Loop=1
2020-03-06T00:09:09.902Z,1583453349.902 [Science:PeakDetectChl](DEBUG): Aggregate::initialize Science:PeakDetectChl
2020-03-06T00:09:09.902Z,1583453349.902 [Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2020-03-06T00:09:09.902Z,1583453349.902 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-03-06T00:09:09.904Z,1583453349.904 [Science:HighestChlPeakReport] Running Loop=1
2020-03-06T00:09:09.904Z,1583453349.904 [Science:HighestChlPeakReport](DEBUG): Aggregate::initialize Science:HighestChlPeakReport
2020-03-06T00:09:09.904Z,1583453349.904 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2020-03-06T00:09:09.904Z,1583453349.904 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2020-03-06T00:09:09.909Z,1583453349.909 [Science:PeakDetectNO3] Running Loop=1
2020-03-06T00:09:09.909Z,1583453349.909 [Science:PeakDetectNO3](DEBUG): Aggregate::initialize Science:PeakDetectNO3
2020-03-06T00:09:09.909Z,1583453349.909 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2020-03-06T00:09:09.910Z,1583453349.910 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-03-06T00:09:09.914Z,1583453349.914 [Science:PeakDetectOil] Running Loop=1
2020-03-06T00:09:09.915Z,1583453349.915 [Science:PeakDetectOil](DEBUG): Aggregate::initialize Science:PeakDetectOil
2020-03-06T00:09:09.915Z,1583453349.915 [Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2020-03-06T00:09:09.915Z,1583453349.915 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-03-06T00:09:09.917Z,1583453349.917 [Science:PeakDetectFDOM] Running Loop=1
2020-03-06T00:09:09.918Z,1583453349.918 [Science:PeakDetectFDOM](DEBUG): Aggregate::initialize Science:PeakDetectFDOM
2020-03-06T00:09:09.918Z,1583453349.918 [Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2020-03-06T00:09:09.918Z,1583453349.918 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-03-06T00:09:09.933Z,1583453349.933 [Science:PeakDetectFDOM] Stopped
2020-03-06T00:09:09.933Z,1583453349.933 [Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize Science:PeakDetectFDOM
2020-03-06T00:09:09.933Z,1583453349.933 [Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2020-03-06T00:09:09.933Z,1583453349.933 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-03-06T00:09:09.933Z,1583453349.933 [Science:PeakDetectOil] Stopped
2020-03-06T00:09:09.933Z,1583453349.933 [Science:PeakDetectOil](DEBUG): Aggregate::uninitialize Science:PeakDetectOil
2020-03-06T00:09:09.933Z,1583453349.933 [Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2020-03-06T00:09:09.933Z,1583453349.933 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-03-06T00:09:09.934Z,1583453349.934 [Science:PeakDetectNO3] Stopped
2020-03-06T00:09:09.934Z,1583453349.934 [Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize Science:PeakDetectNO3
2020-03-06T00:09:09.934Z,1583453349.934 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2020-03-06T00:09:09.934Z,1583453349.934 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-03-06T00:09:09.934Z,1583453349.934 [Science:HighestChlPeakReport] Stopped
2020-03-06T00:09:09.934Z,1583453349.934 [Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize Science:HighestChlPeakReport
2020-03-06T00:09:09.934Z,1583453349.934 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2020-03-06T00:09:09.934Z,1583453349.934 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2020-03-06T00:09:09.934Z,1583453349.934 [Science:PeakDetectChl] Stopped
2020-03-06T00:09:09.934Z,1583453349.934 [Science:PeakDetectChl](DEBUG): Aggregate::uninitialize Science:PeakDetectChl
2020-03-06T00:09:09.934Z,1583453349.934 [Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2020-03-06T00:09:09.934Z,1583453349.934 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-03-06T00:09:09.935Z,1583453349.935 [Science:Read_Oil] Stopped
2020-03-06T00:09:09.935Z,1583453349.935 [Science:H] Running Loop=1
2020-03-06T00:09:09.937Z,1583453349.937 [Science:H](DEBUG): Initialize ReadDataComponent to sense WetLabsUBAT.biolum_potential
2020-03-06T00:09:09.937Z,1583453349.937 [Science:G] Running Loop=1
2020-03-06T00:09:09.938Z,1583453349.938 [Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2020-03-06T00:09:09.938Z,1583453349.938 [Science:F] Stopped
2020-03-06T00:09:09.938Z,1583453349.938 [Science:E] Stopped
2020-03-06T00:09:09.938Z,1583453349.938 [Science:D] Running Loop=1
2020-03-06T00:09:09.939Z,1583453349.939 [Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2020-03-06T00:09:09.939Z,1583453349.939 [Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2020-03-06T00:09:09.940Z,1583453349.940 [Science:C] Running Loop=1
2020-03-06T00:09:09.940Z,1583453349.940 [Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2020-03-06T00:09:09.941Z,1583453349.941 [Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2020-03-06T00:09:09.941Z,1583453349.941 [Science:B] Stopped
2020-03-06T00:09:09.941Z,1583453349.941 [Science:A] Stopped
2020-03-06T00:09:10.334Z,1583453350.334 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2020-03-06T00:09:10.410Z,1583453350.410 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2020-03-06T00:09:10.410Z,1583453350.410 [WetLabsBB2FL](INFO): Powering up
2020-03-06T00:09:10.429Z,1583453350.429 [WetLabsUBAT](INFO): Initializing
2020-03-06T00:09:10.431Z,1583453350.431 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: -ldir /mnt/mmc/LRAUV/Logs/latest
2020-03-06T00:09:10.432Z,1583453350.432 [WetLabsUBAT](INFO): Powering up
2020-03-06T00:09:10.432Z,1583453350.432 [WetLabsUBAT](INFO): Checking LCM
2020-03-06T00:09:10.433Z,1583453350.433 [WetLabsUBAT](INFO): LCM OK
2020-03-06T00:09:10.440Z,1583453350.440 [WetLabsUBAT](INFO): setup() - setLineFormat()
2020-03-06T00:09:13.130Z,1583453353.130 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:09:13.130Z,1583453353.130 [RDI_Pathfinder] No Fault, FailCount= 3
2020-03-06T00:09:13.346Z,1583453353.346 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2020-03-06T00:09:20.731Z,1583453360.731 [WetLabsUBAT](INFO): Got instrument prompt
2020-03-06T00:09:22.871Z,1583453362.871 [WetLabsUBAT](INFO): Got instrument prompt
2020-03-06T00:09:23.933Z,1583453363.933 [WetLabsUBAT..StreamSerialDriver](INFO): device metadata:
Override SerialDriver::getDeviceMetadata()
2020-03-06T00:09:26.051Z,1583453366.051 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2020-03-06T00:09:26.052Z,1583453366.052 [RDI_Pathfinder] Data Fault, FailCount= 1
2020-03-06T00:09:26.052Z,1583453366.052 [RDI_Pathfinder](ERROR): Data Fault
2020-03-06T00:09:26.130Z,1583453366.130 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2020-03-06T00:09:26.513Z,1583453366.513 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:09:27.282Z,1583453367.282 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:09:27.282Z,1583453367.282 [RDI_Pathfinder] No Fault, FailCount= 1
2020-03-06T00:09:28.677Z,1583453368.677 [CommandLine](IMPORTANT): got command report touch WetLabsUBAT.flow_rate
2020-03-06T00:09:29.306Z,1583453369.306 [Reporter](INFO): WetLabsUBAT.flow_rate 0.345492 l/s
2020-03-06T00:09:30.108Z,1583453370.108 [Reporter](INFO): WetLabsUBAT.flow_rate 0.344578 l/s
2020-03-06T00:09:31.335Z,1583453371.335 [Reporter](INFO): WetLabsUBAT.flow_rate 0.349148 l/s
2020-03-06T00:09:32.172Z,1583453372.172 [Reporter](INFO): WetLabsUBAT.flow_rate 0.346863 l/s
2020-03-06T00:09:33.414Z,1583453373.414 [Reporter](INFO): WetLabsUBAT.flow_rate 0.346863 l/s
2020-03-06T00:09:34.159Z,1583453374.159 [Reporter](INFO): WetLabsUBAT.flow_rate 0.346406 l/s
2020-03-06T00:09:35.317Z,1583453375.317 [CommandLine](IMPORTANT): got command ubat off
2020-03-06T00:09:35.318Z,1583453375.318 [CommandLine](IMPORTANT): Disabling UBAT.
2020-03-06T00:09:35.396Z,1583453375.396 [Reporter](INFO): WetLabsUBAT.flow_rate 0.346863 l/s
2020-03-06T00:09:36.167Z,1583453376.167 [Reporter](INFO): WetLabsUBAT.flow_rate no_value
2020-03-06T00:09:36.549Z,1583453376.549 [WetLabsUBAT](INFO): Powering down
2020-03-06T00:09:40.171Z,1583453380.171 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600
2020-03-06T00:09:41.388Z,1583453381.388 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:09:42.194Z,1583453382.194 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:09:43.031Z,1583453383.031 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:09:44.218Z,1583453384.218 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:09:44.327Z,1583453384.327 [CommandLine](IMPORTANT): got command ubat on
2020-03-06T00:09:44.327Z,1583453384.327 [CommandLine](IMPORTANT): Enabling UBAT.
2020-03-06T00:09:45.041Z,1583453385.041 [WetLabsUBAT](INFO): Initializing
2020-03-06T00:09:45.043Z,1583453385.043 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: -ldir /mnt/mmc/LRAUV/Logs/latest
2020-03-06T00:09:45.043Z,1583453385.043 [WetLabsUBAT](INFO): Powering up
2020-03-06T00:09:45.043Z,1583453385.043 [WetLabsUBAT](INFO): Checking LCM
2020-03-06T00:09:45.044Z,1583453385.044 [WetLabsUBAT](INFO): LCM OK
2020-03-06T00:09:45.045Z,1583453385.045 [WetLabsUBAT](INFO): setup() - setLineFormat()
2020-03-06T00:09:45.055Z,1583453385.055 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:09:46.224Z,1583453386.224 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:09:47.049Z,1583453387.049 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:09:48.247Z,1583453388.247 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:09:49.063Z,1583453389.063 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:09:49.861Z,1583453389.861 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-03-06T00:09:49.861Z,1583453389.861 [RDI_Pathfinder] Communications Fault, FailCount= 2
2020-03-06T00:09:49.861Z,1583453389.861 [RDI_Pathfinder](ERROR): Communications Fault
2020-03-06T00:09:49.926Z,1583453389.926 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:09:50.333Z,1583453390.333 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:09:51.112Z,1583453391.112 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:09:51.112Z,1583453391.112 [RDI_Pathfinder] No Fault, FailCount= 2
2020-03-06T00:09:55.339Z,1583453395.339 [WetLabsUBAT](INFO): Got instrument prompt
2020-03-06T00:09:57.475Z,1583453397.475 [WetLabsUBAT](INFO): Got instrument prompt
2020-03-06T00:09:58.545Z,1583453398.545 [WetLabsUBAT..StreamSerialDriver](INFO): device metadata:
Override SerialDriver::getDeviceMetadata()
2020-03-06T00:10:03.648Z,1583453403.648 [Reporter](INFO): WetLabsUBAT.flow_rate 0.353261 l/s
2020-03-06T00:10:04.005Z,1583453404.005 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600
2020-03-06T00:10:04.900Z,1583453404.900 [Reporter](INFO): WetLabsUBAT.flow_rate 0.347777 l/s
2020-03-06T00:10:05.237Z,1583453405.237 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:10:05.690Z,1583453405.690 [Reporter](INFO): WetLabsUBAT.flow_rate 0.350062 l/s
2020-03-06T00:10:06.045Z,1583453406.045 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:10:06.902Z,1583453406.902 [Reporter](INFO): WetLabsUBAT.flow_rate 0.348234 l/s
2020-03-06T00:10:07.244Z,1583453407.244 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:10:08.044Z,1583453408.044 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:10:08.118Z,1583453408.118 [Reporter](INFO): WetLabsUBAT.flow_rate 0.350062 l/s
2020-03-06T00:10:10.094Z,1583453410.094 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:10:10.117Z,1583453410.117 [Reporter](INFO): WetLabsUBAT.flow_rate 0.348234 l/s
2020-03-06T00:10:10.911Z,1583453410.911 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:10:11.876Z,1583453411.876 [CommandLine](IMPORTANT): got command ubat off
2020-03-06T00:10:11.877Z,1583453411.877 [CommandLine](IMPORTANT): Disabling UBAT.
2020-03-06T00:10:12.092Z,1583453412.092 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:10:12.165Z,1583453412.165 [Reporter](INFO): WetLabsUBAT.flow_rate 0.349148 l/s
2020-03-06T00:10:12.908Z,1583453412.908 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:10:13.197Z,1583453413.197 [WetLabsUBAT](INFO): Powering down
2020-03-06T00:10:13.705Z,1583453413.705 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-03-06T00:10:13.705Z,1583453413.705 [RDI_Pathfinder] Communications Fault, FailCount= 3
2020-03-06T00:10:13.705Z,1583453413.705 [RDI_Pathfinder](ERROR): Communications Fault
2020-03-06T00:10:13.771Z,1583453413.771 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:10:13.772Z,1583453413.772 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:10:14.181Z,1583453414.181 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:10:14.224Z,1583453414.224 [CBIT](INFO): Critical error at 20200306T001013
2020-03-06T00:10:14.225Z,1583453414.225 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-03-06T00:10:14.542Z,1583453414.542 [MissionManager](INFO): MissionManager is completed.
2020-03-06T00:10:14.542Z,1583453414.542 [MissionManager](INFO): Uninitializing Mission Science
2020-03-06T00:10:14.542Z,1583453414.542 [Science] Stopped
2020-03-06T00:10:14.542Z,1583453414.542 [Science](DEBUG): Aggregate::uninitialize Science
2020-03-06T00:10:14.542Z,1583453414.542 [Science:C] Stopped
2020-03-06T00:10:14.543Z,1583453414.543 [Science:D] Stopped
2020-03-06T00:10:14.543Z,1583453414.543 [Science:G] Stopped
2020-03-06T00:10:14.543Z,1583453414.543 [Science:H] Stopped
2020-03-06T00:10:14.946Z,1583453414.946 [MissionManager](IMPORTANT): Started mission Default
2020-03-06T00:10:14.946Z,1583453414.946 [Default] Running Loop=1
2020-03-06T00:10:14.946Z,1583453414.946 [Default](DEBUG): Aggregate::initialize Default
2020-03-06T00:10:14.946Z,1583453414.946 [Default:B.GoToSurface] Running Loop=1
2020-03-06T00:10:14.946Z,1583453414.946 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-03-06T00:10:14.946Z,1583453414.946 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-03-06T00:10:14.947Z,1583453414.947 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-03-06T00:10:14.947Z,1583453414.947 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-03-06T00:10:14.947Z,1583453414.947 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-03-06T00:10:14.948Z,1583453414.948 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-03-06T00:10:14.948Z,1583453414.948 [Default:A.Wait] Running Loop=1
2020-03-06T00:10:14.948Z,1583453414.948 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-03-06T00:10:15.093Z,1583453415.093 [WetLabsBB2FL](INFO): Powering down
2020-03-06T00:10:28.262Z,1583453428.262 [Default:A.Wait](INFO): Done Waiting.
2020-03-06T00:10:28.262Z,1583453428.262 [Default:A.Wait] Stopped
2020-03-06T00:10:28.262Z,1583453428.262 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-03-06T00:10:28.666Z,1583453428.666 [Default:CheckIn] Running Loop=1
2020-03-06T00:10:28.666Z,1583453428.666 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-03-06T00:10:28.666Z,1583453428.666 [Default:CheckIn:Read_GPS] Running Loop=1
2020-03-06T00:10:43.182Z,1583453443.182 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-03-06T00:10:44.397Z,1583453444.397 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001051.00,A,3648.16781,N,12147.27722,W,0.700,46.41,060320,,,D*48
2020-03-06T00:10:44.417Z,1583453444.417 [NAL9602](INFO): GPS fix at 20200306T001051: (36.802797, -121.787954)
2020-03-06T00:10:44.448Z,1583453444.448 [Default:CheckIn:Read_GPS] Stopped
2020-03-06T00:10:44.448Z,1583453444.448 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-03-06T00:10:44.856Z,1583453444.856 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-03-06T00:10:53.721Z,1583453453.721 [DataOverHttps](INFO): Sending 265 bytes from file Logs/20200306T000451/Courier0004.lzma
2020-03-06T00:10:55.710Z,1583453455.710 [DataOverHttps](INFO): Moved sent file to Logs/20200306T000451/Courier0004.lzma.bak
2020-03-06T00:10:55.710Z,1583453455.710 [DataOverHttps](INFO): SBD MOMSN=12298489
2020-03-06T00:11:13.453Z,1583453473.453 [DataOverHttps](INFO): Sending 180 bytes from file Logs/20200305T235139/Express0008.lzma
2020-03-06T00:11:15.458Z,1583453475.458 [DataOverHttps](INFO): Moved sent file to Logs/20200305T235139/Express0008.lzma.bak
2020-03-06T00:11:15.458Z,1583453475.458 [DataOverHttps](INFO): SBD MOMSN=12298493
2020-03-06T00:11:16.750Z,1583453476.750 [NAL9602](INFO): Not Powering down - fast GPS
2020-03-06T00:11:32.900Z,1583453492.900 [DataOverHttps](INFO): Sending 936 bytes from file Logs/20200306T000451/Express0001.lzma
2020-03-06T00:11:34.898Z,1583453494.898 [DataOverHttps](INFO): Moved sent file to Logs/20200306T000451/Express0001.lzma.bak
2020-03-06T00:11:34.898Z,1583453494.898 [DataOverHttps](INFO): SBD MOMSN=12298499
2020-03-06T00:11:53.842Z,1583453513.842 [DataOverHttps](INFO): Sending 775 bytes from file Logs/20200306T000451/Express0005.lzma
2020-03-06T00:11:55.850Z,1583453515.850 [DataOverHttps](INFO): Moved sent file to Logs/20200306T000451/Express0005.lzma.bak
2020-03-06T00:11:55.850Z,1583453515.850 [DataOverHttps](INFO): SBD MOMSN=12298522
2020-03-06T00:11:57.196Z,1583453517.196 [Default:CheckIn:Read_Iridium] Stopped
2020-03-06T00:11:57.196Z,1583453517.196 [Default:CheckIn:C.Wait] Running Loop=1
2020-03-06T00:11:57.196Z,1583453517.196 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-03-06T00:12:31.929Z,1583453551.929 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-03-06T00:12:31.932Z,1583453551.932 [BPC1](INFO): Received data from all battery sticks.
2020-03-06T00:13:13.942Z,1583453593.942 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:13:13.942Z,1583453593.942 [RDI_Pathfinder] No Fault, FailCount= 3
2020-03-06T00:13:26.887Z,1583453606.887 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2020-03-06T00:13:26.887Z,1583453606.887 [RDI_Pathfinder] Data Fault, FailCount= 1
2020-03-06T00:13:26.887Z,1583453606.887 [RDI_Pathfinder](ERROR): Data Fault
2020-03-06T00:13:26.906Z,1583453606.906 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2020-03-06T00:13:27.333Z,1583453607.333 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:13:28.098Z,1583453608.098 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:13:28.098Z,1583453608.098 [RDI_Pathfinder] No Fault, FailCount= 1
2020-03-06T00:13:40.994Z,1583453620.994 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600
2020-03-06T00:13:42.215Z,1583453622.215 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:13:43.014Z,1583453623.014 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:13:43.853Z,1583453623.853 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:13:45.045Z,1583453625.045 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:13:46.250Z,1583453626.250 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:13:47.071Z,1583453627.071 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:13:47.898Z,1583453627.898 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:13:49.075Z,1583453629.075 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:13:49.891Z,1583453629.891 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:13:50.688Z,1583453630.688 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-03-06T00:13:50.688Z,1583453630.688 [RDI_Pathfinder] Communications Fault, FailCount= 2
2020-03-06T00:13:50.688Z,1583453630.688 [RDI_Pathfinder](ERROR): Communications Fault
2020-03-06T00:13:50.738Z,1583453630.738 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:13:51.161Z,1583453631.161 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:13:51.936Z,1583453631.936 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:13:51.936Z,1583453631.936 [RDI_Pathfinder] No Fault, FailCount= 2
2020-03-06T00:14:04.832Z,1583453644.832 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600
2020-03-06T00:14:06.040Z,1583453646.040 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:14:06.861Z,1583453646.861 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:14:08.063Z,1583453648.063 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:14:08.881Z,1583453648.881 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:14:09.710Z,1583453649.710 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:14:10.892Z,1583453650.892 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:14:11.720Z,1583453651.720 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:14:12.914Z,1583453652.914 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:14:13.731Z,1583453653.731 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:14:14.523Z,1583453654.523 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-03-06T00:14:14.523Z,1583453654.523 [RDI_Pathfinder] Communications Fault, FailCount= 3
2020-03-06T00:14:14.523Z,1583453654.523 [RDI_Pathfinder](ERROR): Communications Fault
2020-03-06T00:14:14.581Z,1583453654.581 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:14:14.581Z,1583453654.581 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:14:15.005Z,1583453655.005 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:14:15.043Z,1583453655.043 [CBIT](INFO): Critical error at 20200306T001414
2020-03-06T00:16:57.766Z,1583453817.766 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-03-06T00:16:57.766Z,1583453817.766 [Default:CheckIn:C.Wait] Stopped
2020-03-06T00:16:57.766Z,1583453817.766 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-03-06T00:16:57.767Z,1583453817.767 [Default:CheckIn:D] Running Loop=1
2020-03-06T00:16:58.153Z,1583453818.153 [Default:CheckIn:D] Stopped
2020-03-06T00:16:58.153Z,1583453818.153 [Default:CheckIn:E] Running Loop=1
2020-03-06T00:16:58.565Z,1583453818.565 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.720110 min 
2020-03-06T00:16:58.565Z,1583453818.565 [Default:CheckIn:E] Stopped
2020-03-06T00:16:58.565Z,1583453818.565 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-03-06T00:16:58.565Z,1583453818.565 [Default:CheckIn] Stopped
2020-03-06T00:16:58.566Z,1583453818.566 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-03-06T00:16:58.566Z,1583453818.566 [Default:CheckIn](INFO): Running loop #2
2020-03-06T00:16:58.566Z,1583453818.566 [Default:CheckIn] Running Loop=2
2020-03-06T00:16:58.566Z,1583453818.566 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-03-06T00:16:58.566Z,1583453818.566 [Default:CheckIn:Read_GPS] Running Loop=1
2020-03-06T00:17:00.560Z,1583453820.560 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001707.00,A,3648.16895,N,12147.28274,W,0.253,46.41,060320,,,D*4C
2020-03-06T00:17:00.562Z,1583453820.562 [NAL9602](INFO): GPS fix at 20200306T001707: (36.802816, -121.788046)
2020-03-06T00:17:00.604Z,1583453820.604 [Default:CheckIn:Read_GPS] Stopped
2020-03-06T00:17:00.604Z,1583453820.604 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-03-06T00:17:08.257Z,1583453828.257 [DataOverHttps](INFO): Sending 273 bytes from file Logs/20200306T000451/Courier0007.lzma
2020-03-06T00:17:10.263Z,1583453830.263 [DataOverHttps](INFO): Moved sent file to Logs/20200306T000451/Courier0007.lzma.bak
2020-03-06T00:17:10.263Z,1583453830.263 [DataOverHttps](INFO): SBD MOMSN=12298540
2020-03-06T00:17:14.757Z,1583453834.757 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:17:14.757Z,1583453834.757 [RDI_Pathfinder] No Fault, FailCount= 3
2020-03-06T00:17:27.313Z,1583453847.313 [DataOverHttps](INFO): Sending 200 bytes from file Logs/20200306T000451/Express0008.lzma
2020-03-06T00:17:27.660Z,1583453847.660 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2020-03-06T00:17:27.660Z,1583453847.660 [RDI_Pathfinder] Data Fault, FailCount= 1
2020-03-06T00:17:27.660Z,1583453847.660 [RDI_Pathfinder](ERROR): Data Fault
2020-03-06T00:17:27.677Z,1583453847.677 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2020-03-06T00:17:28.113Z,1583453848.113 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:17:28.880Z,1583453848.880 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:17:28.880Z,1583453848.880 [RDI_Pathfinder] No Fault, FailCount= 1
2020-03-06T00:17:29.318Z,1583453849.318 [DataOverHttps](INFO): Moved sent file to Logs/20200306T000451/Express0008.lzma.bak
2020-03-06T00:17:29.318Z,1583453849.318 [DataOverHttps](INFO): SBD MOMSN=12298544
2020-03-06T00:17:30.893Z,1583453850.893 [Default:CheckIn:Read_Iridium] Stopped
2020-03-06T00:17:30.894Z,1583453850.894 [Default:CheckIn:C.Wait] Running Loop=1
2020-03-06T00:17:30.894Z,1583453850.894 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-03-06T00:17:31.258Z,1583453851.258 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-03-06T00:17:31.341Z,1583453851.341 [NAL9602](FAULT): received: 
+CSQ:1

OK
2020-03-06T00:17:31.341Z,1583453851.341 [NAL9602] Data Fault, FailCount= 1
2020-03-06T00:17:31.341Z,1583453851.341 [NAL9602](ERROR): Data Fault
2020-03-06T00:17:31.405Z,1583453851.405 [CBIT](ERROR): Data Fault in component: NAL9602
2020-03-06T00:17:31.670Z,1583453851.670 [NAL9602](INFO): Powering down
2020-03-06T00:17:32.555Z,1583453852.555 [CBIT](INFO): Clearing failed state for component NAL9602
2020-03-06T00:17:32.555Z,1583453852.555 [NAL9602] No Fault, FailCount= 1
2020-03-06T00:17:41.777Z,1583453861.777 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600
2020-03-06T00:17:43.002Z,1583453863.002 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:17:43.833Z,1583453863.833 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:17:45.414Z,1583453865.414 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:17:46.252Z,1583453866.252 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:17:46.660Z,1583453866.660 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:17:47.836Z,1583453867.836 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:17:48.648Z,1583453868.648 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:17:49.861Z,1583453869.861 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:17:50.670Z,1583453870.670 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:17:51.471Z,1583453871.471 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-03-06T00:17:51.471Z,1583453871.471 [RDI_Pathfinder] Communications Fault, FailCount= 2
2020-03-06T00:17:51.471Z,1583453871.471 [RDI_Pathfinder](ERROR): Communications Fault
2020-03-06T00:17:51.509Z,1583453871.509 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:17:51.949Z,1583453871.949 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:17:52.714Z,1583453872.714 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:17:52.714Z,1583453872.714 [RDI_Pathfinder] No Fault, FailCount= 2
2020-03-06T00:18:01.966Z,1583453881.966 [NAL9602](INFO): Powering up NAL9602
2020-03-06T00:18:05.629Z,1583453885.629 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600
2020-03-06T00:18:06.829Z,1583453886.829 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:18:07.667Z,1583453887.667 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:18:09.249Z,1583453889.249 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:18:10.118Z,1583453890.118 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:18:10.505Z,1583453890.505 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:18:11.689Z,1583453891.689 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:18:12.503Z,1583453892.503 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:18:12.875Z,1583453892.875 [NAL9602](INFO): NAL9602 initialized
2020-03-06T00:18:13.700Z,1583453893.700 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:18:14.501Z,1583453894.501 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:18:15.321Z,1583453895.321 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-03-06T00:18:15.321Z,1583453895.321 [RDI_Pathfinder] Communications Fault, FailCount= 3
2020-03-06T00:18:15.322Z,1583453895.322 [RDI_Pathfinder](ERROR): Communications Fault
2020-03-06T00:18:15.338Z,1583453895.338 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:18:15.339Z,1583453895.339 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:18:15.785Z,1583453895.785 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:18:15.807Z,1583453895.807 [CBIT](INFO): Critical error at 20200306T001815
2020-03-06T00:18:43.990Z,1583453923.990 [NAL9602](INFO): Not Powering down - fast GPS
2020-03-06T00:21:15.536Z,1583454075.536 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:21:15.537Z,1583454075.537 [RDI_Pathfinder] No Fault, FailCount= 3
2020-03-06T00:21:28.475Z,1583454088.475 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2020-03-06T00:21:28.475Z,1583454088.475 [RDI_Pathfinder] Data Fault, FailCount= 1
2020-03-06T00:21:28.475Z,1583454088.475 [RDI_Pathfinder](ERROR): Data Fault
2020-03-06T00:21:28.501Z,1583454088.501 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2020-03-06T00:21:28.929Z,1583454088.929 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:21:29.694Z,1583454089.694 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:21:29.694Z,1583454089.694 [RDI_Pathfinder] No Fault, FailCount= 1
2020-03-06T00:21:42.599Z,1583454102.599 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600
2020-03-06T00:21:43.810Z,1583454103.810 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:21:44.647Z,1583454104.647 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:21:46.230Z,1583454106.230 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:21:47.059Z,1583454107.059 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:21:47.466Z,1583454107.466 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:21:48.657Z,1583454108.657 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:21:49.461Z,1583454109.461 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:21:50.676Z,1583454110.676 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:21:51.476Z,1583454111.476 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:21:52.292Z,1583454112.292 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-03-06T00:21:52.292Z,1583454112.292 [RDI_Pathfinder] Communications Fault, FailCount= 2
2020-03-06T00:21:52.292Z,1583454112.292 [RDI_Pathfinder](ERROR): Communications Fault
2020-03-06T00:21:52.316Z,1583454112.316 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:21:52.758Z,1583454112.758 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:21:53.514Z,1583454113.514 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:21:53.514Z,1583454113.514 [RDI_Pathfinder] No Fault, FailCount= 2
2020-03-06T00:22:06.436Z,1583454126.436 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600
2020-03-06T00:22:07.641Z,1583454127.641 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:22:08.472Z,1583454128.472 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:22:10.061Z,1583454130.061 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:22:10.898Z,1583454130.898 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:22:11.302Z,1583454131.302 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:22:12.495Z,1583454132.495 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:22:13.314Z,1583454133.314 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:22:14.510Z,1583454134.510 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:22:15.327Z,1583454135.327 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:22:16.120Z,1583454136.120 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-03-06T00:22:16.120Z,1583454136.120 [RDI_Pathfinder] Communications Fault, FailCount= 3
2020-03-06T00:22:16.120Z,1583454136.120 [RDI_Pathfinder](ERROR): Communications Fault
2020-03-06T00:22:16.138Z,1583454136.138 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:22:16.138Z,1583454136.138 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:22:16.609Z,1583454136.609 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:22:16.647Z,1583454136.647 [CBIT](INFO): Critical error at 20200306T002216
2020-03-06T00:22:31.484Z,1583454151.484 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-03-06T00:22:31.484Z,1583454151.484 [Default:CheckIn:C.Wait] Stopped
2020-03-06T00:22:31.484Z,1583454151.484 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-03-06T00:22:31.501Z,1583454151.501 [Default:CheckIn:D] Running Loop=1
2020-03-06T00:22:31.890Z,1583454151.890 [Default:CheckIn:D] Stopped
2020-03-06T00:22:31.890Z,1583454151.890 [Default:CheckIn:E] Running Loop=1
2020-03-06T00:22:32.304Z,1583454152.304 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.282401 min 
2020-03-06T00:22:32.304Z,1583454152.304 [Default:CheckIn:E] Stopped
2020-03-06T00:22:32.304Z,1583454152.304 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-03-06T00:22:32.304Z,1583454152.304 [Default:CheckIn] Stopped
2020-03-06T00:22:32.304Z,1583454152.304 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-03-06T00:22:32.304Z,1583454152.304 [Default:CheckIn](INFO): Running loop #3
2020-03-06T00:22:32.305Z,1583454152.305 [Default:CheckIn] Running Loop=3
2020-03-06T00:22:32.305Z,1583454152.305 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-03-06T00:22:32.305Z,1583454152.305 [Default:CheckIn:Read_GPS] Running Loop=1
2020-03-06T00:22:34.292Z,1583454154.292 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002241.00,A,3648.16691,N,12147.28584,W,0.078,0.00,060320,,,A*73
2020-03-06T00:22:34.294Z,1583454154.294 [NAL9602](INFO): GPS fix at 20200306T002241: (36.802782, -121.788097)
2020-03-06T00:22:34.334Z,1583454154.334 [Default:CheckIn:Read_GPS] Stopped
2020-03-06T00:22:34.334Z,1583454154.334 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-03-06T00:22:36.710Z,1583454156.710 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-03-06T00:22:41.601Z,1583454161.601 [DataOverHttps](INFO): Sending 124 bytes from file Logs/20200306T000451/Courier0010.lzma
2020-03-06T00:22:43.606Z,1583454163.606 [DataOverHttps](INFO): Moved sent file to Logs/20200306T000451/Courier0010.lzma.bak
2020-03-06T00:22:43.607Z,1583454163.607 [DataOverHttps](INFO): SBD MOMSN=12298569
2020-03-06T00:23:00.569Z,1583454180.569 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20200306T000451/Express0011.lzma
2020-03-06T00:23:02.574Z,1583454182.574 [DataOverHttps](INFO): Moved sent file to Logs/20200306T000451/Express0011.lzma.bak
2020-03-06T00:23:02.574Z,1583454182.574 [DataOverHttps](INFO): SBD MOMSN=12298574
2020-03-06T00:23:04.204Z,1583454184.204 [Default:CheckIn:Read_Iridium] Stopped
2020-03-06T00:23:04.205Z,1583454184.205 [Default:CheckIn:C.Wait] Running Loop=1
2020-03-06T00:23:04.205Z,1583454184.205 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-03-06T00:23:07.430Z,1583454187.430 [NAL9602](INFO): Not Powering down - fast GPS
2020-03-06T00:25:16.320Z,1583454316.320 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:25:16.320Z,1583454316.320 [RDI_Pathfinder] No Fault, FailCount= 3
2020-03-06T00:25:29.275Z,1583454329.275 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2020-03-06T00:25:29.275Z,1583454329.275 [RDI_Pathfinder] Data Fault, FailCount= 1
2020-03-06T00:25:29.275Z,1583454329.275 [RDI_Pathfinder](ERROR): Data Fault
2020-03-06T00:25:29.302Z,1583454329.302 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2020-03-06T00:25:29.713Z,1583454329.713 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:25:30.490Z,1583454330.490 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:25:30.490Z,1583454330.490 [RDI_Pathfinder] No Fault, FailCount= 1
2020-03-06T00:25:43.379Z,1583454343.379 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600
2020-03-06T00:25:44.594Z,1583454344.594 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:25:45.419Z,1583454345.419 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:25:47.014Z,1583454347.014 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:25:47.846Z,1583454347.846 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:25:48.254Z,1583454348.254 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:25:49.446Z,1583454349.446 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:25:50.255Z,1583454350.255 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:25:51.454Z,1583454351.454 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:25:52.273Z,1583454352.273 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:25:53.068Z,1583454353.068 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-03-06T00:25:53.068Z,1583454353.068 [RDI_Pathfinder] Communications Fault, FailCount= 2
2020-03-06T00:25:53.068Z,1583454353.068 [RDI_Pathfinder](ERROR): Communications Fault
2020-03-06T00:25:53.085Z,1583454353.085 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:25:53.549Z,1583454353.549 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:25:54.317Z,1583454354.317 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:25:54.317Z,1583454354.317 [RDI_Pathfinder] No Fault, FailCount= 2
2020-03-06T00:26:07.212Z,1583454367.212 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600
2020-03-06T00:26:08.425Z,1583454368.425 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:26:09.261Z,1583454369.261 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:26:10.846Z,1583454370.846 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:26:11.685Z,1583454371.685 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:26:12.085Z,1583454372.085 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:26:13.274Z,1583454373.274 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:26:14.090Z,1583454374.090 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:26:15.307Z,1583454375.307 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:26:16.110Z,1583454376.110 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:26:16.914Z,1583454376.914 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-03-06T00:26:16.914Z,1583454376.914 [RDI_Pathfinder] Communications Fault, FailCount= 3
2020-03-06T00:26:16.914Z,1583454376.914 [RDI_Pathfinder](ERROR): Communications Fault
2020-03-06T00:26:16.935Z,1583454376.935 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:26:16.936Z,1583454376.936 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:26:17.377Z,1583454377.377 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:26:17.399Z,1583454377.399 [CBIT](INFO): Critical error at 20200306T002616
2020-03-06T00:28:04.780Z,1583454484.780 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-03-06T00:28:04.780Z,1583454484.780 [Default:CheckIn:C.Wait] Stopped
2020-03-06T00:28:04.780Z,1583454484.780 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-03-06T00:28:04.781Z,1583454484.781 [Default:CheckIn:D] Running Loop=1
2020-03-06T00:28:05.193Z,1583454485.193 [Default:CheckIn:D] Stopped
2020-03-06T00:28:05.193Z,1583454485.193 [Default:CheckIn:E] Running Loop=1
2020-03-06T00:28:05.600Z,1583454485.600 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.837445 min 
2020-03-06T00:28:05.600Z,1583454485.600 [Default:CheckIn:E] Stopped
2020-03-06T00:28:05.600Z,1583454485.600 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-03-06T00:28:05.600Z,1583454485.600 [Default:CheckIn] Stopped
2020-03-06T00:28:05.600Z,1583454485.600 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-03-06T00:28:05.600Z,1583454485.600 [Default:CheckIn](INFO): Running loop #4
2020-03-06T00:28:05.601Z,1583454485.601 [Default:CheckIn] Running Loop=4
2020-03-06T00:28:05.601Z,1583454485.601 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-03-06T00:28:05.601Z,1583454485.601 [Default:CheckIn:Read_GPS] Running Loop=1
2020-03-06T00:28:07.588Z,1583454487.588 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002814.00,A,3648.16501,N,12147.28385,W,0.389,359.59,060320,,,A*7A
2020-03-06T00:28:07.590Z,1583454487.590 [NAL9602](INFO): GPS fix at 20200306T002814: (36.802750, -121.788064)
2020-03-06T00:28:07.616Z,1583454487.616 [Default:CheckIn:Read_GPS] Stopped
2020-03-06T00:28:07.616Z,1583454487.616 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-03-06T00:28:14.653Z,1583454494.653 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200306T000451/Courier0013.lzma
2020-03-06T00:28:16.658Z,1583454496.658 [DataOverHttps](INFO): Moved sent file to Logs/20200306T000451/Courier0013.lzma.bak
2020-03-06T00:28:16.658Z,1583454496.658 [DataOverHttps](INFO): SBD MOMSN=12298580
2020-03-06T00:28:33.504Z,1583454513.504 [NAL9602](INFO): SBD MO Status=2, MOMSN=8038, MT Status=2, MTMSN=0
2020-03-06T00:28:33.504Z,1583454513.504 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-03-06T00:28:33.609Z,1583454513.609 [DataOverHttps](INFO): Sending 225 bytes from file Logs/20200306T000451/Express0014.lzma
2020-03-06T00:28:35.614Z,1583454515.614 [DataOverHttps](INFO): Moved sent file to Logs/20200306T000451/Express0014.lzma.bak
2020-03-06T00:28:35.614Z,1583454515.614 [DataOverHttps](INFO): SBD MOMSN=12298584
2020-03-06T00:28:37.177Z,1583454517.177 [Default:CheckIn:Read_Iridium] Stopped
2020-03-06T00:28:37.177Z,1583454517.177 [Default:CheckIn:C.Wait] Running Loop=1
2020-03-06T00:28:37.177Z,1583454517.177 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-03-06T00:29:17.211Z,1583454557.211 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:29:17.211Z,1583454557.211 [RDI_Pathfinder] No Fault, FailCount= 3
2020-03-06T00:29:30.101Z,1583454570.101 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2020-03-06T00:29:30.101Z,1583454570.101 [RDI_Pathfinder] Data Fault, FailCount= 1
2020-03-06T00:29:30.101Z,1583454570.101 [RDI_Pathfinder](ERROR): Data Fault
2020-03-06T00:29:30.118Z,1583454570.118 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2020-03-06T00:29:30.549Z,1583454570.549 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:29:31.309Z,1583454571.309 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:29:31.309Z,1583454571.309 [RDI_Pathfinder] No Fault, FailCount= 1
2020-03-06T00:29:44.225Z,1583454584.225 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600
2020-03-06T00:29:45.430Z,1583454585.430 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:29:46.642Z,1583454586.642 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:29:47.858Z,1583454587.858 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:29:48.687Z,1583454588.687 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:29:49.095Z,1583454589.095 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:29:50.283Z,1583454590.283 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:29:51.105Z,1583454591.105 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:29:52.306Z,1583454592.306 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:29:53.106Z,1583454593.106 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:29:53.918Z,1583454593.918 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-03-06T00:29:53.919Z,1583454593.919 [RDI_Pathfinder] Communications Fault, FailCount= 2
2020-03-06T00:29:53.919Z,1583454593.919 [RDI_Pathfinder](ERROR): Communications Fault
2020-03-06T00:29:53.937Z,1583454593.937 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:29:54.393Z,1583454594.393 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:29:55.160Z,1583454595.160 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:29:55.165Z,1583454595.165 [RDI_Pathfinder] No Fault, FailCount= 2
2020-03-06T00:30:04.404Z,1583454604.404 [NAL9602](INFO): SBD MO Status=2, MOMSN=8038, MT Status=2, MTMSN=0
2020-03-06T00:30:04.404Z,1583454604.404 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-03-06T00:30:08.062Z,1583454608.062 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600
2020-03-06T00:30:09.266Z,1583454609.266 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:30:10.107Z,1583454610.107 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:30:11.706Z,1583454611.706 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:30:12.536Z,1583454612.536 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:30:12.953Z,1583454612.953 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:30:14.118Z,1583454614.118 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:30:14.940Z,1583454614.940 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:30:16.138Z,1583454616.138 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:30:16.965Z,1583454616.965 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:30:17.750Z,1583454617.750 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-03-06T00:30:17.751Z,1583454617.751 [RDI_Pathfinder] Communications Fault, FailCount= 3
2020-03-06T00:30:17.751Z,1583454617.751 [RDI_Pathfinder](ERROR): Communications Fault
2020-03-06T00:30:17.768Z,1583454617.768 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:30:17.769Z,1583454617.769 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:30:18.229Z,1583454618.229 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:30:18.271Z,1583454618.271 [CBIT](INFO): Critical error at 20200306T003017
2020-03-06T00:30:54.099Z,1583454654.099 [NAL9602](INFO): SBD MO Status=2, MOMSN=8038, MT Status=2, MTMSN=0
2020-03-06T00:30:54.100Z,1583454654.100 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-03-06T00:32:17.728Z,1583454737.728 [NAL9602](INFO): SBD MO Status=0, MOMSN=8038, MT Status=0, MTMSN=0
2020-03-06T00:32:17.728Z,1583454737.728 [NAL9602](INFO): No messages in MT queue
2020-03-06T00:32:48.438Z,1583454768.438 [NAL9602](INFO): Not Powering down - fast GPS
2020-03-06T00:33:17.953Z,1583454797.953 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:33:17.953Z,1583454797.953 [RDI_Pathfinder] No Fault, FailCount= 3
2020-03-06T00:33:30.888Z,1583454810.888 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2020-03-06T00:33:30.888Z,1583454810.888 [RDI_Pathfinder] Data Fault, FailCount= 1
2020-03-06T00:33:30.888Z,1583454810.888 [RDI_Pathfinder](ERROR): Data Fault
2020-03-06T00:33:30.906Z,1583454810.906 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2020-03-06T00:33:31.337Z,1583454811.337 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:33:32.093Z,1583454812.093 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:33:32.093Z,1583454812.093 [RDI_Pathfinder] No Fault, FailCount= 1
2020-03-06T00:33:37.756Z,1583454817.756 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-03-06T00:33:37.756Z,1583454817.756 [Default:CheckIn:C.Wait] Stopped
2020-03-06T00:33:37.756Z,1583454817.756 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-03-06T00:33:37.757Z,1583454817.757 [Default:CheckIn:D] Running Loop=1
2020-03-06T00:33:38.148Z,1583454818.148 [Default:CheckIn:D] Stopped
2020-03-06T00:33:38.148Z,1583454818.148 [Default:CheckIn:E] Running Loop=1
2020-03-06T00:33:38.549Z,1583454818.549 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.386698 min 
2020-03-06T00:33:38.549Z,1583454818.549 [Default:CheckIn:E] Stopped
2020-03-06T00:33:38.549Z,1583454818.549 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-03-06T00:33:38.549Z,1583454818.549 [Default:CheckIn] Stopped
2020-03-06T00:33:38.549Z,1583454818.549 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-03-06T00:33:38.549Z,1583454818.549 [Default:CheckIn](INFO): Running loop #5
2020-03-06T00:33:38.549Z,1583454818.549 [Default:CheckIn] Running Loop=5
2020-03-06T00:33:38.550Z,1583454818.550 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-03-06T00:33:38.550Z,1583454818.550 [Default:CheckIn:Read_GPS] Running Loop=1
2020-03-06T00:33:40.546Z,1583454820.546 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003347.00,A,3648.16778,N,12147.28360,W,0.039,359.59,060320,,,A*79
2020-03-06T00:33:40.549Z,1583454820.549 [NAL9602](INFO): GPS fix at 20200306T003347: (36.802796, -121.788060)
2020-03-06T00:33:40.603Z,1583454820.603 [Default:CheckIn:Read_GPS] Stopped
2020-03-06T00:33:40.603Z,1583454820.603 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-03-06T00:33:45.015Z,1583454825.015 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600
2020-03-06T00:33:46.222Z,1583454826.222 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:33:47.069Z,1583454827.069 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:33:48.305Z,1583454828.305 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200306T000451/Courier0016.lzma
2020-03-06T00:33:48.656Z,1583454828.656 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:33:49.481Z,1583454829.481 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:33:49.882Z,1583454829.882 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:33:50.310Z,1583454830.310 [DataOverHttps](INFO): Moved sent file to Logs/20200306T000451/Courier0016.lzma.bak
2020-03-06T00:33:50.310Z,1583454830.310 [DataOverHttps](INFO): SBD MOMSN=12298609
2020-03-06T00:33:51.069Z,1583454831.069 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:33:51.901Z,1583454831.901 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:33:53.090Z,1583454833.090 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:33:53.905Z,1583454833.905 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:33:54.708Z,1583454834.708 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-03-06T00:33:54.709Z,1583454834.709 [RDI_Pathfinder] Communications Fault, FailCount= 2
2020-03-06T00:33:54.709Z,1583454834.709 [RDI_Pathfinder](ERROR): Communications Fault
2020-03-06T00:33:54.737Z,1583454834.737 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:33:55.185Z,1583454835.185 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:33:55.975Z,1583454835.975 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:33:55.975Z,1583454835.975 [RDI_Pathfinder] No Fault, FailCount= 2
2020-03-06T00:34:08.849Z,1583454848.849 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600
2020-03-06T00:34:09.725Z,1583454849.725 [DataOverHttps](INFO): Sending 174 bytes from file Logs/20200306T000451/Express0017.lzma
2020-03-06T00:34:10.054Z,1583454850.054 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:34:11.280Z,1583454851.280 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:34:11.730Z,1583454851.730 [DataOverHttps](INFO): Moved sent file to Logs/20200306T000451/Express0017.lzma.bak
2020-03-06T00:34:11.730Z,1583454851.730 [DataOverHttps](INFO): SBD MOMSN=12298613
2020-03-06T00:34:12.490Z,1583454852.490 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:34:13.316Z,1583454853.316 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:34:13.332Z,1583454853.332 [Default:CheckIn:Read_Iridium] Stopped
2020-03-06T00:34:13.332Z,1583454853.332 [Default:CheckIn:C.Wait] Running Loop=1
2020-03-06T00:34:13.332Z,1583454853.332 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-03-06T00:34:13.723Z,1583454853.723 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:34:14.910Z,1583454854.910 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:34:15.733Z,1583454855.733 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:34:16.934Z,1583454856.934 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:34:17.738Z,1583454857.738 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:34:18.542Z,1583454858.542 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-03-06T00:34:18.543Z,1583454858.543 [RDI_Pathfinder] Communications Fault, FailCount= 3
2020-03-06T00:34:18.543Z,1583454858.543 [RDI_Pathfinder](ERROR): Communications Fault
2020-03-06T00:34:18.569Z,1583454858.569 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:34:18.569Z,1583454858.569 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:34:19.017Z,1583454859.017 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:34:19.039Z,1583454859.039 [CBIT](INFO): Critical error at 20200306T003418
2020-03-06T00:34:28.224Z,1583454868.224 [NAL9602](INFO): SBD MO Status=2, MOMSN=8039, MT Status=2, MTMSN=0
2020-03-06T00:34:28.224Z,1583454868.224 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-03-06T00:35:04.582Z,1583454904.582 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-03-06T00:35:04.582Z,1583454904.582 [DropWeight] Hardware Fault, FailCount= 1
2020-03-06T00:35:04.582Z,1583454904.582 [DropWeight](ERROR): Hardware Fault
2020-03-06T00:35:04.612Z,1583454904.612 [CBIT](INFO): Critical error at 20200306T003504
2020-03-06T00:35:04.614Z,1583454904.614 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-03-06T00:35:04.615Z,1583454904.615 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-03-06T00:35:05.031Z,1583454905.031 [CBIT](INFO): Critical error at 20200306T003504
2020-03-06T00:37:18.756Z,1583455038.756 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:37:18.756Z,1583455038.756 [RDI_Pathfinder] No Fault, FailCount= 3
2020-03-06T00:37:31.674Z,1583455051.674 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2020-03-06T00:37:31.674Z,1583455051.674 [RDI_Pathfinder] Data Fault, FailCount= 1
2020-03-06T00:37:31.674Z,1583455051.674 [RDI_Pathfinder](ERROR): Data Fault
2020-03-06T00:37:31.692Z,1583455051.692 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2020-03-06T00:37:32.125Z,1583455052.125 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:37:32.885Z,1583455052.885 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:37:32.885Z,1583455052.885 [RDI_Pathfinder] No Fault, FailCount= 1
2020-03-06T00:37:45.813Z,1583455065.813 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600
2020-03-06T00:37:47.031Z,1583455067.031 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:37:48.225Z,1583455068.225 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:37:49.426Z,1583455069.426 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:37:50.303Z,1583455070.303 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:37:50.679Z,1583455070.679 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:37:51.850Z,1583455071.850 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:37:52.670Z,1583455072.670 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:37:53.875Z,1583455073.875 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:37:54.723Z,1583455074.723 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:37:55.496Z,1583455075.496 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-03-06T00:37:55.496Z,1583455075.496 [RDI_Pathfinder] Communications Fault, FailCount= 2
2020-03-06T00:37:55.496Z,1583455075.496 [RDI_Pathfinder](ERROR): Communications Fault
2020-03-06T00:37:55.522Z,1583455075.522 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:37:55.981Z,1583455075.981 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:37:56.769Z,1583455076.769 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:37:56.769Z,1583455076.769 [RDI_Pathfinder] No Fault, FailCount= 2
2020-03-06T00:38:09.637Z,1583455089.637 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600
2020-03-06T00:38:10.838Z,1583455090.838 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:38:11.686Z,1583455091.686 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:38:13.274Z,1583455093.274 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:38:14.109Z,1583455094.109 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:38:14.499Z,1583455094.499 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:38:15.686Z,1583455095.686 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:38:16.521Z,1583455096.521 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:38:17.721Z,1583455097.721 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:38:18.520Z,1583455098.520 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:38:19.331Z,1583455099.331 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-03-06T00:38:19.331Z,1583455099.331 [RDI_Pathfinder] Communications Fault, FailCount= 3
2020-03-06T00:38:19.331Z,1583455099.331 [RDI_Pathfinder](ERROR): Communications Fault
2020-03-06T00:38:19.349Z,1583455099.349 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:38:19.350Z,1583455099.350 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:38:19.813Z,1583455099.813 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:38:19.829Z,1583455099.829 [CBIT](INFO): Critical error at 20200306T003819
2020-03-06T00:38:42.742Z,1583455122.742 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-03-06T00:39:13.446Z,1583455153.446 [NAL9602](INFO): Not Powering down - fast GPS
2020-03-06T00:39:13.869Z,1583455153.869 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-03-06T00:39:13.869Z,1583455153.869 [Default:CheckIn:C.Wait] Stopped
2020-03-06T00:39:13.869Z,1583455153.869 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-03-06T00:39:13.870Z,1583455153.870 [Default:CheckIn:D] Running Loop=1
2020-03-06T00:39:14.284Z,1583455154.284 [Default:CheckIn:D] Stopped
2020-03-06T00:39:14.284Z,1583455154.284 [Default:CheckIn:E] Running Loop=1
2020-03-06T00:39:14.690Z,1583455154.690 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.988965 min 
2020-03-06T00:39:14.690Z,1583455154.690 [Default:CheckIn:E] Stopped
2020-03-06T00:39:14.690Z,1583455154.690 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-03-06T00:39:14.690Z,1583455154.690 [Default:CheckIn] Stopped
2020-03-06T00:39:14.690Z,1583455154.690 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-03-06T00:39:14.691Z,1583455154.691 [Default:CheckIn](INFO): Running loop #6
2020-03-06T00:39:14.691Z,1583455154.691 [Default:CheckIn] Running Loop=6
2020-03-06T00:39:14.691Z,1583455154.691 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-03-06T00:39:14.691Z,1583455154.691 [Default:CheckIn:Read_GPS] Running Loop=1
2020-03-06T00:39:16.679Z,1583455156.679 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003923.00,A,3648.17176,N,12147.28148,W,0.661,30.29,060320,,,A*40
2020-03-06T00:39:16.682Z,1583455156.682 [NAL9602](INFO): GPS fix at 20200306T003923: (36.802863, -121.788025)
2020-03-06T00:39:16.725Z,1583455156.725 [Default:CheckIn:Read_GPS] Stopped
2020-03-06T00:39:16.725Z,1583455156.725 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-03-06T00:39:24.482Z,1583455164.482 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20200306T000451/Courier0019.lzma
2020-03-06T00:39:26.486Z,1583455166.486 [DataOverHttps](INFO): Moved sent file to Logs/20200306T000451/Courier0019.lzma.bak
2020-03-06T00:39:26.486Z,1583455166.486 [DataOverHttps](INFO): SBD MOMSN=12298619
2020-03-06T00:39:45.641Z,1583455185.641 [DataOverHttps](INFO): Sending 221 bytes from file Logs/20200306T000451/Express0020.lzma
2020-03-06T00:39:47.402Z,1583455187.402 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-03-06T00:39:47.485Z,1583455187.485 [NAL9602](FAULT): received: 
+CSQ:0

OK39, 2, 0, 0, 0

OK
2020-03-06T00:39:47.485Z,1583455187.485 [NAL9602] Data Fault, FailCount= 1
2020-03-06T00:39:47.485Z,1583455187.485 [NAL9602](ERROR): Data Fault
2020-03-06T00:39:47.537Z,1583455187.537 [CBIT](ERROR): Data Fault in component: NAL9602
2020-03-06T00:39:47.646Z,1583455187.646 [DataOverHttps](INFO): Moved sent file to Logs/20200306T000451/Express0020.lzma.bak
2020-03-06T00:39:47.646Z,1583455187.646 [DataOverHttps](INFO): SBD MOMSN=12298627
2020-03-06T00:39:47.802Z,1583455187.802 [NAL9602](INFO): Powering down
2020-03-06T00:39:48.647Z,1583455188.647 [CBIT](INFO): Clearing failed state for component NAL9602
2020-03-06T00:39:48.647Z,1583455188.647 [NAL9602] No Fault, FailCount= 1
2020-03-06T00:39:49.051Z,1583455189.051 [Default:CheckIn:Read_Iridium] Stopped
2020-03-06T00:39:49.051Z,1583455189.051 [Default:CheckIn:C.Wait] Running Loop=1
2020-03-06T00:39:49.051Z,1583455189.051 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-03-06T00:40:18.108Z,1583455218.108 [NAL9602](INFO): Powering up NAL9602
2020-03-06T00:40:29.027Z,1583455229.027 [NAL9602](INFO): NAL9602 initialized
2020-03-06T00:41:00.133Z,1583455260.133 [NAL9602](INFO): Not Powering down - fast GPS
2020-03-06T00:41:19.586Z,1583455279.586 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:41:19.586Z,1583455279.586 [RDI_Pathfinder] No Fault, FailCount= 3
2020-03-06T00:41:32.503Z,1583455292.503 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2020-03-06T00:41:32.504Z,1583455292.504 [RDI_Pathfinder] Data Fault, FailCount= 1
2020-03-06T00:41:32.504Z,1583455292.504 [RDI_Pathfinder](ERROR): Data Fault
2020-03-06T00:41:32.561Z,1583455292.561 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2020-03-06T00:41:32.941Z,1583455292.941 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:41:33.709Z,1583455293.709 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:41:33.709Z,1583455293.709 [RDI_Pathfinder] No Fault, FailCount= 1
2020-03-06T00:41:46.603Z,1583455306.603 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600
2020-03-06T00:41:47.812Z,1583455307.812 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:41:48.658Z,1583455308.658 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:41:50.239Z,1583455310.239 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:41:51.078Z,1583455311.078 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:41:51.472Z,1583455311.472 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:41:52.660Z,1583455312.660 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:41:53.502Z,1583455313.502 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:41:54.689Z,1583455314.689 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:41:55.490Z,1583455315.490 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:41:56.306Z,1583455316.306 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-03-06T00:41:56.306Z,1583455316.306 [RDI_Pathfinder] Communications Fault, FailCount= 2
2020-03-06T00:41:56.306Z,1583455316.306 [RDI_Pathfinder](ERROR): Communications Fault
2020-03-06T00:41:56.330Z,1583455316.330 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:41:56.769Z,1583455316.769 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:41:57.526Z,1583455317.526 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-03-06T00:41:57.526Z,1583455317.526 [RDI_Pathfinder] No Fault, FailCount= 2
2020-03-06T00:42:10.476Z,1583455330.476 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 600
2020-03-06T00:42:11.653Z,1583455331.653 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:42:12.490Z,1583455332.490 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:42:14.085Z,1583455334.085 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:42:14.931Z,1583455334.931 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:42:15.330Z,1583455335.330 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:42:16.497Z,1583455336.497 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:42:17.314Z,1583455337.314 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:42:18.518Z,1583455338.518 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:42:19.324Z,1583455339.324 [RDI_Pathfinder](ERROR): Failed to parse:

2020-03-06T00:42:20.152Z,1583455340.152 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-03-06T00:42:20.152Z,1583455340.152 [RDI_Pathfinder] Communications Fault, FailCount= 3
2020-03-06T00:42:20.152Z,1583455340.152 [RDI_Pathfinder](ERROR): Communications Fault
2020-03-06T00:42:20.193Z,1583455340.193 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:42:20.194Z,1583455340.194 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2020-03-06T00:42:20.617Z,1583455340.617 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:42:20.651Z,1583455340.651 [CBIT](INFO): Critical error at 20200306T004220
2020-03-06T00:43:24.618Z,1583455404.618 [CommandLine](IMPORTANT): got command quit
2020-03-06T00:43:25.629Z,1583455405.629 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-03-06T00:43:25.629Z,1583455405.629 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-03-06T00:43:25.741Z,1583455405.741 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-03-06T00:43:25.741Z,1583455405.741 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-03-06T00:43:25.742Z,1583455405.742 [CommandLine](INFO): Join timeout helper Thread ID is 7332
2020-03-06T00:43:25.742Z,1583455405.742 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-03-06T00:43:25.742Z,1583455405.742 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-03-06T00:43:25.742Z,1583455405.742 [NavChartDb](INFO): Join timeout helper Thread ID is 7333
2020-03-06T00:43:25.937Z,1583455405.937 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-03-06T00:43:25.937Z,1583455405.937 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-03-06T00:43:25.941Z,1583455405.941 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2020-03-06T00:43:25.941Z,1583455405.941 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2020-03-06T00:43:25.941Z,1583455405.941 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 7334
2020-03-06T00:43:26.185Z,1583455406.185 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2020-03-06T00:43:26.186Z,1583455406.186 [WetLabsUBAT](INFO): Powering down
2020-03-06T00:43:26.186Z,1583455406.186 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2020-03-06T00:43:26.189Z,1583455406.189 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-03-06T00:43:26.189Z,1583455406.189 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-03-06T00:43:26.189Z,1583455406.189 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7335
2020-03-06T00:43:26.277Z,1583455406.277 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-03-06T00:43:26.277Z,1583455406.277 [WetLabsBB2FL](INFO): Powering down
2020-03-06T00:43:26.278Z,1583455406.278 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-03-06T00:43:26.296Z,1583455406.296 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-03-06T00:43:26.296Z,1583455406.296 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-03-06T00:43:26.296Z,1583455406.296 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7336
2020-03-06T00:43:27.089Z,1583455407.089 [CTD_Seabird](INFO): Powering down
2020-03-06T00:43:27.101Z,1583455407.101 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-03-06T00:43:27.101Z,1583455407.101 [CTD_Seabird](INFO): Powering down
2020-03-06T00:43:27.113Z,1583455407.113 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-03-06T00:43:27.122Z,1583455407.122 [ComponentRegistry](INFO): Shutting down BackSeatDriver ThreadHandler
2020-03-06T00:43:27.122Z,1583455407.122 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-03-06T00:43:27.122Z,1583455407.122 [BackSeatDriver](INFO): Join timeout helper Thread ID is 7337
2020-03-06T00:43:27.325Z,1583455407.325 [BackSeatDriver ThreadHandler](INFO): Uninitializing protected caller thread.
2020-03-06T00:43:27.325Z,1583455407.325 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-03-06T00:43:27.345Z,1583455407.345 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-03-06T00:43:27.346Z,1583455407.346 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-03-06T00:43:27.346Z,1583455407.346 [Radio_Surface](INFO): Join timeout helper Thread ID is 7338
2020-03-06T00:43:27.701Z,1583455407.701 [Radio_Surface](INFO): Powering down
2020-03-06T00:43:27.702Z,1583455407.702 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-03-06T00:43:27.702Z,1583455407.702 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-03-06T00:43:27.709Z,1583455407.709 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-03-06T00:43:27.710Z,1583455407.710 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-03-06T00:43:27.710Z,1583455407.710 [DataOverHttps](INFO): Join timeout helper Thread ID is 7339
2020-03-06T00:43:28.121Z,1583455408.121 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-03-06T00:43:28.122Z,1583455408.122 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-03-06T00:43:28.137Z,1583455408.137 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-03-06T00:43:28.138Z,1583455408.138 [logger ThreadHandler](INFO): Thread cancelled.
2020-03-06T00:43:28.138Z,1583455408.138 [logger](INFO): Join timeout helper Thread ID is 7340
2020-03-06T00:43:28.165Z,1583455408.165 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-03-06T00:43:28.165Z,1583455408.165 [logger ThreadHandler](INFO): Thread cancelled.
2020-03-06T00:43:28.177Z,1583455408.177 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-03-06T00:43:28.178Z,1583455408.178 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-03-06T00:43:28.178Z,1583455408.178 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-03-06T00:43:28.178Z,1583455408.178 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-03-06T00:43:28.178Z,1583455408.178 [controlThread](INFO): Join timeout helper Thread ID is 7341
2020-03-06T00:43:28.377Z,1583455408.377 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-03-06T00:43:28.377Z,1583455408.377 [controlThread](DEBUG): Uninitializing ControlThread
2020-03-06T00:43:28.377Z,1583455408.377 [AHRS_M2](INFO): Powering down
2020-03-06T00:43:28.449Z,1583455408.449 [NAL9602](INFO): Powering down
2020-03-06T00:43:28.451Z,1583455408.451 [RDI_Pathfinder](INFO): Powering down
2020-03-06T00:43:28.451Z,1583455408.451 [DAT](INFO): Powering down
2020-03-06T00:43:28.569Z,1583455408.569 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-03-06T00:43:28.570Z,1583455408.570 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-03-06T00:43:28.571Z,1583455408.571 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-03-06T00:43:28.571Z,1583455408.571 [MissionManager](INFO): Uninitializing Mission Default
2020-03-06T00:43:28.571Z,1583455408.571 [Default] Stopped
2020-03-06T00:43:28.572Z,1583455408.572 [Default](DEBUG): Aggregate::uninitialize Default
2020-03-06T00:43:28.572Z,1583455408.572 [Default:B.GoToSurface] Stopped
2020-03-06T00:43:28.572Z,1583455408.572 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-03-06T00:43:28.572Z,1583455408.572 [Default:CheckIn] Stopped
2020-03-06T00:43:28.572Z,1583455408.572 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-03-06T00:43:28.572Z,1583455408.572 [Default:CheckIn:C.Wait] Stopped
2020-03-06T00:43:28.572Z,1583455408.572 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-03-06T00:43:28.574Z,1583455408.574 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-03-06T00:43:28.575Z,1583455408.575 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-03-06T00:43:28.575Z,1583455408.575 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-03-06T00:43:28.575Z,1583455408.575 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-03-06T00:43:28.575Z,1583455408.575 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-03-06T00:43:28.576Z,1583455408.576 [BuoyancyServo](INFO): Powering down
2020-03-06T00:43:28.589Z,1583455408.589 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-03-06T00:43:28.589Z,1583455408.589 [ElevatorServo](INFO): Powering down
2020-03-06T00:43:28.590Z,1583455408.590 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-03-06T00:43:28.590Z,1583455408.590 [MassServo](INFO): Powering down
2020-03-06T00:43:28.591Z,1583455408.591 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-03-06T00:43:28.591Z,1583455408.591 [RudderServo](INFO): Powering down
2020-03-06T00:43:28.592Z,1583455408.592 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-03-06T00:43:28.592Z,1583455408.592 [ThrusterServo](INFO): Powering down
2020-03-06T00:43:28.592Z,1583455408.592 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-03-06T00:43:28.593Z,1583455408.593 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-03-06T00:43:28.593Z,1583455408.593 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-03-06T00:43:28.593Z,1583455408.593 [CBIT](DEBUG): Powering off loads.
2020-03-06T00:43:28.605Z,1583455408.605 [CBIT](DEBUG): Disabling WDT.
2020-03-06T00:43:28.617Z,1583455408.617 [CBIT](DEBUG): Opening all GF detection circuits.
2020-03-06T00:43:28.618Z,1583455408.618 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-03-06T00:43:28.714Z,1583455408.714 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-03-06T00:43:28.720Z,1583455408.720 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-03-06T00:43:28.774Z,1583455408.774 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2020-03-06T00:43:28.775Z,1583455408.775 [WetLabsUBAT](INFO): WetLabs destructor: dataLog is open - close it
2020-03-06T00:43:28.775Z,1583455408.775 [WetLabsUBAT](INFO): WetLabs destructor: done
2020-03-06T00:43:28.778Z,1583455408.778 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-03-06T00:43:28.781Z,1583455408.781 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-03-06T00:43:28.783Z,1583455408.783 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-03-06T00:43:28.793Z,1583455408.793 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-03-06T00:43:28.864Z,1583455408.864 [logger ThreadHandler](INFO): Thread cancelled.