2019-04-10T00:45:26.925Z,1554857126.925 [Supervisor](DEBUG): Initializing supervisor.
2019-04-10T00:45:26.928Z,1554857126.928 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-10T00:45:26.929Z,1554857126.929 [SyncHandler](INFO): Protected caller Thread ID is 300
2019-04-10T00:45:26.929Z,1554857126.929 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-04-10T00:45:26.930Z,1554857126.930 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-04-10T00:45:26.930Z,1554857126.930 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 301
2019-04-10T00:45:26.933Z,1554857126.933 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-04-10T00:45:26.945Z,1554857126.945 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-04-10T00:45:26.946Z,1554857126.946 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-04-10T00:45:26.946Z,1554857126.946 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 302
2019-04-10T00:45:26.947Z,1554857126.947 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-04-10T00:45:26.948Z,1554857126.948 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-04-10T00:45:26.948Z,1554857126.948 [logger ThreadHandler](INFO): Protected caller Thread ID is 303
2019-04-10T00:45:26.950Z,1554857126.950 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-04-10T00:45:26.951Z,1554857126.951 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-04-10T00:45:26.952Z,1554857126.952 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-04-10T00:45:27.046Z,1554857127.046 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-04-10T00:45:27.046Z,1554857127.046 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-04-10T00:45:27.246Z,1554857127.246 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-04-10T00:45:27.246Z,1554857127.246 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-04-10T00:45:27.381Z,1554857127.381 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-04-10T00:45:27.382Z,1554857127.382 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-04-10T00:45:27.848Z,1554857127.848 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-04-10T00:45:27.849Z,1554857127.849 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-04-10T00:45:28.291Z,1554857128.291 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-04-10T00:45:28.292Z,1554857128.292 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-04-10T00:45:28.762Z,1554857128.762 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-04-10T00:45:28.763Z,1554857128.763 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-04-10T00:45:29.068Z,1554857129.068 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-04-10T00:45:29.069Z,1554857129.069 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-04-10T00:45:29.401Z,1554857129.401 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-04-10T00:45:29.402Z,1554857129.402 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-04-10T00:45:29.812Z,1554857129.812 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-04-10T00:45:29.813Z,1554857129.813 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-04-10T00:45:30.015Z,1554857130.015 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-04-10T00:45:30.015Z,1554857130.015 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-04-10T00:45:30.121Z,1554857130.121 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-04-10T00:45:30.122Z,1554857130.122 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-04-10T00:45:30.201Z,1554857130.201 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-04-10T00:45:30.313Z,1554857130.313 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-04-10T00:45:30.314Z,1554857130.314 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-04-10T00:45:30.507Z,1554857130.507 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-04-10T00:45:30.508Z,1554857130.508 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-04-10T00:45:30.714Z,1554857130.714 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-04-10T00:45:30.716Z,1554857130.716 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-04-10T00:45:30.717Z,1554857130.717 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-04-10T00:45:30.798Z,1554857130.798 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-04-10T00:45:31.029Z,1554857131.029 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-04-10T00:45:31.030Z,1554857131.030 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-04-10T00:45:31.124Z,1554857131.124 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-04-10T00:45:31.296Z,1554857131.296 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-04-10T00:45:31.509Z,1554857131.509 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-04-10T00:45:31.594Z,1554857131.594 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-04-10T00:45:31.769Z,1554857131.769 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-04-10T00:45:32.087Z,1554857132.087 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-04-10T00:45:32.540Z,1554857132.540 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-04-10T00:45:32.664Z,1554857132.664 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-04-10T00:45:32.766Z,1554857132.766 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2019-04-10T00:45:32.766Z,1554857132.766 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2019-04-10T00:45:32.768Z,1554857132.768 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-04-10T00:45:32.866Z,1554857132.866 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-04-10T00:45:32.867Z,1554857132.867 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-04-10T00:45:32.967Z,1554857132.967 [BuoyancyServo] Loaded
2019-04-10T00:45:32.968Z,1554857132.968 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-04-10T00:45:32.983Z,1554857132.983 [ElevatorServo] Loaded
2019-04-10T00:45:32.983Z,1554857132.983 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-04-10T00:45:32.998Z,1554857132.998 [MassServo] Loaded
2019-04-10T00:45:32.998Z,1554857132.998 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-04-10T00:45:33.013Z,1554857133.013 [RudderServo] Loaded
2019-04-10T00:45:33.014Z,1554857133.014 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-04-10T00:45:33.028Z,1554857133.028 [ThrusterServo] Loaded
2019-04-10T00:45:33.028Z,1554857133.028 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-04-10T00:45:33.028Z,1554857133.028 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-04-10T00:45:33.029Z,1554857133.029 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-04-10T00:45:33.164Z,1554857133.164 [SBIT](DEBUG): Construct Startup Built In Test.
2019-04-10T00:45:33.192Z,1554857133.192 [SBIT] Loaded
2019-04-10T00:45:33.193Z,1554857133.193 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-04-10T00:45:33.193Z,1554857133.193 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-04-10T00:45:33.221Z,1554857133.221 [IBIT] Loaded
2019-04-10T00:45:33.221Z,1554857133.221 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-04-10T00:45:33.225Z,1554857133.225 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-04-10T00:45:33.610Z,1554857133.610 [CBIT] Loaded
2019-04-10T00:45:33.610Z,1554857133.610 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-04-10T00:45:33.610Z,1554857133.610 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-04-10T00:45:33.611Z,1554857133.611 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-04-10T00:45:33.794Z,1554857133.794 [Aanderaa_O2] Loaded
2019-04-10T00:45:33.795Z,1554857133.795 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-04-10T00:45:33.804Z,1554857133.804 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-04-10T00:45:33.810Z,1554857133.810 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-04-10T00:45:33.811Z,1554857133.811 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-04-10T00:45:33.816Z,1554857133.816 [CTD_Seabird](INFO): created writer for : depth
2019-04-10T00:45:33.817Z,1554857133.817 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-04-10T00:45:33.822Z,1554857133.822 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-04-10T00:45:33.822Z,1554857133.822 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-04-10T00:45:33.828Z,1554857133.828 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-04-10T00:45:33.828Z,1554857133.828 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-04-10T00:45:33.833Z,1554857133.833 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-04-10T00:45:33.834Z,1554857133.834 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-04-10T00:45:33.840Z,1554857133.840 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-04-10T00:45:33.840Z,1554857133.840 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-04-10T00:45:33.845Z,1554857133.845 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-04-10T00:45:33.871Z,1554857133.871 [CTD_Seabird] Loaded
2019-04-10T00:45:33.872Z,1554857133.872 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-04-10T00:45:33.873Z,1554857133.873 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406654E0
2019-04-10T00:45:33.873Z,1554857133.873 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 384
2019-04-10T00:45:33.899Z,1554857133.899 [ESPComponent] Loaded
2019-04-10T00:45:33.899Z,1554857133.899 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-04-10T00:45:33.913Z,1554857133.913 [PAR_Licor] Loaded
2019-04-10T00:45:33.913Z,1554857133.913 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-04-10T00:45:33.961Z,1554857133.961 [WetLabsBB2FL] Loaded
2019-04-10T00:45:33.961Z,1554857133.961 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-04-10T00:45:33.962Z,1554857133.962 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406954E0
2019-04-10T00:45:33.962Z,1554857133.962 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 385
2019-04-10T00:45:33.963Z,1554857133.963 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-04-10T00:45:33.964Z,1554857133.964 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-04-10T00:45:34.004Z,1554857134.004 [DepthRateCalculator] Loaded
2019-04-10T00:45:34.004Z,1554857134.004 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-04-10T00:45:34.010Z,1554857134.010 [PitchRateCalculator] Loaded
2019-04-10T00:45:34.010Z,1554857134.010 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-04-10T00:45:34.026Z,1554857134.026 [SpeedCalculator] Loaded
2019-04-10T00:45:34.026Z,1554857134.026 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-04-10T00:45:34.048Z,1554857134.048 [TempGradientCalculator] Loaded
2019-04-10T00:45:34.048Z,1554857134.048 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-04-10T00:45:34.054Z,1554857134.054 [YawRateCalculator] Loaded
2019-04-10T00:45:34.054Z,1554857134.054 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-04-10T00:45:34.087Z,1554857134.087 [ElevatorOffsetCalculator] Loaded
2019-04-10T00:45:34.087Z,1554857134.087 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-04-10T00:45:34.088Z,1554857134.088 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-04-10T00:45:34.088Z,1554857134.088 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-04-10T00:45:34.115Z,1554857134.115 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-04-10T00:45:34.115Z,1554857134.115 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-04-10T00:45:34.217Z,1554857134.217 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-04-10T00:45:34.218Z,1554857134.218 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-04-10T00:45:34.277Z,1554857134.277 [DeadReckonUsingSpeedCalculator] Loaded
2019-04-10T00:45:34.277Z,1554857134.277 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-04-10T00:45:34.337Z,1554857134.337 [DeadReckonWithRespectToSeafloor] Loaded
2019-04-10T00:45:34.337Z,1554857134.337 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-04-10T00:45:34.357Z,1554857134.357 [NavChart] Loaded
2019-04-10T00:45:34.358Z,1554857134.358 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-04-10T00:45:34.362Z,1554857134.362 [UniversalFixResidualReporter] Loaded
2019-04-10T00:45:34.362Z,1554857134.362 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-04-10T00:45:34.362Z,1554857134.362 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-04-10T00:45:34.363Z,1554857134.363 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-04-10T00:45:34.671Z,1554857134.671 [AcousticModem_Benthos_ATM900] Loaded
2019-04-10T00:45:34.672Z,1554857134.672 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-04-10T00:45:34.743Z,1554857134.743 [DataOverHttps] Loaded
2019-04-10T00:45:34.744Z,1554857134.744 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-04-10T00:45:34.745Z,1554857134.745 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409714E0
2019-04-10T00:45:34.745Z,1554857134.745 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 386
2019-04-10T00:45:34.759Z,1554857134.759 [Depth_Keller] Loaded
2019-04-10T00:45:34.759Z,1554857134.759 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-04-10T00:45:34.764Z,1554857134.764 [DropWeight] Loaded
2019-04-10T00:45:34.764Z,1554857134.764 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-04-10T00:45:34.854Z,1554857134.854 [NAL9602] Loaded
2019-04-10T00:45:34.855Z,1554857134.855 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-04-10T00:45:34.860Z,1554857134.860 [Onboard] Loaded
2019-04-10T00:45:34.860Z,1554857134.860 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-04-10T00:45:34.864Z,1554857134.864 [Radio_Surface] Loaded
2019-04-10T00:45:34.864Z,1554857134.864 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-04-10T00:45:34.865Z,1554857134.865 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A14E0
2019-04-10T00:45:34.865Z,1554857134.865 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 388
2019-04-10T00:45:36.153Z,1554857136.153 [BPC1] Loaded
2019-04-10T00:45:36.153Z,1554857136.153 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-04-10T00:45:36.184Z,1554857136.184 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minrange
2019-04-10T00:45:36.186Z,1554857136.186 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.maxrange
2019-04-10T00:45:36.188Z,1554857136.188 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.soundspeed
2019-04-10T00:45:36.189Z,1554857136.189 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.automode
2019-04-10T00:45:36.192Z,1554857136.192 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.gainindex
2019-04-10T00:45:36.194Z,1554857136.194 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minconfidence
2019-04-10T00:45:36.196Z,1554857136.196 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.pingenable
2019-04-10T00:45:36.198Z,1554857136.198 [BR_Ping1D] Loaded
2019-04-10T00:45:36.199Z,1554857136.199 [ComponentRegistry](DEBUG): SyncComponent "BR_Ping1D" handled in the control thread.
2019-04-10T00:45:36.199Z,1554857136.199 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-04-10T00:45:36.200Z,1554857136.200 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-04-10T00:45:36.263Z,1554857136.263 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-04-10T00:45:36.263Z,1554857136.263 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-04-10T00:45:36.328Z,1554857136.328 [VerticalControl](DEBUG): Construct VerticalControl.
2019-04-10T00:45:36.412Z,1554857136.412 [VerticalControl] Loaded
2019-04-10T00:45:36.413Z,1554857136.413 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-04-10T00:45:36.413Z,1554857136.413 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-04-10T00:45:36.470Z,1554857136.470 [HorizontalControl] Loaded
2019-04-10T00:45:36.471Z,1554857136.471 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-04-10T00:45:36.471Z,1554857136.471 [SpeedControl](DEBUG): Construct SpeedControl.
2019-04-10T00:45:36.473Z,1554857136.473 [SpeedControl] Loaded
2019-04-10T00:45:36.473Z,1554857136.473 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-04-10T00:45:36.474Z,1554857136.474 [LoopControl](DEBUG): Construct LoopControl.
2019-04-10T00:45:36.475Z,1554857136.475 [LoopControl] Loaded
2019-04-10T00:45:36.475Z,1554857136.475 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-04-10T00:45:36.475Z,1554857136.475 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-04-10T00:45:36.476Z,1554857136.476 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-04-10T00:45:36.489Z,1554857136.489 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-04-10T00:45:36.490Z,1554857136.490 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-04-10T00:45:36.764Z,1554857136.764 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-04-10T00:45:36.789Z,1554857136.789 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-04-10T00:45:36.790Z,1554857136.790 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-04-10T00:45:36.832Z,1554857136.832 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-04-10T00:45:36.833Z,1554857136.833 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACE4E0
2019-04-10T00:45:36.834Z,1554857136.834 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 389
2019-04-10T00:45:36.839Z,1554857136.839 [Supervisor](INFO): Main Thread ID is 9999
2019-04-10T00:45:36.839Z,1554857136.839 [Supervisor](DEBUG): Running supervisor.
2019-04-10T00:45:36.840Z,1554857136.840 [CommandLine ThreadHandler](INFO): Handler Thread ID is 390
2019-04-10T00:45:36.870Z,1554857136.870 [controlThread ThreadHandler](INFO): Handler Thread ID is 391
2019-04-10T00:45:36.871Z,1554857136.871 [controlThread](DEBUG): Initializing ControlThread
2019-04-10T00:45:36.872Z,1554857136.872 [SBIT](INFO): Initialize SBIT Component.
2019-04-10T00:45:36.873Z,1554857136.873 [SBIT](IMPORTANT): git: 2019-03-18-31-ge98d5dc
2019-04-10T00:45:36.873Z,1554857136.873 [SBIT](INFO): git hash: e98d5dc50cbc39e402f19b9946ff4dc0776c8725
2019-04-10T00:45:36.873Z,1554857136.873 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-04-10T00:45:36.875Z,1554857136.875 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-04-10T00:45:36.876Z,1554857136.876 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-04-10T00:45:36.876Z,1554857136.876 [IBIT](INFO): Initialize IBIT Component.
2019-04-10T00:45:36.877Z,1554857136.877 [CBIT](DEBUG): Initialize CBIT Component.
2019-04-10T00:45:36.878Z,1554857136.878 [logger ThreadHandler](INFO): Handler Thread ID is 392
2019-04-10T00:45:36.890Z,1554857136.890 [CBIT](DEBUG): Initialized mux pins.
2019-04-10T00:45:36.890Z,1554857136.890 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2019-04-10T00:45:36.890Z,1554857136.890 [CBIT](DEBUG): Initializing the watchdog timer.
2019-04-10T00:45:36.906Z,1554857136.906 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 393
2019-04-10T00:45:36.907Z,1554857136.907 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T00:45:36.909Z,1554857136.909 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 395
2019-04-10T00:45:36.910Z,1554857136.910 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 396
2019-04-10T00:45:36.912Z,1554857136.912 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-04-10T00:45:36.914Z,1554857136.914 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-04-10T00:45:36.914Z,1554857136.914 [CBIT](DEBUG): Initializing heartbeat.
2019-04-10T00:45:36.931Z,1554857136.931 [WetLabsBB2FL](INFO): Powering down
2019-04-10T00:45:36.975Z,1554857136.975 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T00:45:36.976Z,1554857136.976 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 397
2019-04-10T00:45:36.986Z,1554857136.986 [CBIT](DEBUG): Deactivating GF circuits.
2019-04-10T00:45:36.986Z,1554857136.986 [CBIT](DEBUG): Deactivating emergency mode.
2019-04-10T00:45:36.986Z,1554857136.986 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 398
2019-04-10T00:45:36.990Z,1554857136.990 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-10T00:45:36.990Z,1554857136.990 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-04-10T00:45:36.990Z,1554857136.990 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-10T00:45:36.990Z,1554857136.990 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-04-10T00:45:36.990Z,1554857136.990 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-10T00:45:36.991Z,1554857136.991 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-04-10T00:45:36.991Z,1554857136.991 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-10T00:45:36.991Z,1554857136.991 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-04-10T00:45:36.991Z,1554857136.991 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-10T00:45:36.991Z,1554857136.991 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-04-10T00:45:36.992Z,1554857136.992 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-10T00:45:36.992Z,1554857136.992 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-04-10T00:45:36.992Z,1554857136.992 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-10T00:45:36.992Z,1554857136.992 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-04-10T00:45:36.992Z,1554857136.992 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-10T00:45:36.992Z,1554857136.992 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-04-10T00:45:37.022Z,1554857137.022 [CBIT](DEBUG): Backplane powered.
2019-04-10T00:45:37.026Z,1554857137.026 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-04-10T00:45:37.027Z,1554857137.027 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-04-10T00:45:37.027Z,1554857137.027 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-04-10T00:45:37.027Z,1554857137.027 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-04-10T00:45:37.028Z,1554857137.028 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-04-10T00:45:37.028Z,1554857137.028 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-04-10T00:45:37.029Z,1554857137.029 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-10T00:45:37.031Z,1554857137.031 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-04-10T00:45:37.031Z,1554857137.031 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-04-10T00:45:37.037Z,1554857137.037 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-04-10T00:45:37.039Z,1554857137.039 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-04-10T00:45:37.040Z,1554857137.040 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-04-10T00:45:37.040Z,1554857137.040 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-04-10T00:45:37.041Z,1554857137.041 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-04-10T00:45:37.080Z,1554857137.080 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-10T00:45:37.115Z,1554857137.115 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2019-04-10T00:45:37.116Z,1554857137.116 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-04-10T00:45:37.187Z,1554857137.187 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-04-10T00:45:37.188Z,1554857137.188 [Default:A.Wait](DEBUG): Construct Wait.
2019-04-10T00:45:37.206Z,1554857137.206 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-10T00:45:37.233Z,1554857137.233 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-04-10T00:45:37.235Z,1554857137.235 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-04-10T00:45:37.245Z,1554857137.245 [Default:E.Execute](DEBUG): Construct Execute.
2019-04-10T00:45:37.248Z,1554857137.248 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2019-04-10T00:45:37.262Z,1554857137.262 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,Depth_Keller,DropWeight,NAL9602,Onboard,BPC1,BR_Ping1D,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-04-10T00:45:37.295Z,1554857137.295 [ESPComponent](INFO): powering down ESP
2019-04-10T00:45:37.378Z,1554857137.378 [Radio_Surface](INFO): Powering up
2019-04-10T00:45:37.790Z,1554857137.790 [PAR_Licor](INFO): A/D timeout, 8 tries over 144 ms

2019-04-10T00:45:37.790Z,1554857137.790 [PAR_Licor] Data Fault, FailCount= 1
2019-04-10T00:45:37.791Z,1554857137.791 [PAR_Licor](ERROR): Data Fault
2019-04-10T00:45:37.791Z,1554857137.791 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T00:45:37.791Z,1554857137.791 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T00:45:37.896Z,1554857137.896 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-04-10T00:45:37.902Z,1554857137.902 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-04-10T00:45:37.904Z,1554857137.904 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-10T00:45:37.911Z,1554857137.911 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-10T00:45:37.912Z,1554857137.912 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-10T00:45:37.918Z,1554857137.918 [MassServo](DEBUG): Initializing MassServo.
2019-04-10T00:45:37.919Z,1554857137.919 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-10T00:45:37.926Z,1554857137.926 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-10T00:45:37.927Z,1554857137.927 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-04-10T00:45:37.934Z,1554857137.934 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-04-10T00:45:37.937Z,1554857137.937 [CommandLine](FAULT): Scheduling is paused
2019-04-10T00:45:37.937Z,1554857137.937 [CBIT](INFO): Critical error at 20190410T004536
2019-04-10T00:45:37.937Z,1554857137.937 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-04-10T00:45:37.943Z,1554857137.943 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T00:45:37.943Z,1554857137.943 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T00:45:37.943Z,1554857137.943 [PAR_Licor] No Fault, FailCount= 1
2019-04-10T00:45:38.045Z,1554857138.045 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-04-10T00:45:38.045Z,1554857138.045 [DropWeight] Hardware Fault, FailCount= 1
2019-04-10T00:45:38.045Z,1554857138.045 [DropWeight](ERROR): Hardware Fault
2019-04-10T00:45:38.097Z,1554857138.097 [CBIT](INFO): Critical error at 20190410T004538
2019-04-10T00:45:38.100Z,1554857138.100 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-04-10T00:45:38.101Z,1554857138.101 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-04-10T00:45:38.482Z,1554857138.482 [PAR_Licor](INFO): A/D timeout, 8 tries over 130 ms

2019-04-10T00:45:38.482Z,1554857138.482 [PAR_Licor] Data Fault, FailCount= 2
2019-04-10T00:45:38.482Z,1554857138.482 [PAR_Licor](ERROR): Data Fault
2019-04-10T00:45:38.516Z,1554857138.516 [BR_Ping1D](INFO): startup: 0
2019-04-10T00:45:38.516Z,1554857138.516 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T00:45:38.530Z,1554857138.530 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-10T00:45:38.530Z,1554857138.530 [BR_Ping1D](INFO): data 011E
2019-04-10T00:45:38.530Z,1554857138.530 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T00:45:38.531Z,1554857138.531 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-10T00:45:38.531Z,1554857138.531 [BR_Ping1D](INFO): 42520100EB030000840100
2019-04-10T00:45:38.531Z,1554857138.531 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-10T00:45:38.531Z,1554857138.531 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T00:45:38.532Z,1554857138.532 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-10T00:45:38.532Z,1554857138.532 [BR_Ping1D](INFO): 42520100EE030000870100
2019-04-10T00:45:38.731Z,1554857138.731 [CBIT](INFO): Critical error at 20190410T004538
2019-04-10T00:45:38.734Z,1554857138.734 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T00:45:38.734Z,1554857138.734 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T00:45:38.734Z,1554857138.734 [PAR_Licor] No Fault, FailCount= 2
2019-04-10T00:45:38.779Z,1554857138.779 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:39.181Z,1554857139.181 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:39.181Z,1554857139.181 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:39.182Z,1554857139.182 [BR_Ping1D](INFO): startup: 1
2019-04-10T00:45:39.182Z,1554857139.182 [BR_Ping1D](INFO): UART requesting status
2019-04-10T00:45:39.182Z,1554857139.182 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T00:45:39.190Z,1554857139.190 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T00:45:39.198Z,1554857139.198 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T00:45:39.706Z,1554857139.706 [PAR_Licor](INFO): A/D timeout, 8 tries over 143 ms

2019-04-10T00:45:39.706Z,1554857139.706 [PAR_Licor] Data Fault, FailCount= 1
2019-04-10T00:45:39.706Z,1554857139.706 [PAR_Licor](ERROR): Data Fault
2019-04-10T00:45:39.744Z,1554857139.744 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:39.744Z,1554857139.744 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:39.744Z,1554857139.744 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:39.744Z,1554857139.744 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:39.745Z,1554857139.745 [BR_Ping1D](INFO): elapsed 1.2284
2019-04-10T00:45:39.745Z,1554857139.745 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:39.745Z,1554857139.745 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:39.745Z,1554857139.745 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:39.746Z,1554857139.746 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:39.828Z,1554857139.828 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T00:45:39.828Z,1554857139.828 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T00:45:39.828Z,1554857139.828 [PAR_Licor] No Fault, FailCount= 1
2019-04-10T00:45:39.989Z,1554857139.989 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:39.989Z,1554857139.989 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:39.990Z,1554857139.990 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:39.990Z,1554857139.990 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:39.990Z,1554857139.990 [BR_Ping1D](INFO): elapsed 1.4736
2019-04-10T00:45:39.990Z,1554857139.990 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:39.990Z,1554857139.990 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:39.991Z,1554857139.991 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:39.991Z,1554857139.991 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:40.394Z,1554857140.394 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:40.394Z,1554857140.394 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:40.394Z,1554857140.394 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:40.394Z,1554857140.394 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:40.394Z,1554857140.394 [BR_Ping1D](INFO): elapsed 1.8782
2019-04-10T00:45:40.395Z,1554857140.395 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:40.395Z,1554857140.395 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:40.395Z,1554857140.395 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:40.395Z,1554857140.395 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:40.797Z,1554857140.797 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:40.797Z,1554857140.797 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:40.798Z,1554857140.798 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:40.798Z,1554857140.798 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:40.798Z,1554857140.798 [BR_Ping1D](INFO): elapsed 2.2818
2019-04-10T00:45:40.798Z,1554857140.798 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:40.799Z,1554857140.799 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:40.799Z,1554857140.799 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:40.799Z,1554857140.799 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:41.231Z,1554857141.231 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:41.231Z,1554857141.231 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:41.231Z,1554857141.231 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:41.231Z,1554857141.231 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:41.231Z,1554857141.231 [BR_Ping1D](INFO): elapsed 2.7149
2019-04-10T00:45:41.231Z,1554857141.231 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:41.232Z,1554857141.232 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:41.232Z,1554857141.232 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:41.232Z,1554857141.232 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:41.782Z,1554857141.782 [PAR_Licor](INFO): A/D timeout, 8 tries over 125 ms

2019-04-10T00:45:41.782Z,1554857141.782 [PAR_Licor] Data Fault, FailCount= 1
2019-04-10T00:45:41.782Z,1554857141.782 [PAR_Licor](ERROR): Data Fault
2019-04-10T00:45:41.823Z,1554857141.823 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:41.823Z,1554857141.823 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:41.823Z,1554857141.823 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:41.823Z,1554857141.823 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:41.824Z,1554857141.824 [BR_Ping1D](INFO): elapsed 3.3073
2019-04-10T00:45:41.824Z,1554857141.824 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:41.824Z,1554857141.824 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:41.824Z,1554857141.824 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:41.824Z,1554857141.824 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:41.911Z,1554857141.911 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T00:45:41.912Z,1554857141.912 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T00:45:41.912Z,1554857141.912 [PAR_Licor] No Fault, FailCount= 1
2019-04-10T00:45:42.082Z,1554857142.082 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:42.082Z,1554857142.082 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:42.083Z,1554857142.083 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:42.083Z,1554857142.083 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:42.083Z,1554857142.083 [BR_Ping1D](INFO): elapsed 3.5666
2019-04-10T00:45:42.083Z,1554857142.083 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:42.083Z,1554857142.083 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:42.084Z,1554857142.084 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:42.084Z,1554857142.084 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:42.486Z,1554857142.486 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:42.486Z,1554857142.486 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:42.486Z,1554857142.486 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:42.487Z,1554857142.487 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:42.487Z,1554857142.487 [BR_Ping1D](INFO): elapsed 3.9705
2019-04-10T00:45:42.487Z,1554857142.487 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:42.487Z,1554857142.487 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:42.487Z,1554857142.487 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:42.487Z,1554857142.487 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:43.010Z,1554857143.010 [PAR_Licor](INFO): A/D timeout, 9 tries over 143 ms

2019-04-10T00:45:43.010Z,1554857143.010 [PAR_Licor] Data Fault, FailCount= 1
2019-04-10T00:45:43.010Z,1554857143.010 [PAR_Licor](ERROR): Data Fault
2019-04-10T00:45:43.031Z,1554857143.031 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:43.031Z,1554857143.031 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:43.031Z,1554857143.031 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:43.031Z,1554857143.031 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:43.031Z,1554857143.031 [BR_Ping1D](INFO): elapsed 4.5150
2019-04-10T00:45:43.032Z,1554857143.032 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:43.032Z,1554857143.032 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:43.032Z,1554857143.032 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:43.032Z,1554857143.032 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:43.082Z,1554857143.082 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T00:45:43.083Z,1554857143.083 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T00:45:43.083Z,1554857143.083 [PAR_Licor] No Fault, FailCount= 1
2019-04-10T00:45:43.294Z,1554857143.294 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:43.294Z,1554857143.294 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:43.294Z,1554857143.294 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:43.294Z,1554857143.294 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:43.294Z,1554857143.294 [BR_Ping1D](INFO): elapsed 4.7781
2019-04-10T00:45:43.295Z,1554857143.295 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:43.295Z,1554857143.295 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:43.295Z,1554857143.295 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:43.295Z,1554857143.295 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:43.814Z,1554857143.814 [PAR_Licor](INFO): A/D timeout, 9 tries over 139 ms

2019-04-10T00:45:43.814Z,1554857143.814 [PAR_Licor] Data Fault, FailCount= 2
2019-04-10T00:45:43.814Z,1554857143.814 [PAR_Licor](ERROR): Data Fault
2019-04-10T00:45:43.858Z,1554857143.858 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:43.859Z,1554857143.859 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:43.859Z,1554857143.859 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:43.859Z,1554857143.859 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:43.859Z,1554857143.859 [BR_Ping1D](INFO): elapsed 5.3428
2019-04-10T00:45:43.859Z,1554857143.859 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:43.859Z,1554857143.859 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T00:45:43.860Z,1554857143.860 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-10T00:45:43.860Z,1554857143.860 [BR_Ping1D](ERROR): Data Fault
2019-04-10T00:45:44.146Z,1554857144.146 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T00:45:44.146Z,1554857144.146 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T00:45:44.146Z,1554857144.146 [PAR_Licor] No Fault, FailCount= 2
2019-04-10T00:45:44.147Z,1554857144.147 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T00:45:44.192Z,1554857144.192 [BR_Ping1D](INFO): Stop
2019-04-10T00:45:44.192Z,1554857144.192 [BR_Ping1D](INFO): uninitialize
2019-04-10T00:45:44.192Z,1554857144.192 [BR_Ping1D](INFO): Powering down
2019-04-10T00:45:44.578Z,1554857144.578 [BR_Ping1D](INFO): Stopping
2019-04-10T00:45:44.981Z,1554857144.981 [BR_Ping1D](INFO): Stopping
2019-04-10T00:45:45.510Z,1554857145.510 [PAR_Licor](INFO): A/D timeout, 8 tries over 147 ms

2019-04-10T00:45:45.510Z,1554857145.510 [PAR_Licor] Data Fault, FailCount= 1
2019-04-10T00:45:45.510Z,1554857145.510 [PAR_Licor](ERROR): Data Fault
2019-04-10T00:45:45.536Z,1554857145.536 [BR_Ping1D](INFO): Stopped
2019-04-10T00:45:45.627Z,1554857145.627 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T00:45:45.627Z,1554857145.627 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T00:45:45.627Z,1554857145.627 [PAR_Licor] No Fault, FailCount= 1
2019-04-10T00:45:45.627Z,1554857145.627 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T00:45:45.627Z,1554857145.627 [BR_Ping1D] No Fault, FailCount= 1
2019-04-10T00:45:45.790Z,1554857145.790 [BR_Ping1D](INFO): Start
2019-04-10T00:45:46.194Z,1554857146.194 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:46.597Z,1554857146.597 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:46.597Z,1554857146.597 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:46.598Z,1554857146.598 [BR_Ping1D](INFO): startup: 0
2019-04-10T00:45:46.598Z,1554857146.598 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T00:45:46.610Z,1554857146.610 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-10T00:45:46.610Z,1554857146.610 [BR_Ping1D](INFO): data 011E
2019-04-10T00:45:46.610Z,1554857146.610 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T00:45:46.611Z,1554857146.611 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-10T00:45:46.611Z,1554857146.611 [BR_Ping1D](INFO): 42520100EB030000840100
2019-04-10T00:45:46.611Z,1554857146.611 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-10T00:45:46.617Z,1554857146.617 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T00:45:46.631Z,1554857146.631 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-10T00:45:46.631Z,1554857146.631 [BR_Ping1D](INFO): 42520100EE030000870100
2019-04-10T00:45:47.110Z,1554857147.110 [PAR_Licor](INFO): A/D timeout, 7 tries over 131 ms

2019-04-10T00:45:47.110Z,1554857147.110 [PAR_Licor] Data Fault, FailCount= 1
2019-04-10T00:45:47.110Z,1554857147.110 [PAR_Licor](ERROR): Data Fault
2019-04-10T00:45:47.151Z,1554857147.151 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:47.151Z,1554857147.151 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:47.151Z,1554857147.151 [BR_Ping1D](INFO): startup: 1
2019-04-10T00:45:47.151Z,1554857147.151 [BR_Ping1D](INFO): UART requesting status
2019-04-10T00:45:47.151Z,1554857147.151 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T00:45:47.158Z,1554857147.158 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T00:45:47.166Z,1554857147.166 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T00:45:47.182Z,1554857147.182 [CBIT](CRITICAL): Environmental Failure. Press:14.875262 PSI. Humidity:37%. Temp:25 C. ABORTING MISSION
2019-04-10T00:45:47.182Z,1554857147.182 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T00:45:47.183Z,1554857147.183 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T00:45:47.183Z,1554857147.183 [PAR_Licor] No Fault, FailCount= 1
2019-04-10T00:45:47.382Z,1554857147.382 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T00:45:47.382Z,1554857147.382 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-10T00:45:47.382Z,1554857147.382 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T00:45:47.406Z,1554857147.406 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:47.406Z,1554857147.406 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:47.406Z,1554857147.406 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:47.406Z,1554857147.406 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:47.406Z,1554857147.406 [BR_Ping1D](INFO): elapsed 0.8084
2019-04-10T00:45:47.407Z,1554857147.407 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:47.407Z,1554857147.407 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:47.407Z,1554857147.407 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:47.407Z,1554857147.407 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:47.457Z,1554857147.457 [CBIT](INFO): Critical error at 20190410T004547
2019-04-10T00:45:47.460Z,1554857147.460 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T00:45:47.906Z,1554857147.906 [Aanderaa_O2](INFO): Powering down
2019-04-10T00:45:47.995Z,1554857147.995 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:47.995Z,1554857147.995 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:47.995Z,1554857147.995 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:47.995Z,1554857147.995 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:47.995Z,1554857147.995 [BR_Ping1D](INFO): elapsed 1.3974
2019-04-10T00:45:47.996Z,1554857147.996 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:47.996Z,1554857147.996 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:47.996Z,1554857147.996 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:47.996Z,1554857147.996 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:48.213Z,1554857148.213 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:48.213Z,1554857148.213 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:48.214Z,1554857148.214 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:48.214Z,1554857148.214 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:48.214Z,1554857148.214 [BR_Ping1D](INFO): elapsed 1.6160
2019-04-10T00:45:48.214Z,1554857148.214 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:48.214Z,1554857148.214 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:48.215Z,1554857148.215 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:48.215Z,1554857148.215 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:48.734Z,1554857148.734 [PAR_Licor](INFO): A/D timeout, 8 tries over 139 ms

2019-04-10T00:45:48.734Z,1554857148.734 [PAR_Licor] Data Fault, FailCount= 1
2019-04-10T00:45:48.734Z,1554857148.734 [PAR_Licor](ERROR): Data Fault
2019-04-10T00:45:48.768Z,1554857148.768 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:48.768Z,1554857148.768 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:48.769Z,1554857148.769 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:48.769Z,1554857148.769 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:48.769Z,1554857148.769 [BR_Ping1D](INFO): elapsed 2.1710
2019-04-10T00:45:48.769Z,1554857148.769 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:48.769Z,1554857148.769 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:48.770Z,1554857148.770 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:48.770Z,1554857148.770 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:48.819Z,1554857148.819 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T00:45:48.819Z,1554857148.819 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-10T00:45:48.819Z,1554857148.819 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T00:45:48.819Z,1554857148.819 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T00:45:48.819Z,1554857148.819 [PAR_Licor] No Fault, FailCount= 1
2019-04-10T00:45:49.017Z,1554857149.017 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:49.017Z,1554857149.017 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:49.017Z,1554857149.017 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:49.017Z,1554857149.017 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:49.017Z,1554857149.017 [BR_Ping1D](INFO): elapsed 2.4195
2019-04-10T00:45:49.018Z,1554857149.018 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:49.018Z,1554857149.018 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:49.018Z,1554857149.018 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:49.018Z,1554857149.018 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:49.443Z,1554857149.443 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:49.443Z,1554857149.443 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:49.443Z,1554857149.443 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:49.443Z,1554857149.443 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:49.444Z,1554857149.444 [BR_Ping1D](INFO): elapsed 2.8456
2019-04-10T00:45:49.444Z,1554857149.444 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:49.444Z,1554857149.444 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:49.444Z,1554857149.444 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:49.444Z,1554857149.444 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:49.942Z,1554857149.942 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-10T00:45:49.942Z,1554857149.942 [PAR_Licor] Data Fault, FailCount= 1
2019-04-10T00:45:49.942Z,1554857149.942 [PAR_Licor](ERROR): Data Fault
2019-04-10T00:45:49.975Z,1554857149.975 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004466
2019-04-10T00:45:49.987Z,1554857149.987 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:49.987Z,1554857149.987 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:49.988Z,1554857149.988 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:49.988Z,1554857149.988 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:49.988Z,1554857149.988 [BR_Ping1D](INFO): elapsed 3.3900
2019-04-10T00:45:49.988Z,1554857149.988 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:49.988Z,1554857149.988 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:49.989Z,1554857149.989 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:49.989Z,1554857149.989 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:50.018Z,1554857150.018 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T00:45:50.019Z,1554857150.019 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T00:45:50.019Z,1554857150.019 [PAR_Licor] No Fault, FailCount= 1
2019-04-10T00:45:50.234Z,1554857150.234 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:50.234Z,1554857150.234 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:50.234Z,1554857150.234 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:50.234Z,1554857150.234 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:50.234Z,1554857150.234 [BR_Ping1D](INFO): elapsed 3.6364
2019-04-10T00:45:50.235Z,1554857150.235 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:50.235Z,1554857150.235 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:50.235Z,1554857150.235 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:50.235Z,1554857150.235 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:50.750Z,1554857150.750 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-10T00:45:50.750Z,1554857150.750 [PAR_Licor] Data Fault, FailCount= 2
2019-04-10T00:45:50.750Z,1554857150.750 [PAR_Licor](ERROR): Data Fault
2019-04-10T00:45:50.791Z,1554857150.791 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:50.791Z,1554857150.791 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:50.791Z,1554857150.791 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:50.791Z,1554857150.791 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:50.792Z,1554857150.792 [BR_Ping1D](INFO): elapsed 4.1935
2019-04-10T00:45:50.792Z,1554857150.792 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:50.792Z,1554857150.792 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:50.792Z,1554857150.792 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:50.792Z,1554857150.792 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:50.807Z,1554857150.807 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T00:45:50.807Z,1554857150.807 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T00:45:50.807Z,1554857150.807 [PAR_Licor] No Fault, FailCount= 2
2019-04-10T00:45:50.962Z,1554857150.962 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T00:45:50.962Z,1554857150.962 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-10T00:45:50.962Z,1554857150.962 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T00:45:51.042Z,1554857151.042 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:51.042Z,1554857151.042 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:51.043Z,1554857151.043 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:51.043Z,1554857151.043 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:51.043Z,1554857151.043 [BR_Ping1D](INFO): elapsed 4.4451
2019-04-10T00:45:51.043Z,1554857151.043 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:51.044Z,1554857151.044 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:51.044Z,1554857151.044 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:51.044Z,1554857151.044 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:51.095Z,1554857151.095 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T00:45:51.366Z,1554857151.366 [CTD_Seabird](INFO): Powering down
2019-04-10T00:45:51.445Z,1554857151.445 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:51.445Z,1554857151.445 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:51.446Z,1554857151.446 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:51.446Z,1554857151.446 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:51.446Z,1554857151.446 [BR_Ping1D](INFO): elapsed 4.8482
2019-04-10T00:45:51.446Z,1554857151.446 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:51.447Z,1554857151.447 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:51.447Z,1554857151.447 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:51.447Z,1554857151.447 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:51.958Z,1554857151.958 [PAR_Licor](INFO): A/D timeout, 9 tries over 130 ms

2019-04-10T00:45:51.958Z,1554857151.958 [PAR_Licor] Data Fault, FailCount= 1
2019-04-10T00:45:51.958Z,1554857151.958 [PAR_Licor](ERROR): Data Fault
2019-04-10T00:45:51.992Z,1554857151.992 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:51.992Z,1554857151.992 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:51.992Z,1554857151.992 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:51.993Z,1554857151.993 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:51.993Z,1554857151.993 [BR_Ping1D](INFO): elapsed 5.3948
2019-04-10T00:45:51.993Z,1554857151.993 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:51.993Z,1554857151.993 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T00:45:51.993Z,1554857151.993 [BR_Ping1D] Data Fault, FailCount= 2
2019-04-10T00:45:51.993Z,1554857151.993 [BR_Ping1D](ERROR): Data Fault
2019-04-10T00:45:52.042Z,1554857152.042 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T00:45:52.043Z,1554857152.043 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T00:45:52.043Z,1554857152.043 [PAR_Licor] No Fault, FailCount= 1
2019-04-10T00:45:52.043Z,1554857152.043 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T00:45:52.253Z,1554857152.253 [BR_Ping1D](INFO): Stop
2019-04-10T00:45:52.253Z,1554857152.253 [BR_Ping1D](INFO): uninitialize
2019-04-10T00:45:52.253Z,1554857152.253 [BR_Ping1D](INFO): Powering down
2019-04-10T00:45:52.347Z,1554857152.347 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-10T00:45:52.347Z,1554857152.347 [CTD_Seabird] No Fault, FailCount= 1
2019-04-10T00:45:52.735Z,1554857152.735 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T00:45:52.736Z,1554857152.736 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T00:45:52.764Z,1554857152.764 [BR_Ping1D](INFO): Stopping
2019-04-10T00:45:53.062Z,1554857153.062 [BR_Ping1D](INFO): Stopping
2019-04-10T00:45:53.586Z,1554857153.586 [PAR_Licor](INFO): A/D timeout, 9 tries over 142 ms

2019-04-10T00:45:53.586Z,1554857153.586 [PAR_Licor] Data Fault, FailCount= 1
2019-04-10T00:45:53.586Z,1554857153.586 [PAR_Licor](ERROR): Data Fault
2019-04-10T00:45:53.622Z,1554857153.622 [BR_Ping1D](INFO): Stopped
2019-04-10T00:45:53.671Z,1554857153.671 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T00:45:53.671Z,1554857153.671 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T00:45:53.671Z,1554857153.671 [PAR_Licor] No Fault, FailCount= 1
2019-04-10T00:45:53.671Z,1554857153.671 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T00:45:53.672Z,1554857153.672 [BR_Ping1D] No Fault, FailCount= 2
2019-04-10T00:45:53.869Z,1554857153.869 [BR_Ping1D](INFO): Start
2019-04-10T00:45:54.394Z,1554857154.394 [PAR_Licor](INFO): A/D timeout, 9 tries over 142 ms

2019-04-10T00:45:54.394Z,1554857154.394 [PAR_Licor] Data Fault, FailCount= 2
2019-04-10T00:45:54.394Z,1554857154.394 [PAR_Licor](ERROR): Data Fault
2019-04-10T00:45:54.430Z,1554857154.430 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:54.430Z,1554857154.430 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:54.430Z,1554857154.430 [BR_Ping1D](INFO): startup: 0
2019-04-10T00:45:54.431Z,1554857154.431 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T00:45:54.442Z,1554857154.442 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-10T00:45:54.442Z,1554857154.442 [BR_Ping1D](INFO): data 011E
2019-04-10T00:45:54.442Z,1554857154.442 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T00:45:54.443Z,1554857154.443 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-10T00:45:54.443Z,1554857154.443 [BR_Ping1D](INFO): 42520100EB030000840100
2019-04-10T00:45:54.443Z,1554857154.443 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-10T00:45:54.443Z,1554857154.443 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T00:45:54.443Z,1554857154.443 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-10T00:45:54.443Z,1554857154.443 [BR_Ping1D](INFO): 42520100EE030000870100
2019-04-10T00:45:54.486Z,1554857154.486 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T00:45:54.487Z,1554857154.487 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T00:45:54.487Z,1554857154.487 [PAR_Licor] No Fault, FailCount= 2
2019-04-10T00:45:54.677Z,1554857154.677 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:55.186Z,1554857155.186 [PAR_Licor](INFO): A/D timeout, 8 tries over 126 ms

2019-04-10T00:45:55.186Z,1554857155.186 [PAR_Licor] Data Fault, FailCount= 3
2019-04-10T00:45:55.186Z,1554857155.186 [PAR_Licor](ERROR): Data Fault
2019-04-10T00:45:55.208Z,1554857155.208 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:55.208Z,1554857155.208 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:55.208Z,1554857155.208 [BR_Ping1D](INFO): startup: 1
2019-04-10T00:45:55.208Z,1554857155.208 [BR_Ping1D](INFO): UART requesting status
2019-04-10T00:45:55.209Z,1554857155.209 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T00:45:55.218Z,1554857155.218 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T00:45:55.226Z,1554857155.226 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T00:45:55.249Z,1554857155.249 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T00:45:55.249Z,1554857155.249 [CBIT](CRITICAL): Data Fault in component: PAR_Licor
2019-04-10T00:45:55.481Z,1554857155.481 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:55.481Z,1554857155.481 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:55.482Z,1554857155.482 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:55.482Z,1554857155.482 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:55.482Z,1554857155.482 [BR_Ping1D](INFO): elapsed 1.0513
2019-04-10T00:45:55.482Z,1554857155.482 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:55.482Z,1554857155.482 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:55.483Z,1554857155.483 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:55.483Z,1554857155.483 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:55.498Z,1554857155.498 [CBIT](INFO): Critical error at 20190410T004555
2019-04-10T00:45:55.887Z,1554857155.887 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:55.887Z,1554857155.887 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:55.887Z,1554857155.887 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:55.887Z,1554857155.887 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:55.887Z,1554857155.887 [BR_Ping1D](INFO): elapsed 1.4565
2019-04-10T00:45:55.887Z,1554857155.887 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:55.888Z,1554857155.888 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:55.888Z,1554857155.888 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:55.888Z,1554857155.888 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:56.289Z,1554857156.289 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:56.289Z,1554857156.289 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:56.289Z,1554857156.289 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:56.290Z,1554857156.290 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:56.290Z,1554857156.290 [BR_Ping1D](INFO): elapsed 1.8592
2019-04-10T00:45:56.290Z,1554857156.290 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:56.290Z,1554857156.290 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:56.290Z,1554857156.290 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:56.291Z,1554857156.291 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:56.694Z,1554857156.694 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:56.694Z,1554857156.694 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:56.694Z,1554857156.694 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:56.694Z,1554857156.694 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:56.694Z,1554857156.694 [BR_Ping1D](INFO): elapsed 2.2637
2019-04-10T00:45:56.695Z,1554857156.695 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:56.695Z,1554857156.695 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:56.695Z,1554857156.695 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:56.695Z,1554857156.695 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:57.098Z,1554857157.098 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:57.098Z,1554857157.098 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:57.098Z,1554857157.098 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:57.098Z,1554857157.098 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:57.099Z,1554857157.099 [BR_Ping1D](INFO): elapsed 2.6680
2019-04-10T00:45:57.099Z,1554857157.099 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:57.099Z,1554857157.099 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:57.099Z,1554857157.099 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:57.099Z,1554857157.099 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:57.502Z,1554857157.502 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:57.503Z,1554857157.503 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:57.503Z,1554857157.503 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:57.503Z,1554857157.503 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:57.503Z,1554857157.503 [BR_Ping1D](INFO): elapsed 3.0725
2019-04-10T00:45:57.503Z,1554857157.503 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:57.504Z,1554857157.504 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:57.504Z,1554857157.504 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:57.504Z,1554857157.504 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:57.887Z,1554857157.887 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T00:45:57.887Z,1554857157.887 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-04-10T00:45:57.887Z,1554857157.887 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T00:45:57.906Z,1554857157.906 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:57.906Z,1554857157.906 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:57.907Z,1554857157.907 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:57.907Z,1554857157.907 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:57.907Z,1554857157.907 [BR_Ping1D](INFO): elapsed 3.4762
2019-04-10T00:45:57.907Z,1554857157.907 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:57.907Z,1554857157.907 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:57.907Z,1554857157.907 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:57.908Z,1554857157.908 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:57.943Z,1554857157.943 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T00:45:58.291Z,1554857158.291 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T00:45:58.451Z,1554857158.451 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:58.451Z,1554857158.451 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:58.451Z,1554857158.451 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:58.451Z,1554857158.451 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:58.452Z,1554857158.452 [BR_Ping1D](INFO): elapsed 4.0210
2019-04-10T00:45:58.452Z,1554857158.452 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:58.452Z,1554857158.452 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:58.452Z,1554857158.452 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:58.452Z,1554857158.452 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:58.713Z,1554857158.713 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:58.713Z,1554857158.713 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:58.713Z,1554857158.713 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:58.714Z,1554857158.714 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:58.714Z,1554857158.714 [BR_Ping1D](INFO): elapsed 4.2830
2019-04-10T00:45:58.714Z,1554857158.714 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:58.714Z,1554857158.714 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:58.714Z,1554857158.714 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:58.715Z,1554857158.715 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:59.098Z,1554857159.098 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T00:45:59.099Z,1554857159.099 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-10T00:45:59.099Z,1554857159.099 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T00:45:59.119Z,1554857159.119 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:59.119Z,1554857159.119 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:59.119Z,1554857159.119 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:59.119Z,1554857159.119 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:59.119Z,1554857159.119 [BR_Ping1D](INFO): elapsed 4.6888
2019-04-10T00:45:59.120Z,1554857159.120 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:59.120Z,1554857159.120 [BR_Ping1D](INFO): No UART data
2019-04-10T00:45:59.120Z,1554857159.120 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:45:59.120Z,1554857159.120 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:45:59.135Z,1554857159.135 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T00:45:59.135Z,1554857159.135 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T00:45:59.135Z,1554857159.135 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-04-10T00:45:59.622Z,1554857159.622 [Aanderaa_O2](INFO): Powering down
2019-04-10T00:45:59.642Z,1554857159.642 [BR_Ping1D](INFO): Starting
2019-04-10T00:45:59.642Z,1554857159.642 [BR_Ping1D](INFO): readConfig
2019-04-10T00:45:59.642Z,1554857159.642 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:45:59.642Z,1554857159.642 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:45:59.642Z,1554857159.642 [BR_Ping1D](INFO): elapsed 5.2117
2019-04-10T00:45:59.643Z,1554857159.643 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:45:59.643Z,1554857159.643 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T00:45:59.643Z,1554857159.643 [BR_Ping1D] Data Fault, FailCount= 3
2019-04-10T00:45:59.643Z,1554857159.643 [BR_Ping1D](ERROR): Data Fault
2019-04-10T00:45:59.684Z,1554857159.684 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T00:45:59.926Z,1554857159.926 [BR_Ping1D](INFO): Stop
2019-04-10T00:45:59.926Z,1554857159.926 [BR_Ping1D](INFO): uninitialize
2019-04-10T00:45:59.926Z,1554857159.926 [BR_Ping1D](INFO): Powering down
2019-04-10T00:46:00.330Z,1554857160.330 [BR_Ping1D](INFO): Stopping
2019-04-10T00:46:00.363Z,1554857160.363 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T00:46:00.363Z,1554857160.363 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-10T00:46:00.733Z,1554857160.733 [BR_Ping1D](INFO): Stopping
2019-04-10T00:46:01.150Z,1554857161.150 [BR_Ping1D](INFO): Stopped
2019-04-10T00:46:01.183Z,1554857161.183 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T00:46:01.183Z,1554857161.183 [BR_Ping1D] No Fault, FailCount= 3
2019-04-10T00:46:01.523Z,1554857161.523 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T00:46:01.523Z,1554857161.523 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T00:46:01.567Z,1554857161.567 [BR_Ping1D](INFO): Start
2019-04-10T00:46:01.592Z,1554857161.592 [SBIT](IMPORTANT): Beginning Startup BIT
2019-04-10T00:46:01.625Z,1554857161.625 [CBIT](IMPORTANT): Beginning ground fault scan
2019-04-10T00:46:01.946Z,1554857161.946 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:02.350Z,1554857162.350 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:02.350Z,1554857162.350 [BR_Ping1D](INFO): readConfig
2019-04-10T00:46:02.350Z,1554857162.350 [BR_Ping1D](INFO): startup: 0
2019-04-10T00:46:02.351Z,1554857162.351 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T00:46:02.362Z,1554857162.362 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-10T00:46:02.362Z,1554857162.362 [BR_Ping1D](INFO): data 011E
2019-04-10T00:46:02.362Z,1554857162.362 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T00:46:02.362Z,1554857162.362 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-10T00:46:02.363Z,1554857162.363 [BR_Ping1D](INFO): 42520100EB030000840100
2019-04-10T00:46:02.363Z,1554857162.363 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-10T00:46:02.363Z,1554857162.363 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T00:46:02.363Z,1554857162.363 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-10T00:46:02.363Z,1554857162.363 [BR_Ping1D](INFO): 42520100EE030000870100
2019-04-10T00:46:02.755Z,1554857162.755 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:03.159Z,1554857163.159 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:03.160Z,1554857163.160 [BR_Ping1D](INFO): readConfig
2019-04-10T00:46:03.160Z,1554857163.160 [BR_Ping1D](INFO): startup: 1
2019-04-10T00:46:03.160Z,1554857163.160 [BR_Ping1D](INFO): UART requesting status
2019-04-10T00:46:03.160Z,1554857163.160 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T00:46:03.166Z,1554857163.166 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T00:46:03.174Z,1554857163.174 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T00:46:03.561Z,1554857163.561 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:03.561Z,1554857163.561 [BR_Ping1D](INFO): readConfig
2019-04-10T00:46:03.562Z,1554857163.562 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:46:03.562Z,1554857163.562 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:46:03.562Z,1554857163.562 [BR_Ping1D](INFO): elapsed 1.2111
2019-04-10T00:46:03.562Z,1554857163.562 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:46:03.562Z,1554857163.562 [BR_Ping1D](INFO): No UART data
2019-04-10T00:46:03.562Z,1554857163.562 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:46:03.563Z,1554857163.563 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:46:03.966Z,1554857163.966 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:03.966Z,1554857163.966 [BR_Ping1D](INFO): readConfig
2019-04-10T00:46:03.967Z,1554857163.967 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:46:03.967Z,1554857163.967 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:46:03.967Z,1554857163.967 [BR_Ping1D](INFO): elapsed 1.6161
2019-04-10T00:46:03.967Z,1554857163.967 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:46:03.967Z,1554857163.967 [BR_Ping1D](INFO): No UART data
2019-04-10T00:46:03.967Z,1554857163.967 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:46:03.968Z,1554857163.968 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:46:04.569Z,1554857164.569 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:04.569Z,1554857164.569 [BR_Ping1D](INFO): readConfig
2019-04-10T00:46:04.569Z,1554857164.569 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:46:04.569Z,1554857164.569 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:46:04.570Z,1554857164.570 [BR_Ping1D](INFO): elapsed 2.2189
2019-04-10T00:46:04.570Z,1554857164.570 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:46:04.570Z,1554857164.570 [BR_Ping1D](INFO): No UART data
2019-04-10T00:46:04.570Z,1554857164.570 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:46:04.570Z,1554857164.570 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:46:04.947Z,1554857164.947 [NAL9602](INFO): Powering up NAL9602
2019-04-10T00:46:04.963Z,1554857164.963 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:04.963Z,1554857164.963 [BR_Ping1D](INFO): readConfig
2019-04-10T00:46:04.964Z,1554857164.964 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:46:04.964Z,1554857164.964 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:46:04.964Z,1554857164.964 [BR_Ping1D](INFO): elapsed 2.6130
2019-04-10T00:46:04.964Z,1554857164.964 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:46:04.964Z,1554857164.964 [BR_Ping1D](INFO): No UART data
2019-04-10T00:46:04.964Z,1554857164.964 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:46:04.964Z,1554857164.964 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:46:05.365Z,1554857165.365 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:05.365Z,1554857165.365 [BR_Ping1D](INFO): readConfig
2019-04-10T00:46:05.366Z,1554857165.366 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:46:05.366Z,1554857165.366 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:46:05.366Z,1554857165.366 [BR_Ping1D](INFO): elapsed 3.0154
2019-04-10T00:46:05.366Z,1554857165.366 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:46:05.367Z,1554857165.367 [BR_Ping1D](INFO): No UART data
2019-04-10T00:46:05.367Z,1554857165.367 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:46:05.367Z,1554857165.367 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:46:05.770Z,1554857165.770 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:05.770Z,1554857165.770 [BR_Ping1D](INFO): readConfig
2019-04-10T00:46:05.770Z,1554857165.770 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:46:05.771Z,1554857165.771 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:46:05.771Z,1554857165.771 [BR_Ping1D](INFO): elapsed 3.4200
2019-04-10T00:46:05.771Z,1554857165.771 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:46:05.771Z,1554857165.771 [BR_Ping1D](INFO): No UART data
2019-04-10T00:46:05.771Z,1554857165.771 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:46:05.771Z,1554857165.771 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:46:05.786Z,1554857165.786 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T00:46:05.786Z,1554857165.786 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-10T00:46:05.786Z,1554857165.786 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T00:46:05.875Z,1554857165.875 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T00:46:06.177Z,1554857166.177 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:06.177Z,1554857166.177 [BR_Ping1D](INFO): readConfig
2019-04-10T00:46:06.177Z,1554857166.177 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:46:06.178Z,1554857166.178 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:46:06.178Z,1554857166.178 [BR_Ping1D](INFO): elapsed 3.8271
2019-04-10T00:46:06.178Z,1554857166.178 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:46:06.178Z,1554857166.178 [BR_Ping1D](INFO): No UART data
2019-04-10T00:46:06.178Z,1554857166.178 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:46:06.179Z,1554857166.179 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:46:06.190Z,1554857166.190 [CTD_Seabird](INFO): Powering down
2019-04-10T00:46:06.577Z,1554857166.577 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:06.578Z,1554857166.578 [BR_Ping1D](INFO): readConfig
2019-04-10T00:46:06.578Z,1554857166.578 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:46:06.578Z,1554857166.578 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:46:06.578Z,1554857166.578 [BR_Ping1D](INFO): elapsed 4.2275
2019-04-10T00:46:06.578Z,1554857166.578 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:46:06.579Z,1554857166.579 [BR_Ping1D](INFO): No UART data
2019-04-10T00:46:06.579Z,1554857166.579 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:46:06.579Z,1554857166.579 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:46:06.981Z,1554857166.981 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:06.981Z,1554857166.981 [BR_Ping1D](INFO): readConfig
2019-04-10T00:46:06.981Z,1554857166.981 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:46:06.982Z,1554857166.982 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:46:06.982Z,1554857166.982 [BR_Ping1D](INFO): elapsed 4.6311
2019-04-10T00:46:06.982Z,1554857166.982 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:46:06.982Z,1554857166.982 [BR_Ping1D](INFO): No UART data
2019-04-10T00:46:06.982Z,1554857166.982 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:46:06.983Z,1554857166.983 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:46:07.386Z,1554857167.386 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:07.386Z,1554857167.386 [BR_Ping1D](INFO): readConfig
2019-04-10T00:46:07.386Z,1554857167.386 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:46:07.386Z,1554857167.386 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:46:07.387Z,1554857167.387 [BR_Ping1D](INFO): elapsed 5.0357
2019-04-10T00:46:07.387Z,1554857167.387 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:46:07.387Z,1554857167.387 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T00:46:07.387Z,1554857167.387 [BR_Ping1D] Data Fault, FailCount= 4
2019-04-10T00:46:07.387Z,1554857167.387 [BR_Ping1D](ERROR): Data Fault
2019-04-10T00:46:07.447Z,1554857167.447 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-10T00:46:07.447Z,1554857167.447 [CTD_Seabird] No Fault, FailCount= 2
2019-04-10T00:46:07.448Z,1554857167.448 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T00:46:07.534Z,1554857167.534 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T00:46:07.542Z,1554857167.542 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T00:46:07.789Z,1554857167.789 [BR_Ping1D](INFO): Stop
2019-04-10T00:46:07.789Z,1554857167.789 [BR_Ping1D](INFO): uninitialize
2019-04-10T00:46:07.789Z,1554857167.789 [BR_Ping1D](INFO): Powering down
2019-04-10T00:46:08.194Z,1554857168.194 [BR_Ping1D](INFO): Stopping
2019-04-10T00:46:08.598Z,1554857168.598 [BR_Ping1D](INFO): Stopping
2019-04-10T00:46:09.002Z,1554857169.002 [BR_Ping1D](INFO): Stopped
2019-04-10T00:46:09.063Z,1554857169.063 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T00:46:09.063Z,1554857169.063 [BR_Ping1D] No Fault, FailCount= 4
2019-04-10T00:46:09.409Z,1554857169.409 [BR_Ping1D](INFO): Start
2019-04-10T00:46:09.810Z,1554857169.810 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:10.214Z,1554857170.214 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:10.214Z,1554857170.214 [BR_Ping1D](INFO): readConfig
2019-04-10T00:46:10.215Z,1554857170.215 [BR_Ping1D](INFO): startup: 0
2019-04-10T00:46:10.215Z,1554857170.215 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T00:46:10.226Z,1554857170.226 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-10T00:46:10.226Z,1554857170.226 [BR_Ping1D](INFO): data 011E
2019-04-10T00:46:10.226Z,1554857170.226 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T00:46:10.226Z,1554857170.226 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-10T00:46:10.227Z,1554857170.227 [BR_Ping1D](INFO): 42520100EB030000840100
2019-04-10T00:46:10.227Z,1554857170.227 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-10T00:46:10.227Z,1554857170.227 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T00:46:10.227Z,1554857170.227 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-10T00:46:10.227Z,1554857170.227 [BR_Ping1D](INFO): 42520100EE030000870100
2019-04-10T00:46:10.618Z,1554857170.618 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:11.003Z,1554857171.003 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T00:46:11.003Z,1554857171.003 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-10T00:46:11.003Z,1554857171.003 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T00:46:11.034Z,1554857171.034 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:11.034Z,1554857171.034 [BR_Ping1D](INFO): readConfig
2019-04-10T00:46:11.034Z,1554857171.034 [BR_Ping1D](INFO): startup: 1
2019-04-10T00:46:11.034Z,1554857171.034 [BR_Ping1D](INFO): UART requesting status
2019-04-10T00:46:11.034Z,1554857171.034 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T00:46:11.042Z,1554857171.042 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T00:46:11.050Z,1554857171.050 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T00:46:11.131Z,1554857171.131 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T00:46:11.131Z,1554857171.131 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-10T00:46:11.526Z,1554857171.526 [Aanderaa_O2](INFO): Powering down
2019-04-10T00:46:11.546Z,1554857171.546 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:11.546Z,1554857171.546 [BR_Ping1D](INFO): readConfig
2019-04-10T00:46:11.547Z,1554857171.547 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:46:11.547Z,1554857171.547 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:46:11.547Z,1554857171.547 [BR_Ping1D](INFO): elapsed 1.3320
2019-04-10T00:46:11.547Z,1554857171.547 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:46:11.547Z,1554857171.547 [BR_Ping1D](INFO): No UART data
2019-04-10T00:46:11.547Z,1554857171.547 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:46:11.547Z,1554857171.547 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:46:11.832Z,1554857171.832 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:11.832Z,1554857171.832 [BR_Ping1D](INFO): readConfig
2019-04-10T00:46:11.832Z,1554857171.832 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:46:11.832Z,1554857171.832 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:46:11.832Z,1554857171.832 [BR_Ping1D](INFO): elapsed 1.6176
2019-04-10T00:46:11.833Z,1554857171.833 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:46:11.833Z,1554857171.833 [BR_Ping1D](INFO): No UART data
2019-04-10T00:46:11.833Z,1554857171.833 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:46:11.833Z,1554857171.833 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:46:12.234Z,1554857172.234 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:12.234Z,1554857172.234 [BR_Ping1D](INFO): readConfig
2019-04-10T00:46:12.235Z,1554857172.235 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:46:12.235Z,1554857172.235 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:46:12.235Z,1554857172.235 [BR_Ping1D](INFO): elapsed 2.0200
2019-04-10T00:46:12.235Z,1554857172.235 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:46:12.235Z,1554857172.235 [BR_Ping1D](INFO): No UART data
2019-04-10T00:46:12.235Z,1554857172.235 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:46:12.236Z,1554857172.236 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:46:12.323Z,1554857172.323 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.007609
CHAN A1 (24V): -0.127655
CHAN A2 (12V): -0.005481
CHAN A3 (5V): -0.003119
CHAN B0 (3.3V): -0.001593
CHAN B1 (3.15aV): -0.000916
CHAN B2 (3.15bV): -0.000985
CHAN B3 (GND): 0.000062
OPEN: -0.000910
Full Scale Calc: 4.765 mA, -1.589 mA
2019-04-10T00:46:12.637Z,1554857172.637 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:12.637Z,1554857172.637 [BR_Ping1D](INFO): readConfig
2019-04-10T00:46:12.637Z,1554857172.637 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:46:12.638Z,1554857172.638 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:46:12.638Z,1554857172.638 [BR_Ping1D](INFO): elapsed 2.4230
2019-04-10T00:46:12.638Z,1554857172.638 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:46:12.638Z,1554857172.638 [BR_Ping1D](INFO): No UART data
2019-04-10T00:46:12.638Z,1554857172.638 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:46:12.638Z,1554857172.638 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:46:13.041Z,1554857173.041 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:13.042Z,1554857173.042 [BR_Ping1D](INFO): readConfig
2019-04-10T00:46:13.042Z,1554857173.042 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:46:13.042Z,1554857173.042 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:46:13.042Z,1554857173.042 [BR_Ping1D](INFO): elapsed 2.8274
2019-04-10T00:46:13.042Z,1554857173.042 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:46:13.043Z,1554857173.043 [BR_Ping1D](INFO): No UART data
2019-04-10T00:46:13.043Z,1554857173.043 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:46:13.043Z,1554857173.043 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:46:13.446Z,1554857173.446 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:13.446Z,1554857173.446 [BR_Ping1D](INFO): readConfig
2019-04-10T00:46:13.446Z,1554857173.446 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:46:13.446Z,1554857173.446 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:46:13.446Z,1554857173.446 [BR_Ping1D](INFO): elapsed 3.2316
2019-04-10T00:46:13.447Z,1554857173.447 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:46:13.447Z,1554857173.447 [BR_Ping1D](INFO): No UART data
2019-04-10T00:46:13.447Z,1554857173.447 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:46:13.447Z,1554857173.447 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:46:13.850Z,1554857173.850 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:13.850Z,1554857173.850 [BR_Ping1D](INFO): readConfig
2019-04-10T00:46:13.850Z,1554857173.850 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:46:13.850Z,1554857173.850 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:46:13.850Z,1554857173.850 [BR_Ping1D](INFO): elapsed 3.6355
2019-04-10T00:46:13.851Z,1554857173.851 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:46:13.851Z,1554857173.851 [BR_Ping1D](INFO): No UART data
2019-04-10T00:46:13.851Z,1554857173.851 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:46:13.851Z,1554857173.851 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:46:14.253Z,1554857174.253 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:14.253Z,1554857174.253 [BR_Ping1D](INFO): readConfig
2019-04-10T00:46:14.253Z,1554857174.253 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:46:14.253Z,1554857174.253 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:46:14.254Z,1554857174.254 [BR_Ping1D](INFO): elapsed 4.0389
2019-04-10T00:46:14.254Z,1554857174.254 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:46:14.254Z,1554857174.254 [BR_Ping1D](INFO): No UART data
2019-04-10T00:46:14.254Z,1554857174.254 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:46:14.254Z,1554857174.254 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:46:14.658Z,1554857174.658 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:14.658Z,1554857174.658 [BR_Ping1D](INFO): readConfig
2019-04-10T00:46:14.659Z,1554857174.659 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:46:14.659Z,1554857174.659 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:46:14.659Z,1554857174.659 [BR_Ping1D](INFO): elapsed 4.4440
2019-04-10T00:46:14.659Z,1554857174.659 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:46:14.659Z,1554857174.659 [BR_Ping1D](INFO): No UART data
2019-04-10T00:46:14.659Z,1554857174.659 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:46:14.659Z,1554857174.659 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:46:15.064Z,1554857175.064 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:15.065Z,1554857175.065 [BR_Ping1D](INFO): readConfig
2019-04-10T00:46:15.065Z,1554857175.065 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:46:15.065Z,1554857175.065 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:46:15.065Z,1554857175.065 [BR_Ping1D](INFO): elapsed 4.8503
2019-04-10T00:46:15.065Z,1554857175.065 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:46:15.066Z,1554857175.066 [BR_Ping1D](INFO): No UART data
2019-04-10T00:46:15.066Z,1554857175.066 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:46:15.066Z,1554857175.066 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:46:15.480Z,1554857175.480 [BR_Ping1D](INFO): Starting
2019-04-10T00:46:15.480Z,1554857175.480 [BR_Ping1D](INFO): readConfig
2019-04-10T00:46:15.480Z,1554857175.480 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:46:15.481Z,1554857175.481 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:46:15.481Z,1554857175.481 [BR_Ping1D](INFO): elapsed 5.2660
2019-04-10T00:46:15.481Z,1554857175.481 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:46:15.481Z,1554857175.481 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T00:46:15.481Z,1554857175.481 [BR_Ping1D] Data Fault, FailCount= 5
2019-04-10T00:46:15.481Z,1554857175.481 [BR_Ping1D](ERROR): Data Fault
2019-04-10T00:46:15.501Z,1554857175.501 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T00:46:15.502Z,1554857175.502 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-04-10T00:46:15.855Z,1554857175.855 [NAL9602](INFO): NAL9602 initialized
2019-04-10T00:46:15.870Z,1554857175.870 [BR_Ping1D](INFO): Stop
2019-04-10T00:46:15.870Z,1554857175.870 [BR_Ping1D](INFO): uninitialize
2019-04-10T00:46:15.870Z,1554857175.870 [BR_Ping1D](INFO): Powering down
2019-04-10T00:46:15.984Z,1554857175.984 [CBIT](INFO): Critical error at 20190410T004615
2019-04-10T00:46:16.283Z,1554857176.283 [BR_Ping1D](INFO): Stopping
2019-04-10T00:46:16.674Z,1554857176.674 [NAL9602](DEBUG): Fix Requested
2019-04-10T00:46:16.689Z,1554857176.689 [BR_Ping1D](INFO): Stopping
2019-04-10T00:46:17.097Z,1554857177.097 [BR_Ping1D](INFO): Stopped
2019-04-10T00:46:20.586Z,1554857180.586 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T00:46:20.586Z,1554857180.586 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-10T00:46:20.586Z,1554857180.586 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T00:46:20.943Z,1554857180.943 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T00:46:20.943Z,1554857180.943 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-10T00:46:20.990Z,1554857180.990 [CTD_Seabird](INFO): Powering down
2019-04-10T00:46:21.622Z,1554857181.622 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T00:46:21.622Z,1554857181.622 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2019-04-10T00:46:21.622Z,1554857181.622 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T00:46:21.751Z,1554857181.751 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T00:46:22.028Z,1554857182.028 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T00:46:22.967Z,1554857182.967 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T00:46:22.967Z,1554857182.967 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2019-04-10T00:46:25.306Z,1554857185.306 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T00:46:25.306Z,1554857185.306 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T00:46:45.594Z,1554857205.594 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T00:46:45.595Z,1554857205.595 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2019-04-10T00:46:45.595Z,1554857205.595 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T00:46:45.751Z,1554857205.751 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T00:46:45.999Z,1554857205.999 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T00:46:47.123Z,1554857207.123 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T00:46:47.123Z,1554857207.123 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2019-04-10T00:46:49.230Z,1554857209.230 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T00:46:49.231Z,1554857209.231 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T00:46:51.338Z,1554857211.338 [BPC1](ERROR): BPC1B: No match for serial number 018D in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2019-04-10T00:46:51.674Z,1554857211.674 [BPC1](ERROR): BPC1A: No match for serial number 04CC in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2019-04-10T00:46:51.686Z,1554857211.686 [BPC1](FAULT): Failed to parse data from all battery packs.
2019-04-10T00:46:51.686Z,1554857211.686 [BPC1] Data Fault, FailCount= 1
2019-04-10T00:46:51.686Z,1554857211.686 [BPC1](ERROR): Data Fault
2019-04-10T00:46:51.701Z,1554857211.701 [CBIT](ERROR): Data Fault in component: BPC1
2019-04-10T00:46:55.334Z,1554857215.334 [SBIT](IMPORTANT): SBIT PASSED
2019-04-10T00:46:55.351Z,1554857215.351 [CommandLine](IMPORTANT): got command configSet list
2019-04-10T00:46:55.351Z,1554857215.351 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-04-10T00:46:55.352Z,1554857215.352 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-04-10T00:46:55.352Z,1554857215.352 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2019-04-10T00:46:55.733Z,1554857215.733 [MissionManager](IMPORTANT): Started mission Startup
2019-04-10T00:46:55.733Z,1554857215.733 [Startup] Running Loop=1
2019-04-10T00:46:55.734Z,1554857215.734 [Startup](DEBUG): Aggregate::initialize Startup
2019-04-10T00:46:55.734Z,1554857215.734 [Startup:A.GoToSurface] Running Loop=1
2019-04-10T00:46:55.734Z,1554857215.734 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-10T00:46:55.734Z,1554857215.734 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-10T00:46:55.735Z,1554857215.735 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-10T00:46:55.735Z,1554857215.735 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-10T00:46:55.736Z,1554857215.736 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-10T00:46:55.736Z,1554857215.736 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-10T00:46:55.737Z,1554857215.737 [Startup:StartupSatComms] Running Loop=1
2019-04-10T00:46:55.737Z,1554857215.737 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-04-10T00:46:55.738Z,1554857215.738 [Startup:StartupSatComms:A] Running Loop=1
2019-04-10T00:46:56.139Z,1554857216.139 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-04-10T00:47:09.430Z,1554857229.430 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T00:47:09.430Z,1554857229.430 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2019-04-10T00:47:09.430Z,1554857229.430 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T00:47:09.473Z,1554857229.473 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T00:47:09.834Z,1554857229.834 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T00:47:10.693Z,1554857230.693 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T00:47:10.693Z,1554857230.693 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2019-04-10T00:47:13.066Z,1554857233.066 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T00:47:13.067Z,1554857233.067 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T00:47:33.266Z,1554857253.266 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T00:47:33.267Z,1554857253.267 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2019-04-10T00:47:33.267Z,1554857253.267 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T00:47:33.299Z,1554857253.299 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T00:47:33.670Z,1554857253.670 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T00:47:34.513Z,1554857254.513 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T00:47:34.513Z,1554857254.513 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2019-04-10T00:47:36.903Z,1554857256.903 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T00:47:36.903Z,1554857256.903 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T00:47:37.344Z,1554857257.344 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T00:47:37.344Z,1554857257.344 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-04-10T00:47:37.344Z,1554857257.344 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-10T00:47:37.345Z,1554857257.345 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T00:47:37.346Z,1554857257.346 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-04-10T00:47:37.346Z,1554857257.346 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-10T00:47:37.347Z,1554857257.347 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T00:47:37.347Z,1554857257.347 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-04-10T00:47:37.347Z,1554857257.347 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-10T00:47:37.358Z,1554857257.358 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-10T00:47:37.358Z,1554857257.358 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-10T00:47:37.358Z,1554857257.358 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-10T00:47:37.767Z,1554857257.767 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-10T00:47:37.767Z,1554857257.767 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-04-10T00:47:37.767Z,1554857257.767 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-10T00:47:37.768Z,1554857257.768 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-04-10T00:47:37.768Z,1554857257.768 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-10T00:47:37.768Z,1554857257.768 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-04-10T00:47:38.161Z,1554857258.161 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-10T00:47:55.913Z,1554857275.913 [Startup:StartupSatComms:A](INFO): Timed out from 2019-04-10T00:46:55.7Z
2019-04-10T00:47:55.913Z,1554857275.913 [Startup:StartupSatComms:A] Stopped
2019-04-10T00:47:55.913Z,1554857275.913 [Startup:StartupSatComms:B] Running Loop=1
2019-04-10T00:47:56.323Z,1554857276.323 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-10T00:47:57.102Z,1554857277.102 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T00:47:57.102Z,1554857277.102 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2019-04-10T00:47:57.103Z,1554857277.103 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T00:47:57.136Z,1554857277.136 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T00:47:57.506Z,1554857277.506 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T00:47:58.362Z,1554857278.362 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T00:47:58.362Z,1554857278.362 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2019-04-10T00:48:00.739Z,1554857280.739 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T00:48:00.739Z,1554857280.739 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T00:48:08.751Z,1554857288.751 [DataOverHttps](INFO): Sending 313 bytes from file Logs/20190410T003737/Courier0000.lzma
2019-04-10T00:48:09.555Z,1554857289.555 [DataOverHttps](INFO): Moved sent file to Logs/20190410T003737/Courier0000.lzma.bak
2019-04-10T00:48:09.556Z,1554857289.556 [DataOverHttps](INFO): SBD MOMSN=10522174
2019-04-10T00:48:20.938Z,1554857300.938 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T00:48:20.939Z,1554857300.939 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7
2019-04-10T00:48:20.939Z,1554857300.939 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T00:48:21.038Z,1554857301.038 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T00:48:21.343Z,1554857301.343 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T00:48:22.188Z,1554857302.188 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T00:48:22.188Z,1554857302.188 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7
2019-04-10T00:48:24.574Z,1554857304.574 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T00:48:24.575Z,1554857304.575 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T00:48:24.861Z,1554857304.861 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190410T003737/Courier0004.lzma
2019-04-10T00:48:25.667Z,1554857305.667 [DataOverHttps](INFO): Moved sent file to Logs/20190410T003737/Courier0004.lzma.bak
2019-04-10T00:48:25.668Z,1554857305.668 [DataOverHttps](INFO): SBD MOMSN=10522187
2019-04-10T00:48:41.169Z,1554857321.169 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T00:48:41.170Z,1554857321.170 [Aanderaa_O2] No Fault, FailCount= 3
2019-04-10T00:48:44.775Z,1554857324.775 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T00:48:44.775Z,1554857324.775 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 8
2019-04-10T00:48:44.775Z,1554857324.775 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T00:48:44.820Z,1554857324.820 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T00:48:44.821Z,1554857324.821 [CBIT](FAULT): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T00:48:45.178Z,1554857325.178 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T00:48:47.934Z,1554857327.934 [DataOverHttps](INFO): Sending 364 bytes from file Logs/20190410T004526/Courier0000.lzma
2019-04-10T00:48:48.739Z,1554857328.739 [DataOverHttps](INFO): Moved sent file to Logs/20190410T004526/Courier0000.lzma.bak
2019-04-10T00:48:48.740Z,1554857328.740 [DataOverHttps](INFO): SBD MOMSN=10522189
2019-04-10T00:48:50.896Z,1554857330.896 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-10T00:48:50.896Z,1554857330.896 [CTD_Seabird] No Fault, FailCount= 3
2019-04-10T00:48:51.006Z,1554857331.006 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T00:48:51.008Z,1554857331.008 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T00:48:51.642Z,1554857331.642 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T00:48:51.642Z,1554857331.642 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-10T00:48:51.642Z,1554857331.642 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T00:48:51.680Z,1554857331.680 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T00:48:52.166Z,1554857332.166 [Aanderaa_O2](INFO): Powering down
2019-04-10T00:48:52.891Z,1554857332.891 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T00:48:52.891Z,1554857332.891 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-10T00:48:56.131Z,1554857336.131 [Startup:StartupSatComms:B](INFO): Timed out from 2019-04-10T00:47:55.9Z
2019-04-10T00:48:56.131Z,1554857336.131 [Startup:StartupSatComms:B] Stopped
2019-04-10T00:48:56.132Z,1554857336.132 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-04-10T00:48:56.132Z,1554857336.132 [Startup:StartupSatComms] Stopped
2019-04-10T00:48:56.132Z,1554857336.132 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-04-10T00:48:56.132Z,1554857336.132 [Startup](INFO): Completed Startup
2019-04-10T00:48:56.133Z,1554857336.133 [MissionManager](INFO): Startup is completed.
2019-04-10T00:48:56.133Z,1554857336.133 [MissionManager](INFO): Uninitializing Mission Startup
2019-04-10T00:48:56.133Z,1554857336.133 [Startup] Stopped
2019-04-10T00:48:56.133Z,1554857336.133 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-04-10T00:48:56.133Z,1554857336.133 [Startup:A.GoToSurface] Stopped
2019-04-10T00:48:56.133Z,1554857336.133 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-10T00:48:56.539Z,1554857336.539 [MissionManager](IMPORTANT): Started mission Default
2019-04-10T00:48:56.539Z,1554857336.539 [Default] Running Loop=1
2019-04-10T00:48:56.539Z,1554857336.539 [Default](DEBUG): Aggregate::initialize Default
2019-04-10T00:48:56.539Z,1554857336.539 [Default:B.GoToSurface] Running Loop=1
2019-04-10T00:48:56.539Z,1554857336.539 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-10T00:48:56.539Z,1554857336.539 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-10T00:48:56.540Z,1554857336.540 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-10T00:48:56.540Z,1554857336.540 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-10T00:48:56.540Z,1554857336.540 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-10T00:48:56.541Z,1554857336.541 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-10T00:48:56.541Z,1554857336.541 [Default:A.Wait] Running Loop=1
2019-04-10T00:48:56.541Z,1554857336.541 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-04-10T00:49:03.358Z,1554857343.358 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T00:49:03.358Z,1554857343.358 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-10T00:49:03.358Z,1554857343.358 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T00:49:03.416Z,1554857343.416 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T00:49:03.882Z,1554857343.882 [Aanderaa_O2](INFO): Powering down
2019-04-10T00:49:04.058Z,1554857344.058 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T00:49:04.058Z,1554857344.058 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-10T00:49:04.058Z,1554857344.058 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T00:49:04.197Z,1554857344.197 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T00:49:04.462Z,1554857344.462 [CTD_Seabird](INFO): Powering down
2019-04-10T00:49:04.608Z,1554857344.608 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T00:49:04.609Z,1554857344.609 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-10T00:49:05.421Z,1554857345.421 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-10T00:49:05.422Z,1554857345.422 [CTD_Seabird] No Fault, FailCount= 1
2019-04-10T00:49:05.810Z,1554857345.810 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T00:49:05.812Z,1554857345.812 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T00:49:09.847Z,1554857349.847 [Default:A.Wait](INFO): Done Waiting.
2019-04-10T00:49:09.848Z,1554857349.848 [Default:A.Wait] Stopped
2019-04-10T00:49:09.848Z,1554857349.848 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-04-10T00:49:10.252Z,1554857350.252 [Default:CheckIn] Running Loop=1
2019-04-10T00:49:10.252Z,1554857350.252 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-10T00:49:10.252Z,1554857350.252 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-10T00:49:10.662Z,1554857350.662 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-04-10T00:49:15.074Z,1554857355.074 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T00:49:15.074Z,1554857355.074 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-10T00:49:15.074Z,1554857355.074 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T00:49:15.120Z,1554857355.120 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T00:49:15.121Z,1554857355.121 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-10T00:49:15.598Z,1554857355.598 [Aanderaa_O2](INFO): Powering down
2019-04-10T00:49:17.562Z,1554857357.562 [DataOverHttps](INFO): Sending 263 bytes from file Logs/20190409T230637/Express0029.lzma
2019-04-10T00:49:18.368Z,1554857358.368 [DataOverHttps](INFO): Moved sent file to Logs/20190409T230637/Express0029.lzma.bak
2019-04-10T00:49:18.368Z,1554857358.368 [DataOverHttps](INFO): SBD MOMSN=10522204
2019-04-10T00:49:18.862Z,1554857358.862 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T00:49:18.862Z,1554857358.862 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-10T00:49:18.862Z,1554857358.862 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T00:49:19.148Z,1554857359.148 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T00:49:19.266Z,1554857359.266 [CTD_Seabird](INFO): Powering down
2019-04-10T00:49:20.364Z,1554857360.364 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-10T00:49:20.364Z,1554857360.364 [CTD_Seabird] No Fault, FailCount= 2
2019-04-10T00:49:20.606Z,1554857360.606 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T00:49:20.608Z,1554857360.608 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T00:49:33.658Z,1554857373.658 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T00:49:33.658Z,1554857373.658 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-10T00:49:33.658Z,1554857373.658 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T00:49:33.701Z,1554857373.701 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T00:49:33.701Z,1554857373.701 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-10T00:49:34.062Z,1554857374.062 [CTD_Seabird](INFO): Powering down
2019-04-10T00:49:38.541Z,1554857378.541 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T00:49:38.541Z,1554857378.541 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-04-10T00:49:38.541Z,1554857378.541 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-10T00:49:38.543Z,1554857378.543 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T00:49:38.543Z,1554857378.543 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-04-10T00:49:38.543Z,1554857378.543 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-10T00:49:38.544Z,1554857378.544 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T00:49:38.544Z,1554857378.544 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-04-10T00:49:38.544Z,1554857378.544 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-10T00:49:38.596Z,1554857378.596 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-10T00:49:38.596Z,1554857378.596 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-10T00:49:38.596Z,1554857378.596 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-10T00:49:38.947Z,1554857378.947 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-10T00:49:38.947Z,1554857378.947 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-04-10T00:49:38.948Z,1554857378.948 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-10T00:49:38.948Z,1554857378.948 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-04-10T00:49:38.948Z,1554857378.948 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-10T00:49:38.948Z,1554857378.948 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-04-10T00:49:39.341Z,1554857379.341 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-10T00:51:15.529Z,1554857475.529 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T00:51:15.529Z,1554857475.529 [BR_Ping1D] No Fault, FailCount= 5
2019-04-10T00:51:15.890Z,1554857475.890 [BR_Ping1D](INFO): Start
2019-04-10T00:51:16.294Z,1554857476.294 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:16.694Z,1554857476.694 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:16.694Z,1554857476.694 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:16.695Z,1554857476.695 [BR_Ping1D](INFO): startup: 0
2019-04-10T00:51:16.695Z,1554857476.695 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T00:51:16.706Z,1554857476.706 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-10T00:51:16.706Z,1554857476.706 [BR_Ping1D](INFO): data 011E
2019-04-10T00:51:16.707Z,1554857476.707 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T00:51:16.707Z,1554857476.707 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-10T00:51:16.707Z,1554857476.707 [BR_Ping1D](INFO): 42520100EB030000840100
2019-04-10T00:51:16.707Z,1554857476.707 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-10T00:51:16.707Z,1554857476.707 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T00:51:16.708Z,1554857476.708 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-10T00:51:16.708Z,1554857476.708 [BR_Ping1D](INFO): 42520100EE030000870100
2019-04-10T00:51:17.113Z,1554857477.113 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:17.524Z,1554857477.524 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:17.525Z,1554857477.525 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:17.525Z,1554857477.525 [BR_Ping1D](INFO): startup: 1
2019-04-10T00:51:17.525Z,1554857477.525 [BR_Ping1D](INFO): UART requesting status
2019-04-10T00:51:17.525Z,1554857477.525 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T00:51:17.534Z,1554857477.534 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T00:51:17.542Z,1554857477.542 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T00:51:17.923Z,1554857477.923 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:17.923Z,1554857477.923 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:17.924Z,1554857477.924 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:17.924Z,1554857477.924 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:17.924Z,1554857477.924 [BR_Ping1D](INFO): elapsed 1.2291
2019-04-10T00:51:17.924Z,1554857477.924 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:17.924Z,1554857477.924 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:17.925Z,1554857477.925 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:17.925Z,1554857477.925 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:18.327Z,1554857478.327 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:18.327Z,1554857478.327 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:18.327Z,1554857478.327 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:18.327Z,1554857478.327 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:18.327Z,1554857478.327 [BR_Ping1D](INFO): elapsed 1.6324
2019-04-10T00:51:18.328Z,1554857478.328 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:18.328Z,1554857478.328 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:18.328Z,1554857478.328 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:18.328Z,1554857478.328 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:18.718Z,1554857478.718 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:18.719Z,1554857478.719 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:18.719Z,1554857478.719 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:18.719Z,1554857478.719 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:18.719Z,1554857478.719 [BR_Ping1D](INFO): elapsed 2.0242
2019-04-10T00:51:18.719Z,1554857478.719 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:18.720Z,1554857478.720 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:18.720Z,1554857478.720 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:18.720Z,1554857478.720 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:19.122Z,1554857479.122 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:19.122Z,1554857479.122 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:19.122Z,1554857479.122 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:19.122Z,1554857479.122 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:19.122Z,1554857479.122 [BR_Ping1D](INFO): elapsed 2.4273
2019-04-10T00:51:19.123Z,1554857479.123 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:19.123Z,1554857479.123 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:19.123Z,1554857479.123 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:19.123Z,1554857479.123 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:19.511Z,1554857479.511 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-04-10T00:51:19.526Z,1554857479.526 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:19.526Z,1554857479.526 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:19.526Z,1554857479.526 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:19.527Z,1554857479.527 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:19.527Z,1554857479.527 [BR_Ping1D](INFO): elapsed 2.8317
2019-04-10T00:51:19.527Z,1554857479.527 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:19.527Z,1554857479.527 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:19.527Z,1554857479.527 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:19.527Z,1554857479.527 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:19.929Z,1554857479.929 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:19.929Z,1554857479.929 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:19.930Z,1554857479.930 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:19.930Z,1554857479.930 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:19.930Z,1554857479.930 [BR_Ping1D](INFO): elapsed 3.2350
2019-04-10T00:51:19.930Z,1554857479.930 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:19.930Z,1554857479.930 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:19.931Z,1554857479.931 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:19.931Z,1554857479.931 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:20.330Z,1554857480.330 [NAL9602](DEBUG): Fix Requested
2019-04-10T00:51:20.344Z,1554857480.344 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:20.345Z,1554857480.345 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:20.345Z,1554857480.345 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:20.345Z,1554857480.345 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:20.345Z,1554857480.345 [BR_Ping1D](INFO): elapsed 3.6502
2019-04-10T00:51:20.345Z,1554857480.345 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:20.346Z,1554857480.346 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:20.346Z,1554857480.346 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:20.346Z,1554857480.346 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:20.740Z,1554857480.740 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:20.740Z,1554857480.740 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:20.740Z,1554857480.740 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:20.740Z,1554857480.740 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:20.740Z,1554857480.740 [BR_Ping1D](INFO): elapsed 4.0454
2019-04-10T00:51:20.741Z,1554857480.741 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:20.741Z,1554857480.741 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:20.741Z,1554857480.741 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:20.741Z,1554857480.741 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:21.145Z,1554857481.145 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:21.145Z,1554857481.145 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:21.145Z,1554857481.145 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:21.146Z,1554857481.146 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:21.146Z,1554857481.146 [BR_Ping1D](INFO): elapsed 4.4508
2019-04-10T00:51:21.146Z,1554857481.146 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:21.146Z,1554857481.146 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:21.146Z,1554857481.146 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:21.147Z,1554857481.147 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:21.545Z,1554857481.545 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:21.546Z,1554857481.546 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:21.546Z,1554857481.546 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:21.546Z,1554857481.546 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:21.546Z,1554857481.546 [BR_Ping1D](INFO): elapsed 4.8512
2019-04-10T00:51:21.546Z,1554857481.546 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:21.547Z,1554857481.547 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:21.547Z,1554857481.547 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:21.547Z,1554857481.547 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:21.950Z,1554857481.950 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:21.950Z,1554857481.950 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:21.951Z,1554857481.951 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:21.951Z,1554857481.951 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:21.951Z,1554857481.951 [BR_Ping1D](INFO): elapsed 5.2558
2019-04-10T00:51:21.951Z,1554857481.951 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:21.951Z,1554857481.951 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T00:51:21.951Z,1554857481.951 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-10T00:51:21.951Z,1554857481.951 [BR_Ping1D](ERROR): Data Fault
2019-04-10T00:51:21.967Z,1554857481.967 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T00:51:22.364Z,1554857482.364 [BR_Ping1D](INFO): Stop
2019-04-10T00:51:22.364Z,1554857482.364 [BR_Ping1D](INFO): uninitialize
2019-04-10T00:51:22.364Z,1554857482.364 [BR_Ping1D](INFO): Powering down
2019-04-10T00:51:22.769Z,1554857482.769 [BR_Ping1D](INFO): Stopping
2019-04-10T00:51:23.159Z,1554857483.159 [BR_Ping1D](INFO): Stopping
2019-04-10T00:51:23.566Z,1554857483.566 [BR_Ping1D](INFO): Stopped
2019-04-10T00:51:23.588Z,1554857483.588 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T00:51:23.588Z,1554857483.588 [BR_Ping1D] No Fault, FailCount= 1
2019-04-10T00:51:23.973Z,1554857483.973 [BR_Ping1D](INFO): Start
2019-04-10T00:51:24.370Z,1554857484.370 [NAL9602](DEBUG): Fix Requested
2019-04-10T00:51:24.385Z,1554857484.385 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:24.794Z,1554857484.794 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:24.794Z,1554857484.794 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:24.795Z,1554857484.795 [BR_Ping1D](INFO): startup: 0
2019-04-10T00:51:24.795Z,1554857484.795 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T00:51:24.806Z,1554857484.806 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-10T00:51:24.806Z,1554857484.806 [BR_Ping1D](INFO): data 011E
2019-04-10T00:51:24.806Z,1554857484.806 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T00:51:24.807Z,1554857484.807 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-10T00:51:24.807Z,1554857484.807 [BR_Ping1D](INFO): 42520100EB030000840100
2019-04-10T00:51:24.808Z,1554857484.808 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-10T00:51:24.809Z,1554857484.809 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T00:51:24.810Z,1554857484.810 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-10T00:51:24.810Z,1554857484.810 [BR_Ping1D](INFO): 42520100EE030000870100
2019-04-10T00:51:25.200Z,1554857485.200 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:25.590Z,1554857485.590 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:25.590Z,1554857485.590 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:25.590Z,1554857485.590 [BR_Ping1D](INFO): startup: 1
2019-04-10T00:51:25.590Z,1554857485.590 [BR_Ping1D](INFO): UART requesting status
2019-04-10T00:51:25.590Z,1554857485.590 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T00:51:25.598Z,1554857485.598 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T00:51:25.606Z,1554857485.606 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T00:51:25.990Z,1554857485.990 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:25.990Z,1554857485.990 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:25.990Z,1554857485.990 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:25.990Z,1554857485.990 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:25.991Z,1554857485.991 [BR_Ping1D](INFO): elapsed 1.1956
2019-04-10T00:51:25.991Z,1554857485.991 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:25.991Z,1554857485.991 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:25.991Z,1554857485.991 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:25.991Z,1554857485.991 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:26.394Z,1554857486.394 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:26.395Z,1554857486.395 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:26.395Z,1554857486.395 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:26.395Z,1554857486.395 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:26.395Z,1554857486.395 [BR_Ping1D](INFO): elapsed 1.6002
2019-04-10T00:51:26.395Z,1554857486.395 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:26.396Z,1554857486.396 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:26.396Z,1554857486.396 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:26.396Z,1554857486.396 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:26.799Z,1554857486.799 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:26.799Z,1554857486.799 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:26.799Z,1554857486.799 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:26.799Z,1554857486.799 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:26.800Z,1554857486.800 [BR_Ping1D](INFO): elapsed 2.0046
2019-04-10T00:51:26.800Z,1554857486.800 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:26.800Z,1554857486.800 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:26.800Z,1554857486.800 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:26.800Z,1554857486.800 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:27.198Z,1554857487.198 [NAL9602](DEBUG): Fix Requested
2019-04-10T00:51:27.212Z,1554857487.212 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:27.212Z,1554857487.212 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:27.213Z,1554857487.213 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:27.213Z,1554857487.213 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:27.213Z,1554857487.213 [BR_Ping1D](INFO): elapsed 2.4178
2019-04-10T00:51:27.213Z,1554857487.213 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:27.213Z,1554857487.213 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:27.213Z,1554857487.213 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:27.213Z,1554857487.213 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:27.620Z,1554857487.620 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:27.620Z,1554857487.620 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:27.621Z,1554857487.621 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:27.621Z,1554857487.621 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:27.621Z,1554857487.621 [BR_Ping1D](INFO): elapsed 2.8260
2019-04-10T00:51:27.621Z,1554857487.621 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:27.621Z,1554857487.621 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:27.622Z,1554857487.622 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:27.622Z,1554857487.622 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:28.022Z,1554857488.022 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:28.022Z,1554857488.022 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:28.023Z,1554857488.023 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:28.023Z,1554857488.023 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:28.023Z,1554857488.023 [BR_Ping1D](INFO): elapsed 3.2279
2019-04-10T00:51:28.023Z,1554857488.023 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:28.023Z,1554857488.023 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:28.023Z,1554857488.023 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:28.024Z,1554857488.024 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:28.414Z,1554857488.414 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:28.414Z,1554857488.414 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:28.414Z,1554857488.414 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:28.414Z,1554857488.414 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:28.414Z,1554857488.414 [BR_Ping1D](INFO): elapsed 3.6194
2019-04-10T00:51:28.415Z,1554857488.415 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:28.415Z,1554857488.415 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:28.415Z,1554857488.415 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:28.415Z,1554857488.415 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:28.817Z,1554857488.817 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:28.818Z,1554857488.818 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:28.818Z,1554857488.818 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:28.818Z,1554857488.818 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:28.818Z,1554857488.818 [BR_Ping1D](INFO): elapsed 4.0232
2019-04-10T00:51:28.818Z,1554857488.818 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:28.819Z,1554857488.819 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:28.819Z,1554857488.819 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:28.819Z,1554857488.819 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:29.222Z,1554857489.222 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:29.222Z,1554857489.222 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:29.223Z,1554857489.223 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:29.223Z,1554857489.223 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:29.223Z,1554857489.223 [BR_Ping1D](INFO): elapsed 4.4278
2019-04-10T00:51:29.223Z,1554857489.223 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:29.223Z,1554857489.223 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:29.223Z,1554857489.223 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:29.223Z,1554857489.224 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:29.626Z,1554857489.626 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:29.626Z,1554857489.626 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:29.626Z,1554857489.626 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:29.626Z,1554857489.626 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:29.627Z,1554857489.627 [BR_Ping1D](INFO): elapsed 4.8316
2019-04-10T00:51:29.627Z,1554857489.627 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:29.627Z,1554857489.627 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:29.627Z,1554857489.627 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:29.627Z,1554857489.627 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:30.030Z,1554857490.030 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:30.030Z,1554857490.030 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:30.030Z,1554857490.030 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:30.030Z,1554857490.030 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:30.031Z,1554857490.031 [BR_Ping1D](INFO): elapsed 5.2355
2019-04-10T00:51:30.031Z,1554857490.031 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:30.031Z,1554857490.031 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T00:51:30.031Z,1554857490.031 [BR_Ping1D] Data Fault, FailCount= 2
2019-04-10T00:51:30.031Z,1554857490.031 [BR_Ping1D](ERROR): Data Fault
2019-04-10T00:51:30.052Z,1554857490.052 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T00:51:30.430Z,1554857490.430 [NAL9602](DEBUG): Fix Requested
2019-04-10T00:51:30.444Z,1554857490.444 [BR_Ping1D](INFO): Stop
2019-04-10T00:51:30.444Z,1554857490.444 [BR_Ping1D](INFO): uninitialize
2019-04-10T00:51:30.445Z,1554857490.445 [BR_Ping1D](INFO): Powering down
2019-04-10T00:51:30.856Z,1554857490.856 [BR_Ping1D](INFO): Stopping
2019-04-10T00:51:31.256Z,1554857491.256 [BR_Ping1D](INFO): Stopping
2019-04-10T00:51:31.654Z,1554857491.654 [BR_Ping1D](INFO): Stopped
2019-04-10T00:51:31.669Z,1554857491.669 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T00:51:31.669Z,1554857491.669 [BR_Ping1D] No Fault, FailCount= 2
2019-04-10T00:51:32.049Z,1554857492.049 [BR_Ping1D](INFO): Start
2019-04-10T00:51:32.458Z,1554857492.458 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:32.861Z,1554857492.861 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:32.862Z,1554857492.862 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:32.862Z,1554857492.862 [BR_Ping1D](INFO): startup: 0
2019-04-10T00:51:32.862Z,1554857492.862 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T00:51:32.874Z,1554857492.874 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-10T00:51:32.874Z,1554857492.874 [BR_Ping1D](INFO): data 011E
2019-04-10T00:51:32.874Z,1554857492.874 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T00:51:32.875Z,1554857492.875 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-10T00:51:32.876Z,1554857492.876 [BR_Ping1D](INFO): 42520100EB030000840100
2019-04-10T00:51:32.876Z,1554857492.876 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-10T00:51:32.877Z,1554857492.877 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T00:51:32.878Z,1554857492.878 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-10T00:51:32.878Z,1554857492.878 [BR_Ping1D](INFO): 42520100EE030000870100
2019-04-10T00:51:33.258Z,1554857493.258 [NAL9602](DEBUG): Fix Requested
2019-04-10T00:51:33.272Z,1554857493.272 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:33.684Z,1554857493.684 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:33.684Z,1554857493.684 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:33.684Z,1554857493.684 [BR_Ping1D](INFO): startup: 1
2019-04-10T00:51:33.684Z,1554857493.684 [BR_Ping1D](INFO): UART requesting status
2019-04-10T00:51:33.685Z,1554857493.685 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T00:51:33.694Z,1554857493.694 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T00:51:33.702Z,1554857493.702 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T00:51:34.081Z,1554857494.081 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:34.081Z,1554857494.081 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:34.081Z,1554857494.081 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:34.081Z,1554857494.081 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:34.081Z,1554857494.081 [BR_Ping1D](INFO): elapsed 1.2189
2019-04-10T00:51:34.081Z,1554857494.081 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:34.082Z,1554857494.082 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:34.082Z,1554857494.082 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:34.082Z,1554857494.082 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:34.479Z,1554857494.479 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:34.479Z,1554857494.479 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:34.479Z,1554857494.479 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:34.479Z,1554857494.479 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:34.479Z,1554857494.479 [BR_Ping1D](INFO): elapsed 1.6169
2019-04-10T00:51:34.479Z,1554857494.479 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:34.480Z,1554857494.480 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:34.480Z,1554857494.480 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:34.480Z,1554857494.480 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:34.882Z,1554857494.882 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:34.882Z,1554857494.882 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:34.883Z,1554857494.883 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:34.883Z,1554857494.883 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:34.883Z,1554857494.883 [BR_Ping1D](INFO): elapsed 2.0207
2019-04-10T00:51:34.883Z,1554857494.883 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:34.883Z,1554857494.883 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:34.884Z,1554857494.884 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:34.884Z,1554857494.884 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:35.282Z,1554857495.282 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:35.283Z,1554857495.283 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:35.283Z,1554857495.283 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:35.283Z,1554857495.283 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:35.283Z,1554857495.283 [BR_Ping1D](INFO): elapsed 2.4208
2019-04-10T00:51:35.283Z,1554857495.283 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:35.284Z,1554857495.284 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:35.284Z,1554857495.284 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:35.284Z,1554857495.284 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:35.686Z,1554857495.686 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:35.686Z,1554857495.686 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:35.686Z,1554857495.686 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:35.686Z,1554857495.686 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:35.686Z,1554857495.686 [BR_Ping1D](INFO): elapsed 2.8240
2019-04-10T00:51:35.687Z,1554857495.687 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:35.687Z,1554857495.687 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:35.687Z,1554857495.687 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:35.687Z,1554857495.687 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:36.086Z,1554857496.086 [NAL9602](DEBUG): Fix Requested
2019-04-10T00:51:36.100Z,1554857496.100 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:36.100Z,1554857496.100 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:36.101Z,1554857496.101 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:36.101Z,1554857496.101 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:36.101Z,1554857496.101 [BR_Ping1D](INFO): elapsed 3.2386
2019-04-10T00:51:36.101Z,1554857496.101 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:36.101Z,1554857496.101 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:36.101Z,1554857496.101 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:36.102Z,1554857496.102 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:36.509Z,1554857496.509 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:36.509Z,1554857496.509 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:36.509Z,1554857496.509 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:36.509Z,1554857496.509 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:36.509Z,1554857496.509 [BR_Ping1D](INFO): elapsed 3.6471
2019-04-10T00:51:36.510Z,1554857496.510 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:36.510Z,1554857496.510 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:36.510Z,1554857496.510 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:36.510Z,1554857496.510 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:36.908Z,1554857496.908 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:36.908Z,1554857496.908 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:36.908Z,1554857496.908 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:36.909Z,1554857496.909 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:36.909Z,1554857496.909 [BR_Ping1D](INFO): elapsed 4.0464
2019-04-10T00:51:36.909Z,1554857496.909 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:36.909Z,1554857496.909 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:36.909Z,1554857496.909 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:36.909Z,1554857496.909 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:37.303Z,1554857497.303 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:37.303Z,1554857497.303 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:37.303Z,1554857497.303 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:37.303Z,1554857497.303 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:37.304Z,1554857497.304 [BR_Ping1D](INFO): elapsed 4.4414
2019-04-10T00:51:37.304Z,1554857497.304 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:37.304Z,1554857497.304 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:37.304Z,1554857497.304 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:37.304Z,1554857497.304 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:37.708Z,1554857497.708 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:37.708Z,1554857497.708 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:37.708Z,1554857497.708 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:37.708Z,1554857497.708 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:37.708Z,1554857497.708 [BR_Ping1D](INFO): elapsed 4.8459
2019-04-10T00:51:37.708Z,1554857497.708 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:37.709Z,1554857497.709 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:37.709Z,1554857497.709 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:37.709Z,1554857497.709 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:38.109Z,1554857498.109 [NAL9602](DEBUG): Fix Requested
2019-04-10T00:51:38.123Z,1554857498.123 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:38.124Z,1554857498.124 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:38.124Z,1554857498.124 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:38.124Z,1554857498.124 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:38.124Z,1554857498.124 [BR_Ping1D](INFO): elapsed 5.2619
2019-04-10T00:51:38.124Z,1554857498.124 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:38.125Z,1554857498.125 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T00:51:38.125Z,1554857498.125 [BR_Ping1D] Data Fault, FailCount= 3
2019-04-10T00:51:38.125Z,1554857498.125 [BR_Ping1D](ERROR): Data Fault
2019-04-10T00:51:38.140Z,1554857498.140 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T00:51:38.531Z,1554857498.531 [BR_Ping1D](INFO): Stop
2019-04-10T00:51:38.532Z,1554857498.532 [BR_Ping1D](INFO): uninitialize
2019-04-10T00:51:38.532Z,1554857498.532 [BR_Ping1D](INFO): Powering down
2019-04-10T00:51:38.929Z,1554857498.929 [BR_Ping1D](INFO): Stopping
2019-04-10T00:51:39.322Z,1554857499.322 [BR_Ping1D](INFO): Stopping
2019-04-10T00:51:39.342Z,1554857499.342 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T00:51:39.342Z,1554857499.342 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2019-04-10T00:51:39.342Z,1554857499.342 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-10T00:51:39.353Z,1554857499.353 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-10T00:51:39.723Z,1554857499.723 [BR_Ping1D](INFO): Stopped
2019-04-10T00:51:39.729Z,1554857499.729 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T00:51:39.729Z,1554857499.729 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-04-10T00:51:39.729Z,1554857499.729 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-10T00:51:39.731Z,1554857499.731 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T00:51:39.731Z,1554857499.731 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-04-10T00:51:39.731Z,1554857499.731 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-10T00:51:39.754Z,1554857499.754 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-10T00:51:39.754Z,1554857499.754 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-10T00:51:39.754Z,1554857499.754 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-10T00:51:39.754Z,1554857499.754 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2019-04-10T00:51:39.755Z,1554857499.755 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T00:51:39.755Z,1554857499.755 [BR_Ping1D] No Fault, FailCount= 3
2019-04-10T00:51:40.126Z,1554857500.126 [NAL9602](DEBUG): Fix Requested
2019-04-10T00:51:40.141Z,1554857500.141 [BR_Ping1D](INFO): Start
2019-04-10T00:51:40.167Z,1554857500.167 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-10T00:51:40.167Z,1554857500.167 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-04-10T00:51:40.168Z,1554857500.168 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-10T00:51:40.168Z,1554857500.168 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-04-10T00:51:40.548Z,1554857500.548 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:40.557Z,1554857500.557 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-10T00:51:40.952Z,1554857500.952 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:40.952Z,1554857500.952 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:40.952Z,1554857500.952 [BR_Ping1D](INFO): startup: 0
2019-04-10T00:51:40.953Z,1554857500.953 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T00:51:40.966Z,1554857500.966 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-10T00:51:40.966Z,1554857500.966 [BR_Ping1D](INFO): data 011E
2019-04-10T00:51:40.966Z,1554857500.966 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T00:51:40.967Z,1554857500.967 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-10T00:51:40.967Z,1554857500.967 [BR_Ping1D](INFO): 42520100EB030000840100
2019-04-10T00:51:40.968Z,1554857500.968 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-10T00:51:40.969Z,1554857500.969 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T00:51:40.969Z,1554857500.969 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-10T00:51:40.970Z,1554857500.970 [BR_Ping1D](INFO): 42520100EE030000870100
2019-04-10T00:51:41.345Z,1554857501.345 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:41.745Z,1554857501.745 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:41.745Z,1554857501.745 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:41.746Z,1554857501.746 [BR_Ping1D](INFO): startup: 1
2019-04-10T00:51:41.746Z,1554857501.746 [BR_Ping1D](INFO): UART requesting status
2019-04-10T00:51:41.746Z,1554857501.746 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T00:51:41.759Z,1554857501.759 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T00:51:41.768Z,1554857501.768 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T00:51:42.150Z,1554857502.150 [NAL9602](DEBUG): Fix Requested
2019-04-10T00:51:42.164Z,1554857502.164 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:42.165Z,1554857502.165 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:42.165Z,1554857502.165 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:42.165Z,1554857502.165 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:42.165Z,1554857502.165 [BR_Ping1D](INFO): elapsed 1.2125
2019-04-10T00:51:42.165Z,1554857502.165 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:42.166Z,1554857502.166 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:42.183Z,1554857502.183 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:42.183Z,1554857502.183 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:42.568Z,1554857502.568 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:42.568Z,1554857502.568 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:42.568Z,1554857502.569 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:42.569Z,1554857502.569 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:42.569Z,1554857502.569 [BR_Ping1D](INFO): elapsed 1.6161
2019-04-10T00:51:42.569Z,1554857502.569 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:42.569Z,1554857502.569 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:42.569Z,1554857502.569 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:42.569Z,1554857502.569 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:42.968Z,1554857502.968 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:42.969Z,1554857502.969 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:42.969Z,1554857502.969 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:42.969Z,1554857502.969 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:42.969Z,1554857502.969 [BR_Ping1D](INFO): elapsed 2.0165
2019-04-10T00:51:42.969Z,1554857502.969 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:42.970Z,1554857502.970 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:42.970Z,1554857502.970 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:42.970Z,1554857502.970 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:43.361Z,1554857503.361 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:43.362Z,1554857503.362 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:43.362Z,1554857503.362 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:43.362Z,1554857503.362 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:43.362Z,1554857503.362 [BR_Ping1D](INFO): elapsed 2.4096
2019-04-10T00:51:43.362Z,1554857503.362 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:43.363Z,1554857503.363 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:43.363Z,1554857503.363 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:43.363Z,1554857503.363 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:43.766Z,1554857503.766 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:43.766Z,1554857503.766 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:43.766Z,1554857503.766 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:43.766Z,1554857503.766 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:43.767Z,1554857503.767 [BR_Ping1D](INFO): elapsed 2.8140
2019-04-10T00:51:43.767Z,1554857503.767 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:43.767Z,1554857503.767 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:43.767Z,1554857503.767 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:43.767Z,1554857503.767 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:44.169Z,1554857504.169 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:44.169Z,1554857504.169 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:44.170Z,1554857504.170 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:44.170Z,1554857504.170 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:44.170Z,1554857504.170 [BR_Ping1D](INFO): elapsed 3.2172
2019-04-10T00:51:44.170Z,1554857504.170 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:44.170Z,1554857504.170 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:44.170Z,1554857504.170 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:44.171Z,1554857504.171 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:44.580Z,1554857504.580 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:44.580Z,1554857504.580 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:44.580Z,1554857504.580 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:44.580Z,1554857504.580 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:44.581Z,1554857504.581 [BR_Ping1D](INFO): elapsed 3.6278
2019-04-10T00:51:44.581Z,1554857504.581 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:44.581Z,1554857504.581 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:44.581Z,1554857504.581 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:44.581Z,1554857504.581 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:44.977Z,1554857504.977 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:44.978Z,1554857504.978 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:44.982Z,1554857504.982 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:44.982Z,1554857504.982 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:44.982Z,1554857504.982 [BR_Ping1D](INFO): elapsed 4.0298
2019-04-10T00:51:44.983Z,1554857504.983 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:44.983Z,1554857504.983 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:44.983Z,1554857504.983 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:44.983Z,1554857504.983 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:45.378Z,1554857505.378 [NAL9602](DEBUG): Fix Requested
2019-04-10T00:51:45.392Z,1554857505.392 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:45.393Z,1554857505.393 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:45.393Z,1554857505.393 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:45.393Z,1554857505.393 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:45.393Z,1554857505.393 [BR_Ping1D](INFO): elapsed 4.4405
2019-04-10T00:51:45.393Z,1554857505.393 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:45.394Z,1554857505.394 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:45.394Z,1554857505.394 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:45.394Z,1554857505.394 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:45.440Z,1554857505.440 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T00:51:45.440Z,1554857505.440 [Aanderaa_O2] No Fault, FailCount= 3
2019-04-10T00:51:45.800Z,1554857505.800 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:45.800Z,1554857505.800 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:45.800Z,1554857505.800 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:45.800Z,1554857505.800 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:45.801Z,1554857505.801 [BR_Ping1D](INFO): elapsed 4.8479
2019-04-10T00:51:45.801Z,1554857505.801 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:45.801Z,1554857505.801 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:45.801Z,1554857505.801 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:45.801Z,1554857505.801 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:46.213Z,1554857506.213 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:46.213Z,1554857506.213 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:46.213Z,1554857506.213 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:46.213Z,1554857506.213 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:46.213Z,1554857506.213 [BR_Ping1D](INFO): elapsed 5.2606
2019-04-10T00:51:46.213Z,1554857506.213 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:46.214Z,1554857506.214 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T00:51:46.214Z,1554857506.214 [BR_Ping1D] Data Fault, FailCount= 4
2019-04-10T00:51:46.214Z,1554857506.214 [BR_Ping1D](ERROR): Data Fault
2019-04-10T00:51:46.269Z,1554857506.269 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T00:51:46.593Z,1554857506.593 [BR_Ping1D](INFO): Stop
2019-04-10T00:51:46.593Z,1554857506.593 [BR_Ping1D](INFO): uninitialize
2019-04-10T00:51:46.593Z,1554857506.593 [BR_Ping1D](INFO): Powering down
2019-04-10T00:51:46.998Z,1554857506.998 [BR_Ping1D](INFO): Stopping
2019-04-10T00:51:47.406Z,1554857507.406 [BR_Ping1D](INFO): Stopping
2019-04-10T00:51:47.807Z,1554857507.807 [BR_Ping1D](INFO): Stopped
2019-04-10T00:51:47.824Z,1554857507.824 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T00:51:47.824Z,1554857507.824 [BR_Ping1D] No Fault, FailCount= 4
2019-04-10T00:51:48.210Z,1554857508.210 [NAL9602](DEBUG): Fix Requested
2019-04-10T00:51:48.224Z,1554857508.224 [BR_Ping1D](INFO): Start
2019-04-10T00:51:48.636Z,1554857508.636 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:49.028Z,1554857509.028 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:49.028Z,1554857509.028 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:49.028Z,1554857509.028 [BR_Ping1D](INFO): startup: 0
2019-04-10T00:51:49.029Z,1554857509.029 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T00:51:49.047Z,1554857509.047 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-10T00:51:49.047Z,1554857509.047 [BR_Ping1D](INFO): data 011E
2019-04-10T00:51:49.047Z,1554857509.047 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T00:51:49.049Z,1554857509.049 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-10T00:51:49.049Z,1554857509.049 [BR_Ping1D](INFO): 42520100EB030000840100
2019-04-10T00:51:49.049Z,1554857509.049 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-10T00:51:49.049Z,1554857509.049 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T00:51:49.060Z,1554857509.060 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-10T00:51:49.060Z,1554857509.060 [BR_Ping1D](INFO): 42520100EE030000870100
2019-04-10T00:51:49.422Z,1554857509.422 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:49.830Z,1554857509.830 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:49.830Z,1554857509.830 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:49.830Z,1554857509.830 [BR_Ping1D](INFO): startup: 1
2019-04-10T00:51:49.830Z,1554857509.830 [BR_Ping1D](INFO): UART requesting status
2019-04-10T00:51:49.830Z,1554857509.830 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T00:51:49.838Z,1554857509.838 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T00:51:49.846Z,1554857509.846 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T00:51:50.234Z,1554857510.234 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:50.234Z,1554857510.234 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:50.234Z,1554857510.234 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:50.234Z,1554857510.234 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:50.234Z,1554857510.234 [BR_Ping1D](INFO): elapsed 1.2058
2019-04-10T00:51:50.235Z,1554857510.235 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:50.235Z,1554857510.235 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:50.235Z,1554857510.235 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:50.235Z,1554857510.235 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:50.640Z,1554857510.640 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:50.640Z,1554857510.640 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:50.640Z,1554857510.640 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:50.640Z,1554857510.640 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:50.640Z,1554857510.640 [BR_Ping1D](INFO): elapsed 1.6116
2019-04-10T00:51:50.640Z,1554857510.640 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:50.641Z,1554857510.641 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:50.641Z,1554857510.641 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:50.641Z,1554857510.641 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:51.036Z,1554857511.036 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:51.036Z,1554857511.036 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:51.036Z,1554857511.036 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:51.036Z,1554857511.036 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:51.036Z,1554857511.036 [BR_Ping1D](INFO): elapsed 2.0076
2019-04-10T00:51:51.036Z,1554857511.036 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:51.037Z,1554857511.037 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:51.037Z,1554857511.037 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:51.037Z,1554857511.037 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:51.438Z,1554857511.438 [NAL9602](DEBUG): Fix Requested
2019-04-10T00:51:51.454Z,1554857511.454 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:51.454Z,1554857511.454 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:51.454Z,1554857511.454 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:51.455Z,1554857511.455 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:51.455Z,1554857511.455 [BR_Ping1D](INFO): elapsed 2.4261
2019-04-10T00:51:51.455Z,1554857511.455 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:51.455Z,1554857511.455 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:51.455Z,1554857511.455 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:51.455Z,1554857511.455 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:51.875Z,1554857511.875 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:51.875Z,1554857511.875 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:51.875Z,1554857511.875 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:51.875Z,1554857511.875 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:51.875Z,1554857511.875 [BR_Ping1D](INFO): elapsed 2.8467
2019-04-10T00:51:51.876Z,1554857511.876 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:51.876Z,1554857511.876 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:51.876Z,1554857511.876 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:51.876Z,1554857511.876 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:52.260Z,1554857512.260 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:52.260Z,1554857512.260 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:52.261Z,1554857512.261 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:52.261Z,1554857512.261 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:52.261Z,1554857512.261 [BR_Ping1D](INFO): elapsed 3.2322
2019-04-10T00:51:52.261Z,1554857512.261 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:52.261Z,1554857512.261 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:52.261Z,1554857512.261 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:52.262Z,1554857512.262 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:52.656Z,1554857512.656 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:52.656Z,1554857512.656 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:52.656Z,1554857512.656 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:52.656Z,1554857512.656 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:52.657Z,1554857512.657 [BR_Ping1D](INFO): elapsed 3.6278
2019-04-10T00:51:52.657Z,1554857512.657 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:52.657Z,1554857512.657 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:52.657Z,1554857512.657 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:52.657Z,1554857512.657 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:53.059Z,1554857513.059 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:53.059Z,1554857513.059 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:53.060Z,1554857513.060 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:53.060Z,1554857513.060 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:53.060Z,1554857513.060 [BR_Ping1D](INFO): elapsed 4.0312
2019-04-10T00:51:53.060Z,1554857513.060 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:53.060Z,1554857513.060 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:53.060Z,1554857513.060 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:53.061Z,1554857513.061 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:53.463Z,1554857513.463 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:53.463Z,1554857513.463 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:53.463Z,1554857513.463 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:53.463Z,1554857513.463 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:53.464Z,1554857513.464 [BR_Ping1D](INFO): elapsed 4.4348
2019-04-10T00:51:53.464Z,1554857513.464 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:53.464Z,1554857513.464 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:53.464Z,1554857513.464 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:53.464Z,1554857513.464 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:53.865Z,1554857513.865 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:53.865Z,1554857513.865 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:53.866Z,1554857513.866 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:53.866Z,1554857513.866 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:53.866Z,1554857513.866 [BR_Ping1D](INFO): elapsed 4.8373
2019-04-10T00:51:53.866Z,1554857513.866 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:53.866Z,1554857513.866 [BR_Ping1D](INFO): No UART data
2019-04-10T00:51:53.867Z,1554857513.867 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T00:51:53.867Z,1554857513.867 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T00:51:54.266Z,1554857514.266 [NAL9602](DEBUG): Fix Requested
2019-04-10T00:51:54.280Z,1554857514.280 [BR_Ping1D](INFO): Starting
2019-04-10T00:51:54.281Z,1554857514.281 [BR_Ping1D](INFO): readConfig
2019-04-10T00:51:54.281Z,1554857514.281 [BR_Ping1D](INFO): startup: 2
2019-04-10T00:51:54.281Z,1554857514.281 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T00:51:54.281Z,1554857514.281 [BR_Ping1D](INFO): elapsed 5.2525
2019-04-10T00:51:54.281Z,1554857514.281 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T00:51:54.281Z,1554857514.281 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T00:51:54.282Z,1554857514.282 [BR_Ping1D] Data Fault, FailCount= 5
2019-04-10T00:51:54.282Z,1554857514.282 [BR_Ping1D](ERROR): Data Fault
2019-04-10T00:51:54.297Z,1554857514.297 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T00:51:54.297Z,1554857514.297 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-04-10T00:51:54.688Z,1554857514.688 [BR_Ping1D](INFO): Stop
2019-04-10T00:51:54.688Z,1554857514.688 [BR_Ping1D](INFO): uninitialize
2019-04-10T00:51:54.688Z,1554857514.688 [BR_Ping1D](INFO): Powering down
2019-04-10T00:51:54.798Z,1554857514.798 [CBIT](INFO): Critical error at 20190410T005154
2019-04-10T00:51:55.095Z,1554857515.095 [BR_Ping1D](INFO): Stopping
2019-04-10T00:51:55.481Z,1554857515.481 [BR_Ping1D](INFO): Stopping
2019-04-10T00:51:55.867Z,1554857515.867 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T00:51:55.867Z,1554857515.867 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-10T00:51:55.867Z,1554857515.867 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T00:51:55.887Z,1554857515.887 [BR_Ping1D](INFO): Stopped
2019-04-10T00:51:55.902Z,1554857515.902 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T00:51:56.390Z,1554857516.390 [Aanderaa_O2](INFO): Powering down
2019-04-10T00:51:57.098Z,1554857517.098 [NAL9602](DEBUG): Fix Requested
2019-04-10T00:51:57.175Z,1554857517.175 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T00:51:57.175Z,1554857517.175 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-10T00:51:59.522Z,1554857519.522 [NAL9602](DEBUG): Fix Requested
2019-04-10T00:52:01.259Z,1554857521.259 [CommandLine](IMPORTANT): got command restart application
2019-04-10T00:52:02.262Z,1554857522.262 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T00:52:02.262Z,1554857522.262 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-10T00:52:02.346Z,1554857522.346 [NAL9602](DEBUG): Fix Requested
2019-04-10T00:52:02.390Z,1554857522.390 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-04-10T00:52:02.390Z,1554857522.390 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-10T00:52:02.391Z,1554857522.391 [CommandLine](INFO): Join timeout helper Thread ID is 458
2019-04-10T00:52:02.391Z,1554857522.391 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-04-10T00:52:02.391Z,1554857522.391 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-10T00:52:02.392Z,1554857522.392 [NavChartDb](INFO): Join timeout helper Thread ID is 459
2019-04-10T00:52:02.722Z,1554857522.722 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T00:52:02.722Z,1554857522.722 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-10T00:52:02.742Z,1554857522.742 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-04-10T00:52:02.742Z,1554857522.742 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-10T00:52:02.742Z,1554857522.742 [Radio_Surface](INFO): Join timeout helper Thread ID is 460
2019-04-10T00:52:02.882Z,1554857522.882 [Radio_Surface](INFO): Powering down
2019-04-10T00:52:02.883Z,1554857522.883 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T00:52:02.883Z,1554857522.883 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-10T00:52:02.890Z,1554857522.890 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-04-10T00:52:02.890Z,1554857522.890 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-10T00:52:02.890Z,1554857522.890 [DataOverHttps](INFO): Join timeout helper Thread ID is 461
2019-04-10T00:52:03.070Z,1554857523.070 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T00:52:03.070Z,1554857523.070 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-10T00:52:03.078Z,1554857523.078 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-04-10T00:52:03.078Z,1554857523.078 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-10T00:52:03.078Z,1554857523.078 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 462
2019-04-10T00:52:03.578Z,1554857523.578 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T00:52:03.579Z,1554857523.579 [WetLabsBB2FL](INFO): Powering down
2019-04-10T00:52:03.579Z,1554857523.579 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-10T00:52:03.583Z,1554857523.583 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-04-10T00:52:03.583Z,1554857523.583 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-10T00:52:03.583Z,1554857523.583 [CTD_Seabird](INFO): Join timeout helper Thread ID is 463
2019-04-10T00:52:03.690Z,1554857523.690 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T00:52:03.690Z,1554857523.690 [CTD_Seabird](INFO): Powering down
2019-04-10T00:52:03.702Z,1554857523.702 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-10T00:52:03.711Z,1554857523.711 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-04-10T00:52:03.711Z,1554857523.711 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-10T00:52:03.711Z,1554857523.711 [logger](INFO): Join timeout helper Thread ID is 464
2019-04-10T00:52:03.714Z,1554857523.714 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T00:52:03.714Z,1554857523.714 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-10T00:52:03.731Z,1554857523.731 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-04-10T00:52:03.731Z,1554857523.731 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-10T00:52:03.731Z,1554857523.731 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-04-10T00:52:03.731Z,1554857523.731 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-10T00:52:03.731Z,1554857523.731 [controlThread](INFO): Join timeout helper Thread ID is 465
2019-04-10T00:52:03.942Z,1554857523.942 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T00:52:03.942Z,1554857523.942 [controlThread](DEBUG): Uninitializing ControlThread
2019-04-10T00:52:04.062Z,1554857524.062 [Aanderaa_O2](INFO): Powering down
2019-04-10T00:52:04.064Z,1554857524.064 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T00:52:04.065Z,1554857524.065 [NAL9602](INFO): Powering down
2019-04-10T00:52:04.067Z,1554857524.067 [BR_Ping1D](INFO): uninitialize
2019-04-10T00:52:04.067Z,1554857524.067 [BR_Ping1D](INFO): Powering down
2019-04-10T00:52:04.068Z,1554857524.068 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-04-10T00:52:04.069Z,1554857524.069 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-04-10T00:52:04.070Z,1554857524.070 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-04-10T00:52:04.071Z,1554857524.071 [MissionManager](INFO): Uninitializing Mission Default
2019-04-10T00:52:04.071Z,1554857524.071 [Default] Stopped
2019-04-10T00:52:04.071Z,1554857524.071 [Default](DEBUG): Aggregate::uninitialize Default
2019-04-10T00:52:04.071Z,1554857524.071 [Default:B.GoToSurface] Stopped
2019-04-10T00:52:04.071Z,1554857524.071 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-10T00:52:04.071Z,1554857524.071 [Default:CheckIn] Stopped
2019-04-10T00:52:04.071Z,1554857524.071 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-10T00:52:04.071Z,1554857524.071 [Default:CheckIn:Read_GPS] Stopped
2019-04-10T00:52:04.074Z,1554857524.074 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-04-10T00:52:04.074Z,1554857524.074 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-04-10T00:52:04.074Z,1554857524.074 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-04-10T00:52:04.075Z,1554857524.075 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-04-10T00:52:04.075Z,1554857524.075 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-04-10T00:52:04.075Z,1554857524.075 [BuoyancyServo](INFO): Powering down
2019-04-10T00:52:04.090Z,1554857524.090 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-10T00:52:04.090Z,1554857524.090 [ElevatorServo](INFO): Powering down
2019-04-10T00:52:04.091Z,1554857524.091 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-10T00:52:04.091Z,1554857524.091 [MassServo](INFO): Powering down
2019-04-10T00:52:04.092Z,1554857524.092 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-10T00:52:04.092Z,1554857524.092 [RudderServo](INFO): Powering down
2019-04-10T00:52:04.093Z,1554857524.093 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-04-10T00:52:04.093Z,1554857524.093 [ThrusterServo](INFO): Powering down
2019-04-10T00:52:04.094Z,1554857524.094 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-04-10T00:52:04.094Z,1554857524.094 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-04-10T00:52:04.095Z,1554857524.095 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-04-10T00:52:04.095Z,1554857524.095 [CBIT](DEBUG): Powering off loads.
2019-04-10T00:52:04.106Z,1554857524.106 [CBIT](DEBUG): Disabling WDT.
2019-04-10T00:52:04.118Z,1554857524.118 [CBIT](DEBUG): Opening all GF detection circuits.
2019-04-10T00:52:04.119Z,1554857524.119 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-10T00:52:04.157Z,1554857524.157 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-10T00:52:04.162Z,1554857524.162 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-10T00:52:04.230Z,1554857524.230 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-10T00:52:04.235Z,1554857524.235 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-10T00:52:04.286Z,1554857524.286 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-10T00:52:04.343Z,1554857524.343 [logger ThreadHandler](INFO): Thread cancelled.