2019-05-15T22:47:51.810Z,1557960471.810 [Supervisor](DEBUG): Initializing supervisor.
2019-05-15T22:47:51.813Z,1557960471.813 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-05-15T22:47:51.814Z,1557960471.814 [SyncHandler](INFO): Protected caller Thread ID is 4811
2019-05-15T22:47:51.814Z,1557960471.814 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-05-15T22:47:51.815Z,1557960471.815 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-05-15T22:47:51.816Z,1557960471.816 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4812
2019-05-15T22:47:51.819Z,1557960471.819 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-05-15T22:47:51.831Z,1557960471.831 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-05-15T22:47:51.832Z,1557960471.832 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-05-15T22:47:51.832Z,1557960471.832 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4813
2019-05-15T22:47:51.833Z,1557960471.833 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-05-15T22:47:51.834Z,1557960471.834 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-05-15T22:47:51.834Z,1557960471.834 [logger ThreadHandler](INFO): Protected caller Thread ID is 4814
2019-05-15T22:47:51.836Z,1557960471.836 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-05-15T22:47:51.837Z,1557960471.837 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-05-15T22:47:51.838Z,1557960471.838 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-05-15T22:47:52.276Z,1557960472.276 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-05-15T22:47:52.276Z,1557960472.276 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-05-15T22:47:52.423Z,1557960472.423 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-05-15T22:47:52.424Z,1557960472.424 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-05-15T22:47:52.505Z,1557960472.505 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-05-15T22:47:52.605Z,1557960472.605 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-05-15T22:47:52.606Z,1557960472.606 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-05-15T22:47:52.702Z,1557960472.702 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-05-15T22:47:52.703Z,1557960472.703 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-05-15T22:47:53.093Z,1557960473.093 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-05-15T22:47:53.093Z,1557960473.093 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-05-15T22:47:53.198Z,1557960473.198 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-05-15T22:47:53.198Z,1557960473.198 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-05-15T22:47:53.670Z,1557960473.670 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-05-15T22:47:53.671Z,1557960473.671 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-05-15T22:47:53.880Z,1557960473.880 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-05-15T22:47:53.880Z,1557960473.880 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-05-15T22:47:54.193Z,1557960474.193 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-05-15T22:47:54.194Z,1557960474.194 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-05-15T22:47:54.423Z,1557960474.423 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-05-15T22:47:54.424Z,1557960474.424 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-05-15T22:47:54.924Z,1557960474.924 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-05-15T22:47:54.925Z,1557960474.925 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-05-15T22:47:55.523Z,1557960475.523 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-05-15T22:47:55.524Z,1557960475.524 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-05-15T22:47:55.869Z,1557960475.869 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-05-15T22:47:55.870Z,1557960475.870 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-05-15T22:47:56.081Z,1557960476.081 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-05-15T22:47:56.083Z,1557960476.083 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2019-05-15T22:47:56.084Z,1557960476.084 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2019-05-15T22:47:56.277Z,1557960476.277 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2019-05-15T22:47:56.361Z,1557960476.362 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2019-05-15T22:47:56.482Z,1557960476.482 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2019-05-15T22:47:56.568Z,1557960476.569 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2019-05-15T22:47:56.803Z,1557960476.803 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-05-15T22:47:56.804Z,1557960476.804 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2019-05-15T22:47:56.902Z,1557960476.902 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2019-05-15T22:47:57.015Z,1557960477.015 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2019-05-15T22:47:57.112Z,1557960477.112 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2019-05-15T22:47:57.270Z,1557960477.270 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2019-05-15T22:47:57.374Z,1557960477.374 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2019-05-15T22:47:57.475Z,1557960477.475 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2019-05-15T22:47:57.475Z,1557960477.475 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-05-15T22:47:57.478Z,1557960477.478 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-05-15T22:47:57.492Z,1557960477.492 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-05-15T22:47:57.492Z,1557960477.492 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-05-15T22:47:57.559Z,1557960477.559 [VerticalControl](DEBUG): Construct VerticalControl.
2019-05-15T22:47:57.674Z,1557960477.674 [VerticalControl] Loaded
2019-05-15T22:47:57.675Z,1557960477.675 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-05-15T22:47:57.675Z,1557960477.675 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-05-15T22:47:57.745Z,1557960477.745 [HorizontalControl] Loaded
2019-05-15T22:47:57.745Z,1557960477.745 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-05-15T22:47:57.746Z,1557960477.746 [SpeedControl](DEBUG): Construct SpeedControl.
2019-05-15T22:47:57.752Z,1557960477.752 [SpeedControl] Loaded
2019-05-15T22:47:57.752Z,1557960477.752 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-05-15T22:47:57.753Z,1557960477.753 [LoopControl](DEBUG): Construct LoopControl.
2019-05-15T22:47:57.753Z,1557960477.753 [LoopControl] Loaded
2019-05-15T22:47:57.754Z,1557960477.754 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-05-15T22:47:57.754Z,1557960477.754 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-05-15T22:47:57.754Z,1557960477.754 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-05-15T22:47:57.887Z,1557960477.887 [BuoyancyServo] Loaded
2019-05-15T22:47:57.887Z,1557960477.887 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-05-15T22:47:57.899Z,1557960477.899 [ElevatorServo] Loaded
2019-05-15T22:47:57.899Z,1557960477.899 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-05-15T22:47:57.910Z,1557960477.910 [MassServo] Loaded
2019-05-15T22:47:57.910Z,1557960477.910 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-05-15T22:47:57.921Z,1557960477.921 [RudderServo] Loaded
2019-05-15T22:47:57.921Z,1557960477.921 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-05-15T22:47:57.932Z,1557960477.932 [ThrusterServo] Loaded
2019-05-15T22:47:57.933Z,1557960477.933 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-05-15T22:47:57.933Z,1557960477.933 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-05-15T22:47:57.934Z,1557960477.934 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-05-15T22:47:57.974Z,1557960477.974 [DepthRateCalculator] Loaded
2019-05-15T22:47:57.974Z,1557960477.974 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-05-15T22:47:57.980Z,1557960477.980 [PitchRateCalculator] Loaded
2019-05-15T22:47:57.980Z,1557960477.980 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-05-15T22:47:57.995Z,1557960477.995 [SpeedCalculator] Loaded
2019-05-15T22:47:57.996Z,1557960477.996 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-05-15T22:47:58.017Z,1557960478.017 [TempGradientCalculator] Loaded
2019-05-15T22:47:58.017Z,1557960478.017 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-05-15T22:47:58.022Z,1557960478.022 [YawRateCalculator] Loaded
2019-05-15T22:47:58.023Z,1557960478.023 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-05-15T22:47:58.052Z,1557960478.052 [ElevatorOffsetCalculator] Loaded
2019-05-15T22:47:58.052Z,1557960478.052 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-05-15T22:47:58.053Z,1557960478.053 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-05-15T22:47:58.053Z,1557960478.053 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-05-15T22:47:58.158Z,1557960478.158 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-05-15T22:47:58.159Z,1557960478.159 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-05-15T22:47:58.179Z,1557960478.179 [NavChart] Loaded
2019-05-15T22:47:58.180Z,1557960478.180 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-05-15T22:47:58.184Z,1557960478.184 [UniversalFixResidualReporter] Loaded
2019-05-15T22:47:58.184Z,1557960478.184 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-05-15T22:47:58.184Z,1557960478.184 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-05-15T22:47:58.185Z,1557960478.185 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-05-15T22:47:58.562Z,1557960478.562 [AHRS_M2] Loaded
2019-05-15T22:47:58.563Z,1557960478.563 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-05-15T22:47:58.641Z,1557960478.641 [DataOverHttps] Loaded
2019-05-15T22:47:58.641Z,1557960478.641 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-05-15T22:47:58.642Z,1557960478.642 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408114E0
2019-05-15T22:47:58.643Z,1557960478.643 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4893
2019-05-15T22:47:58.656Z,1557960478.656 [Depth_Keller] Loaded
2019-05-15T22:47:58.656Z,1557960478.656 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-05-15T22:47:58.661Z,1557960478.661 [DropWeight] Loaded
2019-05-15T22:47:58.662Z,1557960478.662 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-05-15T22:47:58.760Z,1557960478.760 [NAL9602] Loaded
2019-05-15T22:47:58.761Z,1557960478.761 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-05-15T22:47:58.777Z,1557960478.777 [Onboard] Loaded
2019-05-15T22:47:58.777Z,1557960478.777 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-05-15T22:47:58.780Z,1557960478.780 [Radio_Surface] Loaded
2019-05-15T22:47:58.781Z,1557960478.781 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-05-15T22:47:58.782Z,1557960478.782 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408414E0
2019-05-15T22:47:58.782Z,1557960478.782 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4894
2019-05-15T22:48:00.762Z,1557960480.762 [BPC1] Loaded
2019-05-15T22:48:00.762Z,1557960480.762 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-05-15T22:48:00.774Z,1557960480.774 [BR_Ping1D] Loaded
2019-05-15T22:48:00.775Z,1557960480.775 [ComponentRegistry](DEBUG): SyncComponent "BR_Ping1D" handled in the control thread.
2019-05-15T22:48:00.775Z,1557960480.775 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-05-15T22:48:00.776Z,1557960480.776 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-05-15T22:48:00.846Z,1557960480.846 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-05-15T22:48:00.847Z,1557960480.847 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-05-15T22:48:00.945Z,1557960480.945 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-05-15T22:48:00.945Z,1557960480.945 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-05-15T22:48:00.971Z,1557960480.971 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-05-15T22:48:00.972Z,1557960480.972 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-05-15T22:48:01.222Z,1557960481.222 [CTD_NeilBrown] Loaded
2019-05-15T22:48:01.222Z,1557960481.222 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-05-15T22:48:01.223Z,1557960481.223 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409CD4E0
2019-05-15T22:48:01.224Z,1557960481.224 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 4895
2019-05-15T22:48:01.233Z,1557960481.233 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-05-15T22:48:01.238Z,1557960481.238 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-05-15T22:48:01.240Z,1557960481.240 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-05-15T22:48:01.245Z,1557960481.245 [CTD_Seabird](INFO): created writer for : depth
2019-05-15T22:48:01.245Z,1557960481.245 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-05-15T22:48:01.250Z,1557960481.250 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-05-15T22:48:01.251Z,1557960481.251 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-05-15T22:48:01.256Z,1557960481.256 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-05-15T22:48:01.256Z,1557960481.256 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-05-15T22:48:01.261Z,1557960481.261 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-05-15T22:48:01.262Z,1557960481.262 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-05-15T22:48:01.267Z,1557960481.267 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-05-15T22:48:01.268Z,1557960481.268 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-05-15T22:48:01.273Z,1557960481.273 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-05-15T22:48:01.300Z,1557960481.300 [CTD_Seabird] Loaded
2019-05-15T22:48:01.300Z,1557960481.300 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-05-15T22:48:01.301Z,1557960481.301 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409FD4E0
2019-05-15T22:48:01.302Z,1557960481.302 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4896
2019-05-15T22:48:01.316Z,1557960481.316 [PAR_Licor] Loaded
2019-05-15T22:48:01.317Z,1557960481.317 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-05-15T22:48:01.365Z,1557960481.365 [WetLabsBB2FL] Loaded
2019-05-15T22:48:01.365Z,1557960481.365 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-05-15T22:48:01.366Z,1557960481.366 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A2D4E0
2019-05-15T22:48:01.366Z,1557960481.366 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4897
2019-05-15T22:48:01.367Z,1557960481.367 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-05-15T22:48:01.368Z,1557960481.368 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-05-15T22:48:01.664Z,1557960481.664 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-05-15T22:48:01.664Z,1557960481.664 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-05-15T22:48:01.801Z,1557960481.801 [SBIT](DEBUG): Construct Startup Built In Test.
2019-05-15T22:48:01.812Z,1557960481.812 [SBIT] Loaded
2019-05-15T22:48:01.813Z,1557960481.813 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-05-15T22:48:01.813Z,1557960481.813 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-05-15T22:48:01.825Z,1557960481.825 [IBIT] Loaded
2019-05-15T22:48:01.825Z,1557960481.825 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-05-15T22:48:01.828Z,1557960481.828 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-05-15T22:48:01.969Z,1557960481.969 [CBIT] Loaded
2019-05-15T22:48:01.969Z,1557960481.969 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-05-15T22:48:01.970Z,1557960481.970 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-05-15T22:48:01.973Z,1557960481.973 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-05-15T22:48:01.974Z,1557960481.974 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-05-15T22:48:01.981Z,1557960481.981 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-05-15T22:48:01.982Z,1557960481.982 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B0C4E0
2019-05-15T22:48:01.982Z,1557960481.982 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4898
2019-05-15T22:48:01.987Z,1557960481.987 [Supervisor](INFO): Main Thread ID is 803
2019-05-15T22:48:01.987Z,1557960481.987 [Supervisor](DEBUG): Running supervisor.
2019-05-15T22:48:01.988Z,1557960481.988 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4899
2019-05-15T22:48:01.990Z,1557960481.990 [controlThread ThreadHandler](INFO): Handler Thread ID is 4900
2019-05-15T22:48:01.990Z,1557960481.990 [controlThread](DEBUG): Initializing ControlThread
2019-05-15T22:48:01.991Z,1557960481.991 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-05-15T22:48:01.993Z,1557960481.993 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-05-15T22:48:01.994Z,1557960481.994 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-05-15T22:48:01.994Z,1557960481.994 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-05-15T22:48:01.995Z,1557960481.995 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-05-15T22:48:01.996Z,1557960481.996 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-05-15T22:48:01.996Z,1557960481.996 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-05-15T22:48:01.996Z,1557960481.996 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-05-15T22:48:01.997Z,1557960481.997 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-05-15T22:48:01.997Z,1557960481.997 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-05-15T22:48:01.998Z,1557960481.998 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-05-15T22:48:01.999Z,1557960481.999 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-05-15T22:48:02.005Z,1557960482.005 [SBIT](INFO): Initialize SBIT Component.
2019-05-15T22:48:02.006Z,1557960482.006 [SBIT](IMPORTANT): git: 2019-05-01-16-g58f4cc3
2019-05-15T22:48:02.006Z,1557960482.006 [SBIT](INFO): git hash: 58f4cc369d803afb19aea4821bdb4c673a350570
2019-05-15T22:48:02.006Z,1557960482.006 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-05-15T22:48:02.007Z,1557960482.007 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-05-15T22:48:02.008Z,1557960482.008 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-05-15T22:48:02.009Z,1557960482.009 [IBIT](INFO): Initialize IBIT Component.
2019-05-15T22:48:02.010Z,1557960482.010 [CBIT](DEBUG): Initialize CBIT Component.
2019-05-15T22:48:02.011Z,1557960482.011 [logger ThreadHandler](INFO): Handler Thread ID is 4901
2019-05-15T22:48:02.021Z,1557960482.021 [CBIT](DEBUG): Initialized mux pins.
2019-05-15T22:48:02.021Z,1557960482.021 [CBIT](DEBUG): Initializing the watchdog timer.
2019-05-15T22:48:02.029Z,1557960482.029 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4902
2019-05-15T22:48:02.030Z,1557960482.030 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-05-15T22:48:02.045Z,1557960482.045 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4903
2019-05-15T22:48:02.051Z,1557960482.051 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2019-05-15T22:48:02.051Z,1557960482.051 [CBIT](DEBUG): Initializing heartbeat.
2019-05-15T22:48:02.057Z,1557960482.057 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 4904
2019-05-15T22:48:02.069Z,1557960482.069 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4905
2019-05-15T22:48:02.070Z,1557960482.070 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-15T22:48:02.073Z,1557960482.073 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-15T22:48:02.075Z,1557960482.075 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4907
2019-05-15T22:48:02.076Z,1557960482.076 [WetLabsBB2FL](INFO): Powering down
2019-05-15T22:48:02.109Z,1557960482.109 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4908
2019-05-15T22:48:02.112Z,1557960482.112 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-05-15T22:48:02.113Z,1557960482.113 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-05-15T22:48:02.113Z,1557960482.113 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-05-15T22:48:02.113Z,1557960482.113 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-05-15T22:48:02.113Z,1557960482.113 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-05-15T22:48:02.114Z,1557960482.114 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-05-15T22:48:02.114Z,1557960482.114 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-05-15T22:48:02.114Z,1557960482.114 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-05-15T22:48:02.114Z,1557960482.114 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-05-15T22:48:02.114Z,1557960482.114 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-05-15T22:48:02.115Z,1557960482.115 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-05-15T22:48:02.115Z,1557960482.115 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-05-15T22:48:02.115Z,1557960482.115 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-05-15T22:48:02.115Z,1557960482.115 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-05-15T22:48:02.115Z,1557960482.115 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-05-15T22:48:02.116Z,1557960482.116 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-05-15T22:48:02.125Z,1557960482.125 [CBIT](DEBUG): Deactivating GF circuits.
2019-05-15T22:48:02.125Z,1557960482.125 [CBIT](DEBUG): Deactivating emergency mode.
2019-05-15T22:48:02.161Z,1557960482.161 [CBIT](DEBUG): Backplane powered.
2019-05-15T22:48:02.162Z,1557960482.162 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-05-15T22:48:02.171Z,1557960482.171 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-15T22:48:02.182Z,1557960482.182 [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-05-15T22:48:02.183Z,1557960482.183 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-05-15T22:48:02.249Z,1557960482.249 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-05-15T22:48:02.250Z,1557960482.250 [Default:A.Wait](DEBUG): Construct Wait.
2019-05-15T22:48:02.252Z,1557960482.252 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-15T22:48:02.292Z,1557960482.292 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-05-15T22:48:02.294Z,1557960482.294 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-05-15T22:48:02.316Z,1557960482.316 [Default:E.Execute](DEBUG): Construct Execute.
2019-05-15T22:48:02.320Z,1557960482.320 [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-05-15T22:48:02.337Z,1557960482.337 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,BPC1,BR_Ping1D,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-05-15T22:48:02.345Z,1557960482.345 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-05-15T22:48:02.418Z,1557960482.418 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar
2019-05-15T22:48:02.449Z,1557960482.449 [Radio_Surface](INFO): Powering up
2019-05-15T22:48:02.570Z,1557960482.570 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-05-15T22:48:02.776Z,1557960482.776 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-05-15T22:48:02.781Z,1557960482.781 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-05-15T22:48:02.787Z,1557960482.787 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-05-15T22:48:02.793Z,1557960482.793 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-05-15T22:48:02.815Z,1557960482.815 [MassServo](DEBUG): Initializing EZServoServo.
2019-05-15T22:48:02.821Z,1557960482.821 [MassServo](DEBUG): Initializing MassServo.
2019-05-15T22:48:02.839Z,1557960482.839 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-15T22:48:02.845Z,1557960482.845 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-15T22:48:02.855Z,1557960482.855 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-05-15T22:48:02.861Z,1557960482.861 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-05-15T22:48:02.906Z,1557960482.906 [CommandLine](FAULT): Scheduling is paused
2019-05-15T22:48:02.906Z,1557960482.906 [CBIT](INFO): Critical error at 20190515T224802
2019-05-15T22:48:02.906Z,1557960482.906 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-05-15T22:48:03.095Z,1557960483.095 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-05-15T22:48:03.095Z,1557960483.095 [DropWeight] Hardware Fault, FailCount= 1
2019-05-15T22:48:03.095Z,1557960483.095 [DropWeight](ERROR): Hardware Fault
2019-05-15T22:48:03.121Z,1557960483.121 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:48:03.185Z,1557960483.185 [CBIT](INFO): Critical error at 20190515T224803
2019-05-15T22:48:03.188Z,1557960483.188 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-05-15T22:48:03.189Z,1557960483.189 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-05-15T22:48:03.741Z,1557960483.741 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-05-15T22:48:03.741Z,1557960483.741 [RudderServo](FAULT): Rudder failed to initialize
2019-05-15T22:48:03.741Z,1557960483.741 [RudderServo] Communications Fault, FailCount= 1
2019-05-15T22:48:03.741Z,1557960483.741 [RudderServo](ERROR): Communications Fault
2019-05-15T22:48:03.851Z,1557960483.851 [CBIT](INFO): Critical error at 20190515T224803
2019-05-15T22:48:03.853Z,1557960483.853 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-05-15T22:48:03.882Z,1557960483.882 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:48:04.046Z,1557960484.046 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-05-15T22:48:04.046Z,1557960484.046 [RudderServo](INFO): Powering down
2019-05-15T22:48:04.281Z,1557960484.281 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:48:04.282Z,1557960484.282 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:48:04.282Z,1557960484.282 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:48:04.284Z,1557960484.284 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:48:04.284Z,1557960484.284 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:48:04.285Z,1557960484.285 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:48:04.285Z,1557960484.285 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:48:04.285Z,1557960484.285 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:48:04.285Z,1557960484.286 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:48:04.286Z,1557960484.286 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:48:04.686Z,1557960484.686 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:48:04.751Z,1557960484.751 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-15T22:48:04.870Z,1557960484.870 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-15T22:48:04.875Z,1557960484.875 [CBIT](INFO): Clearing failed state for component RudderServo
2019-05-15T22:48:04.875Z,1557960484.875 [RudderServo] No Fault, FailCount= 1
2019-05-15T22:48:05.538Z,1557960485.538 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:48:05.922Z,1557960485.922 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:48:05.923Z,1557960485.923 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:48:05.923Z,1557960485.923 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:48:05.925Z,1557960485.925 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:48:05.925Z,1557960485.925 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:48:05.925Z,1557960485.925 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:48:05.925Z,1557960485.925 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:48:05.926Z,1557960485.926 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:48:05.926Z,1557960485.926 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:48:05.926Z,1557960485.926 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:48:06.326Z,1557960486.326 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:48:07.134Z,1557960487.134 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:48:07.537Z,1557960487.537 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:48:07.538Z,1557960487.538 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:48:07.538Z,1557960487.538 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:48:07.540Z,1557960487.540 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:48:07.540Z,1557960487.540 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:48:07.540Z,1557960487.540 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:48:07.541Z,1557960487.541 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:48:07.541Z,1557960487.541 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:48:07.541Z,1557960487.541 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:48:07.542Z,1557960487.542 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:48:07.942Z,1557960487.942 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:48:08.826Z,1557960488.826 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:48:09.226Z,1557960489.226 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:48:09.226Z,1557960489.226 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:48:09.226Z,1557960489.226 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:48:09.228Z,1557960489.228 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:48:09.229Z,1557960489.229 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:48:09.229Z,1557960489.229 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:48:09.229Z,1557960489.229 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:48:09.230Z,1557960489.230 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:48:09.230Z,1557960489.230 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:48:09.230Z,1557960489.230 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:48:09.230Z,1557960489.230 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:48:09.230Z,1557960489.230 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:48:09.231Z,1557960489.231 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:48:09.334Z,1557960489.334 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:48:09.629Z,1557960489.629 [BR_Ping1D](INFO): Powering down
2019-05-15T22:48:10.998Z,1557960490.998 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:48:10.999Z,1557960490.999 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:48:12.042Z,1557960492.042 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:48:12.862Z,1557960492.862 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:48:13.254Z,1557960493.254 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:48:13.258Z,1557960493.258 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:48:13.258Z,1557960493.258 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:48:13.258Z,1557960493.258 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:48:13.259Z,1557960493.259 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:48:13.259Z,1557960493.259 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:48:13.259Z,1557960493.259 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:48:13.259Z,1557960493.259 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:48:13.260Z,1557960493.260 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:48:13.260Z,1557960493.260 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:48:13.260Z,1557960493.260 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:48:13.310Z,1557960493.310 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:48:13.657Z,1557960493.657 [BR_Ping1D](INFO): Powering down
2019-05-15T22:48:14.944Z,1557960494.944 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:48:14.944Z,1557960494.944 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:48:15.121Z,1557960495.121 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-15T22:48:15.121Z,1557960495.121 [CTD_Seabird] Communications Fault, FailCount= 1
2019-05-15T22:48:15.121Z,1557960495.121 [CTD_Seabird](ERROR): Communications Fault
2019-05-15T22:48:15.365Z,1557960495.365 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-15T22:48:15.525Z,1557960495.525 [CTD_Seabird](INFO): Powering down
2019-05-15T22:48:16.082Z,1557960496.082 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:48:16.580Z,1557960496.580 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-15T22:48:16.580Z,1557960496.580 [CTD_Seabird] No Fault, FailCount= 1
2019-05-15T22:48:16.889Z,1557960496.889 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-15T22:48:16.891Z,1557960496.891 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-15T22:48:16.892Z,1557960496.892 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:48:17.293Z,1557960497.293 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:48:17.294Z,1557960497.294 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:48:17.294Z,1557960497.294 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:48:17.296Z,1557960497.296 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:48:17.296Z,1557960497.296 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:48:17.296Z,1557960497.296 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:48:17.297Z,1557960497.297 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:48:17.297Z,1557960497.297 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:48:17.298Z,1557960497.298 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:48:17.298Z,1557960497.298 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:48:17.298Z,1557960497.298 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:48:17.298Z,1557960497.298 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:48:17.298Z,1557960497.298 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:48:17.366Z,1557960497.366 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:48:17.698Z,1557960497.698 [BR_Ping1D](INFO): Powering down
2019-05-15T22:48:18.962Z,1557960498.962 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:48:18.962Z,1557960498.962 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:48:20.122Z,1557960500.122 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:48:20.505Z,1557960500.505 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-05-15T22:48:20.505Z,1557960500.505 [AHRS_M2] Data Fault, FailCount= 1
2019-05-15T22:48:20.505Z,1557960500.505 [AHRS_M2](ERROR): Data Fault
2019-05-15T22:48:20.593Z,1557960500.593 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-05-15T22:48:20.898Z,1557960500.898 [AHRS_M2](INFO): Powering down
2019-05-15T22:48:20.990Z,1557960500.990 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:48:21.322Z,1557960501.322 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:48:21.323Z,1557960501.323 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:48:21.323Z,1557960501.323 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:48:21.325Z,1557960501.325 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:48:21.325Z,1557960501.325 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:48:21.325Z,1557960501.325 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:48:21.325Z,1557960501.325 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:48:21.326Z,1557960501.326 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:48:21.326Z,1557960501.326 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:48:21.326Z,1557960501.326 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:48:21.326Z,1557960501.326 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:48:21.327Z,1557960501.327 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:48:21.327Z,1557960501.327 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:48:21.375Z,1557960501.375 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:48:21.726Z,1557960501.726 [BR_Ping1D](INFO): Powering down
2019-05-15T22:48:21.862Z,1557960501.862 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-05-15T22:48:21.862Z,1557960501.862 [AHRS_M2] No Fault, FailCount= 1
2019-05-15T22:48:22.109Z,1557960502.109 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-05-15T22:48:22.989Z,1557960502.989 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:48:22.989Z,1557960502.989 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:48:24.150Z,1557960504.150 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:48:24.957Z,1557960504.957 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:48:25.385Z,1557960505.385 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:48:25.389Z,1557960505.389 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:48:25.389Z,1557960505.389 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:48:25.390Z,1557960505.390 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:48:25.390Z,1557960505.390 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:48:25.390Z,1557960505.390 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:48:25.390Z,1557960505.390 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:48:25.391Z,1557960505.391 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:48:25.391Z,1557960505.391 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:48:25.391Z,1557960505.391 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:48:25.391Z,1557960505.391 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:48:25.475Z,1557960505.475 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:48:25.849Z,1557960505.849 [BR_Ping1D](INFO): Powering down
2019-05-15T22:48:27.061Z,1557960507.061 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:48:27.061Z,1557960507.061 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:48:28.202Z,1557960508.202 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:48:28.994Z,1557960508.994 [NAL9602](INFO): Powering up NAL9602
2019-05-15T22:48:29.011Z,1557960509.011 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:48:29.413Z,1557960509.413 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:48:29.417Z,1557960509.417 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:48:29.417Z,1557960509.417 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:48:29.418Z,1557960509.418 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:48:29.418Z,1557960509.418 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:48:29.418Z,1557960509.418 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:48:29.418Z,1557960509.418 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:48:29.419Z,1557960509.419 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:48:29.419Z,1557960509.419 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:48:29.419Z,1557960509.419 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:48:29.419Z,1557960509.419 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:48:29.469Z,1557960509.469 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:48:29.817Z,1557960509.817 [BR_Ping1D](INFO): Powering down
2019-05-15T22:48:29.949Z,1557960509.949 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-15T22:48:29.949Z,1557960509.949 [CTD_Seabird] Communications Fault, FailCount= 2
2019-05-15T22:48:29.949Z,1557960509.949 [CTD_Seabird](ERROR): Communications Fault
2019-05-15T22:48:30.301Z,1557960510.301 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-15T22:48:30.353Z,1557960510.353 [CTD_Seabird](INFO): Powering down
2019-05-15T22:48:30.708Z,1557960510.708 [SBIT](IMPORTANT): Beginning Startup BIT
2019-05-15T22:48:30.713Z,1557960510.713 [CBIT](IMPORTANT): Beginning ground fault scan
2019-05-15T22:48:31.131Z,1557960511.131 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:48:31.131Z,1557960511.131 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:48:31.530Z,1557960511.530 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-15T22:48:31.530Z,1557960511.530 [CTD_Seabird] No Fault, FailCount= 2
2019-05-15T22:48:31.697Z,1557960511.697 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-15T22:48:31.705Z,1557960511.705 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-15T22:48:32.242Z,1557960512.242 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:48:33.241Z,1557960513.241 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:48:33.621Z,1557960513.621 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:48:33.622Z,1557960513.622 [BR_Ping1D](INFO): Not enough UART bytes for payload try again on next cycle
2019-05-15T22:48:33.622Z,1557960513.622 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:48:34.026Z,1557960514.026 [BR_Ping1D](INFO): Not enough UART bytes for payload try again on next cycle
2019-05-15T22:48:34.026Z,1557960514.026 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:48:34.429Z,1557960514.429 [BR_Ping1D](INFO): Not enough UART bytes for payload try again on next cycle
2019-05-15T22:48:34.429Z,1557960514.429 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:48:34.834Z,1557960514.834 [BR_Ping1D](ERROR): Using more than 1 sec to receive one packet, resetting UART
2019-05-15T22:48:34.834Z,1557960514.834 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:48:34.838Z,1557960514.838 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:48:34.838Z,1557960514.838 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:48:34.838Z,1557960514.838 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:48:34.838Z,1557960514.838 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:48:34.839Z,1557960514.839 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:48:34.839Z,1557960514.839 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:48:34.839Z,1557960514.839 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:48:34.839Z,1557960514.839 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:48:34.839Z,1557960514.839 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:48:34.839Z,1557960514.839 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:48:34.958Z,1557960514.958 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:48:35.244Z,1557960515.244 [BR_Ping1D](INFO): Powering down
2019-05-15T22:48:35.623Z,1557960515.623 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-05-15T22:48:35.623Z,1557960515.623 [AHRS_M2] Data Fault, FailCount= 2
2019-05-15T22:48:35.623Z,1557960515.623 [AHRS_M2](ERROR): Data Fault
2019-05-15T22:48:35.830Z,1557960515.830 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-05-15T22:48:36.013Z,1557960516.013 [AHRS_M2](INFO): Powering down
2019-05-15T22:48:36.534Z,1557960516.534 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:48:36.535Z,1557960516.535 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:48:36.947Z,1557960516.947 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-05-15T22:48:36.947Z,1557960516.947 [AHRS_M2] No Fault, FailCount= 2
2019-05-15T22:48:37.225Z,1557960517.225 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-05-15T22:48:37.649Z,1557960517.649 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:48:38.457Z,1557960518.457 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:48:38.866Z,1557960518.866 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:48:38.871Z,1557960518.871 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:48:38.871Z,1557960518.871 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:48:38.871Z,1557960518.871 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:48:38.871Z,1557960518.871 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:48:38.872Z,1557960518.872 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:48:38.872Z,1557960518.872 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:48:38.872Z,1557960518.872 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:48:38.872Z,1557960518.872 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:48:38.872Z,1557960518.872 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:48:38.872Z,1557960518.872 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:48:38.938Z,1557960518.938 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:48:39.265Z,1557960519.265 [BR_Ping1D](INFO): Powering down
2019-05-15T22:48:40.058Z,1557960520.058 [NAL9602](INFO): NAL9602 initialized
2019-05-15T22:48:40.781Z,1557960520.781 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-05-15T22:48:40.931Z,1557960520.931 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:48:40.931Z,1557960520.931 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:48:41.285Z,1557960521.285 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-05-15T22:48:41.301Z,1557960521.301 [NAL9602](DEBUG): Fix Requested
2019-05-15T22:48:41.937Z,1557960521.937 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-05-15T22:48:42.006Z,1557960522.006 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:48:42.062Z,1557960522.062 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.028084
CHAN A1 (24V): 0.001238
CHAN A2 (12V): -0.004345
CHAN A3 (5V): -0.003505
CHAN B0 (3.3V): -0.001216
CHAN B1 (3.15aV): -0.001256
CHAN B2 (3.15bV): -0.000831
CHAN B3 (GND): 0.000003
OPEN: 0.003395
Full Scale Calc: 4.765 mA, -1.589 mA
2019-05-15T22:48:42.510Z,1557960522.510 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:48:42.914Z,1557960522.914 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:48:42.915Z,1557960522.915 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:48:42.915Z,1557960522.915 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:48:42.917Z,1557960522.917 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:48:42.917Z,1557960522.917 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:48:42.918Z,1557960522.918 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:48:42.918Z,1557960522.918 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:48:42.918Z,1557960522.918 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:48:42.918Z,1557960522.918 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:48:42.919Z,1557960522.919 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:48:42.919Z,1557960522.919 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:48:42.919Z,1557960522.919 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:48:42.919Z,1557960522.919 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:48:43.034Z,1557960523.034 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:48:43.328Z,1557960523.328 [BR_Ping1D](INFO): Powering down
2019-05-15T22:48:44.579Z,1557960524.579 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:48:44.579Z,1557960524.579 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:48:44.749Z,1557960524.749 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-15T22:48:44.749Z,1557960524.749 [CTD_Seabird] Communications Fault, FailCount= 3
2019-05-15T22:48:44.749Z,1557960524.749 [CTD_Seabird](ERROR): Communications Fault
2019-05-15T22:48:44.998Z,1557960524.998 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-15T22:48:44.999Z,1557960524.999 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-05-15T22:48:45.153Z,1557960525.153 [CTD_Seabird](INFO): Powering down
2019-05-15T22:48:45.747Z,1557960525.747 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:48:46.666Z,1557960526.666 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:48:47.062Z,1557960527.062 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:48:47.063Z,1557960527.063 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:48:47.063Z,1557960527.063 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:48:47.065Z,1557960527.065 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:48:47.065Z,1557960527.065 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:48:47.065Z,1557960527.065 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:48:47.065Z,1557960527.065 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:48:47.066Z,1557960527.066 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:48:47.066Z,1557960527.066 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:48:47.066Z,1557960527.066 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:48:47.066Z,1557960527.066 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:48:47.066Z,1557960527.066 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:48:47.067Z,1557960527.067 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:48:47.191Z,1557960527.191 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:48:47.472Z,1557960527.472 [BR_Ping1D](INFO): Powering down
2019-05-15T22:48:48.826Z,1557960528.826 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:48:48.826Z,1557960528.826 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:48:49.890Z,1557960529.890 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:48:50.698Z,1557960530.698 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:48:51.108Z,1557960531.108 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:48:51.109Z,1557960531.109 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:48:51.109Z,1557960531.109 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:48:51.112Z,1557960531.112 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:48:51.112Z,1557960531.112 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:48:51.516Z,1557960531.516 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:48:51.910Z,1557960531.910 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:48:52.293Z,1557960532.293 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-05-15T22:48:52.293Z,1557960532.293 [AHRS_M2] Data Fault, FailCount= 3
2019-05-15T22:48:52.293Z,1557960532.293 [AHRS_M2](ERROR): Data Fault
2019-05-15T22:48:52.314Z,1557960532.314 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:48:52.434Z,1557960532.434 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-05-15T22:48:52.685Z,1557960532.685 [AHRS_M2](INFO): Powering down
2019-05-15T22:48:52.796Z,1557960532.796 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:48:53.121Z,1557960533.121 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:48:53.525Z,1557960533.525 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:48:53.626Z,1557960533.626 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-05-15T22:48:53.627Z,1557960533.627 [AHRS_M2] No Fault, FailCount= 3
2019-05-15T22:48:53.897Z,1557960533.897 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-05-15T22:48:53.936Z,1557960533.936 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:48:54.331Z,1557960534.331 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:48:54.732Z,1557960534.732 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:48:55.130Z,1557960535.130 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-05-15T22:48:55.130Z,1557960535.130 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:48:55.130Z,1557960535.130 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:48:55.239Z,1557960535.239 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:48:55.534Z,1557960535.534 [BR_Ping1D](INFO): Powering down
2019-05-15T22:48:56.823Z,1557960536.823 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:48:56.823Z,1557960536.823 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:48:57.988Z,1557960537.988 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:48:58.800Z,1557960538.800 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:48:59.186Z,1557960539.186 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:48:59.186Z,1557960539.186 [BR_Ping1D](INFO): CRC error in received UART packet
2019-05-15T22:48:59.186Z,1557960539.186 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:48:59.594Z,1557960539.594 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:48:59.594Z,1557960539.594 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:48:59.594Z,1557960539.594 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:48:59.594Z,1557960539.594 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:48:59.595Z,1557960539.595 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:48:59.595Z,1557960539.595 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:48:59.595Z,1557960539.595 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:48:59.595Z,1557960539.595 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:48:59.595Z,1557960539.595 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:48:59.595Z,1557960539.595 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:48:59.691Z,1557960539.691 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:48:59.993Z,1557960539.993 [BR_Ping1D](INFO): Powering down
2019-05-15T22:49:01.267Z,1557960541.267 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:49:01.267Z,1557960541.267 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:49:02.410Z,1557960542.410 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:49:03.263Z,1557960543.263 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:49:03.670Z,1557960543.670 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:49:03.671Z,1557960543.671 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:49:03.671Z,1557960543.671 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:49:03.673Z,1557960543.673 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 230104704, Device: 1500000
2019-05-15T22:49:03.673Z,1557960543.673 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:49:03.674Z,1557960543.674 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:49:03.674Z,1557960543.674 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:49:03.674Z,1557960543.674 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:49:03.674Z,1557960543.674 [BR_Ping1D](INFO): (soundspeed) 230104704 <-> 1500000
2019-05-15T22:49:03.675Z,1557960543.675 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:49:03.675Z,1557960543.675 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:49:03.675Z,1557960543.675 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:49:03.675Z,1557960543.675 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:49:03.734Z,1557960543.734 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:49:04.044Z,1557960544.044 [BR_Ping1D](INFO): Powering down
2019-05-15T22:49:05.278Z,1557960545.278 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:49:05.278Z,1557960545.278 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:49:06.458Z,1557960546.458 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:49:07.241Z,1557960547.241 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-05-15T22:49:07.241Z,1557960547.241 [AHRS_M2] Data Fault, FailCount= 4
2019-05-15T22:49:07.241Z,1557960547.241 [AHRS_M2](ERROR): Data Fault
2019-05-15T22:49:07.259Z,1557960547.259 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:49:07.283Z,1557960547.283 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-05-15T22:49:07.636Z,1557960547.636 [AHRS_M2](INFO): Powering down
2019-05-15T22:49:07.731Z,1557960547.731 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:49:07.731Z,1557960547.731 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:49:07.732Z,1557960547.732 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:49:07.734Z,1557960547.734 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:49:07.734Z,1557960547.734 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:49:07.734Z,1557960547.734 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:49:07.734Z,1557960547.734 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:49:07.735Z,1557960547.735 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:49:07.735Z,1557960547.735 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:49:07.735Z,1557960547.735 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:49:07.735Z,1557960547.735 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:49:07.735Z,1557960547.735 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:49:07.735Z,1557960547.735 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:49:07.822Z,1557960547.822 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:49:08.068Z,1557960548.068 [BR_Ping1D](INFO): Powering down
2019-05-15T22:49:08.497Z,1557960548.497 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-05-15T22:49:08.498Z,1557960548.498 [AHRS_M2] No Fault, FailCount= 4
2019-05-15T22:49:08.845Z,1557960548.845 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-05-15T22:49:09.323Z,1557960549.323 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:49:09.323Z,1557960549.323 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:49:10.482Z,1557960550.482 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:49:11.295Z,1557960551.295 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:49:11.701Z,1557960551.701 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:49:11.702Z,1557960551.702 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:49:11.702Z,1557960551.702 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:49:11.704Z,1557960551.704 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:49:11.704Z,1557960551.704 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:49:11.704Z,1557960551.704 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:49:11.704Z,1557960551.704 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:49:11.705Z,1557960551.705 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:49:11.705Z,1557960551.705 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:49:11.706Z,1557960551.706 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:49:11.706Z,1557960551.706 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:49:11.706Z,1557960551.706 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:49:11.706Z,1557960551.706 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:49:11.810Z,1557960551.810 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:49:12.127Z,1557960552.127 [BR_Ping1D](INFO): Powering down
2019-05-15T22:49:13.586Z,1557960553.586 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:49:13.586Z,1557960553.586 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:49:14.653Z,1557960554.653 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:49:15.515Z,1557960555.515 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:49:15.930Z,1557960555.930 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:49:15.930Z,1557960555.930 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:49:15.931Z,1557960555.931 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:49:15.932Z,1557960555.932 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:49:15.933Z,1557960555.933 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:49:15.933Z,1557960555.933 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:49:15.933Z,1557960555.933 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:49:15.934Z,1557960555.934 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:49:15.934Z,1557960555.934 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:49:15.934Z,1557960555.934 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:49:15.934Z,1557960555.934 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:49:15.934Z,1557960555.934 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:49:15.934Z,1557960555.934 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:49:16.167Z,1557960556.167 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:49:16.298Z,1557960556.298 [BR_Ping1D](INFO): Powering down
2019-05-15T22:49:17.531Z,1557960557.531 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:49:17.531Z,1557960557.531 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:49:18.696Z,1557960558.696 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:49:19.513Z,1557960559.513 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:49:19.904Z,1557960559.904 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:49:19.905Z,1557960559.905 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:49:19.905Z,1557960559.905 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:49:19.907Z,1557960559.907 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:49:19.907Z,1557960559.907 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:49:19.907Z,1557960559.907 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:49:19.907Z,1557960559.907 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:49:19.908Z,1557960559.908 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:49:19.908Z,1557960559.908 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:49:19.908Z,1557960559.908 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:49:19.908Z,1557960559.908 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:49:19.908Z,1557960559.908 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:49:19.908Z,1557960559.908 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:49:20.023Z,1557960560.023 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:49:20.298Z,1557960560.298 [BR_Ping1D](INFO): Powering down
2019-05-15T22:49:21.566Z,1557960561.566 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:49:21.567Z,1557960561.567 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:49:22.293Z,1557960562.293 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-05-15T22:49:22.293Z,1557960562.293 [AHRS_M2] Data Fault, FailCount= 5
2019-05-15T22:49:22.293Z,1557960562.293 [AHRS_M2](ERROR): Data Fault
2019-05-15T22:49:22.381Z,1557960562.381 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-05-15T22:49:22.381Z,1557960562.381 [CBIT](CRITICAL): Data Fault in component: AHRS_M2
2019-05-15T22:49:22.685Z,1557960562.685 [AHRS_M2](INFO): Powering down
2019-05-15T22:49:22.778Z,1557960562.778 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:49:22.814Z,1557960562.814 [CBIT](INFO): Critical error at 20190515T224922
2019-05-15T22:49:23.515Z,1557960563.515 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:49:23.929Z,1557960563.929 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:49:23.930Z,1557960563.930 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:49:23.930Z,1557960563.930 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:49:23.931Z,1557960563.931 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:49:23.932Z,1557960563.932 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:49:23.932Z,1557960563.932 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:49:23.932Z,1557960563.932 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:49:23.932Z,1557960563.932 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:49:23.933Z,1557960563.933 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:49:23.933Z,1557960563.933 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:49:23.933Z,1557960563.933 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:49:23.933Z,1557960563.933 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:49:23.933Z,1557960563.933 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:49:23.958Z,1557960563.958 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:49:24.332Z,1557960564.332 [BR_Ping1D](INFO): Powering down
2019-05-15T22:49:24.430Z,1557960564.430 [SBIT](IMPORTANT): SBIT PASSED
2019-05-15T22:49:24.509Z,1557960564.509 [CommandLine](IMPORTANT): got command configSet list
2019-05-15T22:49:24.510Z,1557960564.510 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-05-15T22:49:24.510Z,1557960564.510 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;

2019-05-15T22:49:24.758Z,1557960564.758 [MissionManager](IMPORTANT): Started mission Startup
2019-05-15T22:49:24.758Z,1557960564.758 [Startup] Running Loop=1
2019-05-15T22:49:24.758Z,1557960564.758 [Startup](DEBUG): Aggregate::initialize Startup
2019-05-15T22:49:24.758Z,1557960564.758 [Startup:A.GoToSurface] Running Loop=1
2019-05-15T22:49:24.758Z,1557960564.758 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-15T22:49:24.759Z,1557960564.759 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-15T22:49:24.759Z,1557960564.759 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-15T22:49:24.760Z,1557960564.760 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-15T22:49:24.760Z,1557960564.760 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-15T22:49:24.760Z,1557960564.760 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-15T22:49:24.768Z,1557960564.768 [Startup:StartupSatComms] Running Loop=1
2019-05-15T22:49:24.768Z,1557960564.768 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-05-15T22:49:24.768Z,1557960564.768 [Startup:StartupSatComms:A] Running Loop=1
2019-05-15T22:49:25.160Z,1557960565.160 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-05-15T22:49:25.563Z,1557960565.563 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:49:25.563Z,1557960565.563 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:49:26.746Z,1557960566.746 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:49:27.554Z,1557960567.554 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:49:27.959Z,1557960567.959 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:49:27.959Z,1557960567.959 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:49:27.960Z,1557960567.960 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:49:27.962Z,1557960567.962 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:49:27.962Z,1557960567.962 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:49:27.962Z,1557960567.962 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:49:27.962Z,1557960567.962 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:49:27.963Z,1557960567.963 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:49:27.963Z,1557960567.963 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:49:27.963Z,1557960567.963 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:49:27.963Z,1557960567.963 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:49:27.963Z,1557960567.963 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:49:27.963Z,1557960567.963 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:49:27.986Z,1557960567.986 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:49:28.427Z,1557960568.427 [BR_Ping1D](INFO): Powering down
2019-05-15T22:49:29.687Z,1557960569.687 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:49:29.687Z,1557960569.687 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:49:30.798Z,1557960570.798 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:49:31.597Z,1557960571.597 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:49:32.003Z,1557960572.003 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:49:32.004Z,1557960572.004 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:49:32.004Z,1557960572.004 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:49:32.006Z,1557960572.006 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:49:32.006Z,1557960572.006 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:49:32.006Z,1557960572.006 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:49:32.006Z,1557960572.006 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:49:32.007Z,1557960572.007 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:49:32.007Z,1557960572.007 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:49:32.007Z,1557960572.007 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:49:32.007Z,1557960572.007 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:49:32.008Z,1557960572.008 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:49:32.008Z,1557960572.008 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:49:32.072Z,1557960572.072 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:49:32.401Z,1557960572.401 [BR_Ping1D](INFO): Powering down
2019-05-15T22:49:33.638Z,1557960573.638 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:49:33.638Z,1557960573.638 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:49:34.836Z,1557960574.836 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:49:35.649Z,1557960575.649 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:49:36.038Z,1557960576.038 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:49:36.039Z,1557960576.039 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:49:36.039Z,1557960576.039 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:49:36.041Z,1557960576.041 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:49:36.042Z,1557960576.042 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:49:36.442Z,1557960576.442 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:49:36.846Z,1557960576.846 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:49:37.254Z,1557960577.254 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:49:37.655Z,1557960577.655 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:49:38.058Z,1557960578.058 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:49:38.470Z,1557960578.470 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:49:38.874Z,1557960578.874 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:49:39.270Z,1557960579.270 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:49:39.690Z,1557960579.690 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:49:40.090Z,1557960580.090 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-05-15T22:49:40.090Z,1557960580.090 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:49:40.090Z,1557960580.090 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:49:40.141Z,1557960580.141 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:49:40.493Z,1557960580.493 [BR_Ping1D](INFO): Powering down
2019-05-15T22:49:41.727Z,1557960581.727 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:49:41.727Z,1557960581.727 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:49:42.986Z,1557960582.986 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:49:43.726Z,1557960583.726 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:49:44.130Z,1557960584.130 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:49:44.131Z,1557960584.131 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:49:44.131Z,1557960584.131 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:49:44.133Z,1557960584.133 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 230104704, Device: 1500000
2019-05-15T22:49:44.133Z,1557960584.133 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:49:44.134Z,1557960584.134 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:49:44.134Z,1557960584.134 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:49:44.134Z,1557960584.134 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:49:44.134Z,1557960584.134 [BR_Ping1D](INFO): (soundspeed) 230104704 <-> 1500000
2019-05-15T22:49:44.135Z,1557960584.135 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:49:44.135Z,1557960584.135 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:49:44.135Z,1557960584.135 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:49:44.135Z,1557960584.135 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:49:44.158Z,1557960584.158 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:49:44.521Z,1557960584.521 [BR_Ping1D](INFO): Powering down
2019-05-15T22:49:45.774Z,1557960585.774 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:49:45.774Z,1557960585.774 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:49:46.946Z,1557960586.946 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:49:47.754Z,1557960587.754 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:49:48.158Z,1557960588.158 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:49:48.159Z,1557960588.159 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:49:48.159Z,1557960588.159 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:49:48.161Z,1557960588.161 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:49:48.161Z,1557960588.161 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:49:48.161Z,1557960588.161 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:49:48.162Z,1557960588.162 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:49:48.162Z,1557960588.162 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:49:48.162Z,1557960588.162 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:49:48.162Z,1557960588.162 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:49:48.163Z,1557960588.163 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:49:48.163Z,1557960588.163 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:49:48.163Z,1557960588.163 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:49:48.186Z,1557960588.186 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:49:48.569Z,1557960588.569 [BR_Ping1D](INFO): Powering down
2019-05-15T22:49:49.788Z,1557960589.788 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:49:49.788Z,1557960589.788 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:49:51.001Z,1557960591.001 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:49:51.799Z,1557960591.799 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:49:52.206Z,1557960592.206 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:49:52.206Z,1557960592.206 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:49:52.207Z,1557960592.207 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:49:52.208Z,1557960592.208 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:49:52.209Z,1557960592.209 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:49:52.209Z,1557960592.209 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:49:52.209Z,1557960592.209 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:49:52.209Z,1557960592.209 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:49:52.210Z,1557960592.210 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:49:52.210Z,1557960592.210 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:49:52.210Z,1557960592.210 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:49:52.210Z,1557960592.210 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:49:52.210Z,1557960592.210 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:49:52.228Z,1557960592.228 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:49:52.603Z,1557960592.603 [BR_Ping1D](INFO): Powering down
2019-05-15T22:49:53.839Z,1557960593.839 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:49:53.839Z,1557960593.839 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:49:55.027Z,1557960595.027 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:49:55.917Z,1557960595.917 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:49:56.274Z,1557960596.274 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:49:56.275Z,1557960596.275 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:49:56.275Z,1557960596.275 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:49:56.277Z,1557960596.277 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 230104704, Device: 1500000
2019-05-15T22:49:56.278Z,1557960596.278 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:49:56.278Z,1557960596.278 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:49:56.278Z,1557960596.278 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:49:56.278Z,1557960596.278 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:49:56.279Z,1557960596.279 [BR_Ping1D](INFO): (soundspeed) 230104704 <-> 1500000
2019-05-15T22:49:56.279Z,1557960596.279 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:49:56.279Z,1557960596.279 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:49:56.279Z,1557960596.279 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:49:56.279Z,1557960596.279 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:49:56.315Z,1557960596.315 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:49:56.678Z,1557960596.678 [BR_Ping1D](INFO): Powering down
2019-05-15T22:49:57.878Z,1557960597.878 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:49:57.878Z,1557960597.878 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:49:59.076Z,1557960599.076 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:49:59.874Z,1557960599.874 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:50:00.278Z,1557960600.278 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:50:00.279Z,1557960600.279 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:50:00.279Z,1557960600.279 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:50:00.281Z,1557960600.281 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:50:00.281Z,1557960600.281 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:50:00.281Z,1557960600.281 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:50:00.282Z,1557960600.282 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:50:00.282Z,1557960600.282 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:50:00.282Z,1557960600.282 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:50:00.283Z,1557960600.283 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:50:00.283Z,1557960600.283 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:50:00.283Z,1557960600.283 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:50:00.283Z,1557960600.283 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:50:00.306Z,1557960600.306 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:50:00.696Z,1557960600.696 [BR_Ping1D](INFO): Powering down
2019-05-15T22:50:01.926Z,1557960601.926 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:50:01.927Z,1557960601.927 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:50:02.315Z,1557960602.315 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-15T22:50:02.315Z,1557960602.315 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-05-15T22:50:02.315Z,1557960602.315 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-15T22:50:02.328Z,1557960602.328 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-15T22:50:02.733Z,1557960602.733 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-15T22:50:02.733Z,1557960602.733 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-05-15T22:50:03.107Z,1557960603.107 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:50:03.914Z,1557960603.914 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:50:04.318Z,1557960604.318 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:50:04.319Z,1557960604.319 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:50:04.319Z,1557960604.319 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:50:04.321Z,1557960604.321 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:50:04.322Z,1557960604.322 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:50:04.322Z,1557960604.322 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:50:04.322Z,1557960604.322 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:50:04.322Z,1557960604.322 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:50:04.323Z,1557960604.323 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:50:04.323Z,1557960604.323 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:50:04.323Z,1557960604.323 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:50:04.323Z,1557960604.323 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:50:04.323Z,1557960604.323 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:50:04.346Z,1557960604.346 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:50:04.722Z,1557960604.722 [BR_Ping1D](INFO): Powering down
2019-05-15T22:50:05.978Z,1557960605.978 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:50:05.978Z,1557960605.978 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:50:07.161Z,1557960607.161 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:50:07.954Z,1557960607.954 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:50:08.358Z,1557960608.358 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:50:08.359Z,1557960608.359 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:50:08.359Z,1557960608.359 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:50:08.361Z,1557960608.361 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:50:08.361Z,1557960608.361 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:50:08.361Z,1557960608.361 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:50:08.361Z,1557960608.361 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:50:08.362Z,1557960608.362 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:50:08.362Z,1557960608.362 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:50:08.362Z,1557960608.362 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:50:08.362Z,1557960608.362 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:50:08.362Z,1557960608.362 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:50:08.362Z,1557960608.362 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:50:08.383Z,1557960608.383 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:50:08.826Z,1557960608.826 [BR_Ping1D](INFO): Powering down
2019-05-15T22:50:10.052Z,1557960610.052 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:50:10.052Z,1557960610.052 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:50:11.196Z,1557960611.196 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:50:12.009Z,1557960612.009 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:50:12.411Z,1557960612.411 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:50:12.412Z,1557960612.412 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:50:12.412Z,1557960612.412 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:50:12.414Z,1557960612.414 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:50:12.414Z,1557960612.414 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:50:12.414Z,1557960612.414 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:50:12.414Z,1557960612.414 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:50:12.415Z,1557960612.415 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:50:12.415Z,1557960612.415 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:50:12.415Z,1557960612.415 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:50:12.415Z,1557960612.415 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:50:12.416Z,1557960612.416 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:50:12.416Z,1557960612.416 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:50:12.517Z,1557960612.517 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:50:12.802Z,1557960612.802 [BR_Ping1D](INFO): Powering down
2019-05-15T22:50:14.031Z,1557960614.031 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:50:14.032Z,1557960614.032 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:50:15.230Z,1557960615.230 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:50:16.034Z,1557960616.034 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:50:16.453Z,1557960616.453 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:50:16.453Z,1557960616.453 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:50:16.454Z,1557960616.454 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:50:16.455Z,1557960616.455 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:50:16.456Z,1557960616.456 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:50:16.456Z,1557960616.456 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:50:16.456Z,1557960616.456 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:50:16.456Z,1557960616.456 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:50:16.457Z,1557960616.457 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:50:16.457Z,1557960616.457 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:50:16.457Z,1557960616.457 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:50:16.457Z,1557960616.457 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:50:16.457Z,1557960616.457 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:50:16.511Z,1557960616.511 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:50:16.852Z,1557960616.852 [BR_Ping1D](INFO): Powering down
2019-05-15T22:50:18.078Z,1557960618.078 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:50:18.079Z,1557960618.079 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:50:19.271Z,1557960619.271 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:50:20.074Z,1557960620.074 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:50:20.479Z,1557960620.479 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:50:20.479Z,1557960620.479 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:50:20.480Z,1557960620.480 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:50:20.482Z,1557960620.482 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:50:20.482Z,1557960620.482 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:50:20.482Z,1557960620.482 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:50:20.482Z,1557960620.482 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:50:20.483Z,1557960620.483 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:50:20.483Z,1557960620.483 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:50:20.483Z,1557960620.483 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:50:20.483Z,1557960620.483 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:50:20.483Z,1557960620.483 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:50:20.483Z,1557960620.483 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:50:20.503Z,1557960620.503 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:50:20.882Z,1557960620.882 [BR_Ping1D](INFO): Powering down
2019-05-15T22:50:22.180Z,1557960622.180 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:50:22.180Z,1557960622.180 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:50:23.345Z,1557960623.345 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:50:24.126Z,1557960624.126 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:50:24.515Z,1557960624.515 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:50:24.516Z,1557960624.516 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:50:24.516Z,1557960624.516 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:50:24.518Z,1557960624.518 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:50:24.518Z,1557960624.518 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:50:24.518Z,1557960624.518 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:50:24.518Z,1557960624.518 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:50:24.519Z,1557960624.519 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:50:24.519Z,1557960624.519 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:50:24.519Z,1557960624.519 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:50:24.519Z,1557960624.519 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:50:24.519Z,1557960624.519 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:50:24.520Z,1557960624.520 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:50:24.569Z,1557960624.569 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:50:24.928Z,1557960624.928 [BR_Ping1D](INFO): Powering down
2019-05-15T22:50:25.040Z,1557960625.040 [Startup:StartupSatComms:A](INFO): Timed out from 2019-05-15T22:49:24.8Z
2019-05-15T22:50:25.040Z,1557960625.040 [Startup:StartupSatComms:A] Stopped
2019-05-15T22:50:25.040Z,1557960625.040 [Startup:StartupSatComms:B] Running Loop=1
2019-05-15T22:50:25.355Z,1557960625.355 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-05-15T22:50:26.282Z,1557960626.282 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:50:26.283Z,1557960626.283 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:50:27.456Z,1557960627.456 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:50:28.260Z,1557960628.260 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:50:28.490Z,1557960628.490 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004522
2019-05-15T22:50:28.666Z,1557960628.666 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:50:28.666Z,1557960628.666 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:50:28.667Z,1557960628.667 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:50:28.668Z,1557960628.668 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:50:28.669Z,1557960628.669 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:50:28.669Z,1557960628.669 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:50:28.669Z,1557960628.669 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:50:28.670Z,1557960628.670 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:50:28.670Z,1557960628.670 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:50:28.670Z,1557960628.670 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:50:28.670Z,1557960628.670 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:50:28.670Z,1557960628.670 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:50:28.670Z,1557960628.670 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:50:28.694Z,1557960628.694 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:50:29.064Z,1557960629.064 [BR_Ping1D](INFO): Powering down
2019-05-15T22:50:30.298Z,1557960630.298 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:50:30.298Z,1557960630.298 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:50:31.489Z,1557960631.489 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:50:32.310Z,1557960632.310 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:50:32.709Z,1557960632.709 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:50:32.710Z,1557960632.710 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:50:32.710Z,1557960632.710 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:50:32.711Z,1557960632.711 [BR_Ping1D](INFO): CRC error in received UART packet
2019-05-15T22:50:32.711Z,1557960632.711 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:50:33.114Z,1557960633.114 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:50:33.115Z,1557960633.115 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:50:33.507Z,1557960633.507 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:50:33.911Z,1557960633.911 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:50:34.315Z,1557960634.315 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:50:34.719Z,1557960634.719 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:50:35.192Z,1557960635.192 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:50:35.540Z,1557960635.540 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:50:35.950Z,1557960635.950 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:50:36.423Z,1557960636.423 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:50:36.745Z,1557960636.745 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-05-15T22:50:36.746Z,1557960636.746 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:50:36.746Z,1557960636.746 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:50:36.764Z,1557960636.764 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:50:37.166Z,1557960637.166 [BR_Ping1D](INFO): Powering down
2019-05-15T22:50:38.403Z,1557960638.403 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:50:38.403Z,1557960638.403 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:50:39.567Z,1557960639.567 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:50:40.374Z,1557960640.374 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:50:40.779Z,1557960640.779 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:50:40.779Z,1557960640.779 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:50:40.779Z,1557960640.779 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:50:40.781Z,1557960640.781 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:50:40.782Z,1557960640.782 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:50:40.782Z,1557960640.782 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:50:40.782Z,1557960640.782 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:50:40.782Z,1557960640.782 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:50:40.783Z,1557960640.783 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:50:40.783Z,1557960640.783 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:50:40.783Z,1557960640.783 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:50:40.783Z,1557960640.783 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:50:40.783Z,1557960640.783 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:50:40.806Z,1557960640.806 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:50:41.187Z,1557960641.187 [BR_Ping1D](INFO): Powering down
2019-05-15T22:50:42.434Z,1557960642.434 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:50:42.434Z,1557960642.434 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:50:43.617Z,1557960643.617 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:50:44.414Z,1557960644.414 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:50:44.818Z,1557960644.818 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:50:44.819Z,1557960644.819 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:50:44.819Z,1557960644.819 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:50:44.821Z,1557960644.821 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:50:44.822Z,1557960644.822 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:50:44.822Z,1557960644.822 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:50:44.822Z,1557960644.822 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:50:44.822Z,1557960644.822 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:50:44.823Z,1557960644.823 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:50:44.823Z,1557960644.823 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:50:44.823Z,1557960644.823 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:50:44.823Z,1557960644.823 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:50:44.823Z,1557960644.823 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:50:44.876Z,1557960644.876 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:50:45.227Z,1557960645.227 [BR_Ping1D](INFO): Powering down
2019-05-15T22:50:46.521Z,1557960646.521 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:50:46.533Z,1557960646.533 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:50:47.661Z,1557960647.661 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:50:48.531Z,1557960648.531 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:50:48.886Z,1557960648.886 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:50:48.887Z,1557960648.887 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:50:48.887Z,1557960648.887 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:50:48.889Z,1557960648.889 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:50:48.890Z,1557960648.890 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:50:49.279Z,1557960649.279 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:50:49.678Z,1557960649.678 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:50:50.184Z,1557960650.184 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:50:50.487Z,1557960650.487 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:50:50.890Z,1557960650.890 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:50:51.295Z,1557960651.295 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:50:51.686Z,1557960651.686 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:50:52.097Z,1557960652.097 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:50:52.495Z,1557960652.495 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:50:52.909Z,1557960652.909 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-05-15T22:50:52.909Z,1557960652.909 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:50:52.909Z,1557960652.909 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:50:52.982Z,1557960652.982 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:50:53.312Z,1557960653.312 [BR_Ping1D](INFO): Powering down
2019-05-15T22:50:54.542Z,1557960654.542 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:50:54.542Z,1557960654.542 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:50:55.730Z,1557960655.730 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:50:56.533Z,1557960656.533 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:50:56.939Z,1557960656.939 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:50:56.939Z,1557960656.939 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:50:56.940Z,1557960656.940 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:50:56.942Z,1557960656.942 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:50:56.942Z,1557960656.942 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:50:56.942Z,1557960656.942 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:50:56.942Z,1557960656.942 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:50:56.943Z,1557960656.943 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:50:56.943Z,1557960656.943 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:50:56.943Z,1557960656.943 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:50:56.943Z,1557960656.943 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:50:56.943Z,1557960656.943 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:50:56.943Z,1557960656.943 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:50:57.034Z,1557960657.034 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:50:57.342Z,1557960657.342 [BR_Ping1D](INFO): Powering down
2019-05-15T22:50:58.609Z,1557960658.609 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:50:58.609Z,1557960658.609 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:50:59.766Z,1557960659.766 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:51:00.578Z,1557960660.578 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:51:00.983Z,1557960660.983 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:51:00.984Z,1557960660.984 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:51:00.984Z,1557960660.984 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:51:00.986Z,1557960660.986 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:51:00.986Z,1557960660.986 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:51:00.986Z,1557960660.986 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:51:00.986Z,1557960660.986 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:51:00.987Z,1557960660.987 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:51:00.987Z,1557960660.987 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:51:00.987Z,1557960660.987 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:51:00.987Z,1557960660.987 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:51:00.988Z,1557960660.988 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:51:00.988Z,1557960660.988 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:51:01.006Z,1557960661.006 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:51:01.387Z,1557960661.387 [BR_Ping1D](INFO): Powering down
2019-05-15T22:51:02.651Z,1557960662.651 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:51:02.651Z,1557960662.651 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:51:03.524Z,1557960663.524 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:51:04.234Z,1557960664.234 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:51:04.638Z,1557960664.638 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:51:04.639Z,1557960664.639 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:51:04.639Z,1557960664.639 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:51:04.641Z,1557960664.641 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 230104704, Device: 1500000
2019-05-15T22:51:04.641Z,1557960664.641 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:51:04.642Z,1557960664.642 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:51:04.642Z,1557960664.642 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:51:04.642Z,1557960664.642 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:51:04.642Z,1557960664.642 [BR_Ping1D](INFO): (soundspeed) 230104704 <-> 1500000
2019-05-15T22:51:04.643Z,1557960664.643 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:51:04.643Z,1557960664.643 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:51:04.643Z,1557960664.643 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:51:04.643Z,1557960664.643 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:51:04.741Z,1557960664.741 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:51:05.019Z,1557960665.019 [BR_Ping1D](INFO): Powering down
2019-05-15T22:51:06.255Z,1557960666.255 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:51:06.255Z,1557960666.255 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:51:07.443Z,1557960667.443 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:51:08.251Z,1557960668.251 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:51:08.665Z,1557960668.665 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:51:08.666Z,1557960668.666 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:51:08.666Z,1557960668.666 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:51:08.668Z,1557960668.668 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:51:08.668Z,1557960668.668 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:51:08.668Z,1557960668.668 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:51:08.668Z,1557960668.668 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:51:08.669Z,1557960668.669 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:51:08.669Z,1557960668.669 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:51:08.669Z,1557960668.669 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:51:08.670Z,1557960668.670 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:51:08.670Z,1557960668.670 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:51:08.670Z,1557960668.670 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:51:08.711Z,1557960668.711 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:51:09.069Z,1557960669.069 [BR_Ping1D](INFO): Powering down
2019-05-15T22:51:10.336Z,1557960670.336 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:51:10.336Z,1557960670.336 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:51:11.483Z,1557960671.483 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:51:12.291Z,1557960672.291 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:51:12.696Z,1557960672.696 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:51:12.697Z,1557960672.697 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:51:12.697Z,1557960672.697 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:51:12.699Z,1557960672.699 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:51:12.700Z,1557960672.700 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:51:12.700Z,1557960672.700 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:51:12.700Z,1557960672.700 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:51:12.700Z,1557960672.700 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:51:12.701Z,1557960672.701 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:51:12.701Z,1557960672.701 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:51:12.701Z,1557960672.701 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:51:12.701Z,1557960672.701 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:51:12.701Z,1557960672.701 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:51:12.755Z,1557960672.755 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:51:13.104Z,1557960673.104 [BR_Ping1D](INFO): Powering down
2019-05-15T22:51:14.392Z,1557960674.392 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:51:14.392Z,1557960674.392 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:51:14.902Z,1557960674.902 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-15T22:51:14.902Z,1557960674.902 [CTD_Seabird] No Fault, FailCount= 3
2019-05-15T22:51:15.193Z,1557960675.193 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-15T22:51:15.197Z,1557960675.197 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-15T22:51:15.534Z,1557960675.534 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:51:16.337Z,1557960676.337 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:51:16.734Z,1557960676.734 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:51:16.735Z,1557960676.735 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:51:16.735Z,1557960676.735 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:51:16.767Z,1557960676.767 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:51:17.271Z,1557960677.271 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:51:17.271Z,1557960677.271 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:51:17.551Z,1557960677.551 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:51:17.958Z,1557960677.958 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:51:18.363Z,1557960678.363 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:51:18.754Z,1557960678.754 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:51:19.173Z,1557960679.173 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:51:19.577Z,1557960679.577 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:51:19.977Z,1557960679.977 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:51:20.387Z,1557960680.387 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:51:20.774Z,1557960680.774 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-05-15T22:51:20.774Z,1557960680.774 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:51:20.774Z,1557960680.774 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:51:20.854Z,1557960680.854 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:51:21.178Z,1557960681.178 [BR_Ping1D](INFO): Powering down
2019-05-15T22:51:22.453Z,1557960682.453 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:51:22.453Z,1557960682.453 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:51:23.603Z,1557960683.603 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:51:24.425Z,1557960684.425 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:51:24.825Z,1557960684.825 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:51:24.826Z,1557960684.826 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:51:24.826Z,1557960684.826 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:51:24.828Z,1557960684.828 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:51:24.828Z,1557960684.828 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:51:24.828Z,1557960684.828 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:51:24.829Z,1557960684.829 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:51:24.829Z,1557960684.829 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:51:24.829Z,1557960684.829 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:51:24.830Z,1557960684.830 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:51:24.830Z,1557960684.830 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:51:24.830Z,1557960684.830 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:51:24.830Z,1557960684.830 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:51:24.854Z,1557960684.854 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:51:25.235Z,1557960685.235 [BR_Ping1D](INFO): Powering down
2019-05-15T22:51:25.328Z,1557960685.328 [Startup:StartupSatComms:B](INFO): Timed out from 2019-05-15T22:50:25.0Z
2019-05-15T22:51:25.328Z,1557960685.328 [Startup:StartupSatComms:B] Stopped
2019-05-15T22:51:25.328Z,1557960685.328 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-05-15T22:51:25.328Z,1557960685.328 [Startup:StartupSatComms] Stopped
2019-05-15T22:51:25.329Z,1557960685.329 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-05-15T22:51:25.329Z,1557960685.329 [Startup](INFO): Completed Startup
2019-05-15T22:51:25.330Z,1557960685.330 [MissionManager](INFO): Startup is completed.
2019-05-15T22:51:25.330Z,1557960685.330 [MissionManager](INFO): Uninitializing Mission Startup
2019-05-15T22:51:25.330Z,1557960685.330 [Startup] Stopped
2019-05-15T22:51:25.330Z,1557960685.330 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-05-15T22:51:25.330Z,1557960685.330 [Startup:A.GoToSurface] Stopped
2019-05-15T22:51:25.330Z,1557960685.330 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-15T22:51:25.652Z,1557960685.652 [MissionManager](IMPORTANT): Started mission Default
2019-05-15T22:51:25.652Z,1557960685.652 [Default] Running Loop=1
2019-05-15T22:51:25.652Z,1557960685.652 [Default](DEBUG): Aggregate::initialize Default
2019-05-15T22:51:25.652Z,1557960685.652 [Default:B.GoToSurface] Running Loop=1
2019-05-15T22:51:25.652Z,1557960685.652 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-15T22:51:25.652Z,1557960685.652 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-15T22:51:25.653Z,1557960685.653 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-15T22:51:25.653Z,1557960685.653 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-15T22:51:25.653Z,1557960685.653 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-15T22:51:25.654Z,1557960685.654 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-15T22:51:25.654Z,1557960685.654 [Default:A.Wait] Running Loop=1
2019-05-15T22:51:25.654Z,1557960685.654 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-05-15T22:51:26.489Z,1557960686.489 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:51:26.489Z,1557960686.489 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:51:27.643Z,1557960687.643 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:51:28.245Z,1557960688.245 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-15T22:51:28.245Z,1557960688.245 [CTD_Seabird] Communications Fault, FailCount= 1
2019-05-15T22:51:28.245Z,1557960688.245 [CTD_Seabird](ERROR): Communications Fault
2019-05-15T22:51:28.563Z,1557960688.563 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:51:28.648Z,1557960688.648 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-15T22:51:28.649Z,1557960688.649 [CTD_Seabird](INFO): Powering down
2019-05-15T22:51:28.861Z,1557960688.861 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:51:28.862Z,1557960688.862 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:51:28.862Z,1557960688.862 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:51:28.864Z,1557960688.864 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:51:28.864Z,1557960688.864 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:51:28.864Z,1557960688.864 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:51:28.864Z,1557960688.864 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:51:28.865Z,1557960688.865 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:51:28.865Z,1557960688.865 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:51:28.865Z,1557960688.865 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:51:28.866Z,1557960688.866 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:51:28.866Z,1557960688.866 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:51:28.866Z,1557960688.866 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:51:28.963Z,1557960688.963 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:51:29.270Z,1557960689.270 [BR_Ping1D](INFO): Powering down
2019-05-15T22:51:29.734Z,1557960689.734 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-15T22:51:29.734Z,1557960689.734 [CTD_Seabird] No Fault, FailCount= 1
2019-05-15T22:51:29.993Z,1557960689.993 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-15T22:51:30.001Z,1557960690.001 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-15T22:51:30.569Z,1557960690.569 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:51:30.569Z,1557960690.569 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:51:31.695Z,1557960691.695 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:51:32.491Z,1557960692.491 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:51:32.898Z,1557960692.898 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:51:32.899Z,1557960692.899 [BR_Ping1D](INFO): Not enough UART bytes for payload try again on next cycle
2019-05-15T22:51:32.899Z,1557960692.899 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:51:33.306Z,1557960693.306 [BR_Ping1D](INFO): Not enough UART bytes for payload try again on next cycle
2019-05-15T22:51:33.306Z,1557960693.306 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:51:33.712Z,1557960693.712 [BR_Ping1D](INFO): Not enough UART bytes for payload try again on next cycle
2019-05-15T22:51:33.713Z,1557960693.713 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:51:34.111Z,1557960694.111 [BR_Ping1D](ERROR): Using more than 1 sec to receive one packet, resetting UART
2019-05-15T22:51:34.111Z,1557960694.111 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:51:34.114Z,1557960694.114 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:51:34.115Z,1557960694.115 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:51:34.115Z,1557960694.115 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:51:34.115Z,1557960694.115 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:51:34.115Z,1557960694.115 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:51:34.116Z,1557960694.116 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:51:34.116Z,1557960694.116 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:51:34.116Z,1557960694.116 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:51:34.116Z,1557960694.116 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:51:34.116Z,1557960694.116 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:51:34.199Z,1557960694.199 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:51:34.517Z,1557960694.517 [BR_Ping1D](INFO): Powering down
2019-05-15T22:51:35.762Z,1557960695.762 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:51:35.762Z,1557960695.762 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:51:36.942Z,1557960696.942 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:51:37.746Z,1557960697.746 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:51:38.150Z,1557960698.150 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:51:38.151Z,1557960698.151 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:51:38.151Z,1557960698.151 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:51:38.153Z,1557960698.153 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:51:38.154Z,1557960698.154 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:51:38.154Z,1557960698.154 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:51:38.154Z,1557960698.154 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:51:38.154Z,1557960698.154 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:51:38.155Z,1557960698.155 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:51:38.155Z,1557960698.155 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:51:38.155Z,1557960698.155 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:51:38.155Z,1557960698.155 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:51:38.155Z,1557960698.155 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:51:38.232Z,1557960698.232 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:51:38.558Z,1557960698.558 [BR_Ping1D](INFO): Powering down
2019-05-15T22:51:38.972Z,1557960698.972 [Default:A.Wait](INFO): Done Waiting.
2019-05-15T22:51:38.972Z,1557960698.972 [Default:A.Wait] Stopped
2019-05-15T22:51:38.973Z,1557960698.973 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-05-15T22:51:39.377Z,1557960699.377 [Default:CheckIn] Running Loop=1
2019-05-15T22:51:39.377Z,1557960699.377 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-05-15T22:51:39.377Z,1557960699.377 [Default:CheckIn:Read_GPS] Running Loop=1
2019-05-15T22:51:39.780Z,1557960699.780 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-05-15T22:51:39.793Z,1557960699.793 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:51:39.793Z,1557960699.793 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:51:40.996Z,1557960700.996 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:51:41.850Z,1557960701.850 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:51:42.194Z,1557960702.194 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:51:42.195Z,1557960702.195 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:51:42.195Z,1557960702.195 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:51:42.197Z,1557960702.197 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:51:42.198Z,1557960702.198 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:51:42.198Z,1557960702.198 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:51:42.198Z,1557960702.198 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:51:42.198Z,1557960702.198 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:51:42.199Z,1557960702.199 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:51:42.199Z,1557960702.199 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:51:42.199Z,1557960702.199 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:51:42.199Z,1557960702.199 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:51:42.199Z,1557960702.199 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:51:42.257Z,1557960702.257 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:51:42.610Z,1557960702.610 [BR_Ping1D](INFO): Powering down
2019-05-15T22:51:43.045Z,1557960703.045 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-15T22:51:43.045Z,1557960703.045 [CTD_Seabird] Communications Fault, FailCount= 2
2019-05-15T22:51:43.045Z,1557960703.045 [CTD_Seabird](ERROR): Communications Fault
2019-05-15T22:51:43.423Z,1557960703.423 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-15T22:51:43.449Z,1557960703.449 [CTD_Seabird](INFO): Powering down
2019-05-15T22:51:43.903Z,1557960703.903 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:51:43.903Z,1557960703.903 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:51:44.654Z,1557960704.654 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-15T22:51:44.654Z,1557960704.654 [CTD_Seabird] No Fault, FailCount= 2
2019-05-15T22:51:44.789Z,1557960704.789 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-15T22:51:44.790Z,1557960704.790 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-15T22:51:45.034Z,1557960705.034 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:51:45.836Z,1557960705.836 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:51:46.241Z,1557960706.241 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:51:46.241Z,1557960706.241 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:51:46.242Z,1557960706.242 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:51:46.243Z,1557960706.243 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:51:46.244Z,1557960706.244 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:51:46.244Z,1557960706.244 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:51:46.244Z,1557960706.244 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:51:46.244Z,1557960706.244 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:51:46.245Z,1557960706.245 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:51:46.245Z,1557960706.245 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:51:46.245Z,1557960706.245 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:51:46.245Z,1557960706.245 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:51:46.245Z,1557960706.245 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:51:46.271Z,1557960706.271 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:51:46.649Z,1557960706.649 [BR_Ping1D](INFO): Powering down
2019-05-15T22:51:47.881Z,1557960707.881 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:51:47.881Z,1557960707.881 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:51:49.058Z,1557960709.058 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:51:49.863Z,1557960709.863 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:51:50.274Z,1557960710.274 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:51:50.275Z,1557960710.275 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:51:50.275Z,1557960710.275 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:51:50.277Z,1557960710.277 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:51:50.277Z,1557960710.277 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:51:50.277Z,1557960710.277 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:51:50.277Z,1557960710.277 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:51:50.278Z,1557960710.278 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:51:50.278Z,1557960710.278 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:51:50.278Z,1557960710.278 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:51:50.278Z,1557960710.278 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:51:50.278Z,1557960710.278 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:51:50.279Z,1557960710.279 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:51:50.297Z,1557960710.297 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:51:50.692Z,1557960710.692 [BR_Ping1D](INFO): Powering down
2019-05-15T22:51:51.925Z,1557960711.925 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:51:51.925Z,1557960711.925 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:51:53.095Z,1557960713.095 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:51:53.906Z,1557960713.906 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:51:54.315Z,1557960714.315 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:51:54.315Z,1557960714.315 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:51:54.316Z,1557960714.316 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:51:54.317Z,1557960714.317 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:51:54.318Z,1557960714.318 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:51:54.318Z,1557960714.318 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:51:54.318Z,1557960714.318 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:51:54.318Z,1557960714.318 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:51:54.319Z,1557960714.319 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:51:54.319Z,1557960714.319 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:51:54.319Z,1557960714.319 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:51:54.319Z,1557960714.319 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:51:54.319Z,1557960714.319 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:51:54.342Z,1557960714.342 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:51:54.724Z,1557960714.724 [BR_Ping1D](INFO): Powering down
2019-05-15T22:51:55.995Z,1557960715.995 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:51:55.995Z,1557960715.995 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:51:57.215Z,1557960717.215 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:51:57.861Z,1557960717.861 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-15T22:51:57.861Z,1557960717.861 [CTD_Seabird] Communications Fault, FailCount= 3
2019-05-15T22:51:57.861Z,1557960717.861 [CTD_Seabird](ERROR): Communications Fault
2019-05-15T22:51:57.978Z,1557960717.978 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:51:58.002Z,1557960718.002 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-15T22:51:58.003Z,1557960718.003 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-05-15T22:51:58.265Z,1557960718.265 [CTD_Seabird](INFO): Powering down
2019-05-15T22:51:58.370Z,1557960718.370 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:51:58.371Z,1557960718.371 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:51:58.371Z,1557960718.371 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:51:58.373Z,1557960718.373 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 230104704, Device: 1500000
2019-05-15T22:51:58.374Z,1557960718.374 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:51:58.374Z,1557960718.374 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:51:58.374Z,1557960718.374 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:51:58.374Z,1557960718.374 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:51:58.375Z,1557960718.375 [BR_Ping1D](INFO): (soundspeed) 230104704 <-> 1500000
2019-05-15T22:51:58.375Z,1557960718.375 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:51:58.375Z,1557960718.375 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:51:58.375Z,1557960718.375 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:51:58.375Z,1557960718.375 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:51:58.395Z,1557960718.395 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:51:58.757Z,1557960718.757 [BR_Ping1D](INFO): Powering down
2019-05-15T22:51:59.990Z,1557960719.990 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:51:59.990Z,1557960719.990 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:52:01.193Z,1557960721.193 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:52:01.994Z,1557960721.994 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:52:02.405Z,1557960722.405 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:52:02.406Z,1557960722.406 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:52:02.406Z,1557960722.406 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:52:02.408Z,1557960722.408 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:52:02.408Z,1557960722.408 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:52:02.408Z,1557960722.408 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:52:02.409Z,1557960722.409 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:52:02.409Z,1557960722.409 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:52:02.409Z,1557960722.409 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:52:02.410Z,1557960722.410 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:52:02.410Z,1557960722.410 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:52:02.410Z,1557960722.410 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:52:02.410Z,1557960722.410 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:52:02.513Z,1557960722.513 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:52:02.791Z,1557960722.791 [BR_Ping1D](INFO): Powering down
2019-05-15T22:52:03.214Z,1557960723.214 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-15T22:52:03.214Z,1557960723.214 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-05-15T22:52:03.214Z,1557960723.214 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-15T22:52:03.239Z,1557960723.239 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-15T22:52:03.664Z,1557960723.664 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-15T22:52:03.664Z,1557960723.664 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-05-15T22:52:04.085Z,1557960724.085 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:52:04.085Z,1557960724.085 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:52:05.214Z,1557960725.214 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:52:06.022Z,1557960726.022 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:52:06.441Z,1557960726.441 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:52:06.442Z,1557960726.441 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:52:06.442Z,1557960726.442 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:52:06.444Z,1557960726.444 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:52:06.444Z,1557960726.444 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:52:06.444Z,1557960726.444 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:52:06.444Z,1557960726.444 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:52:06.445Z,1557960726.445 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:52:06.445Z,1557960726.445 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:52:06.445Z,1557960726.445 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:52:06.445Z,1557960726.445 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:52:06.445Z,1557960726.445 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:52:06.445Z,1557960726.445 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:52:06.470Z,1557960726.470 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:52:06.841Z,1557960726.841 [BR_Ping1D](INFO): Powering down
2019-05-15T22:52:08.074Z,1557960728.074 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:52:08.075Z,1557960728.075 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:52:09.261Z,1557960729.261 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:52:10.078Z,1557960730.078 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:52:10.467Z,1557960730.467 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:52:10.467Z,1557960730.467 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:52:10.467Z,1557960730.467 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:52:10.468Z,1557960730.468 [BR_Ping1D](INFO): CRC error in received UART packet
2019-05-15T22:52:10.468Z,1557960730.468 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:52:10.935Z,1557960730.935 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:52:10.936Z,1557960730.936 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:52:11.298Z,1557960731.298 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:52:11.706Z,1557960731.706 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:52:12.118Z,1557960732.118 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:52:12.500Z,1557960732.500 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:52:12.901Z,1557960732.901 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:52:13.294Z,1557960733.294 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:52:13.698Z,1557960733.698 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:52:14.102Z,1557960734.102 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T22:52:14.513Z,1557960734.513 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-05-15T22:52:14.513Z,1557960734.513 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:52:14.513Z,1557960734.513 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:52:14.544Z,1557960734.544 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:52:14.917Z,1557960734.917 [BR_Ping1D](INFO): Powering down
2019-05-15T22:52:16.140Z,1557960736.140 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:52:16.140Z,1557960736.140 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:52:17.348Z,1557960737.348 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:52:18.159Z,1557960738.159 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:52:18.551Z,1557960738.551 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:52:18.552Z,1557960738.552 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:52:18.552Z,1557960738.552 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:52:18.578Z,1557960738.578 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:52:18.579Z,1557960738.579 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:52:18.579Z,1557960738.579 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:52:18.579Z,1557960738.579 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:52:18.579Z,1557960738.579 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:52:18.580Z,1557960738.580 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:52:18.580Z,1557960738.580 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:52:18.580Z,1557960738.580 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:52:18.580Z,1557960738.580 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:52:18.580Z,1557960738.580 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:52:18.605Z,1557960738.605 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:52:18.958Z,1557960738.958 [BR_Ping1D](INFO): Powering down
2019-05-15T22:52:20.187Z,1557960740.187 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:52:20.187Z,1557960740.187 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:52:21.382Z,1557960741.382 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:52:22.231Z,1557960742.231 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:52:22.610Z,1557960742.610 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:52:22.611Z,1557960742.611 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:52:22.611Z,1557960742.611 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:52:22.613Z,1557960742.613 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 230104704, Device: 1500000
2019-05-15T22:52:22.613Z,1557960742.613 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:52:22.613Z,1557960742.613 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:52:22.614Z,1557960742.614 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:52:22.614Z,1557960742.614 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:52:22.614Z,1557960742.614 [BR_Ping1D](INFO): (soundspeed) 230104704 <-> 1500000
2019-05-15T22:52:22.615Z,1557960742.615 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:52:22.615Z,1557960742.615 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:52:22.615Z,1557960742.615 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:52:22.615Z,1557960742.615 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:52:22.691Z,1557960742.691 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:52:23.022Z,1557960743.022 [BR_Ping1D](INFO): Powering down
2019-05-15T22:52:24.282Z,1557960744.282 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:52:24.282Z,1557960744.282 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:52:25.426Z,1557960745.426 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:52:26.222Z,1557960746.222 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:52:26.630Z,1557960746.630 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:52:26.631Z,1557960746.631 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:52:26.631Z,1557960746.631 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:52:26.633Z,1557960746.633 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:52:26.633Z,1557960746.633 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:52:26.633Z,1557960746.633 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:52:26.633Z,1557960746.633 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:52:26.634Z,1557960746.634 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:52:26.634Z,1557960746.634 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:52:26.634Z,1557960746.634 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:52:26.634Z,1557960746.634 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:52:26.635Z,1557960746.635 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:52:26.635Z,1557960746.635 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:52:26.658Z,1557960746.658 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:52:27.030Z,1557960747.030 [BR_Ping1D](INFO): Powering down
2019-05-15T22:52:28.279Z,1557960748.279 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:52:28.279Z,1557960748.279 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:52:29.454Z,1557960749.454 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:52:30.262Z,1557960750.262 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:52:30.670Z,1557960750.670 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:52:30.671Z,1557960750.671 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:52:30.671Z,1557960750.671 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:52:30.673Z,1557960750.673 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:52:30.673Z,1557960750.673 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:52:30.674Z,1557960750.674 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:52:30.674Z,1557960750.674 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:52:30.674Z,1557960750.674 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:52:30.674Z,1557960750.674 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:52:30.675Z,1557960750.675 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:52:30.675Z,1557960750.675 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:52:30.675Z,1557960750.675 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:52:30.675Z,1557960750.675 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:52:30.693Z,1557960750.693 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:52:31.077Z,1557960751.077 [BR_Ping1D](INFO): Powering down
2019-05-15T22:52:32.312Z,1557960752.312 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:52:32.312Z,1557960752.312 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:52:33.508Z,1557960753.508 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:52:34.314Z,1557960754.314 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:52:34.708Z,1557960754.708 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:52:34.708Z,1557960754.708 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:52:34.709Z,1557960754.709 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:52:34.710Z,1557960754.710 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:52:34.711Z,1557960754.711 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:52:34.711Z,1557960754.711 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:52:34.711Z,1557960754.711 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:52:34.711Z,1557960754.711 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:52:34.712Z,1557960754.712 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:52:34.712Z,1557960754.712 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:52:34.712Z,1557960754.712 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:52:34.712Z,1557960754.712 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:52:34.712Z,1557960754.712 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:52:34.772Z,1557960754.772 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:52:35.173Z,1557960755.173 [BR_Ping1D](INFO): Powering down
2019-05-15T22:52:36.358Z,1557960756.358 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:52:36.358Z,1557960756.358 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:52:37.649Z,1557960757.649 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:52:38.370Z,1557960758.370 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:52:38.773Z,1557960758.773 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:52:38.774Z,1557960758.774 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:52:38.774Z,1557960758.774 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:52:38.776Z,1557960758.776 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 230104704, Device: 1500000
2019-05-15T22:52:38.776Z,1557960758.776 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:52:38.777Z,1557960758.777 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:52:38.777Z,1557960758.777 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:52:38.777Z,1557960758.777 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:52:38.777Z,1557960758.777 [BR_Ping1D](INFO): (soundspeed) 230104704 <-> 1500000
2019-05-15T22:52:38.778Z,1557960758.778 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:52:38.778Z,1557960758.778 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:52:38.778Z,1557960758.778 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:52:38.778Z,1557960758.778 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:52:38.815Z,1557960758.815 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:52:39.160Z,1557960759.160 [BR_Ping1D](INFO): Powering down
2019-05-15T22:52:40.417Z,1557960760.417 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:52:40.417Z,1557960760.417 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:52:40.968Z,1557960760.968 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190515T211655/Courier0004.lzma
2019-05-15T22:52:41.570Z,1557960761.570 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:52:41.775Z,1557960761.775 [DataOverHttps](INFO): Moved sent file to Logs/20190515T211655/Courier0004.lzma.bak
2019-05-15T22:52:41.775Z,1557960761.775 [DataOverHttps](INFO): SBD MOMSN=11070673
2019-05-15T22:52:42.382Z,1557960762.382 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:52:42.786Z,1557960762.786 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:52:42.787Z,1557960762.787 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:52:42.787Z,1557960762.787 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:52:42.789Z,1557960762.789 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:52:42.789Z,1557960762.789 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:52:42.789Z,1557960762.789 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:52:42.790Z,1557960762.790 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:52:42.790Z,1557960762.790 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:52:42.790Z,1557960762.790 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:52:42.791Z,1557960762.791 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:52:42.791Z,1557960762.791 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:52:42.791Z,1557960762.791 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:52:42.791Z,1557960762.791 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:52:42.813Z,1557960762.813 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:52:43.204Z,1557960763.204 [BR_Ping1D](INFO): Powering down
2019-05-15T22:52:44.454Z,1557960764.454 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:52:44.454Z,1557960764.454 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:52:45.614Z,1557960765.614 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:52:46.423Z,1557960766.423 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:52:46.827Z,1557960766.827 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:52:46.827Z,1557960766.827 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:52:46.827Z,1557960766.827 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:52:46.829Z,1557960766.829 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:52:46.830Z,1557960766.830 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:52:46.830Z,1557960766.830 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:52:46.830Z,1557960766.830 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:52:46.830Z,1557960766.830 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:52:46.831Z,1557960766.831 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:52:46.831Z,1557960766.831 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:52:46.831Z,1557960766.831 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:52:46.831Z,1557960766.831 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:52:46.831Z,1557960766.831 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:52:46.855Z,1557960766.855 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:52:47.234Z,1557960767.234 [BR_Ping1D](INFO): Powering down
2019-05-15T22:52:48.486Z,1557960768.486 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:52:48.486Z,1557960768.486 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:52:49.678Z,1557960769.678 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:52:50.462Z,1557960770.462 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:52:50.929Z,1557960770.929 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:52:50.929Z,1557960770.929 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:52:50.930Z,1557960770.930 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:52:50.931Z,1557960770.931 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:52:50.932Z,1557960770.932 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:52:50.932Z,1557960770.932 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:52:50.932Z,1557960770.932 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:52:50.932Z,1557960770.932 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:52:50.933Z,1557960770.933 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:52:50.934Z,1557960770.934 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:52:50.934Z,1557960770.934 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:52:50.934Z,1557960770.934 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:52:50.934Z,1557960770.934 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:52:50.994Z,1557960770.994 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:52:51.284Z,1557960771.284 [BR_Ping1D](INFO): Powering down
2019-05-15T22:52:52.497Z,1557960772.497 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:52:52.497Z,1557960772.497 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:52:53.709Z,1557960773.709 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:52:54.518Z,1557960774.518 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:52:54.924Z,1557960774.924 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:52:54.925Z,1557960774.925 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:52:54.925Z,1557960774.925 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:52:54.927Z,1557960774.927 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:52:54.927Z,1557960774.927 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:52:54.927Z,1557960774.927 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:52:54.928Z,1557960774.928 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:52:54.928Z,1557960774.928 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:52:54.928Z,1557960774.928 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:52:54.928Z,1557960774.928 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:52:54.929Z,1557960774.929 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:52:54.929Z,1557960774.929 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:52:54.929Z,1557960774.929 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:52:54.947Z,1557960774.947 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:52:55.310Z,1557960775.310 [BR_Ping1D](INFO): Powering down
2019-05-15T22:52:56.541Z,1557960776.541 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:52:56.541Z,1557960776.541 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:52:57.739Z,1557960777.739 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:52:58.542Z,1557960778.542 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:52:58.961Z,1557960778.961 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:52:58.962Z,1557960778.962 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:52:58.962Z,1557960778.962 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:52:58.964Z,1557960778.964 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:52:58.964Z,1557960778.964 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:52:58.964Z,1557960778.964 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:52:58.964Z,1557960778.964 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:52:58.965Z,1557960778.965 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:52:58.965Z,1557960778.965 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:52:58.965Z,1557960778.965 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:52:58.966Z,1557960778.966 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:52:58.966Z,1557960778.966 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:52:58.966Z,1557960778.966 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:52:59.045Z,1557960779.045 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:52:59.360Z,1557960779.360 [BR_Ping1D](INFO): Powering down
2019-05-15T22:53:00.584Z,1557960780.584 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:53:00.584Z,1557960780.584 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:53:01.778Z,1557960781.778 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:53:02.593Z,1557960782.593 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:53:03.002Z,1557960783.002 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:53:03.003Z,1557960783.003 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:53:03.003Z,1557960783.003 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:53:03.005Z,1557960783.005 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 230104704, Device: 1500000
2019-05-15T22:53:03.005Z,1557960783.005 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:53:03.005Z,1557960783.005 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:53:03.005Z,1557960783.005 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:53:03.006Z,1557960783.006 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:53:03.006Z,1557960783.006 [BR_Ping1D](INFO): (soundspeed) 230104704 <-> 1500000
2019-05-15T22:53:03.006Z,1557960783.006 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:53:03.006Z,1557960783.006 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:53:03.007Z,1557960783.007 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:53:03.007Z,1557960783.007 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:53:03.030Z,1557960783.030 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:53:03.406Z,1557960783.406 [BR_Ping1D](INFO): Powering down
2019-05-15T22:53:04.650Z,1557960784.650 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:53:04.650Z,1557960784.650 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:53:05.814Z,1557960785.814 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:53:06.622Z,1557960786.622 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:53:07.026Z,1557960787.026 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:53:07.027Z,1557960787.027 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:53:07.027Z,1557960787.027 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:53:07.029Z,1557960787.029 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:53:07.029Z,1557960787.029 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:53:07.030Z,1557960787.030 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:53:07.030Z,1557960787.030 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:53:07.030Z,1557960787.030 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:53:07.031Z,1557960787.031 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:53:07.031Z,1557960787.031 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:53:07.031Z,1557960787.031 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:53:07.031Z,1557960787.031 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:53:07.031Z,1557960787.031 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:53:07.054Z,1557960787.054 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:53:07.435Z,1557960787.435 [BR_Ping1D](INFO): Powering down
2019-05-15T22:53:08.695Z,1557960788.695 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:53:08.695Z,1557960788.695 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:53:09.864Z,1557960789.864 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:53:10.698Z,1557960790.698 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:53:11.086Z,1557960791.086 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:53:11.087Z,1557960791.087 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:53:11.087Z,1557960791.087 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:53:11.089Z,1557960791.089 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:53:11.089Z,1557960791.089 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:53:11.089Z,1557960791.089 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:53:11.090Z,1557960791.090 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:53:11.090Z,1557960791.090 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:53:11.090Z,1557960791.090 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:53:11.091Z,1557960791.091 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:53:11.091Z,1557960791.091 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:53:11.091Z,1557960791.091 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:53:11.091Z,1557960791.091 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:53:11.120Z,1557960791.120 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:53:11.489Z,1557960791.489 [BR_Ping1D](INFO): Powering down
2019-05-15T22:53:12.813Z,1557960792.813 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:53:12.813Z,1557960792.813 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:53:13.912Z,1557960793.912 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:53:14.730Z,1557960794.730 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:53:15.129Z,1557960795.129 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:53:15.130Z,1557960795.130 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:53:15.130Z,1557960795.130 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:53:15.132Z,1557960795.132 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:53:15.132Z,1557960795.132 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:53:15.133Z,1557960795.133 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:53:15.133Z,1557960795.133 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:53:15.133Z,1557960795.133 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:53:15.134Z,1557960795.134 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:53:15.134Z,1557960795.134 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:53:15.134Z,1557960795.134 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:53:15.134Z,1557960795.134 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:53:15.134Z,1557960795.134 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:53:15.266Z,1557960795.266 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:53:15.537Z,1557960795.537 [BR_Ping1D](INFO): Powering down
2019-05-15T22:53:16.413Z,1557960796.413 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:53:16.465Z,1557960796.465 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:53:17.655Z,1557960797.655 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:53:18.362Z,1557960798.362 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:53:18.766Z,1557960798.766 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:53:18.767Z,1557960798.767 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:53:18.767Z,1557960798.767 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:53:18.769Z,1557960798.769 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 230104704, Device: 1500000
2019-05-15T22:53:18.769Z,1557960798.769 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:53:18.769Z,1557960798.769 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:53:18.770Z,1557960798.770 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:53:18.770Z,1557960798.770 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:53:18.770Z,1557960798.770 [BR_Ping1D](INFO): (soundspeed) 230104704 <-> 1500000
2019-05-15T22:53:18.770Z,1557960798.770 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:53:18.771Z,1557960798.771 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:53:18.771Z,1557960798.771 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:53:18.771Z,1557960798.771 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:53:18.814Z,1557960798.814 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:53:19.165Z,1557960799.165 [BR_Ping1D](INFO): Powering down
2019-05-15T22:53:20.431Z,1557960800.431 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:53:20.431Z,1557960800.431 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:53:21.579Z,1557960801.579 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T22:53:22.331Z,1557960802.331 [CommandLine](IMPORTANT): got command restart application
2019-05-15T22:53:22.408Z,1557960802.408 [BR_Ping1D](INFO): UART requesting status
2019-05-15T22:53:22.795Z,1557960802.795 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T22:53:22.796Z,1557960802.796 [BR_Ping1D](INFO): unsupported message received, type: 32
2019-05-15T22:53:22.796Z,1557960802.796 [BR_Ping1D](INFO): unsupported message type requested for writing: 32
2019-05-15T22:53:22.798Z,1557960802.798 [BR_Ping1D](ERROR): Soundspeed of device differs from requested value. Requested: 483520000, Device: 1500000
2019-05-15T22:53:22.799Z,1557960802.799 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-05-15T22:53:22.799Z,1557960802.799 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-05-15T22:53:22.799Z,1557960802.799 [BR_Ping1D](INFO): (ping interval) 500 <-> 500
2019-05-15T22:53:22.799Z,1557960802.799 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-05-15T22:53:22.800Z,1557960802.800 [BR_Ping1D](INFO): (soundspeed) 483520000 <-> 1500000
2019-05-15T22:53:22.800Z,1557960802.800 [BR_Ping1D](INFO): (ping enable) 1 <-> 1
2019-05-15T22:53:22.800Z,1557960802.800 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-05-15T22:53:22.800Z,1557960802.800 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T22:53:22.800Z,1557960802.800 [BR_Ping1D](ERROR): Data Fault
2019-05-15T22:53:22.870Z,1557960802.870 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T22:53:23.208Z,1557960803.208 [BR_Ping1D](INFO): Powering down
2019-05-15T22:53:23.340Z,1557960803.340 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-15T22:53:23.340Z,1557960803.340 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-15T22:53:23.389Z,1557960803.389 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-05-15T22:53:23.389Z,1557960803.389 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-15T22:53:23.390Z,1557960803.390 [CommandLine](INFO): Join timeout helper Thread ID is 5016
2019-05-15T22:53:23.409Z,1557960803.409 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-05-15T22:53:23.409Z,1557960803.409 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-15T22:53:23.410Z,1557960803.410 [NavChartDb](INFO): Join timeout helper Thread ID is 5017
2019-05-15T22:53:23.657Z,1557960803.657 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-15T22:53:23.657Z,1557960803.657 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-15T22:53:23.669Z,1557960803.669 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-05-15T22:53:23.669Z,1557960803.669 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-15T22:53:23.669Z,1557960803.669 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5018
2019-05-15T22:53:23.709Z,1557960803.709 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-15T22:53:23.710Z,1557960803.710 [WetLabsBB2FL](INFO): Powering down
2019-05-15T22:53:23.710Z,1557960803.710 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-15T22:53:23.721Z,1557960803.721 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-05-15T22:53:23.721Z,1557960803.721 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-05-15T22:53:23.721Z,1557960803.721 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5019
2019-05-15T22:53:24.045Z,1557960804.045 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-15T22:53:24.045Z,1557960804.045 [CTD_Seabird](INFO): Powering down
2019-05-15T22:53:24.061Z,1557960804.061 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-05-15T22:53:24.081Z,1557960804.081 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-05-15T22:53:24.081Z,1557960804.081 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-15T22:53:24.081Z,1557960804.081 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 5020
2019-05-15T22:53:24.149Z,1557960804.149 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-15T22:53:24.149Z,1557960804.149 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-15T22:53:24.162Z,1557960804.162 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-05-15T22:53:24.162Z,1557960804.162 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-15T22:53:24.162Z,1557960804.162 [Radio_Surface](INFO): Join timeout helper Thread ID is 5021
2019-05-15T22:53:24.169Z,1557960804.169 [Radio_Surface](INFO): Powering down
2019-05-15T22:53:24.170Z,1557960804.170 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-15T22:53:24.170Z,1557960804.170 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-15T22:53:24.171Z,1557960804.171 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-05-15T22:53:24.172Z,1557960804.172 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-15T22:53:24.172Z,1557960804.172 [DataOverHttps](INFO): Join timeout helper Thread ID is 5022
2019-05-15T22:53:24.473Z,1557960804.473 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-15T22:53:24.473Z,1557960804.473 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-15T22:53:24.478Z,1557960804.478 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-05-15T22:53:24.478Z,1557960804.478 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-15T22:53:24.478Z,1557960804.478 [logger](INFO): Join timeout helper Thread ID is 5023
2019-05-15T22:53:24.483Z,1557960804.483 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T22:53:24.483Z,1557960804.483 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T22:53:24.556Z,1557960804.556 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-15T22:53:24.556Z,1557960804.556 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-15T22:53:24.562Z,1557960804.562 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-05-15T22:53:24.562Z,1557960804.562 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-15T22:53:24.562Z,1557960804.562 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-05-15T22:53:24.562Z,1557960804.562 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-15T22:53:24.563Z,1557960804.563 [controlThread](INFO): Join timeout helper Thread ID is 5024
2019-05-15T22:53:24.793Z,1557960804.793 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-15T22:53:24.793Z,1557960804.793 [controlThread](DEBUG): Uninitializing ControlThread
2019-05-15T22:53:24.794Z,1557960804.794 [AHRS_M2](INFO): Powering down
2019-05-15T22:53:24.795Z,1557960804.795 [NAL9602](INFO): Powering down
2019-05-15T22:53:24.796Z,1557960804.796 [BR_Ping1D](INFO): Powering down
2019-05-15T22:53:24.798Z,1557960804.798 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-05-15T22:53:24.798Z,1557960804.798 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-05-15T22:53:24.799Z,1557960804.799 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-05-15T22:53:24.799Z,1557960804.799 [MissionManager](INFO): Uninitializing Mission Default
2019-05-15T22:53:24.799Z,1557960804.799 [Default] Stopped
2019-05-15T22:53:24.799Z,1557960804.799 [Default](DEBUG): Aggregate::uninitialize Default
2019-05-15T22:53:24.799Z,1557960804.799 [Default:B.GoToSurface] Stopped
2019-05-15T22:53:24.799Z,1557960804.799 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-15T22:53:24.799Z,1557960804.799 [Default:CheckIn] Stopped
2019-05-15T22:53:24.800Z,1557960804.800 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-05-15T22:53:24.800Z,1557960804.800 [Default:CheckIn:Read_GPS] Stopped
2019-05-15T22:53:24.802Z,1557960804.802 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-05-15T22:53:24.802Z,1557960804.802 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-05-15T22:53:24.803Z,1557960804.803 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-05-15T22:53:24.803Z,1557960804.803 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-05-15T22:53:24.803Z,1557960804.803 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-05-15T22:53:24.803Z,1557960804.803 [BuoyancyServo](INFO): Powering down
2019-05-15T22:53:24.817Z,1557960804.817 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-05-15T22:53:24.818Z,1557960804.818 [ElevatorServo](INFO): Powering down
2019-05-15T22:53:24.818Z,1557960804.818 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-05-15T22:53:24.819Z,1557960804.819 [MassServo](INFO): Powering down
2019-05-15T22:53:24.819Z,1557960804.819 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-05-15T22:53:24.819Z,1557960804.819 [RudderServo](INFO): Powering down
2019-05-15T22:53:24.820Z,1557960804.820 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-05-15T22:53:24.820Z,1557960804.820 [ThrusterServo](INFO): Powering down
2019-05-15T22:53:24.821Z,1557960804.821 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-05-15T22:53:24.821Z,1557960804.821 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-05-15T22:53:24.822Z,1557960804.822 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-05-15T22:53:24.822Z,1557960804.822 [CBIT](DEBUG): Powering off loads.
2019-05-15T22:53:24.833Z,1557960804.833 [CBIT](DEBUG): Disabling WDT.
2019-05-15T22:53:24.845Z,1557960804.845 [CBIT](DEBUG): Opening all GF detection circuits.
2019-05-15T22:53:24.846Z,1557960804.846 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-15T22:53:24.982Z,1557960804.982 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-15T22:53:24.999Z,1557960804.999 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-15T22:53:25.090Z,1557960805.090 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-15T22:53:25.103Z,1557960805.103 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-05-15T22:53:25.107Z,1557960805.107 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-15T22:53:25.164Z,1557960805.164 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-15T22:53:25.296Z,1557960805.296 [logger ThreadHandler](INFO): Thread cancelled.