2019-04-04T16:57:37.502Z,1554397057.502 [Supervisor](DEBUG): Initializing supervisor.
2019-04-04T16:57:37.505Z,1554397057.505 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-04T16:57:37.505Z,1554397057.505 [SyncHandler](INFO): Protected caller Thread ID is 9669
2019-04-04T16:57:37.506Z,1554397057.506 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-04-04T16:57:37.507Z,1554397057.507 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-04-04T16:57:37.507Z,1554397057.507 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9670
2019-04-04T16:57:37.510Z,1554397057.510 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-04-04T16:57:37.522Z,1554397057.522 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-04-04T16:57:37.523Z,1554397057.523 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-04-04T16:57:37.524Z,1554397057.524 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9671
2019-04-04T16:57:37.524Z,1554397057.524 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-04-04T16:57:37.525Z,1554397057.525 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-04-04T16:57:37.526Z,1554397057.526 [logger ThreadHandler](INFO): Protected caller Thread ID is 9672
2019-04-04T16:57:37.528Z,1554397057.528 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-04-04T16:57:37.528Z,1554397057.528 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-04-04T16:57:37.530Z,1554397057.530 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-04-04T16:57:37.957Z,1554397057.957 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-04-04T16:57:37.957Z,1554397057.957 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-04-04T16:57:38.103Z,1554397058.103 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-04-04T16:57:38.104Z,1554397058.104 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-04-04T16:57:38.186Z,1554397058.186 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-04-04T16:57:38.286Z,1554397058.286 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-04-04T16:57:38.286Z,1554397058.286 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-04-04T16:57:38.383Z,1554397058.383 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-04-04T16:57:38.384Z,1554397058.384 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-04-04T16:57:38.772Z,1554397058.772 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-04-04T16:57:38.772Z,1554397058.772 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-04-04T16:57:38.876Z,1554397058.876 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-04-04T16:57:38.877Z,1554397058.877 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-04-04T16:57:39.644Z,1554397059.644 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-04-04T16:57:39.644Z,1554397059.644 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-04-04T16:57:39.850Z,1554397059.850 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-04-04T16:57:39.851Z,1554397059.851 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-04-04T16:57:40.162Z,1554397060.162 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-04-04T16:57:40.163Z,1554397060.163 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-04-04T16:57:40.379Z,1554397060.379 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-04-04T16:57:40.380Z,1554397060.380 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-04-04T16:57:40.524Z,1554397060.524 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-04-04T16:57:40.525Z,1554397060.525 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-04-04T16:57:41.031Z,1554397061.031 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-04-04T16:57:41.031Z,1554397061.031 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-04-04T16:57:41.373Z,1554397061.373 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-04-04T16:57:41.374Z,1554397061.374 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-04-04T16:57:41.583Z,1554397061.583 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-04-04T16:57:41.585Z,1554397061.585 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2019-04-04T16:57:41.586Z,1554397061.586 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2019-04-04T16:57:41.770Z,1554397061.770 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2019-04-04T16:57:41.854Z,1554397061.854 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2019-04-04T16:57:41.971Z,1554397061.971 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2019-04-04T16:57:42.058Z,1554397062.058 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2019-04-04T16:57:42.285Z,1554397062.285 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-04-04T16:57:42.285Z,1554397062.285 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2019-04-04T16:57:42.382Z,1554397062.382 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2019-04-04T16:57:42.493Z,1554397062.493 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2019-04-04T16:57:42.589Z,1554397062.589 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2019-04-04T16:57:42.743Z,1554397062.743 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2019-04-04T16:57:42.845Z,1554397062.845 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2019-04-04T16:57:42.948Z,1554397062.948 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2019-04-04T16:57:42.948Z,1554397062.948 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-04-04T16:57:42.958Z,1554397062.958 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-04-04T16:57:42.972Z,1554397062.972 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-04-04T16:57:42.972Z,1554397062.972 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-04-04T16:57:43.036Z,1554397063.036 [VerticalControl](DEBUG): Construct VerticalControl.
2019-04-04T16:57:43.147Z,1554397063.147 [VerticalControl] Loaded
2019-04-04T16:57:43.147Z,1554397063.147 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-04-04T16:57:43.148Z,1554397063.148 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-04-04T16:57:43.215Z,1554397063.215 [HorizontalControl] Loaded
2019-04-04T16:57:43.216Z,1554397063.216 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-04-04T16:57:43.216Z,1554397063.216 [SpeedControl](DEBUG): Construct SpeedControl.
2019-04-04T16:57:43.222Z,1554397063.222 [SpeedControl] Loaded
2019-04-04T16:57:43.222Z,1554397063.222 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-04-04T16:57:43.223Z,1554397063.223 [LoopControl](DEBUG): Construct LoopControl.
2019-04-04T16:57:43.223Z,1554397063.223 [LoopControl] Loaded
2019-04-04T16:57:43.224Z,1554397063.224 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-04-04T16:57:43.224Z,1554397063.224 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-04-04T16:57:43.225Z,1554397063.225 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-04-04T16:57:43.339Z,1554397063.339 [BuoyancyServo] Loaded
2019-04-04T16:57:43.339Z,1554397063.339 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-04-04T16:57:43.350Z,1554397063.350 [ElevatorServo] Loaded
2019-04-04T16:57:43.351Z,1554397063.351 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-04-04T16:57:43.362Z,1554397063.362 [MassServo] Loaded
2019-04-04T16:57:43.362Z,1554397063.362 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-04-04T16:57:43.373Z,1554397063.373 [ThrusterServo] Loaded
2019-04-04T16:57:43.373Z,1554397063.373 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-04-04T16:57:43.374Z,1554397063.374 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-04-04T16:57:43.374Z,1554397063.374 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-04-04T16:57:43.413Z,1554397063.413 [DepthRateCalculator] Loaded
2019-04-04T16:57:43.413Z,1554397063.413 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-04-04T16:57:43.418Z,1554397063.418 [PitchRateCalculator] Loaded
2019-04-04T16:57:43.419Z,1554397063.419 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-04-04T16:57:43.433Z,1554397063.433 [SpeedCalculator] Loaded
2019-04-04T16:57:43.434Z,1554397063.434 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-04-04T16:57:43.455Z,1554397063.455 [TempGradientCalculator] Loaded
2019-04-04T16:57:43.455Z,1554397063.455 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-04-04T16:57:43.460Z,1554397063.460 [YawRateCalculator] Loaded
2019-04-04T16:57:43.460Z,1554397063.460 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-04-04T16:57:43.490Z,1554397063.490 [ElevatorOffsetCalculator] Loaded
2019-04-04T16:57:43.490Z,1554397063.490 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-04-04T16:57:43.490Z,1554397063.490 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-04-04T16:57:43.491Z,1554397063.491 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-04-04T16:57:43.593Z,1554397063.593 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-04-04T16:57:43.594Z,1554397063.594 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-04-04T16:57:43.652Z,1554397063.652 [DeadReckonUsingSpeedCalculator] Loaded
2019-04-04T16:57:43.652Z,1554397063.652 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-04-04T16:57:43.673Z,1554397063.673 [NavChart] Loaded
2019-04-04T16:57:43.673Z,1554397063.673 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-04-04T16:57:43.677Z,1554397063.677 [UniversalFixResidualReporter] Loaded
2019-04-04T16:57:43.677Z,1554397063.677 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-04-04T16:57:43.678Z,1554397063.678 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-04-04T16:57:43.678Z,1554397063.678 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-04-04T16:57:43.983Z,1554397063.983 [DataOverHttps] Loaded
2019-04-04T16:57:43.983Z,1554397063.983 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-04-04T16:57:43.984Z,1554397063.984 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408004E0
2019-04-04T16:57:43.985Z,1554397063.985 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9751
2019-04-04T16:57:43.998Z,1554397063.998 [Depth_Keller] Loaded
2019-04-04T16:57:43.998Z,1554397063.998 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-04-04T16:57:44.094Z,1554397064.094 [NAL9602] Loaded
2019-04-04T16:57:44.094Z,1554397064.094 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-04-04T16:57:44.110Z,1554397064.110 [Onboard] Loaded
2019-04-04T16:57:44.110Z,1554397064.110 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-04-04T16:57:44.114Z,1554397064.114 [Radio_Surface] Loaded
2019-04-04T16:57:44.114Z,1554397064.114 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-04-04T16:57:44.115Z,1554397064.115 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408304E0
2019-04-04T16:57:44.115Z,1554397064.115 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9752
2019-04-04T16:57:44.116Z,1554397064.116 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-04-04T16:57:44.117Z,1554397064.117 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-04-04T16:57:44.178Z,1554397064.178 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-04-04T16:57:44.179Z,1554397064.179 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-04-04T16:57:44.269Z,1554397064.269 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-04-04T16:57:44.269Z,1554397064.269 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-04-04T16:57:44.295Z,1554397064.295 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-04-04T16:57:44.296Z,1554397064.296 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-04-04T16:57:44.535Z,1554397064.535 [CTD_NeilBrown] Loaded
2019-04-04T16:57:44.535Z,1554397064.535 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-04-04T16:57:44.536Z,1554397064.536 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409BD4E0
2019-04-04T16:57:44.537Z,1554397064.537 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 9753
2019-04-04T16:57:44.546Z,1554397064.546 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-04-04T16:57:44.551Z,1554397064.551 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-04-04T16:57:44.552Z,1554397064.552 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-04-04T16:57:44.557Z,1554397064.557 [CTD_Seabird](INFO): created writer for : depth
2019-04-04T16:57:44.558Z,1554397064.558 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-04-04T16:57:44.563Z,1554397064.563 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-04-04T16:57:44.563Z,1554397064.563 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-04-04T16:57:44.568Z,1554397064.568 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-04-04T16:57:44.568Z,1554397064.568 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-04-04T16:57:44.573Z,1554397064.573 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-04-04T16:57:44.574Z,1554397064.574 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-04-04T16:57:44.579Z,1554397064.579 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-04-04T16:57:44.579Z,1554397064.579 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-04-04T16:57:44.584Z,1554397064.584 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-04-04T16:57:44.611Z,1554397064.611 [CTD_Seabird] Loaded
2019-04-04T16:57:44.611Z,1554397064.611 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-04-04T16:57:44.612Z,1554397064.612 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409ED4E0
2019-04-04T16:57:44.613Z,1554397064.613 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 9754
2019-04-04T16:57:44.660Z,1554397064.660 [WetLabsBB2FL] Loaded
2019-04-04T16:57:44.661Z,1554397064.661 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-04-04T16:57:44.662Z,1554397064.662 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A1D4E0
2019-04-04T16:57:44.662Z,1554397064.662 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9755
2019-04-04T16:57:44.663Z,1554397064.663 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-04-04T16:57:44.663Z,1554397064.663 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-04-04T16:57:45.255Z,1554397065.255 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-04-04T16:57:45.256Z,1554397065.256 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-04-04T16:57:45.523Z,1554397065.523 [SBIT](DEBUG): Construct Startup Built In Test.
2019-04-04T16:57:45.546Z,1554397065.546 [SBIT] Loaded
2019-04-04T16:57:45.547Z,1554397065.547 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-04-04T16:57:45.547Z,1554397065.547 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-04-04T16:57:45.588Z,1554397065.588 [IBIT] Loaded
2019-04-04T16:57:45.588Z,1554397065.588 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-04-04T16:57:45.591Z,1554397065.591 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-04-04T16:57:45.974Z,1554397065.974 [CBIT] Loaded
2019-04-04T16:57:45.974Z,1554397065.974 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-04-04T16:57:45.975Z,1554397065.975 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-04-04T16:57:45.979Z,1554397065.979 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-04-04T16:57:45.979Z,1554397065.979 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-04-04T16:57:45.986Z,1554397065.986 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-04-04T16:57:45.987Z,1554397065.987 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AF54E0
2019-04-04T16:57:45.987Z,1554397065.987 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9756
2019-04-04T16:57:45.992Z,1554397065.992 [Supervisor](INFO): Main Thread ID is 9550
2019-04-04T16:57:45.992Z,1554397065.992 [Supervisor](DEBUG): Running supervisor.
2019-04-04T16:57:45.993Z,1554397065.993 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9757
2019-04-04T16:57:45.995Z,1554397065.995 [controlThread ThreadHandler](INFO): Handler Thread ID is 9758
2019-04-04T16:57:45.996Z,1554397065.996 [controlThread](DEBUG): Initializing ControlThread
2019-04-04T16:57:45.996Z,1554397065.996 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-04-04T16:57:45.998Z,1554397065.998 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-04-04T16:57:45.999Z,1554397065.999 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-04-04T16:57:45.999Z,1554397065.999 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-04-04T16:57:46.000Z,1554397066.000 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-04-04T16:57:46.001Z,1554397066.001 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-04-04T16:57:46.001Z,1554397066.001 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-04-04T16:57:46.002Z,1554397066.001 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-04-04T16:57:46.002Z,1554397066.002 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-04-04T16:57:46.002Z,1554397066.002 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-04-04T16:57:46.004Z,1554397066.004 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-04T16:57:46.004Z,1554397066.004 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-04-04T16:57:46.004Z,1554397066.004 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-04-04T16:57:46.010Z,1554397066.010 [SBIT](INFO): Initialize SBIT Component.
2019-04-04T16:57:46.010Z,1554397066.010 [SBIT](IMPORTANT): git: 2019-03-18-28-g15bcb3d
2019-04-04T16:57:46.010Z,1554397066.010 [SBIT](INFO): git hash: 15bcb3d0ddea981cd14ea88bd1a5b5086b5d5606
2019-04-04T16:57:46.011Z,1554397066.011 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-04-04T16:57:46.012Z,1554397066.012 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
2019-04-04T16:57:46.013Z,1554397066.013 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-04-04T16:57:46.013Z,1554397066.013 [IBIT](INFO): Initialize IBIT Component.
2019-04-04T16:57:46.014Z,1554397066.014 [CBIT](DEBUG): Initialize CBIT Component.
2019-04-04T16:57:46.015Z,1554397066.015 [logger ThreadHandler](INFO): Handler Thread ID is 9759
2019-04-04T16:57:46.025Z,1554397066.025 [CBIT](DEBUG): Initialized mux pins.
2019-04-04T16:57:46.025Z,1554397066.025 [CBIT](DEBUG): Initializing the watchdog timer.
2019-04-04T16:57:46.033Z,1554397066.033 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9760
2019-04-04T16:57:46.034Z,1554397066.034 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-04-04T16:57:46.045Z,1554397066.045 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9761
2019-04-04T16:57:46.049Z,1554397066.049 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-04-04T16:57:46.049Z,1554397066.049 [CBIT](DEBUG): Initializing heartbeat.
2019-04-04T16:57:46.057Z,1554397066.057 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 9762
2019-04-04T16:57:46.065Z,1554397066.065 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 9763
2019-04-04T16:57:46.066Z,1554397066.066 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-04T16:57:46.069Z,1554397066.069 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-04T16:57:46.070Z,1554397066.070 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9765
2019-04-04T16:57:46.072Z,1554397066.072 [WetLabsBB2FL](INFO): Powering down
2019-04-04T16:57:46.105Z,1554397066.105 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9766
2019-04-04T16:57:46.108Z,1554397066.108 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-04T16:57:46.109Z,1554397066.109 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-04-04T16:57:46.109Z,1554397066.109 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-04T16:57:46.109Z,1554397066.109 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-04-04T16:57:46.109Z,1554397066.109 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-04T16:57:46.109Z,1554397066.109 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-04-04T16:57:46.110Z,1554397066.110 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-04T16:57:46.110Z,1554397066.110 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-04-04T16:57:46.110Z,1554397066.110 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-04T16:57:46.110Z,1554397066.110 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-04-04T16:57:46.110Z,1554397066.110 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-04T16:57:46.111Z,1554397066.111 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-04-04T16:57:46.111Z,1554397066.111 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-04T16:57:46.111Z,1554397066.111 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-04-04T16:57:46.111Z,1554397066.111 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-04T16:57:46.111Z,1554397066.111 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-04-04T16:57:46.121Z,1554397066.121 [CBIT](DEBUG): Deactivating GF circuits.
2019-04-04T16:57:46.121Z,1554397066.121 [CBIT](DEBUG): Deactivating emergency mode.
2019-04-04T16:57:46.157Z,1554397066.157 [CBIT](DEBUG): Backplane powered.
2019-04-04T16:57:46.158Z,1554397066.158 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-04-04T16:57:46.167Z,1554397066.167 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-04T16:57:46.178Z,1554397066.178 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2019-04-04T16:57:46.179Z,1554397066.179 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-04-04T16:57:46.257Z,1554397066.257 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-04-04T16:57:46.258Z,1554397066.258 [Default:A.Wait](DEBUG): Construct Wait.
2019-04-04T16:57:46.260Z,1554397066.260 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-04T16:57:46.287Z,1554397066.287 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-04-04T16:57:46.289Z,1554397066.289 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-04-04T16:57:46.311Z,1554397066.311 [Default:E.Execute](DEBUG): Construct Execute.
2019-04-04T16:57:46.314Z,1554397066.314 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2019-04-04T16:57:46.335Z,1554397066.335 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,NAL9602,Onboard,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-04-04T16:57:46.356Z,1554397066.356 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar
2019-04-04T16:57:46.401Z,1554397066.401 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-04-04T16:57:46.427Z,1554397066.427 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-04-04T16:57:46.433Z,1554397066.433 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-04-04T16:57:46.434Z,1554397066.434 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-04T16:57:46.449Z,1554397066.449 [Radio_Surface](INFO): Powering up
2019-04-04T16:57:46.450Z,1554397066.450 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-04T16:57:46.451Z,1554397066.451 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-04T16:57:46.457Z,1554397066.457 [MassServo](DEBUG): Initializing MassServo.
2019-04-04T16:57:46.458Z,1554397066.458 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-04-04T16:57:46.465Z,1554397066.465 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-04-04T16:57:59.117Z,1554397079.117 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-04T16:57:59.117Z,1554397079.117 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-04T16:57:59.117Z,1554397079.117 [CTD_Seabird](ERROR): Communications Fault
2019-04-04T16:57:59.321Z,1554397079.321 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-04T16:57:59.521Z,1554397079.521 [CTD_Seabird](INFO): Powering down
2019-04-04T16:58:00.613Z,1554397080.613 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-04T16:58:00.613Z,1554397080.613 [CTD_Seabird] No Fault, FailCount= 1
2019-04-04T16:58:00.885Z,1554397080.885 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-04T16:58:00.889Z,1554397080.889 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-04T16:58:13.937Z,1554397093.937 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-04T16:58:13.937Z,1554397093.937 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-04T16:58:13.937Z,1554397093.937 [CTD_Seabird](ERROR): Communications Fault
2019-04-04T16:58:14.218Z,1554397094.218 [NAL9602](INFO): Powering up NAL9602
2019-04-04T16:58:14.266Z,1554397094.266 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-04T16:58:14.341Z,1554397094.341 [CTD_Seabird](INFO): Powering down
2019-04-04T16:58:14.646Z,1554397094.646 [SBIT](IMPORTANT): Beginning Startup BIT
2019-04-04T16:58:14.650Z,1554397094.650 [CBIT](IMPORTANT): Beginning ground fault scan
2019-04-04T16:58:15.606Z,1554397095.606 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-04T16:58:15.606Z,1554397095.606 [CTD_Seabird] No Fault, FailCount= 2
2019-04-04T16:58:15.685Z,1554397095.685 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-04T16:58:15.693Z,1554397095.693 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-04T16:58:25.262Z,1554397105.262 [NAL9602](INFO): NAL9602 initialized
2019-04-04T16:58:25.760Z,1554397105.760 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.018270
CHAN A1 (24V): 0.001257
CHAN A2 (12V): -0.067629
CHAN A3 (5V): -0.002248
CHAN B0 (3.3V): -0.001346
CHAN B1 (3.15aV): -0.000984
CHAN B2 (3.15bV): -0.000710
CHAN B3 (GND): -0.000088
OPEN: -0.001313
Full Scale Calc: 4.765 mA, -1.589 mA
2019-04-04T16:58:26.073Z,1554397106.073 [NAL9602](DEBUG): Fix Requested
2019-04-04T16:58:27.743Z,1554397107.743 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-04-04T16:58:28.737Z,1554397108.737 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-04T16:58:28.737Z,1554397108.737 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-04T16:58:28.737Z,1554397108.737 [CTD_Seabird](ERROR): Communications Fault
2019-04-04T16:58:28.953Z,1554397108.953 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-04T16:58:28.953Z,1554397108.953 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-04T16:58:29.141Z,1554397109.141 [CTD_Seabird](INFO): Powering down
2019-04-04T16:58:54.423Z,1554397134.423 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-04-04T16:58:54.423Z,1554397134.423 [SBIT](FAULT): Control surface position failure.
2019-04-04T16:59:08.194Z,1554397148.194 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-04-04T16:59:08.194Z,1554397148.194 [SBIT](FAULT): Control surface position failure.
2019-04-04T16:59:08.598Z,1554397148.598 [SBIT](CRITICAL): SBIT FAILED
2019-04-04T16:59:08.613Z,1554397148.613 [CommandLine](FAULT): Scheduling is paused
2019-04-04T16:59:08.614Z,1554397148.614 [CBIT](INFO): Critical error at 20190404T165908
2019-04-04T16:59:08.614Z,1554397148.614 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-04-04T16:59:08.642Z,1554397148.642 [CommandLine](IMPORTANT): got command configSet list
2019-04-04T16:59:08.643Z,1554397148.643 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-04-04T16:59:08.643Z,1554397148.643 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-04-04T16:59:08.644Z,1554397148.644 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval=40 minute;

2019-04-04T16:59:08.644Z,1554397148.644 [CommandLine](IMPORTANT): BPC1.batteryStickCommsTimeout=6 hour;

2019-04-04T16:59:08.644Z,1554397148.644 [CommandLine](IMPORTANT): BPC1.loadAtStartup=0 bool;

2019-04-04T16:59:08.644Z,1554397148.644 [CommandLine](IMPORTANT): CBIT.vehicleOpen=1 bool;

2019-04-04T16:59:08.644Z,1554397148.644 [CommandLine](IMPORTANT): Depth_Keller.offset=-0.13 decibar;

2019-04-04T16:59:08.644Z,1554397148.644 [CommandLine](IMPORTANT): Depth_Keller.scale=45.92 micropascal;

2019-04-04T16:59:08.644Z,1554397148.644 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-04-04T16:59:08.644Z,1554397148.644 [CommandLine](IMPORTANT): RudderServo.loadAtStartup=0 bool;

2019-04-04T16:59:08.644Z,1554397148.644 [CommandLine](IMPORTANT): VerticalControl.buoyancyLimitHiCC=995 cubic_centimeter;

2019-04-04T16:59:08.645Z,1554397148.645 [CommandLine](IMPORTANT): VerticalControl.buoyancyPumpDepth=80 meter;

2019-04-04T16:59:09.003Z,1554397149.003 [MissionManager](IMPORTANT): Started mission Startup
2019-04-04T16:59:09.003Z,1554397149.003 [Startup] Running Loop=1
2019-04-04T16:59:09.003Z,1554397149.003 [Startup](DEBUG): Aggregate::initialize Startup
2019-04-04T16:59:09.003Z,1554397149.003 [Startup:A.GoToSurface] Running Loop=1
2019-04-04T16:59:09.003Z,1554397149.003 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-04T16:59:09.004Z,1554397149.004 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-04T16:59:09.005Z,1554397149.005 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-04T16:59:09.005Z,1554397149.005 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-04T16:59:09.005Z,1554397149.005 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-04T16:59:09.006Z,1554397149.006 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-04T16:59:09.007Z,1554397149.007 [Startup:StartupSatComms] Running Loop=1
2019-04-04T16:59:09.007Z,1554397149.007 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-04-04T16:59:09.007Z,1554397149.007 [Startup:StartupSatComms:A] Running Loop=1
2019-04-04T16:59:09.408Z,1554397149.408 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-04-04T16:59:46.164Z,1554397186.164 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-04T16:59:46.164Z,1554397186.164 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-04-04T16:59:46.164Z,1554397186.164 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-04T16:59:46.166Z,1554397186.166 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-04T16:59:46.166Z,1554397186.166 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-04-04T16:59:46.166Z,1554397186.166 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-04T16:59:46.176Z,1554397186.176 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-04T16:59:46.176Z,1554397186.176 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-04T16:59:46.588Z,1554397186.588 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-04T16:59:46.588Z,1554397186.588 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-04-04T16:59:46.589Z,1554397186.589 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-04T16:59:46.589Z,1554397186.589 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-04-04T16:59:46.983Z,1554397186.983 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-04T17:00:09.194Z,1554397209.194 [Startup:StartupSatComms:A](INFO): Timed out from 2019-04-04T16:59:09.0Z
2019-04-04T17:00:09.194Z,1554397209.194 [Startup:StartupSatComms:A] Stopped
2019-04-04T17:00:09.194Z,1554397209.194 [Startup:StartupSatComms:B] Running Loop=1
2019-04-04T17:00:09.598Z,1554397209.598 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-04T17:00:21.379Z,1554397221.379 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004874
2019-04-04T17:00:34.300Z,1554397234.300 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190403T213250/Courier0043.lzma
2019-04-04T17:00:35.107Z,1554397235.107 [DataOverHttps](INFO): Moved sent file to Logs/20190403T213250/Courier0043.lzma.bak
2019-04-04T17:00:35.107Z,1554397235.107 [DataOverHttps](INFO): SBD MOMSN=10438081
2019-04-04T17:00:58.890Z,1554397258.890 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-04T17:00:58.890Z,1554397258.890 [CTD_Seabird] No Fault, FailCount= 3
2019-04-04T17:00:59.161Z,1554397259.161 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-04T17:00:59.163Z,1554397259.163 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-04T17:01:09.389Z,1554397269.389 [Startup:StartupSatComms:B](INFO): Timed out from 2019-04-04T17:00:09.2Z
2019-04-04T17:01:09.389Z,1554397269.389 [Startup:StartupSatComms:B] Stopped
2019-04-04T17:01:09.389Z,1554397269.389 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-04-04T17:01:09.390Z,1554397269.390 [Startup:StartupSatComms] Stopped
2019-04-04T17:01:09.390Z,1554397269.390 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-04-04T17:01:09.390Z,1554397269.390 [Startup](INFO): Completed Startup
2019-04-04T17:01:09.391Z,1554397269.391 [MissionManager](INFO): Startup is completed.
2019-04-04T17:01:09.391Z,1554397269.391 [MissionManager](INFO): Uninitializing Mission Startup
2019-04-04T17:01:09.391Z,1554397269.391 [Startup] Stopped
2019-04-04T17:01:09.391Z,1554397269.391 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-04-04T17:01:09.391Z,1554397269.391 [Startup:A.GoToSurface] Stopped
2019-04-04T17:01:09.391Z,1554397269.391 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-04T17:01:09.797Z,1554397269.797 [MissionManager](IMPORTANT): Started mission Default
2019-04-04T17:01:09.798Z,1554397269.798 [Default] Running Loop=1
2019-04-04T17:01:09.798Z,1554397269.798 [Default](DEBUG): Aggregate::initialize Default
2019-04-04T17:01:09.798Z,1554397269.798 [Default:B.GoToSurface] Running Loop=1
2019-04-04T17:01:09.798Z,1554397269.798 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-04T17:01:09.798Z,1554397269.798 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-04T17:01:09.798Z,1554397269.798 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-04T17:01:09.799Z,1554397269.799 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-04T17:01:09.799Z,1554397269.799 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-04T17:01:09.799Z,1554397269.799 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-04T17:01:09.800Z,1554397269.800 [Default:A.Wait] Running Loop=1
2019-04-04T17:01:09.800Z,1554397269.800 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-04-04T17:01:12.213Z,1554397272.213 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-04T17:01:12.213Z,1554397272.213 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-04T17:01:12.213Z,1554397272.213 [CTD_Seabird](ERROR): Communications Fault
2019-04-04T17:01:12.248Z,1554397272.248 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-04T17:01:12.617Z,1554397272.617 [CTD_Seabird](INFO): Powering down
2019-04-04T17:01:13.837Z,1554397273.837 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-04T17:01:13.837Z,1554397273.837 [CTD_Seabird] No Fault, FailCount= 1
2019-04-04T17:01:13.961Z,1554397273.961 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-04T17:01:13.969Z,1554397273.969 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-04T17:01:23.145Z,1554397283.145 [Default:A.Wait](INFO): Done Waiting.
2019-04-04T17:01:23.145Z,1554397283.145 [Default:A.Wait] Stopped
2019-04-04T17:01:23.145Z,1554397283.145 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-04-04T17:01:23.530Z,1554397283.530 [Default:CheckIn] Running Loop=1
2019-04-04T17:01:23.530Z,1554397283.530 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-04T17:01:23.530Z,1554397283.530 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-04T17:01:23.930Z,1554397283.930 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-04-04T17:01:27.013Z,1554397287.013 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-04T17:01:27.013Z,1554397287.013 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-04T17:01:27.013Z,1554397287.013 [CTD_Seabird](ERROR): Communications Fault
2019-04-04T17:01:27.183Z,1554397287.183 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-04T17:01:27.417Z,1554397287.417 [CTD_Seabird](INFO): Powering down
2019-04-04T17:01:28.388Z,1554397288.388 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-04T17:01:28.388Z,1554397288.388 [CTD_Seabird] No Fault, FailCount= 2
2019-04-04T17:01:28.762Z,1554397288.762 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-04T17:01:28.763Z,1554397288.763 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-04T17:01:41.817Z,1554397301.817 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-04T17:01:41.817Z,1554397301.817 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-04T17:01:41.817Z,1554397301.817 [CTD_Seabird](ERROR): Communications Fault
2019-04-04T17:01:42.137Z,1554397302.137 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-04T17:01:42.137Z,1554397302.137 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-04T17:01:42.221Z,1554397302.221 [CTD_Seabird](INFO): Powering down
2019-04-04T17:01:47.380Z,1554397307.380 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-04T17:01:47.380Z,1554397307.380 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-04-04T17:01:47.380Z,1554397307.380 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-04T17:01:47.381Z,1554397307.381 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-04T17:01:47.382Z,1554397307.382 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-04-04T17:01:47.382Z,1554397307.382 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-04T17:01:47.391Z,1554397307.391 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-04T17:01:47.392Z,1554397307.392 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-04T17:01:47.803Z,1554397307.803 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-04T17:01:47.803Z,1554397307.803 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-04-04T17:01:47.803Z,1554397307.803 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-04T17:01:47.803Z,1554397307.803 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-04-04T17:01:48.182Z,1554397308.182 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-04T17:03:07.340Z,1554397387.340 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20190404T165645/Courier0000.lzma
2019-04-04T17:03:08.146Z,1554397388.146 [DataOverHttps](INFO): Moved sent file to Logs/20190404T165645/Courier0000.lzma.bak
2019-04-04T17:03:08.146Z,1554397388.146 [DataOverHttps](INFO): SBD MOMSN=10438355
2019-04-04T17:03:28.743Z,1554397408.743 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-04-04T17:03:29.569Z,1554397409.569 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:03:34.817Z,1554397414.817 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:03:37.653Z,1554397417.653 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:03:40.877Z,1554397420.877 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:03:43.705Z,1554397423.705 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:03:46.937Z,1554397426.937 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:03:48.564Z,1554397428.564 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-04T17:03:48.564Z,1554397428.564 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-04-04T17:03:48.564Z,1554397428.564 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-04T17:03:48.566Z,1554397428.566 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-04T17:03:48.566Z,1554397428.566 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-04-04T17:03:48.566Z,1554397428.566 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-04T17:03:48.576Z,1554397428.576 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-04T17:03:48.576Z,1554397428.576 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-04T17:03:48.980Z,1554397428.980 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-04T17:03:48.980Z,1554397428.980 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-04-04T17:03:48.981Z,1554397428.981 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-04T17:03:48.981Z,1554397428.981 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-04-04T17:03:49.372Z,1554397429.372 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-04T17:03:49.769Z,1554397429.769 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:03:52.597Z,1554397432.597 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:03:54.617Z,1554397434.617 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:03:57.845Z,1554397437.845 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:04:00.677Z,1554397440.677 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:04:03.905Z,1554397443.905 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:04:06.737Z,1554397446.737 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:04:09.965Z,1554397449.965 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:04:12.018Z,1554397452.018 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-04T17:04:12.018Z,1554397452.018 [CTD_Seabird] No Fault, FailCount= 3
2019-04-04T17:04:12.237Z,1554397452.237 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-04T17:04:12.239Z,1554397452.239 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-04T17:04:12.797Z,1554397452.797 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:04:15.617Z,1554397455.617 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:04:18.857Z,1554397458.857 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:04:21.685Z,1554397461.685 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:04:24.917Z,1554397464.917 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:04:27.745Z,1554397467.745 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:04:30.569Z,1554397470.569 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:04:32.593Z,1554397472.593 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:04:34.613Z,1554397474.613 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:04:37.841Z,1554397477.841 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:04:40.673Z,1554397480.673 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:04:43.901Z,1554397483.901 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:04:46.733Z,1554397486.733 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:04:49.961Z,1554397489.961 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:04:52.793Z,1554397492.793 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:04:55.621Z,1554397495.621 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:04:58.853Z,1554397498.853 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:05:01.681Z,1554397501.681 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:05:04.913Z,1554397504.913 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:05:07.741Z,1554397507.741 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:05:10.565Z,1554397510.565 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:05:12.589Z,1554397512.589 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:05:14.609Z,1554397514.609 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:05:16.625Z,1554397516.625 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:05:19.865Z,1554397519.865 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:05:22.685Z,1554397522.685 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:05:25.921Z,1554397525.921 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:05:28.745Z,1554397528.745 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:05:31.585Z,1554397531.585 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:05:33.601Z,1554397533.601 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:05:35.613Z,1554397535.613 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:05:38.849Z,1554397538.849 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:05:41.677Z,1554397541.677 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:05:44.909Z,1554397544.909 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:05:47.737Z,1554397547.737 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:05:49.768Z,1554397549.768 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-04T17:05:49.768Z,1554397549.768 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-04-04T17:05:49.768Z,1554397549.768 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-04T17:05:49.770Z,1554397549.770 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-04T17:05:49.770Z,1554397549.770 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2019-04-04T17:05:49.770Z,1554397549.770 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-04T17:05:49.780Z,1554397549.780 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-04T17:05:49.780Z,1554397549.780 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-04T17:05:50.180Z,1554397550.180 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-04T17:05:50.180Z,1554397550.180 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-04-04T17:05:50.181Z,1554397550.181 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-04T17:05:50.181Z,1554397550.181 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2019-04-04T17:05:50.567Z,1554397550.567 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-04T17:05:50.969Z,1554397550.969 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:05:53.797Z,1554397553.797 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:05:56.621Z,1554397556.621 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:05:59.857Z,1554397559.857 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:06:02.681Z,1554397562.681 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:06:05.917Z,1554397565.917 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:06:08.741Z,1554397568.741 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:06:11.569Z,1554397571.569 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:06:13.593Z,1554397573.593 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:06:15.613Z,1554397575.613 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:06:17.633Z,1554397577.633 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:06:20.861Z,1554397580.861 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:06:23.693Z,1554397583.693 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:06:23.718Z,1554397583.718 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-04-04T17:01:23.5Z
2019-04-04T17:06:23.718Z,1554397583.718 [Default:CheckIn:Read_GPS] Stopped
2019-04-04T17:06:23.718Z,1554397583.718 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-04-04T17:06:24.122Z,1554397584.122 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-04T17:06:26.921Z,1554397586.921 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:06:28.588Z,1554397588.588 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20190404T165737/Courier0000.lzma
2019-04-04T17:06:29.394Z,1554397589.394 [DataOverHttps](INFO): Moved sent file to Logs/20190404T165737/Courier0000.lzma.bak
2019-04-04T17:06:29.394Z,1554397589.394 [DataOverHttps](INFO): SBD MOMSN=10438416
2019-04-04T17:06:29.753Z,1554397589.753 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:06:32.577Z,1554397592.577 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:06:34.601Z,1554397594.601 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:06:36.617Z,1554397596.617 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:06:39.480Z,1554397599.480 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190404T165737/Courier0004.lzma
2019-04-04T17:06:39.853Z,1554397599.853 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:06:40.286Z,1554397600.286 [DataOverHttps](INFO): Moved sent file to Logs/20190404T165737/Courier0004.lzma.bak
2019-04-04T17:06:40.286Z,1554397600.286 [DataOverHttps](INFO): SBD MOMSN=10438420
2019-04-04T17:06:42.677Z,1554397602.677 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:06:45.909Z,1554397605.909 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:06:48.737Z,1554397608.737 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:06:51.973Z,1554397611.973 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:06:53.033Z,1554397613.033 [DataOverHttps](INFO): Sending 163 bytes from file Logs/20190403T213250/Express0044.lzma
2019-04-04T17:06:53.838Z,1554397613.838 [DataOverHttps](INFO): Moved sent file to Logs/20190403T213250/Express0044.lzma.bak
2019-04-04T17:06:53.839Z,1554397613.839 [DataOverHttps](INFO): SBD MOMSN=10438422
2019-04-04T17:06:54.797Z,1554397614.797 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:06:57.629Z,1554397617.629 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:07:00.857Z,1554397620.857 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:07:03.689Z,1554397623.689 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:07:06.917Z,1554397626.917 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:07:09.749Z,1554397629.749 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:07:12.575Z,1554397632.575 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:07:13.889Z,1554397633.889 [DataOverHttps](INFO): Sending 708 bytes from file Logs/20190404T165645/Express0001.lzma
2019-04-04T17:07:14.597Z,1554397634.597 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:07:14.694Z,1554397634.694 [DataOverHttps](INFO): Moved sent file to Logs/20190404T165645/Express0001.lzma.bak
2019-04-04T17:07:14.694Z,1554397634.694 [DataOverHttps](INFO): SBD MOMSN=10438429
2019-04-04T17:07:16.613Z,1554397636.613 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:07:19.845Z,1554397639.845 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:07:22.677Z,1554397642.677 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:07:25.905Z,1554397645.905 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:07:28.733Z,1554397648.733 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:07:29.666Z,1554397649.666 [DataOverHttps](INFO): Sending 1061 bytes from file Logs/20190404T165737/Express0001.lzma
2019-04-04T17:07:30.470Z,1554397650.470 [DataOverHttps](INFO): Moved sent file to Logs/20190404T165737/Express0001.lzma.bak
2019-04-04T17:07:30.470Z,1554397650.470 [DataOverHttps](INFO): SBD MOMSN=10438446
2019-04-04T17:07:31.969Z,1554397651.969 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:07:34.793Z,1554397654.793 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:07:37.625Z,1554397657.625 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:07:40.853Z,1554397660.853 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:07:42.809Z,1554397662.809 [DataOverHttps](INFO): Sending 346 bytes from file Logs/20190404T165737/Express0005.lzma
2019-04-04T17:07:43.614Z,1554397663.614 [DataOverHttps](INFO): Moved sent file to Logs/20190404T165737/Express0005.lzma.bak
2019-04-04T17:07:43.614Z,1554397663.614 [DataOverHttps](INFO): SBD MOMSN=10438477
2019-04-04T17:07:43.681Z,1554397663.681 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:07:44.906Z,1554397664.906 [Default:CheckIn:Read_Iridium] Stopped
2019-04-04T17:07:44.906Z,1554397664.906 [Default:CheckIn:C.Wait] Running Loop=1
2019-04-04T17:07:44.906Z,1554397664.906 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-04-04T17:07:46.913Z,1554397666.913 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:07:49.741Z,1554397669.741 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:07:50.567Z,1554397670.567 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-04T17:07:50.567Z,1554397670.567 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2019-04-04T17:07:50.567Z,1554397670.567 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-04T17:07:50.577Z,1554397670.577 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-04T17:07:50.964Z,1554397670.964 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-04T17:07:50.964Z,1554397670.964 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-04-04T17:07:50.964Z,1554397670.964 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-04T17:07:50.974Z,1554397670.974 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-04T17:07:50.974Z,1554397670.974 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-04T17:07:50.974Z,1554397670.974 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2019-04-04T17:07:51.368Z,1554397671.368 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-04T17:07:51.377Z,1554397671.377 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-04T17:07:51.377Z,1554397671.377 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-04-04T17:07:52.573Z,1554397672.573 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:07:54.597Z,1554397674.597 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:07:56.613Z,1554397676.613 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:07:59.845Z,1554397679.845 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:08:02.669Z,1554397682.669 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:08:05.901Z,1554397685.901 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:08:08.729Z,1554397688.729 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:08:11.961Z,1554397691.961 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:08:14.789Z,1554397694.789 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:08:17.617Z,1554397697.617 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:08:20.849Z,1554397700.849 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:08:23.677Z,1554397703.677 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:08:26.895Z,1554397706.895 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-04-04T17:08:26.895Z,1554397706.895 [NAL9602] Data Fault, FailCount= 1
2019-04-04T17:08:26.895Z,1554397706.895 [NAL9602](ERROR): Data Fault
2019-04-04T17:08:26.947Z,1554397706.947 [CBIT](ERROR): Data Fault in component: NAL9602
2019-04-04T17:08:27.302Z,1554397707.302 [NAL9602](INFO): Powering down
2019-04-04T17:08:28.133Z,1554397708.133 [CBIT](INFO): Clearing failed state for component NAL9602
2019-04-04T17:08:28.133Z,1554397708.133 [NAL9602] No Fault, FailCount= 1
2019-04-04T17:08:57.602Z,1554397737.602 [NAL9602](INFO): Powering up NAL9602
2019-04-04T17:09:08.510Z,1554397748.510 [NAL9602](INFO): NAL9602 initialized
2019-04-04T17:09:09.329Z,1554397749.329 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:09:12.157Z,1554397752.157 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:09:14.989Z,1554397754.989 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:09:17.005Z,1554397757.005 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:09:20.233Z,1554397760.233 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:09:23.065Z,1554397763.065 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:09:26.297Z,1554397766.297 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:09:29.125Z,1554397769.125 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:09:32.361Z,1554397772.361 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:09:35.185Z,1554397775.185 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:09:38.017Z,1554397778.017 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:09:41.256Z,1554397781.256 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:09:44.077Z,1554397784.077 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:09:47.309Z,1554397787.309 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:09:50.137Z,1554397790.137 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:09:51.754Z,1554397791.754 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-04T17:09:51.754Z,1554397791.754 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2019-04-04T17:09:51.754Z,1554397791.754 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-04T17:09:51.772Z,1554397791.772 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-04T17:09:52.160Z,1554397792.160 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-04T17:09:52.160Z,1554397792.160 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-04-04T17:09:52.160Z,1554397792.160 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-04T17:09:52.171Z,1554397792.171 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-04T17:09:52.171Z,1554397792.171 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-04T17:09:52.171Z,1554397792.171 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2019-04-04T17:09:52.571Z,1554397792.571 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-04T17:09:52.581Z,1554397792.581 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-04T17:09:52.581Z,1554397792.581 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-04-04T17:09:52.961Z,1554397792.961 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:09:54.981Z,1554397794.981 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:09:57.001Z,1554397797.001 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:09:59.021Z,1554397799.021 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:10:02.257Z,1554397802.257 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:10:05.081Z,1554397805.081 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:10:08.317Z,1554397808.317 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:10:11.141Z,1554397811.141 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:10:13.969Z,1554397813.969 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:10:15.985Z,1554397815.985 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:10:18.013Z,1554397818.013 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:10:21.241Z,1554397821.241 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:10:24.073Z,1554397824.073 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:10:27.301Z,1554397827.301 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:10:30.133Z,1554397830.133 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:10:32.957Z,1554397832.957 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:10:34.981Z,1554397834.981 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:10:36.997Z,1554397836.997 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:10:39.017Z,1554397839.017 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:10:42.253Z,1554397842.253 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:10:45.077Z,1554397845.077 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:10:48.313Z,1554397848.313 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:10:51.137Z,1554397851.137 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:10:53.965Z,1554397853.965 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:10:55.985Z,1554397855.985 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:10:58.009Z,1554397858.009 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:11:01.237Z,1554397861.237 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:11:04.069Z,1554397864.069 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:11:05.635Z,1554397865.635 [CommandLine](IMPORTANT): got command run ./Missions/Insert/Science.xml
2019-04-04T17:11:05.635Z,1554397865.635 [MissionManager](INFO): Loading Mission: ./Missions/Insert/Science.xml
2019-04-04T17:11:05.865Z,1554397865.865 [MissionManager](INFO): DefineArg Science.SampleISUS = 0 bool
2019-04-04T17:11:05.868Z,1554397865.868 [MissionManager](INFO): DefineArg Science.SampleISUSmaxPitch = 60.000000 arcdeg
2019-04-04T17:11:05.871Z,1554397865.871 [MissionManager](INFO): DefineArg Science.SampleISUSminPitch = -60.000000 arcdeg
2019-04-04T17:11:05.874Z,1554397865.874 [MissionManager](INFO): DefineArg Science.PeakDetectChlActive = 0 bool
2019-04-04T17:11:05.877Z,1554397865.877 [MissionManager](INFO): DefineArg Science.TimeWindowPeakReport = nan min
2019-04-04T17:11:05.880Z,1554397865.880 [MissionManager](INFO): DefineArg Science.HighestChlPeakReportActive = 0 bool
2019-04-04T17:11:05.882Z,1554397865.882 [MissionManager](INFO): DefineArg Science.PatchTracking = 0 bool
2019-04-04T17:11:05.885Z,1554397865.885 [MissionManager](INFO): DefineArg Science.FilterWidthHorizontal = 3.000000 count
2019-04-04T17:11:05.888Z,1554397865.888 [MissionManager](INFO): DefineArg Science.NumProfilesSlidingwindow = 100.000000 count
2019-04-04T17:11:05.891Z,1554397865.891 [MissionManager](INFO): DefineArg Science.OffPeakFractionHorizontal = 80.000000 %
2019-04-04T17:11:05.894Z,1554397865.894 [MissionManager](INFO): DefineArg Science.PeakDetectNO3Active = 0 bool
2019-04-04T17:11:05.897Z,1554397865.897 [MissionManager](INFO): DefineArg Science.PeakDetectOilActive = 0 bool
2019-04-04T17:11:05.900Z,1554397865.900 [MissionManager](INFO): DefineArg Science.PeakDetectFDOMActive = 0 bool
2019-04-04T17:11:05.902Z,1554397865.902 [MissionManager](INFO): DefineArg Science.UpwardDerivativeOfTemperatureActive = 0 bool
2019-04-04T17:11:05.906Z,1554397865.906 [MissionManager](INFO): DefineArg Science.EnabledAanderaaO2 = 0.000000 bool
2019-04-04T17:11:05.909Z,1554397865.909 [MissionManager](INFO): DefineArg Science.EnabledNeilBrown = 1.000000 bool
2019-04-04T17:11:05.913Z,1554397865.913 [MissionManager](INFO): DefineArg Science.EnabledSeabird = 1.000000 bool
2019-04-04T17:11:05.916Z,1554397865.916 [MissionManager](INFO): DefineArg Science.EnabledTurbulence_NPS = 0.000000 bool
2019-04-04T17:11:05.920Z,1554397865.920 [MissionManager](INFO): DefineArg Science.EnabledWetLabsBB2FL = 1.000000 bool
2019-04-04T17:11:05.923Z,1554397865.923 [MissionManager](INFO): DefineArg Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2019-04-04T17:11:05.927Z,1554397865.927 [MissionManager](INFO): DefineArg Science.EnabledWetLabsUBAT = 0.000000 bool
2019-04-04T17:11:05.930Z,1554397865.930 [MissionManager](INFO): DefineArg Science.EnabledISUS = 0.000000 bool
2019-04-04T17:11:05.933Z,1554397865.933 [MissionManager](INFO): DefineArg Science.LowPassWindowLength = 20.000000 count
2019-04-04T17:11:05.936Z,1554397865.936 [MissionManager](INFO): DefineArg Science.MedianFilterLen = 5.000000 count
2019-04-04T17:11:05.939Z,1554397865.939 [MissionManager](INFO): DefineArg Science.PeakChlShallowBound = nan m
2019-04-04T17:11:05.942Z,1554397865.942 [MissionManager](INFO): DefineArg Science.PeakChlDeepBound = nan m
2019-04-04T17:11:05.945Z,1554397865.945 [MissionManager](INFO): DefineArg Science.PeakShallowBound = nan m
2019-04-04T17:11:05.948Z,1554397865.948 [MissionManager](INFO): DefineArg Science.PeakDeepBound = nan m
2019-04-04T17:11:05.951Z,1554397865.951 [MissionManager](INFO): DefineOutput Science.PeakChl = 0.000000 ug/l
2019-04-04T17:11:05.954Z,1554397865.954 [MissionManager](INFO): DefineOutput Science.PeakChlDepth = 0.000000 m
2019-04-04T17:11:05.958Z,1554397865.958 [MissionManager](INFO): DefineOutput Science.PeakChlTemperature = 0.000000 degC
2019-04-04T17:11:06.029Z,1554397866.029 [MissionManager](INFO): DefineOutput Science.PeakChlLatitude = nan arcdeg
2019-04-04T17:11:06.032Z,1554397866.032 [MissionManager](INFO): DefineOutput Science.PeakChlLongitude = nan arcdeg
2019-04-04T17:11:06.036Z,1554397866.036 [MissionManager](INFO): DefineOutput Science.PeakNO3 = 0.000000 umol/l
2019-04-04T17:11:06.051Z,1554397866.051 [MissionManager](INFO): DefineOutput Science.PeakNO3Depth = 0.000000 m
2019-04-04T17:11:06.062Z,1554397866.062 [MissionManager](INFO): DefineOutput Science.PeakNO3Latitude = nan arcdeg
2019-04-04T17:11:06.096Z,1554397866.096 [MissionManager](INFO): DefineOutput Science.PeakNO3Longitude = nan arcdeg
2019-04-04T17:11:06.131Z,1554397866.131 [MissionManager](INFO): DefineOutput Science.PatchChl = 0.000000 m
2019-04-04T17:11:06.140Z,1554397866.140 [MissionManager](INFO): DefineOutput Science.PatchChlDepth = 0.000000 m
2019-04-04T17:11:06.152Z,1554397866.152 [MissionManager](INFO): DefineOutput Science.PatchChlLatitude = nan arcdeg
2019-04-04T17:11:06.159Z,1554397866.159 [MissionManager](INFO): DefineOutput Science.PatchChlLongitude = nan arcdeg
2019-04-04T17:11:06.163Z,1554397866.163 [MissionManager](INFO): DefineOutput Science.PatchChlDistance = nan m
2019-04-04T17:11:06.175Z,1554397866.175 [MissionManager](INFO): DefineOutput Science.PeakOil = 0.000000 kg/m3
2019-04-04T17:11:06.178Z,1554397866.178 [MissionManager](INFO): DefineOutput Science.PeakOilDepth = 0.000000 m
2019-04-04T17:11:06.190Z,1554397866.190 [MissionManager](INFO): DefineOutput Science.PeakOilLatitude = nan arcdeg
2019-04-04T17:11:06.198Z,1554397866.198 [MissionManager](INFO): DefineOutput Science.PeakOilLongitude = nan arcdeg
2019-04-04T17:11:06.205Z,1554397866.205 [MissionManager](INFO): DefineOutput Science.PeakFDOM = 0.000000 ppb
2019-04-04T17:11:06.208Z,1554397866.208 [MissionManager](INFO): DefineOutput Science.PeakFDOMDepth = 0.000000 m
2019-04-04T17:11:06.216Z,1554397866.216 [MissionManager](INFO): DefineOutput Science.PeakFDOMLatitude = nan arcdeg
2019-04-04T17:11:06.219Z,1554397866.219 [MissionManager](INFO): DefineOutput Science.PeakFDOMLongitude = nan arcdeg
2019-04-04T17:11:06.222Z,1554397866.222 [MissionManager](INFO): DefineOutput Science.PatchFDOMDistance = nan m
2019-04-04T17:11:06.280Z,1554397866.280 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-04-04T17:11:06.302Z,1554397866.302 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2019-04-04T17:11:06.338Z,1554397866.338 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-04-04T17:11:06.369Z,1554397866.369 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-04-04T17:11:06.398Z,1554397866.398 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-04-04T17:11:06.461Z,1554397866.461 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Aggregate xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
           xmlns="Tethys"
           xmlns:Units="Tethys/Units"
           xmlns:Science="Tethys/Science"
           xmlns:Universal="Tethys/Universal"
           xmlns:Trigger="Tethys/Trigger"
           xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                               Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                               Tethys/Science http://okeanids.mbari.org/tethys/Xml/Science.xsd
                               Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd
                               Tethys/Trigger http://okeanids.mbari.org/tethys/Xml/Trigger.xsd"
       Id="Science">

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

<!--debug-->

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

    <Aggregate Id="PeakDetectChl">

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

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

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

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

    </Aggregate>

    <Aggregate Id="HighestChlPeakReport">

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

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

    </Aggregate>

    <Aggregate Id="PeakDetectNO3">

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

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

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

    </Aggregate>

    <Aggregate Id="PeakDetectOil">

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

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

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

    </Aggregate>

    <Aggregate Id="PeakDetectFDOM">

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

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

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

    </Aggregate>

</Aggregate>


2019-04-04T17:11:06.463Z,1554397866.464 [CommandLine](IMPORTANT): Running ./Missions/Insert/Science.xml
2019-04-04T17:11:06.506Z,1554397866.506 [Default] Stopped
2019-04-04T17:11:06.506Z,1554397866.506 [Default](DEBUG): Aggregate::uninitialize Default
2019-04-04T17:11:06.506Z,1554397866.506 [Default:B.GoToSurface] Stopped
2019-04-04T17:11:06.506Z,1554397866.506 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-04T17:11:06.506Z,1554397866.506 [Default:CheckIn] Stopped
2019-04-04T17:11:06.506Z,1554397866.506 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-04T17:11:06.506Z,1554397866.506 [Default:CheckIn:C.Wait] Stopped
2019-04-04T17:11:06.506Z,1554397866.506 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-04-04T17:11:06.507Z,1554397866.507 [MissionManager](IMPORTANT): Started mission Science
2019-04-04T17:11:06.507Z,1554397866.507 [Science] Running Loop=1
2019-04-04T17:11:06.507Z,1554397866.507 [Science](DEBUG): Aggregate::initialize Science
2019-04-04T17:11:06.507Z,1554397866.507 [Science:A] Running Loop=1
2019-04-04T17:11:06.507Z,1554397866.507 [Science:B] Running Loop=1
2019-04-04T17:11:06.507Z,1554397866.507 [Science:C] Running Loop=1
2019-04-04T17:11:06.507Z,1554397866.507 [Science:D] Running Loop=1
2019-04-04T17:11:06.507Z,1554397866.507 [Science:E] Running Loop=1
2019-04-04T17:11:06.507Z,1554397866.507 [Science:F] Running Loop=1
2019-04-04T17:11:06.507Z,1554397866.507 [Science:G] Running Loop=1
2019-04-04T17:11:06.507Z,1554397866.507 [Science:H] Running Loop=1
2019-04-04T17:11:06.508Z,1554397866.508 [Science:Read_Oil] Running Loop=1
2019-04-04T17:11:06.508Z,1554397866.508 [Science:PeakDetectChl] Running Loop=1
2019-04-04T17:11:06.508Z,1554397866.508 [Science:PeakDetectChl](DEBUG): Aggregate::initialize Science:PeakDetectChl
2019-04-04T17:11:06.508Z,1554397866.508 [Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2019-04-04T17:11:06.508Z,1554397866.508 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-04-04T17:11:06.510Z,1554397866.510 [Science:HighestChlPeakReport] Running Loop=1
2019-04-04T17:11:06.510Z,1554397866.510 [Science:HighestChlPeakReport](DEBUG): Aggregate::initialize Science:HighestChlPeakReport
2019-04-04T17:11:06.510Z,1554397866.510 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2019-04-04T17:11:06.510Z,1554397866.510 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2019-04-04T17:11:06.511Z,1554397866.511 [Science:PeakDetectNO3] Running Loop=1
2019-04-04T17:11:06.511Z,1554397866.511 [Science:PeakDetectNO3](DEBUG): Aggregate::initialize Science:PeakDetectNO3
2019-04-04T17:11:06.511Z,1554397866.511 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2019-04-04T17:11:06.514Z,1554397866.514 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-04-04T17:11:06.518Z,1554397866.518 [Science:PeakDetectOil] Running Loop=1
2019-04-04T17:11:06.518Z,1554397866.518 [Science:PeakDetectOil](DEBUG): Aggregate::initialize Science:PeakDetectOil
2019-04-04T17:11:06.519Z,1554397866.519 [Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2019-04-04T17:11:06.519Z,1554397866.519 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-04-04T17:11:06.521Z,1554397866.521 [Science:PeakDetectFDOM] Running Loop=1
2019-04-04T17:11:06.521Z,1554397866.521 [Science:PeakDetectFDOM](DEBUG): Aggregate::initialize Science:PeakDetectFDOM
2019-04-04T17:11:06.521Z,1554397866.521 [Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2019-04-04T17:11:06.521Z,1554397866.521 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-04-04T17:11:06.524Z,1554397866.524 [Science:PeakDetectFDOM] Stopped
2019-04-04T17:11:06.524Z,1554397866.524 [Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize Science:PeakDetectFDOM
2019-04-04T17:11:06.524Z,1554397866.524 [Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2019-04-04T17:11:06.525Z,1554397866.525 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-04-04T17:11:06.525Z,1554397866.525 [Science:PeakDetectOil] Stopped
2019-04-04T17:11:06.533Z,1554397866.533 [Science:PeakDetectOil](DEBUG): Aggregate::uninitialize Science:PeakDetectOil
2019-04-04T17:11:06.533Z,1554397866.533 [Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2019-04-04T17:11:06.534Z,1554397866.534 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-04-04T17:11:06.534Z,1554397866.534 [Science:PeakDetectNO3] Stopped
2019-04-04T17:11:06.534Z,1554397866.534 [Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize Science:PeakDetectNO3
2019-04-04T17:11:06.534Z,1554397866.534 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2019-04-04T17:11:06.534Z,1554397866.534 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-04-04T17:11:06.534Z,1554397866.534 [Science:HighestChlPeakReport] Stopped
2019-04-04T17:11:06.534Z,1554397866.534 [Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize Science:HighestChlPeakReport
2019-04-04T17:11:06.534Z,1554397866.534 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2019-04-04T17:11:06.534Z,1554397866.534 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2019-04-04T17:11:06.534Z,1554397866.534 [Science:PeakDetectChl] Stopped
2019-04-04T17:11:06.534Z,1554397866.534 [Science:PeakDetectChl](DEBUG): Aggregate::uninitialize Science:PeakDetectChl
2019-04-04T17:11:06.534Z,1554397866.534 [Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2019-04-04T17:11:06.535Z,1554397866.535 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-04-04T17:11:06.535Z,1554397866.535 [Science:Read_Oil] Stopped
2019-04-04T17:11:06.535Z,1554397866.535 [Science:H] Stopped
2019-04-04T17:11:06.535Z,1554397866.535 [Science:G] Running Loop=1
2019-04-04T17:11:06.535Z,1554397866.535 [Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2019-04-04T17:11:06.535Z,1554397866.535 [Science:F] Stopped
2019-04-04T17:11:06.536Z,1554397866.536 [Science:E] Stopped
2019-04-04T17:11:06.536Z,1554397866.536 [Science:D] Running Loop=1
2019-04-04T17:11:06.537Z,1554397866.537 [Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2019-04-04T17:11:06.537Z,1554397866.537 [Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2019-04-04T17:11:06.537Z,1554397866.537 [Science:C] Running Loop=1
2019-04-04T17:11:06.538Z,1554397866.538 [Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2019-04-04T17:11:06.541Z,1554397866.541 [Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2019-04-04T17:11:06.541Z,1554397866.541 [Science:B] Stopped
2019-04-04T17:11:06.541Z,1554397866.541 [Science:A] Stopped
2019-04-04T17:11:06.634Z,1554397866.634 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-04-04T17:11:06.634Z,1554397866.634 [WetLabsBB2FL](INFO): Powering up
2019-04-04T17:11:06.635Z,1554397866.635 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2019-04-04T17:11:06.978Z,1554397866.978 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-04T17:11:07.309Z,1554397867.309 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:11:09.990Z,1554397869.990 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2019-04-04T17:11:10.129Z,1554397870.129 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:11:12.507Z,1554397872.507 [CTD_Seabird](INFO): Failure count cleared after critical for CTD_Seabird
2019-04-04T17:11:12.953Z,1554397872.953 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:11:13.778Z,1554397873.778 [CTD_NeilBrown](FAULT): Failed to acquire real or simulated CTD data within timeout.
2019-04-04T17:11:13.778Z,1554397873.778 [CTD_NeilBrown] Communications Fault, FailCount= 1
2019-04-04T17:11:13.778Z,1554397873.778 [CTD_NeilBrown](ERROR): Communications Fault
2019-04-04T17:11:13.802Z,1554397873.802 [CBIT](ERROR): Communications Fault in component: CTD_NeilBrown
2019-04-04T17:11:13.802Z,1554397873.802 [CBIT](FAULT): Communications Fault in component: CTD_NeilBrown
2019-04-04T17:11:14.977Z,1554397874.977 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:11:16.993Z,1554397876.993 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:11:19.013Z,1554397879.013 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:11:22.249Z,1554397882.249 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:11:25.076Z,1554397885.076 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:11:28.309Z,1554397888.309 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:11:31.141Z,1554397891.141 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:11:33.965Z,1554397893.965 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:11:35.981Z,1554397895.981 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:11:38.005Z,1554397898.005 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:11:41.233Z,1554397901.233 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:11:44.065Z,1554397904.065 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:11:47.297Z,1554397907.297 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:11:50.125Z,1554397910.125 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:11:52.960Z,1554397912.960 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-04T17:11:52.960Z,1554397912.960 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2019-04-04T17:11:52.960Z,1554397912.960 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-04T17:11:52.970Z,1554397912.970 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-04T17:11:53.357Z,1554397913.357 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:11:53.385Z,1554397913.385 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-04T17:11:53.385Z,1554397913.385 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2019-04-04T17:11:53.385Z,1554397913.385 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-04T17:11:53.397Z,1554397913.397 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-04T17:11:53.397Z,1554397913.397 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-04T17:11:53.397Z,1554397913.397 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2019-04-04T17:11:53.791Z,1554397913.791 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-04T17:11:53.802Z,1554397913.802 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-04T17:11:53.802Z,1554397913.802 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2019-04-04T17:11:56.185Z,1554397916.185 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:11:59.013Z,1554397919.013 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:12:02.249Z,1554397922.249 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:12:05.073Z,1554397925.073 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:12:08.309Z,1554397928.309 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:12:11.133Z,1554397931.133 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:12:13.965Z,1554397933.965 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:12:15.985Z,1554397935.985 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:12:18.005Z,1554397938.005 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:12:21.237Z,1554397941.237 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:12:24.065Z,1554397944.065 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:12:27.297Z,1554397947.297 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:12:30.125Z,1554397950.125 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:12:33.353Z,1554397953.353 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:12:36.189Z,1554397956.189 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:12:39.013Z,1554397959.013 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:12:42.253Z,1554397962.253 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:12:45.077Z,1554397965.077 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:12:48.309Z,1554397968.309 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:12:51.133Z,1554397971.133 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:12:53.973Z,1554397973.973 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:12:55.985Z,1554397975.985 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:12:58.009Z,1554397978.009 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:13:01.233Z,1554397981.233 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:13:04.065Z,1554397984.065 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:13:07.297Z,1554397987.297 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:13:10.129Z,1554397990.129 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:13:12.953Z,1554397992.953 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:13:14.977Z,1554397994.977 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:13:16.997Z,1554397996.997 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:13:20.229Z,1554398000.229 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:13:23.061Z,1554398003.061 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:13:26.293Z,1554398006.293 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:13:29.117Z,1554398009.117 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:13:32.349Z,1554398012.349 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:13:35.177Z,1554398015.177 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:13:38.005Z,1554398018.005 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:13:41.233Z,1554398021.233 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:13:44.065Z,1554398024.065 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:13:47.293Z,1554398027.293 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:13:50.129Z,1554398030.129 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:13:53.357Z,1554398033.357 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:13:54.188Z,1554398034.188 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-04T17:13:54.188Z,1554398034.188 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2019-04-04T17:13:54.188Z,1554398034.188 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-04T17:13:54.190Z,1554398034.190 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-04T17:13:54.190Z,1554398034.190 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2019-04-04T17:13:54.190Z,1554398034.190 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-04T17:13:54.200Z,1554398034.200 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-04T17:13:54.200Z,1554398034.200 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-04T17:13:54.589Z,1554398034.589 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-04T17:13:54.589Z,1554398034.589 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2019-04-04T17:13:54.589Z,1554398034.589 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-04T17:13:54.589Z,1554398034.589 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2019-04-04T17:13:54.978Z,1554398034.978 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-04T17:13:56.185Z,1554398036.185 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:13:59.009Z,1554398039.009 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:14:02.245Z,1554398042.245 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:14:05.069Z,1554398045.069 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:14:08.305Z,1554398048.305 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:14:11.129Z,1554398051.129 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:14:13.957Z,1554398053.957 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:14:15.981Z,1554398055.981 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:14:18.001Z,1554398058.001 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:14:21.229Z,1554398061.229 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:14:24.061Z,1554398064.061 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:14:27.289Z,1554398067.289 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:14:30.121Z,1554398070.121 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:14:33.349Z,1554398073.349 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:14:36.185Z,1554398076.185 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:14:39.005Z,1554398079.005 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:14:42.241Z,1554398082.241 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:14:45.065Z,1554398085.065 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:14:48.301Z,1554398088.301 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:14:51.125Z,1554398091.125 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:14:54.361Z,1554398094.361 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:14:57.185Z,1554398097.185 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:15:00.021Z,1554398100.021 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:15:03.249Z,1554398103.249 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:15:06.073Z,1554398106.073 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:15:09.309Z,1554398109.309 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:15:12.133Z,1554398112.133 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:15:14.965Z,1554398114.965 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:15:16.981Z,1554398116.981 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:15:19.001Z,1554398119.001 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:15:22.237Z,1554398122.237 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:15:25.069Z,1554398125.069 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:15:28.297Z,1554398128.297 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:15:31.129Z,1554398131.129 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:15:34.381Z,1554398134.381 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:15:37.193Z,1554398137.193 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:15:40.009Z,1554398140.009 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:15:43.245Z,1554398143.245 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:15:46.069Z,1554398146.069 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:15:49.167Z,1554398149.167 [CTD_Seabird](ERROR): Salinity reading out of range: 0.000000 psu
2019-04-04T17:15:49.301Z,1554398149.301 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:15:51.175Z,1554398151.175 [CTD_Seabird](ERROR): Salinity reading out of range: 0.000000 psu
2019-04-04T17:15:52.133Z,1554398152.133 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:15:53.183Z,1554398153.183 [CTD_Seabird](ERROR): Salinity reading out of range: 0.000000 psu
2019-04-04T17:15:55.361Z,1554398155.361 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:15:55.390Z,1554398155.390 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-04T17:15:55.390Z,1554398155.390 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2019-04-04T17:15:55.390Z,1554398155.390 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-04T17:15:55.392Z,1554398155.392 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-04T17:15:55.392Z,1554398155.392 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2019-04-04T17:15:55.392Z,1554398155.392 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-04T17:15:55.402Z,1554398155.402 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-04T17:15:55.403Z,1554398155.403 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-04T17:15:55.812Z,1554398155.812 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-04T17:15:55.812Z,1554398155.812 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2019-04-04T17:15:55.812Z,1554398155.812 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-04T17:15:55.813Z,1554398155.813 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2019-04-04T17:15:56.191Z,1554398156.191 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-04T17:15:58.191Z,1554398158.191 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:16:01.021Z,1554398161.021 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:16:04.253Z,1554398164.253 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:16:07.089Z,1554398167.089 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:16:09.770Z,1554398169.770 [CommandLine](IMPORTANT): got command get PAR_Licor.loadAtStartup 
2019-04-04T17:16:09.770Z,1554398169.770 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup 0 bool
2019-04-04T17:16:10.317Z,1554398170.317 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:16:13.141Z,1554398173.141 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:16:15.965Z,1554398175.965 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:16:17.989Z,1554398177.989 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:16:20.009Z,1554398180.009 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:16:23.237Z,1554398183.237 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:16:26.065Z,1554398186.065 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:16:29.301Z,1554398189.301 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:16:31.775Z,1554398191.775 [CommandLine](IMPORTANT): got command configSet PAR_Licor.loadAtStartup 1.000000 bool persist
2019-04-04T17:16:31.775Z,1554398191.775 [CommandLine](IMPORTANT): configSet PAR_Licor.loadAtStartup requires a restart to take effect.
2019-04-04T17:16:32.133Z,1554398192.133 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:16:34.957Z,1554398194.957 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:16:36.973Z,1554398196.973 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:16:38.993Z,1554398198.993 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:16:42.229Z,1554398202.229 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:16:45.057Z,1554398205.057 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:16:48.289Z,1554398208.289 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:16:51.113Z,1554398211.113 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:16:54.349Z,1554398214.349 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:16:55.423Z,1554398215.423 [CommandLine](IMPORTANT): got command configSet AHRS_M2.loadAtStartup 1.000000 bool persist
2019-04-04T17:16:55.423Z,1554398215.423 [CommandLine](IMPORTANT): configSet AHRS_M2.loadAtStartup requires a restart to take effect.
2019-04-04T17:16:57.173Z,1554398217.173 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:17:00.001Z,1554398220.001 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:17:03.245Z,1554398223.245 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:17:06.061Z,1554398226.061 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:17:06.215Z,1554398226.215 [CommandLine](IMPORTANT): got command restart application
2019-04-04T17:17:07.221Z,1554398227.221 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-04-04T17:17:07.221Z,1554398227.221 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-04T17:17:07.222Z,1554398227.222 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-04T17:17:07.297Z,1554398227.297 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-04-04T17:17:07.298Z,1554398227.297 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-04T17:17:07.298Z,1554398227.298 [CommandLine](INFO): Join timeout helper Thread ID is 9804
2019-04-04T17:17:07.301Z,1554398227.301 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-04-04T17:17:07.301Z,1554398227.301 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-04T17:17:07.302Z,1554398227.302 [NavChartDb](INFO): Join timeout helper Thread ID is 9805
2019-04-04T17:17:07.308Z,1554398227.308 [MissionManager](INFO): MissionManager is completed.
2019-04-04T17:17:07.308Z,1554398227.308 [MissionManager](INFO): Uninitializing Mission Science
2019-04-04T17:17:07.308Z,1554398227.308 [Science] Stopped
2019-04-04T17:17:07.308Z,1554398227.308 [Science](DEBUG): Aggregate::uninitialize Science
2019-04-04T17:17:07.308Z,1554398227.308 [Science:C] Stopped
2019-04-04T17:17:07.308Z,1554398227.308 [Science:D] Stopped
2019-04-04T17:17:07.308Z,1554398227.308 [Science:G] Stopped
2019-04-04T17:17:07.665Z,1554398227.665 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-04T17:17:07.665Z,1554398227.665 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-04T17:17:07.669Z,1554398227.669 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-04-04T17:17:07.669Z,1554398227.669 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-04T17:17:07.669Z,1554398227.669 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9806
2019-04-04T17:17:07.693Z,1554398227.693 [MissionManager](IMPORTANT): Started mission Default
2019-04-04T17:17:07.694Z,1554398227.694 [Default] Running Loop=1
2019-04-04T17:17:07.694Z,1554398227.694 [Default](DEBUG): Aggregate::initialize Default
2019-04-04T17:17:07.694Z,1554398227.694 [Default:B.GoToSurface] Running Loop=1
2019-04-04T17:17:07.694Z,1554398227.694 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-04T17:17:07.694Z,1554398227.694 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-04T17:17:07.694Z,1554398227.694 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-04T17:17:07.695Z,1554398227.695 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-04T17:17:07.695Z,1554398227.695 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-04T17:17:07.695Z,1554398227.695 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-04T17:17:07.696Z,1554398227.696 [Default:A.Wait] Running Loop=1
2019-04-04T17:17:07.696Z,1554398227.696 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-04-04T17:17:07.737Z,1554398227.737 [WetLabsBB2FL](INFO): Powering down
2019-04-04T17:17:07.739Z,1554398227.739 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-04T17:17:07.739Z,1554398227.739 [WetLabsBB2FL](INFO): Powering down
2019-04-04T17:17:07.739Z,1554398227.739 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-04T17:17:07.765Z,1554398227.765 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-04-04T17:17:07.765Z,1554398227.765 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-04T17:17:07.765Z,1554398227.765 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9807
2019-04-04T17:17:08.497Z,1554398228.497 [CTD_Seabird](INFO): Powering down
2019-04-04T17:17:08.625Z,1554398228.625 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-04T17:17:08.625Z,1554398228.625 [CTD_Seabird](INFO): Powering down
2019-04-04T17:17:08.637Z,1554398228.637 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-04T17:17:08.657Z,1554398228.657 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-04-04T17:17:08.657Z,1554398228.657 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-04-04T17:17:08.657Z,1554398228.657 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 9810
2019-04-04T17:17:08.894Z,1554398228.894 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-04T17:17:08.894Z,1554398228.894 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-04-04T17:17:08.910Z,1554398228.910 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-04-04T17:17:08.910Z,1554398228.910 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-04T17:17:08.910Z,1554398228.910 [Radio_Surface](INFO): Join timeout helper Thread ID is 9811
2019-04-04T17:17:09.205Z,1554398229.205 [Radio_Surface](INFO): Powering down
2019-04-04T17:17:09.206Z,1554398229.206 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-04T17:17:09.206Z,1554398229.206 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-04T17:17:09.218Z,1554398229.218 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-04-04T17:17:09.218Z,1554398229.218 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-04T17:17:09.218Z,1554398229.218 [DataOverHttps](INFO): Join timeout helper Thread ID is 9812
2019-04-04T17:17:09.297Z,1554398229.297 [NAL9602](DEBUG): Fix Requested
2019-04-04T17:17:09.509Z,1554398229.509 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-04T17:17:09.509Z,1554398229.509 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-04T17:17:09.526Z,1554398229.526 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-04-04T17:17:09.526Z,1554398229.526 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-04T17:17:09.526Z,1554398229.526 [logger](INFO): Join timeout helper Thread ID is 9813
2019-04-04T17:17:09.565Z,1554398229.565 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-04T17:17:09.565Z,1554398229.565 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-04T17:17:09.570Z,1554398229.570 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-04-04T17:17:09.570Z,1554398229.570 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-04T17:17:09.570Z,1554398229.570 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-04-04T17:17:09.570Z,1554398229.570 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-04T17:17:09.570Z,1554398229.570 [controlThread](INFO): Join timeout helper Thread ID is 9814
2019-04-04T17:17:09.677Z,1554398229.677 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-04T17:17:09.677Z,1554398229.677 [controlThread](DEBUG): Uninitializing ControlThread
2019-04-04T17:17:09.678Z,1554398229.678 [NAL9602](INFO): Powering down
2019-04-04T17:17:09.679Z,1554398229.679 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-04-04T17:17:09.680Z,1554398229.680 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-04-04T17:17:09.681Z,1554398229.681 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-04-04T17:17:09.682Z,1554398229.682 [MissionManager](INFO): Uninitializing Mission Default
2019-04-04T17:17:09.682Z,1554398229.682 [Default] Stopped
2019-04-04T17:17:09.682Z,1554398229.682 [Default](DEBUG): Aggregate::uninitialize Default
2019-04-04T17:17:09.682Z,1554398229.682 [Default:A.Wait] Stopped
2019-04-04T17:17:09.682Z,1554398229.682 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-04-04T17:17:09.682Z,1554398229.682 [Default:B.GoToSurface] Stopped
2019-04-04T17:17:09.682Z,1554398229.682 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-04T17:17:09.684Z,1554398229.684 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-04-04T17:17:09.685Z,1554398229.685 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-04-04T17:17:09.685Z,1554398229.685 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-04-04T17:17:09.685Z,1554398229.685 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-04-04T17:17:09.686Z,1554398229.686 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-04-04T17:17:09.686Z,1554398229.686 [BuoyancyServo](INFO): Powering down
2019-04-04T17:17:09.697Z,1554398229.697 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-04T17:17:09.697Z,1554398229.697 [ElevatorServo](INFO): Powering down
2019-04-04T17:17:09.698Z,1554398229.698 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-04T17:17:09.698Z,1554398229.698 [MassServo](INFO): Powering down
2019-04-04T17:17:09.699Z,1554398229.699 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-04-04T17:17:09.699Z,1554398229.699 [ThrusterServo](INFO): Powering down
2019-04-04T17:17:09.700Z,1554398229.700 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-04-04T17:17:09.700Z,1554398229.700 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-04-04T17:17:09.700Z,1554398229.700 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-04-04T17:17:09.700Z,1554398229.700 [CBIT](DEBUG): Powering off loads.
2019-04-04T17:17:09.713Z,1554398229.713 [CBIT](DEBUG): Disabling WDT.
2019-04-04T17:17:09.725Z,1554398229.725 [CBIT](DEBUG): Opening all GF detection circuits.
2019-04-04T17:17:09.726Z,1554398229.726 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-04T17:17:09.781Z,1554398229.781 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-04T17:17:09.786Z,1554398229.786 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-04T17:17:09.854Z,1554398229.854 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-04T17:17:09.867Z,1554398229.867 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-04T17:17:09.870Z,1554398229.870 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-04-04T17:17:09.937Z,1554398229.937 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-04T17:17:10.074Z,1554398230.074 [logger ThreadHandler](INFO): Thread cancelled.