2019-04-10T01:32:43.030Z,1554859963.030 [Supervisor](DEBUG): Initializing supervisor.
2019-04-10T01:32:43.033Z,1554859963.033 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-10T01:32:43.033Z,1554859963.033 [SyncHandler](INFO): Protected caller Thread ID is 1755
2019-04-10T01:32:43.034Z,1554859963.034 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-04-10T01:32:43.035Z,1554859963.035 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-04-10T01:32:43.036Z,1554859963.036 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1756
2019-04-10T01:32:43.039Z,1554859963.039 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-04-10T01:32:43.051Z,1554859963.051 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-04-10T01:32:43.052Z,1554859963.052 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-04-10T01:32:43.053Z,1554859963.053 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1757
2019-04-10T01:32:43.053Z,1554859963.053 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-04-10T01:32:43.054Z,1554859963.054 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-04-10T01:32:43.055Z,1554859963.055 [logger ThreadHandler](INFO): Protected caller Thread ID is 1758
2019-04-10T01:32:43.057Z,1554859963.057 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-04-10T01:32:43.057Z,1554859963.057 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-04-10T01:32:43.059Z,1554859963.059 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-04-10T01:32:43.153Z,1554859963.153 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-04-10T01:32:43.154Z,1554859963.154 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-04-10T01:32:43.356Z,1554859963.356 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-04-10T01:32:43.356Z,1554859963.356 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-04-10T01:32:43.493Z,1554859963.493 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-04-10T01:32:43.493Z,1554859963.493 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-04-10T01:32:43.953Z,1554859963.953 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-04-10T01:32:43.954Z,1554859963.954 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-04-10T01:32:44.397Z,1554859964.397 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-04-10T01:32:44.397Z,1554859964.397 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-04-10T01:32:44.866Z,1554859964.866 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-04-10T01:32:44.867Z,1554859964.867 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-04-10T01:32:45.174Z,1554859965.174 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-04-10T01:32:45.174Z,1554859965.174 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-04-10T01:32:45.541Z,1554859965.541 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-04-10T01:32:45.542Z,1554859965.542 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-04-10T01:32:46.220Z,1554859966.220 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-04-10T01:32:46.220Z,1554859966.220 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-04-10T01:32:46.370Z,1554859966.370 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-04-10T01:32:46.371Z,1554859966.371 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-04-10T01:32:46.475Z,1554859966.475 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-04-10T01:32:46.475Z,1554859966.475 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-04-10T01:32:46.555Z,1554859966.555 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-04-10T01:32:46.656Z,1554859966.656 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-04-10T01:32:46.657Z,1554859966.657 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-04-10T01:32:46.852Z,1554859966.852 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-04-10T01:32:46.852Z,1554859966.852 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-04-10T01:32:47.061Z,1554859967.061 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-04-10T01:32:47.063Z,1554859967.063 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-04-10T01:32:47.064Z,1554859967.064 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-04-10T01:32:47.147Z,1554859967.147 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-04-10T01:32:47.379Z,1554859967.379 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-04-10T01:32:47.379Z,1554859967.379 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-04-10T01:32:47.474Z,1554859967.474 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-04-10T01:32:47.648Z,1554859967.648 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-04-10T01:32:47.863Z,1554859967.863 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-04-10T01:32:47.947Z,1554859967.947 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-04-10T01:32:48.045Z,1554859968.045 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-04-10T01:32:48.137Z,1554859968.137 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-04-10T01:32:48.288Z,1554859968.288 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-04-10T01:32:48.392Z,1554859968.392 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-04-10T01:32:48.490Z,1554859968.490 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2019-04-10T01:32:48.491Z,1554859968.491 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-04-10T01:32:48.495Z,1554859968.495 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-04-10T01:32:48.593Z,1554859968.593 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-04-10T01:32:48.594Z,1554859968.594 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-04-10T01:32:48.721Z,1554859968.721 [BuoyancyServo] Loaded
2019-04-10T01:32:48.721Z,1554859968.721 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-04-10T01:32:48.736Z,1554859968.736 [ElevatorServo] Loaded
2019-04-10T01:32:48.737Z,1554859968.737 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-04-10T01:32:48.752Z,1554859968.752 [MassServo] Loaded
2019-04-10T01:32:48.752Z,1554859968.752 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-04-10T01:32:48.767Z,1554859968.767 [RudderServo] Loaded
2019-04-10T01:32:48.768Z,1554859968.768 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-04-10T01:32:48.782Z,1554859968.782 [ThrusterServo] Loaded
2019-04-10T01:32:48.782Z,1554859968.782 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-04-10T01:32:48.783Z,1554859968.783 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-04-10T01:32:48.783Z,1554859968.783 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-04-10T01:32:48.918Z,1554859968.918 [SBIT](DEBUG): Construct Startup Built In Test.
2019-04-10T01:32:48.947Z,1554859968.947 [SBIT] Loaded
2019-04-10T01:32:48.947Z,1554859968.947 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-04-10T01:32:48.948Z,1554859968.948 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-04-10T01:32:48.976Z,1554859968.976 [IBIT] Loaded
2019-04-10T01:32:48.976Z,1554859968.976 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-04-10T01:32:48.979Z,1554859968.979 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-04-10T01:32:49.367Z,1554859969.367 [CBIT] Loaded
2019-04-10T01:32:49.367Z,1554859969.367 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-04-10T01:32:49.367Z,1554859969.367 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-04-10T01:32:49.368Z,1554859969.368 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-04-10T01:32:49.551Z,1554859969.551 [Aanderaa_O2] Loaded
2019-04-10T01:32:49.551Z,1554859969.551 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-04-10T01:32:49.561Z,1554859969.561 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-04-10T01:32:49.566Z,1554859969.566 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-04-10T01:32:49.568Z,1554859969.568 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-04-10T01:32:49.573Z,1554859969.573 [CTD_Seabird](INFO): created writer for : depth
2019-04-10T01:32:49.573Z,1554859969.573 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-04-10T01:32:49.579Z,1554859969.579 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-04-10T01:32:49.579Z,1554859969.579 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-04-10T01:32:49.585Z,1554859969.585 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-04-10T01:32:49.585Z,1554859969.585 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-04-10T01:32:49.590Z,1554859969.590 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-04-10T01:32:49.591Z,1554859969.591 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-04-10T01:32:49.597Z,1554859969.597 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-04-10T01:32:49.597Z,1554859969.597 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-04-10T01:32:49.602Z,1554859969.602 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-04-10T01:32:49.629Z,1554859969.629 [CTD_Seabird] Loaded
2019-04-10T01:32:49.629Z,1554859969.629 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-04-10T01:32:49.630Z,1554859969.630 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406654E0
2019-04-10T01:32:49.631Z,1554859969.631 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1837
2019-04-10T01:32:49.657Z,1554859969.657 [ESPComponent] Loaded
2019-04-10T01:32:49.657Z,1554859969.657 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-04-10T01:32:49.671Z,1554859969.671 [PAR_Licor] Loaded
2019-04-10T01:32:49.671Z,1554859969.671 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-04-10T01:32:49.719Z,1554859969.719 [WetLabsBB2FL] Loaded
2019-04-10T01:32:49.720Z,1554859969.720 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-04-10T01:32:49.721Z,1554859969.721 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406954E0
2019-04-10T01:32:49.721Z,1554859969.721 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1838
2019-04-10T01:32:49.722Z,1554859969.722 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-04-10T01:32:49.722Z,1554859969.722 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-04-10T01:32:49.763Z,1554859969.763 [DepthRateCalculator] Loaded
2019-04-10T01:32:49.763Z,1554859969.763 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-04-10T01:32:49.769Z,1554859969.769 [PitchRateCalculator] Loaded
2019-04-10T01:32:49.769Z,1554859969.769 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-04-10T01:32:49.785Z,1554859969.785 [SpeedCalculator] Loaded
2019-04-10T01:32:49.785Z,1554859969.785 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-04-10T01:32:49.807Z,1554859969.807 [TempGradientCalculator] Loaded
2019-04-10T01:32:49.807Z,1554859969.807 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-04-10T01:32:49.813Z,1554859969.813 [YawRateCalculator] Loaded
2019-04-10T01:32:49.813Z,1554859969.813 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-04-10T01:32:49.846Z,1554859969.846 [ElevatorOffsetCalculator] Loaded
2019-04-10T01:32:49.847Z,1554859969.847 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-04-10T01:32:49.847Z,1554859969.847 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-04-10T01:32:49.848Z,1554859969.848 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-04-10T01:32:49.875Z,1554859969.875 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-04-10T01:32:49.875Z,1554859969.875 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-04-10T01:32:49.979Z,1554859969.979 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-04-10T01:32:49.979Z,1554859969.979 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-04-10T01:32:50.039Z,1554859970.039 [DeadReckonUsingSpeedCalculator] Loaded
2019-04-10T01:32:50.039Z,1554859970.039 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-04-10T01:32:50.100Z,1554859970.100 [DeadReckonWithRespectToSeafloor] Loaded
2019-04-10T01:32:50.100Z,1554859970.100 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-04-10T01:32:50.121Z,1554859970.121 [NavChart] Loaded
2019-04-10T01:32:50.121Z,1554859970.121 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-04-10T01:32:50.125Z,1554859970.125 [UniversalFixResidualReporter] Loaded
2019-04-10T01:32:50.125Z,1554859970.125 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-04-10T01:32:50.126Z,1554859970.126 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-04-10T01:32:50.126Z,1554859970.126 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-04-10T01:32:50.493Z,1554859970.493 [AcousticModem_Benthos_ATM900] Loaded
2019-04-10T01:32:50.493Z,1554859970.493 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-04-10T01:32:50.565Z,1554859970.565 [DataOverHttps] Loaded
2019-04-10T01:32:50.565Z,1554859970.565 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-04-10T01:32:50.567Z,1554859970.567 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409714E0
2019-04-10T01:32:50.567Z,1554859970.567 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1839
2019-04-10T01:32:50.580Z,1554859970.580 [Depth_Keller] Loaded
2019-04-10T01:32:50.581Z,1554859970.581 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-04-10T01:32:50.673Z,1554859970.673 [NAL9602] Loaded
2019-04-10T01:32:50.673Z,1554859970.673 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-04-10T01:32:50.679Z,1554859970.679 [Onboard] Loaded
2019-04-10T01:32:50.679Z,1554859970.679 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-04-10T01:32:50.682Z,1554859970.682 [Radio_Surface] Loaded
2019-04-10T01:32:50.683Z,1554859970.683 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-04-10T01:32:50.684Z,1554859970.684 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A14E0
2019-04-10T01:32:50.684Z,1554859970.684 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1840
2019-04-10T01:32:51.980Z,1554859971.980 [BPC1] Loaded
2019-04-10T01:32:51.980Z,1554859971.980 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-04-10T01:32:52.011Z,1554859972.011 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minrange
2019-04-10T01:32:52.013Z,1554859972.013 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.maxrange
2019-04-10T01:32:52.015Z,1554859972.015 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.soundspeed
2019-04-10T01:32:52.017Z,1554859972.017 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.automode
2019-04-10T01:32:52.020Z,1554859972.020 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.gainindex
2019-04-10T01:32:52.022Z,1554859972.022 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minconfidence
2019-04-10T01:32:52.024Z,1554859972.024 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.pingenable
2019-04-10T01:32:52.026Z,1554859972.026 [BR_Ping1D] Loaded
2019-04-10T01:32:52.026Z,1554859972.026 [ComponentRegistry](DEBUG): SyncComponent "BR_Ping1D" handled in the control thread.
2019-04-10T01:32:52.027Z,1554859972.027 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-04-10T01:32:52.027Z,1554859972.027 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-04-10T01:32:52.248Z,1554859972.248 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-04-10T01:32:52.248Z,1554859972.248 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-04-10T01:32:52.579Z,1554859972.579 [VerticalControl](DEBUG): Construct VerticalControl.
2019-04-10T01:32:53.032Z,1554859973.032 [VerticalControl] Loaded
2019-04-10T01:32:53.033Z,1554859973.033 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-04-10T01:32:53.034Z,1554859973.034 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-04-10T01:32:53.092Z,1554859973.092 [HorizontalControl] Loaded
2019-04-10T01:32:53.092Z,1554859973.092 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-04-10T01:32:53.093Z,1554859973.093 [SpeedControl](DEBUG): Construct SpeedControl.
2019-04-10T01:32:53.095Z,1554859973.095 [SpeedControl] Loaded
2019-04-10T01:32:53.095Z,1554859973.095 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-04-10T01:32:53.096Z,1554859973.096 [LoopControl](DEBUG): Construct LoopControl.
2019-04-10T01:32:53.096Z,1554859973.096 [LoopControl] Loaded
2019-04-10T01:32:53.097Z,1554859973.097 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-04-10T01:32:53.097Z,1554859973.097 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-04-10T01:32:53.098Z,1554859973.098 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-04-10T01:32:53.111Z,1554859973.111 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-04-10T01:32:53.111Z,1554859973.111 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-04-10T01:32:53.392Z,1554859973.392 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-04-10T01:32:53.395Z,1554859973.395 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-04-10T01:32:53.396Z,1554859973.396 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-04-10T01:32:53.403Z,1554859973.403 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-04-10T01:32:53.404Z,1554859973.404 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACE4E0
2019-04-10T01:32:53.404Z,1554859973.404 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1841
2019-04-10T01:32:53.409Z,1554859973.409 [Supervisor](INFO): Main Thread ID is 9999
2019-04-10T01:32:53.409Z,1554859973.409 [Supervisor](DEBUG): Running supervisor.
2019-04-10T01:32:53.410Z,1554859973.410 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1842
2019-04-10T01:32:53.412Z,1554859973.412 [controlThread ThreadHandler](INFO): Handler Thread ID is 1843
2019-04-10T01:32:53.413Z,1554859973.413 [controlThread](DEBUG): Initializing ControlThread
2019-04-10T01:32:53.415Z,1554859973.415 [SBIT](INFO): Initialize SBIT Component.
2019-04-10T01:32:53.415Z,1554859973.415 [SBIT](IMPORTANT): git: 2019-03-18-31-ge98d5dc
2019-04-10T01:32:53.415Z,1554859973.415 [SBIT](INFO): git hash: e98d5dc50cbc39e402f19b9946ff4dc0776c8725
2019-04-10T01:32:53.416Z,1554859973.416 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-04-10T01:32:53.417Z,1554859973.417 [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-10T01:32:53.418Z,1554859973.418 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-04-10T01:32:53.419Z,1554859973.419 [IBIT](INFO): Initialize IBIT Component.
2019-04-10T01:32:53.420Z,1554859973.420 [CBIT](DEBUG): Initialize CBIT Component.
2019-04-10T01:32:53.421Z,1554859973.421 [logger ThreadHandler](INFO): Handler Thread ID is 1844
2019-04-10T01:32:53.434Z,1554859973.434 [CBIT](DEBUG): Initialized mux pins.
2019-04-10T01:32:53.434Z,1554859973.434 [CBIT](DEBUG): Initializing the watchdog timer.
2019-04-10T01:32:53.446Z,1554859973.446 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1845
2019-04-10T01:32:53.447Z,1554859973.447 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T01:32:53.450Z,1554859973.450 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T01:32:53.452Z,1554859973.452 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1847
2019-04-10T01:32:53.453Z,1554859973.453 [WetLabsBB2FL](INFO): Powering down
2019-04-10T01:32:53.458Z,1554859973.458 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-04-10T01:32:53.458Z,1554859973.458 [CBIT](DEBUG): Initializing heartbeat.
2019-04-10T01:32:53.482Z,1554859973.482 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1848
2019-04-10T01:32:53.483Z,1554859973.483 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-04-10T01:32:53.492Z,1554859973.492 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1849
2019-04-10T01:32:53.506Z,1554859973.506 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1850
2019-04-10T01:32:53.509Z,1554859973.509 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-10T01:32:53.510Z,1554859973.510 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-04-10T01:32:53.510Z,1554859973.510 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-10T01:32:53.510Z,1554859973.510 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-04-10T01:32:53.510Z,1554859973.510 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-10T01:32:53.511Z,1554859973.511 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-04-10T01:32:53.511Z,1554859973.511 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-10T01:32:53.511Z,1554859973.511 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-04-10T01:32:53.511Z,1554859973.511 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-10T01:32:53.511Z,1554859973.511 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-04-10T01:32:53.512Z,1554859973.512 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-10T01:32:53.512Z,1554859973.512 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-04-10T01:32:53.512Z,1554859973.512 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-10T01:32:53.512Z,1554859973.512 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-04-10T01:32:53.512Z,1554859973.512 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-10T01:32:53.512Z,1554859973.512 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-04-10T01:32:53.530Z,1554859973.530 [CBIT](DEBUG): Deactivating GF circuits.
2019-04-10T01:32:53.530Z,1554859973.530 [CBIT](DEBUG): Deactivating emergency mode.
2019-04-10T01:32:53.566Z,1554859973.566 [CBIT](DEBUG): Backplane powered.
2019-04-10T01:32:53.570Z,1554859973.570 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-04-10T01:32:53.571Z,1554859973.571 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-04-10T01:32:53.571Z,1554859973.571 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-04-10T01:32:53.571Z,1554859973.571 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-04-10T01:32:53.572Z,1554859973.572 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-04-10T01:32:53.572Z,1554859973.572 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-04-10T01:32:53.573Z,1554859973.573 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-10T01:32:53.574Z,1554859973.574 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-04-10T01:32:53.575Z,1554859973.575 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-04-10T01:32:53.579Z,1554859973.579 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-04-10T01:32:53.581Z,1554859973.581 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-04-10T01:32:53.582Z,1554859973.582 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-04-10T01:32:53.582Z,1554859973.582 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-04-10T01:32:53.583Z,1554859973.583 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-04-10T01:32:53.605Z,1554859973.605 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-10T01:32:53.641Z,1554859973.641 [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-10T01:32:53.641Z,1554859973.641 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-04-10T01:32:53.707Z,1554859973.707 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-04-10T01:32:53.708Z,1554859973.708 [Default:A.Wait](DEBUG): Construct Wait.
2019-04-10T01:32:53.750Z,1554859973.750 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-10T01:32:53.780Z,1554859973.780 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-04-10T01:32:53.783Z,1554859973.783 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-04-10T01:32:53.801Z,1554859973.801 [Default:E.Execute](DEBUG): Construct Execute.
2019-04-10T01:32:53.804Z,1554859973.804 [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-10T01:32:53.815Z,1554859973.815 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,Depth_Keller,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-10T01:32:53.843Z,1554859973.843 [ESPComponent](INFO): powering down ESP
2019-04-10T01:32:53.894Z,1554859973.894 [Radio_Surface](INFO): Powering up
2019-04-10T01:32:54.291Z,1554859974.291 [PAR_Licor](INFO): A/D timeout, 8 tries over 129 ms

2019-04-10T01:32:54.291Z,1554859974.291 [PAR_Licor] Data Fault, FailCount= 1
2019-04-10T01:32:54.292Z,1554859974.292 [PAR_Licor](ERROR): Data Fault
2019-04-10T01:32:54.292Z,1554859974.292 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T01:32:54.292Z,1554859974.292 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T01:32:54.315Z,1554859974.315 [Depth_Keller](ERROR): Pressure reading out of range: 1895.684326 decibar
2019-04-10T01:32:54.366Z,1554859974.366 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-04-10T01:32:54.405Z,1554859974.405 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-04-10T01:32:54.407Z,1554859974.407 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-04-10T01:32:54.408Z,1554859974.408 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-10T01:32:54.414Z,1554859974.414 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-10T01:32:54.415Z,1554859974.415 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-10T01:32:54.422Z,1554859974.422 [MassServo](DEBUG): Initializing MassServo.
2019-04-10T01:32:54.423Z,1554859974.423 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-10T01:32:54.430Z,1554859974.430 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-10T01:32:54.431Z,1554859974.431 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-04-10T01:32:54.438Z,1554859974.438 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-04-10T01:32:54.441Z,1554859974.441 [CommandLine](FAULT): Scheduling is paused
2019-04-10T01:32:54.441Z,1554859974.441 [CBIT](INFO): Critical error at 20190410T013252
2019-04-10T01:32:54.441Z,1554859974.441 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-04-10T01:32:54.446Z,1554859974.446 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T01:32:54.446Z,1554859974.446 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T01:32:54.446Z,1554859974.446 [PAR_Licor] No Fault, FailCount= 1
2019-04-10T01:32:54.998Z,1554859974.998 [PAR_Licor](INFO): A/D timeout, 8 tries over 142 ms

2019-04-10T01:32:54.998Z,1554859974.998 [PAR_Licor] Data Fault, FailCount= 2
2019-04-10T01:32:54.998Z,1554859974.998 [PAR_Licor](ERROR): Data Fault
2019-04-10T01:32:55.035Z,1554859975.035 [BR_Ping1D](INFO): startup: 0
2019-04-10T01:32:55.035Z,1554859975.035 [BR_Ping1D](INFO): recvData: FFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF
2019-04-10T01:32:55.035Z,1554859975.035 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T01:32:55.046Z,1554859975.046 [BR_Ping1D](INFO): data: F401
2019-04-10T01:32:55.046Z,1554859975.046 [BR_Ping1D](INFO): test: F401
2019-04-10T01:32:55.046Z,1554859975.046 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T01:32:55.047Z,1554859975.047 [BR_Ping1D](INFO): XXX autoMode1: 01
2019-04-10T01:32:55.047Z,1554859975.047 [BR_Ping1D](INFO): XXX autoMode2: 
2019-04-10T01:32:55.047Z,1554859975.047 [BR_Ping1D](INFO): XXX autoMode3: 1
2019-04-10T01:32:55.047Z,1554859975.047 [BR_Ping1D](INFO): data: 01
2019-04-10T01:32:55.047Z,1554859975.047 [BR_Ping1D](INFO): test: 01
2019-04-10T01:32:55.048Z,1554859975.048 [BR_Ping1D](INFO): 42520100EB030000840114
2019-04-10T01:32:55.048Z,1554859975.048 [BR_Ping1D](INFO): data: 60E31600
2019-04-10T01:32:55.048Z,1554859975.048 [BR_Ping1D](INFO): test: 60E31600
2019-04-10T01:32:55.048Z,1554859975.048 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T01:32:55.048Z,1554859975.048 [BR_Ping1D](INFO): data: 01
2019-04-10T01:32:55.048Z,1554859975.048 [BR_Ping1D](INFO): test: 01
2019-04-10T01:32:55.049Z,1554859975.049 [BR_Ping1D](INFO): 42520100EE030000870116
2019-04-10T01:32:55.401Z,1554859975.401 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T01:32:55.401Z,1554859975.401 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T01:32:55.402Z,1554859975.402 [PAR_Licor] No Fault, FailCount= 2
2019-04-10T01:32:55.437Z,1554859975.437 [BR_Ping1D](INFO): Starting
2019-04-10T01:32:55.837Z,1554859975.837 [BR_Ping1D](INFO): Starting
2019-04-10T01:32:55.837Z,1554859975.837 [BR_Ping1D](INFO): readConfig
2019-04-10T01:32:55.838Z,1554859975.838 [BR_Ping1D](INFO): startup: 1
2019-04-10T01:32:55.838Z,1554859975.838 [BR_Ping1D](INFO): UART requesting status
2019-04-10T01:32:55.838Z,1554859975.838 [BR_Ping1D](INFO): data: 
2019-04-10T01:32:55.838Z,1554859975.838 [BR_Ping1D](INFO): test: 
2019-04-10T01:32:55.838Z,1554859975.838 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T01:32:55.846Z,1554859975.846 [BR_Ping1D](INFO): data: 
2019-04-10T01:32:55.846Z,1554859975.846 [BR_Ping1D](INFO): test: 
2019-04-10T01:32:55.846Z,1554859975.846 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T01:32:55.854Z,1554859975.854 [BR_Ping1D](INFO): data: 
2019-04-10T01:32:55.854Z,1554859975.854 [BR_Ping1D](INFO): test: 
2019-04-10T01:32:55.854Z,1554859975.854 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T01:32:56.354Z,1554859976.354 [PAR_Licor](INFO): A/D timeout, 8 tries over 138 ms

2019-04-10T01:32:56.354Z,1554859976.354 [PAR_Licor] Data Fault, FailCount= 1
2019-04-10T01:32:56.354Z,1554859976.354 [PAR_Licor](ERROR): Data Fault
2019-04-10T01:32:56.390Z,1554859976.390 [BR_Ping1D](INFO): Starting
2019-04-10T01:32:56.390Z,1554859976.390 [BR_Ping1D](INFO): readConfig
2019-04-10T01:32:56.390Z,1554859976.390 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:32:56.390Z,1554859976.390 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:32:56.390Z,1554859976.390 [BR_Ping1D](INFO): elapsed 1.3551
2019-04-10T01:32:56.391Z,1554859976.391 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:32:56.391Z,1554859976.391 [BR_Ping1D](INFO): No UART data
2019-04-10T01:32:56.391Z,1554859976.391 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:32:56.391Z,1554859976.391 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:32:56.438Z,1554859976.438 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T01:32:56.438Z,1554859976.438 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T01:32:56.438Z,1554859976.438 [PAR_Licor] No Fault, FailCount= 1
2019-04-10T01:32:56.641Z,1554859976.641 [BR_Ping1D](INFO): Starting
2019-04-10T01:32:56.641Z,1554859976.641 [BR_Ping1D](INFO): readConfig
2019-04-10T01:32:56.642Z,1554859976.642 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:32:56.642Z,1554859976.642 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:32:56.642Z,1554859976.642 [BR_Ping1D](INFO): elapsed 1.6066
2019-04-10T01:32:56.642Z,1554859976.642 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:32:56.642Z,1554859976.642 [BR_Ping1D](INFO): No UART data
2019-04-10T01:32:56.643Z,1554859976.643 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:32:56.643Z,1554859976.643 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:32:57.045Z,1554859977.045 [BR_Ping1D](INFO): Starting
2019-04-10T01:32:57.046Z,1554859977.046 [BR_Ping1D](INFO): readConfig
2019-04-10T01:32:57.046Z,1554859977.046 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:32:57.046Z,1554859977.046 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:32:57.046Z,1554859977.046 [BR_Ping1D](INFO): elapsed 2.0110
2019-04-10T01:32:57.046Z,1554859977.046 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:32:57.047Z,1554859977.047 [BR_Ping1D](INFO): No UART data
2019-04-10T01:32:57.047Z,1554859977.047 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:32:57.047Z,1554859977.047 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:32:57.449Z,1554859977.449 [BR_Ping1D](INFO): Starting
2019-04-10T01:32:57.449Z,1554859977.449 [BR_Ping1D](INFO): readConfig
2019-04-10T01:32:57.449Z,1554859977.449 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:32:57.450Z,1554859977.450 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:32:57.450Z,1554859977.450 [BR_Ping1D](INFO): elapsed 2.4144
2019-04-10T01:32:57.450Z,1554859977.450 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:32:57.450Z,1554859977.450 [BR_Ping1D](INFO): No UART data
2019-04-10T01:32:57.450Z,1554859977.450 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:32:57.451Z,1554859977.451 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:32:57.881Z,1554859977.881 [BR_Ping1D](INFO): Starting
2019-04-10T01:32:57.881Z,1554859977.881 [BR_Ping1D](INFO): readConfig
2019-04-10T01:32:57.881Z,1554859977.881 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:32:57.882Z,1554859977.882 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:32:57.882Z,1554859977.882 [BR_Ping1D](INFO): elapsed 2.8465
2019-04-10T01:32:57.882Z,1554859977.882 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:32:57.882Z,1554859977.882 [BR_Ping1D](INFO): No UART data
2019-04-10T01:32:57.882Z,1554859977.882 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:32:57.883Z,1554859977.883 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:32:58.442Z,1554859978.442 [PAR_Licor](INFO): A/D timeout, 7 tries over 129 ms

2019-04-10T01:32:58.442Z,1554859978.442 [PAR_Licor] Data Fault, FailCount= 1
2019-04-10T01:32:58.442Z,1554859978.442 [PAR_Licor](ERROR): Data Fault
2019-04-10T01:32:58.465Z,1554859978.465 [BR_Ping1D](INFO): Starting
2019-04-10T01:32:58.465Z,1554859978.465 [BR_Ping1D](INFO): readConfig
2019-04-10T01:32:58.465Z,1554859978.465 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:32:58.466Z,1554859978.466 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:32:58.466Z,1554859978.466 [BR_Ping1D](INFO): elapsed 3.4310
2019-04-10T01:32:58.467Z,1554859978.467 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:32:58.467Z,1554859978.467 [BR_Ping1D](INFO): No UART data
2019-04-10T01:32:58.467Z,1554859978.467 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:32:58.467Z,1554859978.467 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:32:58.546Z,1554859978.546 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T01:32:58.546Z,1554859978.546 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T01:32:58.546Z,1554859978.546 [PAR_Licor] No Fault, FailCount= 1
2019-04-10T01:32:58.739Z,1554859978.739 [BR_Ping1D](INFO): Starting
2019-04-10T01:32:58.739Z,1554859978.739 [BR_Ping1D](INFO): readConfig
2019-04-10T01:32:58.739Z,1554859978.739 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:32:58.739Z,1554859978.739 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:32:58.739Z,1554859978.739 [BR_Ping1D](INFO): elapsed 3.7041
2019-04-10T01:32:58.740Z,1554859978.740 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:32:58.740Z,1554859978.740 [BR_Ping1D](INFO): No UART data
2019-04-10T01:32:58.740Z,1554859978.740 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:32:58.740Z,1554859978.740 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:32:59.258Z,1554859979.258 [PAR_Licor](INFO): A/D timeout, 8 tries over 138 ms

2019-04-10T01:32:59.258Z,1554859979.258 [PAR_Licor] Data Fault, FailCount= 2
2019-04-10T01:32:59.258Z,1554859979.258 [PAR_Licor](ERROR): Data Fault
2019-04-10T01:32:59.293Z,1554859979.293 [BR_Ping1D](INFO): Starting
2019-04-10T01:32:59.293Z,1554859979.293 [BR_Ping1D](INFO): readConfig
2019-04-10T01:32:59.294Z,1554859979.294 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:32:59.294Z,1554859979.294 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:32:59.294Z,1554859979.294 [BR_Ping1D](INFO): elapsed 4.2586
2019-04-10T01:32:59.294Z,1554859979.294 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:32:59.294Z,1554859979.294 [BR_Ping1D](INFO): No UART data
2019-04-10T01:32:59.295Z,1554859979.295 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:32:59.295Z,1554859979.295 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:32:59.358Z,1554859979.358 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T01:32:59.358Z,1554859979.358 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T01:32:59.358Z,1554859979.358 [PAR_Licor] No Fault, FailCount= 2
2019-04-10T01:32:59.547Z,1554859979.547 [BR_Ping1D](INFO): Starting
2019-04-10T01:32:59.547Z,1554859979.547 [BR_Ping1D](INFO): readConfig
2019-04-10T01:32:59.547Z,1554859979.547 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:32:59.547Z,1554859979.547 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:32:59.547Z,1554859979.547 [BR_Ping1D](INFO): elapsed 4.5121
2019-04-10T01:32:59.548Z,1554859979.548 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:32:59.548Z,1554859979.548 [BR_Ping1D](INFO): No UART data
2019-04-10T01:32:59.548Z,1554859979.548 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:32:59.548Z,1554859979.548 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:00.066Z,1554859980.066 [PAR_Licor](INFO): A/D timeout, 9 tries over 138 ms

2019-04-10T01:33:00.066Z,1554859980.066 [PAR_Licor] Data Fault, FailCount= 3
2019-04-10T01:33:00.066Z,1554859980.066 [PAR_Licor](ERROR): Data Fault
2019-04-10T01:33:00.101Z,1554859980.101 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:00.101Z,1554859980.101 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:00.102Z,1554859980.102 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:00.102Z,1554859980.102 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:00.102Z,1554859980.102 [BR_Ping1D](INFO): elapsed 5.0666
2019-04-10T01:33:00.102Z,1554859980.102 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:00.102Z,1554859980.102 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T01:33:00.102Z,1554859980.102 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-10T01:33:00.102Z,1554859980.102 [BR_Ping1D](ERROR): Data Fault
2019-04-10T01:33:00.363Z,1554859980.363 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T01:33:00.364Z,1554859980.364 [CBIT](CRITICAL): Data Fault in component: PAR_Licor
2019-04-10T01:33:00.364Z,1554859980.364 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T01:33:00.407Z,1554859980.407 [BR_Ping1D](INFO): Stop
2019-04-10T01:33:00.407Z,1554859980.407 [BR_Ping1D](INFO): uninitialize
2019-04-10T01:33:00.407Z,1554859980.407 [BR_Ping1D](INFO): Powering down
2019-04-10T01:33:00.564Z,1554859980.564 [CBIT](INFO): Critical error at 20190410T013300
2019-04-10T01:33:00.790Z,1554859980.790 [BR_Ping1D](INFO): Stopping
2019-04-10T01:33:01.195Z,1554859981.195 [BR_Ping1D](INFO): Stopping
2019-04-10T01:33:01.608Z,1554859981.608 [BR_Ping1D](INFO): Stopped
2019-04-10T01:33:01.659Z,1554859981.659 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T01:33:01.659Z,1554859981.659 [BR_Ping1D] No Fault, FailCount= 1
2019-04-10T01:33:02.002Z,1554859982.002 [BR_Ping1D](INFO): Start
2019-04-10T01:33:02.406Z,1554859982.406 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:02.810Z,1554859982.810 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:02.811Z,1554859982.811 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:02.811Z,1554859982.811 [BR_Ping1D](INFO): startup: 0
2019-04-10T01:33:02.811Z,1554859982.811 [BR_Ping1D](INFO): recvData: FFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF
2019-04-10T01:33:02.811Z,1554859982.811 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T01:33:02.822Z,1554859982.822 [BR_Ping1D](INFO): data: F401
2019-04-10T01:33:02.822Z,1554859982.822 [BR_Ping1D](INFO): test: F401
2019-04-10T01:33:02.822Z,1554859982.822 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T01:33:02.822Z,1554859982.822 [BR_Ping1D](INFO): XXX autoMode1: 01
2019-04-10T01:33:02.823Z,1554859982.823 [BR_Ping1D](INFO): XXX autoMode2: 
2019-04-10T01:33:02.823Z,1554859982.823 [BR_Ping1D](INFO): XXX autoMode3: 1
2019-04-10T01:33:02.823Z,1554859982.823 [BR_Ping1D](INFO): data: 01
2019-04-10T01:33:02.823Z,1554859982.823 [BR_Ping1D](INFO): test: 01
2019-04-10T01:33:02.823Z,1554859982.823 [BR_Ping1D](INFO): 42520100EB030000840114
2019-04-10T01:33:02.823Z,1554859982.823 [BR_Ping1D](INFO): data: 60E31600
2019-04-10T01:33:02.824Z,1554859982.824 [BR_Ping1D](INFO): test: 60E31600
2019-04-10T01:33:02.824Z,1554859982.824 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T01:33:02.824Z,1554859982.824 [BR_Ping1D](INFO): data: 01
2019-04-10T01:33:02.824Z,1554859982.824 [BR_Ping1D](INFO): test: 01
2019-04-10T01:33:02.824Z,1554859982.824 [BR_Ping1D](INFO): 42520100EE030000870116
2019-04-10T01:33:03.214Z,1554859983.214 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:03.618Z,1554859983.618 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:03.618Z,1554859983.618 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:03.619Z,1554859983.619 [BR_Ping1D](INFO): startup: 1
2019-04-10T01:33:03.619Z,1554859983.619 [BR_Ping1D](INFO): UART requesting status
2019-04-10T01:33:03.619Z,1554859983.619 [BR_Ping1D](INFO): data: 
2019-04-10T01:33:03.619Z,1554859983.619 [BR_Ping1D](INFO): test: 
2019-04-10T01:33:03.619Z,1554859983.619 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T01:33:03.626Z,1554859983.626 [BR_Ping1D](INFO): data: 
2019-04-10T01:33:03.626Z,1554859983.626 [BR_Ping1D](INFO): test: 
2019-04-10T01:33:03.626Z,1554859983.626 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T01:33:03.634Z,1554859983.634 [BR_Ping1D](INFO): data: 
2019-04-10T01:33:03.634Z,1554859983.634 [BR_Ping1D](INFO): test: 
2019-04-10T01:33:03.634Z,1554859983.634 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T01:33:03.660Z,1554859983.660 [CBIT](CRITICAL): Environmental Failure. Press:14.820937 PSI. Humidity:36%. Temp:26 C. ABORTING MISSION
2019-04-10T01:33:04.002Z,1554859984.002 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T01:33:04.002Z,1554859984.002 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-10T01:33:04.002Z,1554859984.002 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T01:33:04.022Z,1554859984.022 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:04.022Z,1554859984.022 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:04.023Z,1554859984.023 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:04.023Z,1554859984.023 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:04.023Z,1554859984.023 [BR_Ping1D](INFO): elapsed 1.2115
2019-04-10T01:33:04.023Z,1554859984.023 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:04.023Z,1554859984.023 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:04.023Z,1554859984.023 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:04.023Z,1554859984.023 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:04.039Z,1554859984.039 [CBIT](INFO): Critical error at 20190410T013303
2019-04-10T01:33:04.041Z,1554859984.041 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T01:33:04.526Z,1554859984.526 [Aanderaa_O2](INFO): Powering down
2019-04-10T01:33:04.597Z,1554859984.597 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:04.597Z,1554859984.597 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:04.598Z,1554859984.598 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:04.598Z,1554859984.598 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:04.598Z,1554859984.598 [BR_Ping1D](INFO): elapsed 1.7868
2019-04-10T01:33:04.598Z,1554859984.598 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:04.599Z,1554859984.599 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:04.599Z,1554859984.599 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:04.599Z,1554859984.599 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:04.830Z,1554859984.830 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:04.830Z,1554859984.830 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:04.830Z,1554859984.830 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:04.830Z,1554859984.830 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:04.831Z,1554859984.831 [BR_Ping1D](INFO): elapsed 2.0193
2019-04-10T01:33:04.831Z,1554859984.831 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:04.831Z,1554859984.831 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:04.831Z,1554859984.831 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:04.831Z,1554859984.831 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:05.234Z,1554859985.234 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:05.235Z,1554859985.235 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:05.235Z,1554859985.235 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:05.235Z,1554859985.235 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:05.235Z,1554859985.235 [BR_Ping1D](INFO): elapsed 2.4238
2019-04-10T01:33:05.235Z,1554859985.235 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:05.236Z,1554859985.236 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:05.236Z,1554859985.236 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:05.236Z,1554859985.236 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:05.267Z,1554859985.267 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T01:33:05.267Z,1554859985.267 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-10T01:33:05.638Z,1554859985.638 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:05.638Z,1554859985.638 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:05.639Z,1554859985.639 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:05.639Z,1554859985.639 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:05.639Z,1554859985.639 [BR_Ping1D](INFO): elapsed 2.8276
2019-04-10T01:33:05.639Z,1554859985.639 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:05.639Z,1554859985.639 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:05.640Z,1554859985.640 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:05.640Z,1554859985.640 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:06.054Z,1554859986.054 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:06.055Z,1554859986.055 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:06.055Z,1554859986.055 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:06.055Z,1554859986.055 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:06.055Z,1554859986.055 [BR_Ping1D](INFO): elapsed 3.2438
2019-04-10T01:33:06.055Z,1554859986.055 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:06.056Z,1554859986.056 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:06.056Z,1554859986.056 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:06.056Z,1554859986.056 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:06.446Z,1554859986.446 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:06.446Z,1554859986.446 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:06.446Z,1554859986.446 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:06.447Z,1554859986.447 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:06.447Z,1554859986.447 [BR_Ping1D](INFO): elapsed 3.6354
2019-04-10T01:33:06.447Z,1554859986.447 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:06.447Z,1554859986.447 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:06.447Z,1554859986.447 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:06.447Z,1554859986.447 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:06.498Z,1554859986.498 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T01:33:06.499Z,1554859986.499 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-10T01:33:06.499Z,1554859986.499 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T01:33:06.851Z,1554859986.851 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:06.851Z,1554859986.851 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:06.851Z,1554859986.851 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:06.851Z,1554859986.851 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:06.852Z,1554859986.852 [BR_Ping1D](INFO): elapsed 4.0402
2019-04-10T01:33:06.852Z,1554859986.852 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:06.852Z,1554859986.852 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:06.852Z,1554859986.852 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:06.852Z,1554859986.852 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:06.871Z,1554859986.871 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T01:33:06.902Z,1554859986.902 [CTD_Seabird](INFO): Powering down
2019-04-10T01:33:07.254Z,1554859987.254 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:07.254Z,1554859987.254 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:07.254Z,1554859987.254 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:07.254Z,1554859987.254 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:07.255Z,1554859987.255 [BR_Ping1D](INFO): elapsed 4.4433
2019-04-10T01:33:07.255Z,1554859987.255 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:07.255Z,1554859987.255 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:07.255Z,1554859987.255 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:07.255Z,1554859987.255 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:07.658Z,1554859987.658 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:07.659Z,1554859987.659 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:07.659Z,1554859987.659 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:07.659Z,1554859987.659 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:07.659Z,1554859987.659 [BR_Ping1D](INFO): elapsed 4.8479
2019-04-10T01:33:07.659Z,1554859987.659 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:07.660Z,1554859987.660 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:07.660Z,1554859987.660 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:07.660Z,1554859987.660 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:08.066Z,1554859988.066 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:08.066Z,1554859988.066 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:08.066Z,1554859988.066 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:08.066Z,1554859988.066 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:08.067Z,1554859988.067 [BR_Ping1D](INFO): elapsed 5.2553
2019-04-10T01:33:08.067Z,1554859988.067 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:08.067Z,1554859988.067 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T01:33:08.067Z,1554859988.067 [BR_Ping1D] Data Fault, FailCount= 2
2019-04-10T01:33:08.067Z,1554859988.067 [BR_Ping1D](ERROR): Data Fault
2019-04-10T01:33:08.084Z,1554859988.084 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-10T01:33:08.084Z,1554859988.084 [CTD_Seabird] No Fault, FailCount= 1
2019-04-10T01:33:08.085Z,1554859988.085 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T01:33:08.282Z,1554859988.282 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T01:33:08.284Z,1554859988.284 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T01:33:08.478Z,1554859988.478 [BR_Ping1D](INFO): Stop
2019-04-10T01:33:08.478Z,1554859988.478 [BR_Ping1D](INFO): uninitialize
2019-04-10T01:33:08.478Z,1554859988.478 [BR_Ping1D](INFO): Powering down
2019-04-10T01:33:08.870Z,1554859988.870 [BR_Ping1D](INFO): Stopping
2019-04-10T01:33:09.275Z,1554859989.275 [BR_Ping1D](INFO): Stopping
2019-04-10T01:33:09.678Z,1554859989.678 [BR_Ping1D](INFO): Stopped
2019-04-10T01:33:09.695Z,1554859989.695 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T01:33:09.695Z,1554859989.695 [BR_Ping1D] No Fault, FailCount= 2
2019-04-10T01:33:10.083Z,1554859990.083 [BR_Ping1D](INFO): Start
2019-04-10T01:33:10.489Z,1554859990.489 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:10.891Z,1554859990.891 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:10.891Z,1554859990.891 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:10.891Z,1554859990.891 [BR_Ping1D](INFO): startup: 0
2019-04-10T01:33:10.891Z,1554859990.891 [BR_Ping1D](INFO): recvData: FFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF
2019-04-10T01:33:10.891Z,1554859990.891 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T01:33:10.906Z,1554859990.906 [BR_Ping1D](INFO): data: F401
2019-04-10T01:33:10.907Z,1554859990.907 [BR_Ping1D](INFO): test: F401
2019-04-10T01:33:10.907Z,1554859990.907 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T01:33:10.907Z,1554859990.907 [BR_Ping1D](INFO): XXX autoMode1: 01
2019-04-10T01:33:10.907Z,1554859990.907 [BR_Ping1D](INFO): XXX autoMode2: 
2019-04-10T01:33:10.907Z,1554859990.907 [BR_Ping1D](INFO): XXX autoMode3: 1
2019-04-10T01:33:10.908Z,1554859990.908 [BR_Ping1D](INFO): data: 01
2019-04-10T01:33:10.908Z,1554859990.908 [BR_Ping1D](INFO): test: 01
2019-04-10T01:33:10.908Z,1554859990.908 [BR_Ping1D](INFO): 42520100EB030000840114
2019-04-10T01:33:10.908Z,1554859990.908 [BR_Ping1D](INFO): data: 60E31600
2019-04-10T01:33:10.908Z,1554859990.908 [BR_Ping1D](INFO): test: 60E31600
2019-04-10T01:33:10.908Z,1554859990.908 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T01:33:10.909Z,1554859990.909 [BR_Ping1D](INFO): data: 01
2019-04-10T01:33:10.909Z,1554859990.909 [BR_Ping1D](INFO): test: 01
2019-04-10T01:33:10.909Z,1554859990.909 [BR_Ping1D](INFO): 42520100EE030000870116
2019-04-10T01:33:11.316Z,1554859991.316 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:11.696Z,1554859991.696 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:11.696Z,1554859991.696 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:11.696Z,1554859991.696 [BR_Ping1D](INFO): startup: 1
2019-04-10T01:33:11.697Z,1554859991.697 [BR_Ping1D](INFO): UART requesting status
2019-04-10T01:33:11.697Z,1554859991.697 [BR_Ping1D](INFO): data: 
2019-04-10T01:33:11.697Z,1554859991.697 [BR_Ping1D](INFO): test: 
2019-04-10T01:33:11.697Z,1554859991.697 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T01:33:11.706Z,1554859991.706 [BR_Ping1D](INFO): data: 
2019-04-10T01:33:11.706Z,1554859991.706 [BR_Ping1D](INFO): test: 
2019-04-10T01:33:11.706Z,1554859991.706 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T01:33:11.714Z,1554859991.714 [BR_Ping1D](INFO): data: 
2019-04-10T01:33:11.714Z,1554859991.714 [BR_Ping1D](INFO): test: 
2019-04-10T01:33:11.714Z,1554859991.714 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T01:33:12.107Z,1554859992.107 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:12.107Z,1554859992.107 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:12.107Z,1554859992.107 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:12.107Z,1554859992.107 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:12.108Z,1554859992.108 [BR_Ping1D](INFO): elapsed 1.2159
2019-04-10T01:33:12.108Z,1554859992.108 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:12.108Z,1554859992.108 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:12.108Z,1554859992.108 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:12.108Z,1554859992.108 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:12.507Z,1554859992.507 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:12.507Z,1554859992.507 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:12.507Z,1554859992.507 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:12.507Z,1554859992.507 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:12.508Z,1554859992.508 [BR_Ping1D](INFO): elapsed 1.6159
2019-04-10T01:33:12.508Z,1554859992.508 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:12.508Z,1554859992.508 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:12.508Z,1554859992.508 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:12.508Z,1554859992.508 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:12.910Z,1554859992.910 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:12.910Z,1554859992.910 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:12.911Z,1554859992.911 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:12.911Z,1554859992.911 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:12.911Z,1554859992.911 [BR_Ping1D](INFO): elapsed 2.0194
2019-04-10T01:33:12.911Z,1554859992.911 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:12.911Z,1554859992.911 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:12.912Z,1554859992.912 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:12.912Z,1554859992.912 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:13.314Z,1554859993.314 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:13.314Z,1554859993.314 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:13.314Z,1554859993.314 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:13.315Z,1554859993.315 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:13.315Z,1554859993.315 [BR_Ping1D](INFO): elapsed 2.4231
2019-04-10T01:33:13.315Z,1554859993.315 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:13.315Z,1554859993.315 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:13.315Z,1554859993.315 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:13.315Z,1554859993.315 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:13.718Z,1554859993.718 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:13.718Z,1554859993.718 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:13.718Z,1554859993.718 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:13.718Z,1554859993.718 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:13.719Z,1554859993.719 [BR_Ping1D](INFO): elapsed 2.8270
2019-04-10T01:33:13.719Z,1554859993.719 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:13.719Z,1554859993.719 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:13.719Z,1554859993.719 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:13.719Z,1554859993.719 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:14.123Z,1554859994.123 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:14.123Z,1554859994.123 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:14.123Z,1554859994.123 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:14.124Z,1554859994.124 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:14.124Z,1554859994.124 [BR_Ping1D](INFO): elapsed 3.2321
2019-04-10T01:33:14.124Z,1554859994.124 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:14.124Z,1554859994.124 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:14.124Z,1554859994.124 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:14.124Z,1554859994.124 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:14.506Z,1554859994.506 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T01:33:14.507Z,1554859994.507 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-04-10T01:33:14.507Z,1554859994.507 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T01:33:14.526Z,1554859994.526 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:14.526Z,1554859994.526 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:14.526Z,1554859994.526 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:14.526Z,1554859994.526 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:14.526Z,1554859994.526 [BR_Ping1D](INFO): elapsed 3.6348
2019-04-10T01:33:14.527Z,1554859994.527 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:14.527Z,1554859994.527 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:14.527Z,1554859994.527 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:14.527Z,1554859994.527 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:14.555Z,1554859994.555 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T01:33:14.910Z,1554859994.910 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T01:33:15.066Z,1554859995.066 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:15.066Z,1554859995.066 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:15.066Z,1554859995.066 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:15.066Z,1554859995.066 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:15.067Z,1554859995.067 [BR_Ping1D](INFO): elapsed 4.1749
2019-04-10T01:33:15.067Z,1554859995.067 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:15.067Z,1554859995.067 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:15.067Z,1554859995.067 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:15.067Z,1554859995.067 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:15.334Z,1554859995.334 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:15.334Z,1554859995.334 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:15.335Z,1554859995.335 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:15.335Z,1554859995.335 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:15.335Z,1554859995.335 [BR_Ping1D](INFO): elapsed 4.4432
2019-04-10T01:33:15.335Z,1554859995.335 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:15.335Z,1554859995.335 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:15.335Z,1554859995.335 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:15.336Z,1554859995.336 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:15.718Z,1554859995.718 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T01:33:15.718Z,1554859995.718 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-10T01:33:15.718Z,1554859995.718 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T01:33:15.738Z,1554859995.738 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:15.738Z,1554859995.738 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:15.738Z,1554859995.738 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:15.739Z,1554859995.739 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:15.739Z,1554859995.739 [BR_Ping1D](INFO): elapsed 4.8471
2019-04-10T01:33:15.739Z,1554859995.739 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:15.739Z,1554859995.739 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:15.739Z,1554859995.739 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:15.739Z,1554859995.739 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:15.755Z,1554859995.755 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T01:33:15.755Z,1554859995.755 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T01:33:15.755Z,1554859995.755 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-04-10T01:33:16.242Z,1554859996.242 [Aanderaa_O2](INFO): Powering down
2019-04-10T01:33:16.261Z,1554859996.261 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:16.262Z,1554859996.262 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:16.262Z,1554859996.262 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:16.262Z,1554859996.262 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:16.262Z,1554859996.262 [BR_Ping1D](INFO): elapsed 5.3707
2019-04-10T01:33:16.263Z,1554859996.263 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:16.263Z,1554859996.263 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T01:33:16.263Z,1554859996.263 [BR_Ping1D] Data Fault, FailCount= 3
2019-04-10T01:33:16.263Z,1554859996.263 [BR_Ping1D](ERROR): Data Fault
2019-04-10T01:33:16.291Z,1554859996.291 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T01:33:16.546Z,1554859996.546 [BR_Ping1D](INFO): Stop
2019-04-10T01:33:16.546Z,1554859996.546 [BR_Ping1D](INFO): uninitialize
2019-04-10T01:33:16.547Z,1554859996.547 [BR_Ping1D](INFO): Powering down
2019-04-10T01:33:16.950Z,1554859996.950 [BR_Ping1D](INFO): Stopping
2019-04-10T01:33:16.979Z,1554859996.979 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T01:33:16.979Z,1554859996.979 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-10T01:33:17.362Z,1554859997.362 [BR_Ping1D](INFO): Stopping
2019-04-10T01:33:17.769Z,1554859997.769 [BR_Ping1D](INFO): Stopped
2019-04-10T01:33:17.799Z,1554859997.799 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T01:33:17.799Z,1554859997.799 [BR_Ping1D] No Fault, FailCount= 3
2019-04-10T01:33:18.142Z,1554859998.142 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T01:33:18.143Z,1554859998.143 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T01:33:18.181Z,1554859998.181 [BR_Ping1D](INFO): Start
2019-04-10T01:33:18.204Z,1554859998.204 [SBIT](IMPORTANT): Beginning Startup BIT
2019-04-10T01:33:18.220Z,1554859998.220 [CBIT](IMPORTANT): Beginning ground fault scan
2019-04-10T01:33:18.566Z,1554859998.566 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:18.970Z,1554859998.970 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:18.970Z,1554859998.970 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:18.971Z,1554859998.971 [BR_Ping1D](INFO): startup: 0
2019-04-10T01:33:18.971Z,1554859998.971 [BR_Ping1D](INFO): recvData: FFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF
2019-04-10T01:33:18.971Z,1554859998.971 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T01:33:18.982Z,1554859998.982 [BR_Ping1D](INFO): data: F401
2019-04-10T01:33:18.982Z,1554859998.982 [BR_Ping1D](INFO): test: F401
2019-04-10T01:33:18.982Z,1554859998.982 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T01:33:18.983Z,1554859998.983 [BR_Ping1D](INFO): XXX autoMode1: 01
2019-04-10T01:33:18.983Z,1554859998.983 [BR_Ping1D](INFO): XXX autoMode2: 
2019-04-10T01:33:18.983Z,1554859998.983 [BR_Ping1D](INFO): XXX autoMode3: 1
2019-04-10T01:33:18.983Z,1554859998.983 [BR_Ping1D](INFO): data: 01
2019-04-10T01:33:18.983Z,1554859998.983 [BR_Ping1D](INFO): test: 01
2019-04-10T01:33:18.983Z,1554859998.983 [BR_Ping1D](INFO): 42520100EB030000840114
2019-04-10T01:33:18.984Z,1554859998.984 [BR_Ping1D](INFO): data: 60E31600
2019-04-10T01:33:18.984Z,1554859998.984 [BR_Ping1D](INFO): test: 60E31600
2019-04-10T01:33:18.984Z,1554859998.984 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T01:33:18.984Z,1554859998.984 [BR_Ping1D](INFO): data: 01
2019-04-10T01:33:18.984Z,1554859998.984 [BR_Ping1D](INFO): test: 01
2019-04-10T01:33:18.984Z,1554859998.984 [BR_Ping1D](INFO): 42520100EE030000870116
2019-04-10T01:33:19.374Z,1554859999.374 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:19.778Z,1554859999.778 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:19.778Z,1554859999.778 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:19.779Z,1554859999.779 [BR_Ping1D](INFO): startup: 1
2019-04-10T01:33:19.779Z,1554859999.779 [BR_Ping1D](INFO): UART requesting status
2019-04-10T01:33:19.779Z,1554859999.779 [BR_Ping1D](INFO): data: 
2019-04-10T01:33:19.779Z,1554859999.779 [BR_Ping1D](INFO): test: 
2019-04-10T01:33:19.779Z,1554859999.779 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T01:33:19.786Z,1554859999.786 [BR_Ping1D](INFO): data: 
2019-04-10T01:33:19.786Z,1554859999.786 [BR_Ping1D](INFO): test: 
2019-04-10T01:33:19.786Z,1554859999.786 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T01:33:19.794Z,1554859999.794 [BR_Ping1D](INFO): data: 
2019-04-10T01:33:19.794Z,1554859999.794 [BR_Ping1D](INFO): test: 
2019-04-10T01:33:19.794Z,1554859999.794 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T01:33:20.183Z,1554860000.183 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:20.183Z,1554860000.183 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:20.183Z,1554860000.183 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:20.183Z,1554860000.183 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:20.184Z,1554860000.184 [BR_Ping1D](INFO): elapsed 1.2123
2019-04-10T01:33:20.184Z,1554860000.184 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:20.184Z,1554860000.184 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:20.184Z,1554860000.184 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:20.184Z,1554860000.184 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:20.663Z,1554860000.663 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:20.663Z,1554860000.663 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:20.663Z,1554860000.663 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:20.663Z,1554860000.663 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:20.664Z,1554860000.664 [BR_Ping1D](INFO): elapsed 1.6925
2019-04-10T01:33:20.664Z,1554860000.664 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:20.664Z,1554860000.664 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:20.664Z,1554860000.664 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:20.664Z,1554860000.664 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:21.047Z,1554860001.047 [NAL9602](INFO): Powering up NAL9602
2019-04-10T01:33:21.063Z,1554860001.063 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:21.063Z,1554860001.063 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:21.063Z,1554860001.063 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:21.063Z,1554860001.063 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:21.063Z,1554860001.063 [BR_Ping1D](INFO): elapsed 2.0922
2019-04-10T01:33:21.064Z,1554860001.064 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:21.064Z,1554860001.064 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:21.064Z,1554860001.064 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:21.064Z,1554860001.064 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:21.334Z,1554860001.334 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T01:33:21.334Z,1554860001.334 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-10T01:33:21.334Z,1554860001.334 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T01:33:21.466Z,1554860001.466 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:21.466Z,1554860001.466 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:21.466Z,1554860001.466 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:21.466Z,1554860001.466 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:21.467Z,1554860001.467 [BR_Ping1D](INFO): elapsed 2.4955
2019-04-10T01:33:21.467Z,1554860001.467 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:21.467Z,1554860001.467 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:21.467Z,1554860001.467 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:21.467Z,1554860001.467 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:21.611Z,1554860001.611 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T01:33:21.738Z,1554860001.738 [CTD_Seabird](INFO): Powering down
2019-04-10T01:33:21.870Z,1554860001.870 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:21.870Z,1554860001.870 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:21.871Z,1554860001.871 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:21.871Z,1554860001.871 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:21.871Z,1554860001.871 [BR_Ping1D](INFO): elapsed 2.8997
2019-04-10T01:33:21.871Z,1554860001.871 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:21.871Z,1554860001.871 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:21.871Z,1554860001.871 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:21.872Z,1554860001.872 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:22.274Z,1554860002.274 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:22.274Z,1554860002.274 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:22.275Z,1554860002.275 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:22.275Z,1554860002.275 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:22.275Z,1554860002.275 [BR_Ping1D](INFO): elapsed 3.3037
2019-04-10T01:33:22.275Z,1554860002.275 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:22.275Z,1554860002.275 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:22.275Z,1554860002.275 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:22.276Z,1554860002.276 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:22.677Z,1554860002.677 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:22.677Z,1554860002.677 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:22.678Z,1554860002.678 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:22.679Z,1554860002.679 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:22.679Z,1554860002.679 [BR_Ping1D](INFO): elapsed 3.7076
2019-04-10T01:33:22.679Z,1554860002.679 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:22.679Z,1554860002.679 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:22.679Z,1554860002.679 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:22.679Z,1554860002.679 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:22.799Z,1554860002.799 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-10T01:33:22.799Z,1554860002.799 [CTD_Seabird] No Fault, FailCount= 2
2019-04-10T01:33:23.082Z,1554860003.082 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T01:33:23.084Z,1554860003.084 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T01:33:23.082Z,1554860003.082 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:23.084Z,1554860003.084 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:23.084Z,1554860003.084 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:23.085Z,1554860003.085 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:23.085Z,1554860003.085 [BR_Ping1D](INFO): elapsed 4.1136
2019-04-10T01:33:23.085Z,1554860003.085 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:23.085Z,1554860003.085 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:23.085Z,1554860003.085 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:23.085Z,1554860003.085 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:23.485Z,1554860003.485 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:23.485Z,1554860003.485 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:23.486Z,1554860003.486 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:23.486Z,1554860003.486 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:23.486Z,1554860003.486 [BR_Ping1D](INFO): elapsed 4.5148
2019-04-10T01:33:23.486Z,1554860003.486 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:23.486Z,1554860003.486 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:23.487Z,1554860003.487 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:23.487Z,1554860003.487 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:23.890Z,1554860003.890 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:23.890Z,1554860003.890 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:23.890Z,1554860003.890 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:23.890Z,1554860003.890 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:23.891Z,1554860003.891 [BR_Ping1D](INFO): elapsed 4.9195
2019-04-10T01:33:23.891Z,1554860003.891 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:23.891Z,1554860003.891 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:23.891Z,1554860003.891 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:23.891Z,1554860003.891 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:24.293Z,1554860004.293 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:24.293Z,1554860004.293 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:24.294Z,1554860004.294 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:24.294Z,1554860004.294 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:24.294Z,1554860004.294 [BR_Ping1D](INFO): elapsed 5.3227
2019-04-10T01:33:24.294Z,1554860004.294 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:24.294Z,1554860004.294 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T01:33:24.294Z,1554860004.294 [BR_Ping1D] Data Fault, FailCount= 4
2019-04-10T01:33:24.294Z,1554860004.294 [BR_Ping1D](ERROR): Data Fault
2019-04-10T01:33:24.360Z,1554860004.360 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T01:33:24.698Z,1554860004.698 [BR_Ping1D](INFO): Stop
2019-04-10T01:33:24.698Z,1554860004.698 [BR_Ping1D](INFO): uninitialize
2019-04-10T01:33:24.698Z,1554860004.698 [BR_Ping1D](INFO): Powering down
2019-04-10T01:33:25.102Z,1554860005.102 [BR_Ping1D](INFO): Stopping
2019-04-10T01:33:25.506Z,1554860005.506 [BR_Ping1D](INFO): Stopping
2019-04-10T01:33:25.909Z,1554860005.909 [BR_Ping1D](INFO): Stopped
2019-04-10T01:33:25.971Z,1554860005.971 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T01:33:25.971Z,1554860005.971 [BR_Ping1D] No Fault, FailCount= 4
2019-04-10T01:33:26.314Z,1554860006.314 [BR_Ping1D](INFO): Start
2019-04-10T01:33:26.717Z,1554860006.717 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:27.122Z,1554860007.122 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:27.122Z,1554860007.122 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:27.122Z,1554860007.122 [BR_Ping1D](INFO): startup: 0
2019-04-10T01:33:27.122Z,1554860007.122 [BR_Ping1D](INFO): recvData: FFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF
2019-04-10T01:33:27.122Z,1554860007.122 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T01:33:27.134Z,1554860007.134 [BR_Ping1D](INFO): data: F401
2019-04-10T01:33:27.134Z,1554860007.134 [BR_Ping1D](INFO): test: F401
2019-04-10T01:33:27.134Z,1554860007.134 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T01:33:27.134Z,1554860007.134 [BR_Ping1D](INFO): XXX autoMode1: 01
2019-04-10T01:33:27.135Z,1554860007.135 [BR_Ping1D](INFO): XXX autoMode2: 
2019-04-10T01:33:27.135Z,1554860007.135 [BR_Ping1D](INFO): XXX autoMode3: 1
2019-04-10T01:33:27.135Z,1554860007.135 [BR_Ping1D](INFO): data: 01
2019-04-10T01:33:27.135Z,1554860007.135 [BR_Ping1D](INFO): test: 01
2019-04-10T01:33:27.135Z,1554860007.135 [BR_Ping1D](INFO): 42520100EB030000840114
2019-04-10T01:33:27.135Z,1554860007.135 [BR_Ping1D](INFO): data: 60E31600
2019-04-10T01:33:27.136Z,1554860007.136 [BR_Ping1D](INFO): test: 60E31600
2019-04-10T01:33:27.136Z,1554860007.136 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T01:33:27.136Z,1554860007.136 [BR_Ping1D](INFO): data: 01
2019-04-10T01:33:27.136Z,1554860007.136 [BR_Ping1D](INFO): test: 01
2019-04-10T01:33:27.136Z,1554860007.136 [BR_Ping1D](INFO): 42520100EE030000870116
2019-04-10T01:33:27.507Z,1554860007.507 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T01:33:27.507Z,1554860007.507 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-10T01:33:27.507Z,1554860007.507 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T01:33:27.525Z,1554860007.525 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:27.583Z,1554860007.583 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T01:33:27.584Z,1554860007.584 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-10T01:33:28.030Z,1554860008.030 [Aanderaa_O2](INFO): Powering down
2019-04-10T01:33:28.050Z,1554860008.050 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:28.050Z,1554860008.050 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:28.051Z,1554860008.051 [BR_Ping1D](INFO): startup: 1
2019-04-10T01:33:28.051Z,1554860008.051 [BR_Ping1D](INFO): UART requesting status
2019-04-10T01:33:28.051Z,1554860008.051 [BR_Ping1D](INFO): data: 
2019-04-10T01:33:28.051Z,1554860008.051 [BR_Ping1D](INFO): test: 
2019-04-10T01:33:28.051Z,1554860008.051 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T01:33:28.058Z,1554860008.058 [BR_Ping1D](INFO): data: 
2019-04-10T01:33:28.058Z,1554860008.058 [BR_Ping1D](INFO): test: 
2019-04-10T01:33:28.058Z,1554860008.058 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T01:33:28.066Z,1554860008.066 [BR_Ping1D](INFO): data: 
2019-04-10T01:33:28.066Z,1554860008.066 [BR_Ping1D](INFO): test: 
2019-04-10T01:33:28.066Z,1554860008.066 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T01:33:28.334Z,1554860008.334 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:28.334Z,1554860008.334 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:28.334Z,1554860008.334 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:28.334Z,1554860008.334 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:28.334Z,1554860008.334 [BR_Ping1D](INFO): elapsed 1.2119
2019-04-10T01:33:28.335Z,1554860008.335 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:28.335Z,1554860008.335 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:28.335Z,1554860008.335 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:28.335Z,1554860008.335 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:28.739Z,1554860008.739 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:28.739Z,1554860008.739 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:28.739Z,1554860008.739 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:28.739Z,1554860008.739 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:28.739Z,1554860008.739 [BR_Ping1D](INFO): elapsed 1.6169
2019-04-10T01:33:28.740Z,1554860008.740 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:28.740Z,1554860008.740 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:28.740Z,1554860008.740 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:28.740Z,1554860008.740 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:29.141Z,1554860009.141 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:29.141Z,1554860009.141 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:29.141Z,1554860009.141 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:29.141Z,1554860009.141 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:29.142Z,1554860009.142 [BR_Ping1D](INFO): elapsed 2.0192
2019-04-10T01:33:29.142Z,1554860009.142 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:29.142Z,1554860009.142 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:29.142Z,1554860009.142 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:29.142Z,1554860009.142 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:29.211Z,1554860009.211 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.007582
CHAN A1 (24V): -0.128005
CHAN A2 (12V): -0.005426
CHAN A3 (5V): -0.002816
CHAN B0 (3.3V): -0.000898
CHAN B1 (3.15aV): -0.000835
CHAN B2 (3.15bV): -0.001125
CHAN B3 (GND): 0.000108
OPEN: -0.000954
Full Scale Calc: 4.765 mA, -1.589 mA
2019-04-10T01:33:29.546Z,1554860009.546 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:29.546Z,1554860009.546 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:29.546Z,1554860009.546 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:29.546Z,1554860009.546 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:29.546Z,1554860009.546 [BR_Ping1D](INFO): elapsed 2.4239
2019-04-10T01:33:29.547Z,1554860009.547 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:29.547Z,1554860009.547 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:29.547Z,1554860009.547 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:29.547Z,1554860009.547 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:29.949Z,1554860009.949 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:29.949Z,1554860009.949 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:29.950Z,1554860009.950 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:29.950Z,1554860009.950 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:29.950Z,1554860009.950 [BR_Ping1D](INFO): elapsed 2.8277
2019-04-10T01:33:29.950Z,1554860009.950 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:29.951Z,1554860009.951 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:29.951Z,1554860009.951 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:29.951Z,1554860009.951 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:30.354Z,1554860010.354 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:30.354Z,1554860010.354 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:30.354Z,1554860010.354 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:30.354Z,1554860010.354 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:30.354Z,1554860010.354 [BR_Ping1D](INFO): elapsed 3.2318
2019-04-10T01:33:30.355Z,1554860010.355 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:30.355Z,1554860010.355 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:30.355Z,1554860010.355 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:30.355Z,1554860010.355 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:30.758Z,1554860010.758 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:30.758Z,1554860010.758 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:30.758Z,1554860010.758 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:30.759Z,1554860010.759 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:30.759Z,1554860010.759 [BR_Ping1D](INFO): elapsed 3.6362
2019-04-10T01:33:30.759Z,1554860010.759 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:30.759Z,1554860010.759 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:30.759Z,1554860010.759 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:30.759Z,1554860010.759 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:31.162Z,1554860011.162 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:31.162Z,1554860011.162 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:31.162Z,1554860011.162 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:31.163Z,1554860011.163 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:31.163Z,1554860011.163 [BR_Ping1D](INFO): elapsed 4.0402
2019-04-10T01:33:31.163Z,1554860011.163 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:31.163Z,1554860011.163 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:31.163Z,1554860011.163 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:31.163Z,1554860011.163 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:31.577Z,1554860011.577 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:31.577Z,1554860011.577 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:31.577Z,1554860011.577 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:31.577Z,1554860011.577 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:31.577Z,1554860011.577 [BR_Ping1D](INFO): elapsed 4.4548
2019-04-10T01:33:31.578Z,1554860011.578 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:31.578Z,1554860011.578 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:31.578Z,1554860011.578 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:31.578Z,1554860011.578 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:31.955Z,1554860011.955 [NAL9602](INFO): NAL9602 initialized
2019-04-10T01:33:31.970Z,1554860011.970 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:31.970Z,1554860011.970 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:31.970Z,1554860011.970 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:31.971Z,1554860011.971 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:31.971Z,1554860011.971 [BR_Ping1D](INFO): elapsed 4.8482
2019-04-10T01:33:31.971Z,1554860011.971 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:31.971Z,1554860011.971 [BR_Ping1D](INFO): No UART data
2019-04-10T01:33:31.971Z,1554860011.971 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:33:31.971Z,1554860011.971 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:33:32.383Z,1554860012.383 [BR_Ping1D](INFO): Starting
2019-04-10T01:33:32.383Z,1554860012.383 [BR_Ping1D](INFO): readConfig
2019-04-10T01:33:32.383Z,1554860012.383 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:33:32.384Z,1554860012.384 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:33:32.384Z,1554860012.384 [BR_Ping1D](INFO): elapsed 5.2613
2019-04-10T01:33:32.384Z,1554860012.384 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:33:32.384Z,1554860012.384 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T01:33:32.384Z,1554860012.384 [BR_Ping1D] Data Fault, FailCount= 5
2019-04-10T01:33:32.384Z,1554860012.384 [BR_Ping1D](ERROR): Data Fault
2019-04-10T01:33:32.415Z,1554860012.415 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T01:33:32.415Z,1554860012.415 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-04-10T01:33:32.774Z,1554860012.774 [NAL9602](DEBUG): Fix Requested
2019-04-10T01:33:32.789Z,1554860012.789 [BR_Ping1D](INFO): Stop
2019-04-10T01:33:32.789Z,1554860012.789 [BR_Ping1D](INFO): uninitialize
2019-04-10T01:33:32.789Z,1554860012.789 [BR_Ping1D](INFO): Powering down
2019-04-10T01:33:32.916Z,1554860012.916 [CBIT](INFO): Critical error at 20190410T013332
2019-04-10T01:33:33.196Z,1554860013.196 [BR_Ping1D](INFO): Stopping
2019-04-10T01:33:33.765Z,1554860013.765 [BR_Ping1D](INFO): Stopping
2019-04-10T01:33:34.146Z,1554860014.146 [BR_Ping1D](INFO): Stopped
2019-04-10T01:33:36.142Z,1554860016.142 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T01:33:36.142Z,1554860016.142 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-10T01:33:36.142Z,1554860016.142 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T01:33:36.275Z,1554860016.275 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T01:33:36.276Z,1554860016.276 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-10T01:33:36.557Z,1554860016.557 [CTD_Seabird](INFO): Powering down
2019-04-10T01:33:38.166Z,1554860018.166 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T01:33:38.166Z,1554860018.166 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2019-04-10T01:33:38.166Z,1554860018.166 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T01:33:38.287Z,1554860018.287 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T01:33:38.570Z,1554860018.570 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T01:33:39.451Z,1554860019.451 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T01:33:39.452Z,1554860019.452 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2019-04-10T01:33:41.798Z,1554860021.798 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T01:33:41.799Z,1554860021.799 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T01:34:02.074Z,1554860042.074 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T01:34:02.075Z,1554860042.075 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2019-04-10T01:34:02.075Z,1554860042.075 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T01:34:02.223Z,1554860042.223 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T01:34:02.478Z,1554860042.478 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T01:34:03.599Z,1554860043.599 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T01:34:03.599Z,1554860043.599 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2019-04-10T01:34:05.710Z,1554860045.710 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T01:34:05.711Z,1554860045.711 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T01:34:08.158Z,1554860048.158 [BPC1](ERROR): BPC1A: No match for serial number 03FE in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2019-04-10T01:34:08.160Z,1554860048.160 [BPC1](ERROR): BPC1B: No match for serial number 0451 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2019-04-10T01:34:08.160Z,1554860048.160 [BPC1](FAULT): Failed to parse data from all battery packs.
2019-04-10T01:34:08.160Z,1554860048.160 [BPC1] Data Fault, FailCount= 1
2019-04-10T01:34:08.160Z,1554860048.160 [BPC1](ERROR): Data Fault
2019-04-10T01:34:08.175Z,1554860048.175 [CBIT](ERROR): Data Fault in component: BPC1
2019-04-10T01:34:11.815Z,1554860051.815 [SBIT](IMPORTANT): SBIT PASSED
2019-04-10T01:34:20.358Z,1554860060.358 [CommandLine](IMPORTANT): got command configSet list
2019-04-10T01:34:20.359Z,1554860060.359 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-04-10T01:34:20.359Z,1554860060.359 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-04-10T01:34:20.360Z,1554860060.360 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2019-04-10T01:34:20.407Z,1554860060.407 [MissionManager](IMPORTANT): Started mission Startup
2019-04-10T01:34:20.407Z,1554860060.407 [Startup] Running Loop=1
2019-04-10T01:34:20.407Z,1554860060.407 [Startup](DEBUG): Aggregate::initialize Startup
2019-04-10T01:34:20.408Z,1554860060.408 [Startup:A.GoToSurface] Running Loop=1
2019-04-10T01:34:20.408Z,1554860060.408 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-10T01:34:20.408Z,1554860060.408 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-10T01:34:20.409Z,1554860060.409 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-10T01:34:20.409Z,1554860060.409 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-10T01:34:20.409Z,1554860060.409 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-10T01:34:20.410Z,1554860060.410 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-10T01:34:20.411Z,1554860060.411 [Startup:StartupSatComms] Running Loop=1
2019-04-10T01:34:20.412Z,1554860060.412 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-04-10T01:34:20.412Z,1554860060.412 [Startup:StartupSatComms:A] Running Loop=1
2019-04-10T01:34:20.788Z,1554860060.788 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-04-10T01:34:25.999Z,1554860065.999 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T01:34:25.999Z,1554860065.999 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2019-04-10T01:34:25.999Z,1554860065.999 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T01:34:26.043Z,1554860066.043 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T01:34:26.403Z,1554860066.403 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T01:34:27.248Z,1554860067.248 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T01:34:27.248Z,1554860067.248 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2019-04-10T01:34:29.634Z,1554860069.634 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T01:34:29.634Z,1554860069.634 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T01:34:49.835Z,1554860089.835 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T01:34:49.835Z,1554860089.835 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2019-04-10T01:34:49.835Z,1554860089.835 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T01:34:49.874Z,1554860089.874 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T01:34:50.239Z,1554860090.239 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T01:34:51.087Z,1554860091.087 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T01:34:51.088Z,1554860091.088 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2019-04-10T01:34:53.471Z,1554860093.471 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T01:34:53.471Z,1554860093.471 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T01:34:53.902Z,1554860093.902 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T01:34:53.902Z,1554860093.902 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-04-10T01:34:53.902Z,1554860093.902 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-10T01:34:53.904Z,1554860093.904 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T01:34:53.904Z,1554860093.904 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-04-10T01:34:53.904Z,1554860093.904 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-10T01:34:53.906Z,1554860093.906 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T01:34:53.906Z,1554860093.906 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-04-10T01:34:53.906Z,1554860093.906 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-10T01:34:53.916Z,1554860093.916 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-10T01:34:53.917Z,1554860093.917 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-10T01:34:53.917Z,1554860093.917 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-10T01:34:54.315Z,1554860094.315 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-10T01:34:54.315Z,1554860094.315 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-04-10T01:34:54.315Z,1554860094.315 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-10T01:34:54.315Z,1554860094.315 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-04-10T01:34:54.316Z,1554860094.316 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-10T01:34:54.316Z,1554860094.316 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-04-10T01:34:54.717Z,1554860094.717 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-10T01:35:13.671Z,1554860113.671 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T01:35:13.671Z,1554860113.671 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2019-04-10T01:35:13.671Z,1554860113.671 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T01:35:13.724Z,1554860113.724 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T01:35:14.075Z,1554860114.075 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T01:35:14.925Z,1554860114.925 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T01:35:14.925Z,1554860114.925 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2019-04-10T01:35:17.307Z,1554860117.307 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T01:35:17.307Z,1554860117.307 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T01:35:20.564Z,1554860120.564 [Startup:StartupSatComms:A](INFO): Timed out from 2019-04-10T01:34:20.4Z
2019-04-10T01:35:20.564Z,1554860120.564 [Startup:StartupSatComms:A] Stopped
2019-04-10T01:35:20.564Z,1554860120.564 [Startup:StartupSatComms:B] Running Loop=1
2019-04-10T01:35:20.974Z,1554860120.974 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-10T01:35:33.284Z,1554860133.284 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005073
2019-04-10T01:35:37.506Z,1554860137.506 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T01:35:37.506Z,1554860137.506 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7
2019-04-10T01:35:37.506Z,1554860137.506 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T01:35:37.551Z,1554860137.551 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T01:35:37.910Z,1554860137.910 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T01:35:38.768Z,1554860138.768 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T01:35:38.768Z,1554860138.768 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7
2019-04-10T01:35:39.242Z,1554860139.242 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190410T012549/Courier0004.lzma
2019-04-10T01:35:40.048Z,1554860140.048 [DataOverHttps](INFO): Moved sent file to Logs/20190410T012549/Courier0004.lzma.bak
2019-04-10T01:35:40.048Z,1554860140.048 [DataOverHttps](INFO): SBD MOMSN=10522848
2019-04-10T01:35:41.142Z,1554860141.142 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T01:35:41.143Z,1554860141.143 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T01:35:57.745Z,1554860157.745 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T01:35:57.745Z,1554860157.745 [Aanderaa_O2] No Fault, FailCount= 3
2019-04-10T01:36:00.790Z,1554860160.790 [DataOverHttps](INFO): Sending 312 bytes from file Logs/20190410T013006/Courier0000.lzma
2019-04-10T01:36:01.343Z,1554860161.343 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T01:36:01.343Z,1554860161.343 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 8
2019-04-10T01:36:01.343Z,1554860161.343 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T01:36:01.377Z,1554860161.377 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T01:36:01.377Z,1554860161.378 [CBIT](FAULT): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T01:36:01.596Z,1554860161.596 [DataOverHttps](INFO): Moved sent file to Logs/20190410T013006/Courier0000.lzma.bak
2019-04-10T01:36:01.596Z,1554860161.596 [DataOverHttps](INFO): SBD MOMSN=10522850
2019-04-10T01:36:01.747Z,1554860161.747 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T01:36:06.242Z,1554860166.242 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-10T01:36:06.242Z,1554860166.242 [CTD_Seabird] No Fault, FailCount= 3
2019-04-10T01:36:06.578Z,1554860166.578 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T01:36:06.582Z,1554860166.582 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T01:36:08.210Z,1554860168.210 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T01:36:08.210Z,1554860168.210 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-10T01:36:08.210Z,1554860168.210 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T01:36:08.245Z,1554860168.245 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T01:36:08.734Z,1554860168.734 [Aanderaa_O2](INFO): Powering down
2019-04-10T01:36:09.463Z,1554860169.463 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T01:36:09.463Z,1554860169.463 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-10T01:36:19.630Z,1554860179.630 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T01:36:19.630Z,1554860179.630 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-10T01:36:19.630Z,1554860179.630 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T01:36:19.926Z,1554860179.926 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T01:36:19.926Z,1554860179.926 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-10T01:36:19.926Z,1554860179.926 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T01:36:19.979Z,1554860179.979 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T01:36:19.979Z,1554860179.979 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T01:36:20.034Z,1554860180.034 [CTD_Seabird](INFO): Powering down
2019-04-10T01:36:20.450Z,1554860180.450 [Aanderaa_O2](INFO): Powering down
2019-04-10T01:36:20.775Z,1554860180.775 [Startup:StartupSatComms:B](INFO): Timed out from 2019-04-10T01:35:20.6Z
2019-04-10T01:36:20.775Z,1554860180.775 [Startup:StartupSatComms:B] Stopped
2019-04-10T01:36:20.776Z,1554860180.776 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-04-10T01:36:20.776Z,1554860180.776 [Startup:StartupSatComms] Stopped
2019-04-10T01:36:20.776Z,1554860180.776 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-04-10T01:36:20.777Z,1554860180.777 [Startup](INFO): Completed Startup
2019-04-10T01:36:20.777Z,1554860180.777 [MissionManager](INFO): Startup is completed.
2019-04-10T01:36:20.777Z,1554860180.777 [MissionManager](INFO): Uninitializing Mission Startup
2019-04-10T01:36:20.777Z,1554860180.777 [Startup] Stopped
2019-04-10T01:36:20.777Z,1554860180.777 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-04-10T01:36:20.777Z,1554860180.777 [Startup:A.GoToSurface] Stopped
2019-04-10T01:36:20.777Z,1554860180.777 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-10T01:36:21.183Z,1554860181.183 [MissionManager](IMPORTANT): Started mission Default
2019-04-10T01:36:21.183Z,1554860181.183 [Default] Running Loop=1
2019-04-10T01:36:21.183Z,1554860181.183 [Default](DEBUG): Aggregate::initialize Default
2019-04-10T01:36:21.183Z,1554860181.183 [Default:B.GoToSurface] Running Loop=1
2019-04-10T01:36:21.183Z,1554860181.183 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-10T01:36:21.183Z,1554860181.183 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-10T01:36:21.184Z,1554860181.184 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-10T01:36:21.184Z,1554860181.184 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-10T01:36:21.184Z,1554860181.184 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-10T01:36:21.185Z,1554860181.185 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-10T01:36:21.185Z,1554860181.185 [Default:A.Wait] Running Loop=1
2019-04-10T01:36:21.185Z,1554860181.185 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-04-10T01:36:21.195Z,1554860181.195 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T01:36:21.195Z,1554860181.195 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-10T01:36:21.195Z,1554860181.195 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-10T01:36:21.196Z,1554860181.196 [CTD_Seabird] No Fault, FailCount= 1
2019-04-10T01:36:21.378Z,1554860181.378 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T01:36:21.380Z,1554860181.380 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T01:36:31.655Z,1554860191.655 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T01:36:31.655Z,1554860191.655 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-10T01:36:31.655Z,1554860191.655 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T01:36:31.724Z,1554860191.724 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T01:36:31.725Z,1554860191.725 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-10T01:36:31.793Z,1554860191.793 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190410T013006/Courier0004.lzma
2019-04-10T01:36:32.178Z,1554860192.178 [Aanderaa_O2](INFO): Powering down
2019-04-10T01:36:32.598Z,1554860192.598 [DataOverHttps](INFO): Moved sent file to Logs/20190410T013006/Courier0004.lzma.bak
2019-04-10T01:36:32.599Z,1554860192.599 [DataOverHttps](INFO): SBD MOMSN=10522863
2019-04-10T01:36:34.430Z,1554860194.430 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T01:36:34.430Z,1554860194.430 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-10T01:36:34.430Z,1554860194.430 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T01:36:34.508Z,1554860194.508 [Default:A.Wait](INFO): Done Waiting.
2019-04-10T01:36:34.508Z,1554860194.508 [Default:A.Wait] Stopped
2019-04-10T01:36:34.508Z,1554860194.508 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-04-10T01:36:34.517Z,1554860194.517 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T01:36:34.834Z,1554860194.834 [CTD_Seabird](INFO): Powering down
2019-04-10T01:36:34.920Z,1554860194.920 [Default:CheckIn] Running Loop=1
2019-04-10T01:36:34.920Z,1554860194.920 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-10T01:36:34.920Z,1554860194.920 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-10T01:36:35.324Z,1554860195.324 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-04-10T01:36:36.156Z,1554860196.156 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-10T01:36:36.156Z,1554860196.156 [CTD_Seabird] No Fault, FailCount= 2
2019-04-10T01:36:36.178Z,1554860196.178 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T01:36:36.180Z,1554860196.180 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T01:36:40.112Z,1554860200.112 [CommandLine](IMPORTANT): got command restart application
2019-04-10T01:36:41.118Z,1554860201.118 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T01:36:41.118Z,1554860201.118 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:36:41.138Z,1554860201.138 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-04-10T01:36:41.138Z,1554860201.138 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:36:41.139Z,1554860201.139 [CommandLine](INFO): Join timeout helper Thread ID is 1895
2019-04-10T01:36:41.139Z,1554860201.139 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-04-10T01:36:41.139Z,1554860201.139 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:36:41.140Z,1554860201.140 [NavChartDb](INFO): Join timeout helper Thread ID is 1896
2019-04-10T01:36:41.372Z,1554860201.372 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T01:36:41.372Z,1554860201.372 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:36:41.379Z,1554860201.379 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-04-10T01:36:41.380Z,1554860201.380 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:36:41.380Z,1554860201.380 [Radio_Surface](INFO): Join timeout helper Thread ID is 1897
2019-04-10T01:36:41.775Z,1554860201.775 [Radio_Surface](INFO): Powering down
2019-04-10T01:36:41.776Z,1554860201.776 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T01:36:41.776Z,1554860201.776 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:36:41.783Z,1554860201.783 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-04-10T01:36:41.784Z,1554860201.784 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:36:41.784Z,1554860201.784 [DataOverHttps](INFO): Join timeout helper Thread ID is 1898
2019-04-10T01:36:41.910Z,1554860201.910 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T01:36:41.911Z,1554860201.911 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:36:41.926Z,1554860201.926 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-04-10T01:36:41.926Z,1554860201.926 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:36:41.926Z,1554860201.926 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1899
2019-04-10T01:36:42.310Z,1554860202.310 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T01:36:42.311Z,1554860202.311 [WetLabsBB2FL](INFO): Powering down
2019-04-10T01:36:42.311Z,1554860202.311 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:36:42.327Z,1554860202.327 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-04-10T01:36:42.327Z,1554860202.327 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:36:42.327Z,1554860202.327 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1900
2019-04-10T01:36:43.206Z,1554860203.206 [CTD_Seabird](INFO): Powering down
2019-04-10T01:36:43.338Z,1554860203.338 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T01:36:43.338Z,1554860203.338 [CTD_Seabird](INFO): Powering down
2019-04-10T01:36:43.350Z,1554860203.350 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:36:43.355Z,1554860203.355 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-04-10T01:36:43.355Z,1554860203.355 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:36:43.355Z,1554860203.355 [logger](INFO): Join timeout helper Thread ID is 1901
2019-04-10T01:36:43.410Z,1554860203.410 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T01:36:43.410Z,1554860203.410 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:36:43.415Z,1554860203.415 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-04-10T01:36:43.415Z,1554860203.415 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:36:43.415Z,1554860203.415 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-04-10T01:36:43.415Z,1554860203.415 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:36:43.416Z,1554860203.416 [controlThread](INFO): Join timeout helper Thread ID is 1902
2019-04-10T01:36:43.770Z,1554860203.770 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T01:36:43.770Z,1554860203.770 [controlThread](DEBUG): Uninitializing ControlThread
2019-04-10T01:36:43.771Z,1554860203.771 [Aanderaa_O2](INFO): Powering down
2019-04-10T01:36:43.773Z,1554860203.773 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T01:36:43.774Z,1554860203.774 [NAL9602](INFO): Powering down
2019-04-10T01:36:43.775Z,1554860203.775 [BR_Ping1D](INFO): uninitialize
2019-04-10T01:36:43.775Z,1554860203.775 [BR_Ping1D](INFO): Powering down
2019-04-10T01:36:43.776Z,1554860203.776 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-04-10T01:36:43.777Z,1554860203.777 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-04-10T01:36:43.777Z,1554860203.777 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-04-10T01:36:43.778Z,1554860203.778 [MissionManager](INFO): Uninitializing Mission Default
2019-04-10T01:36:43.778Z,1554860203.778 [Default] Stopped
2019-04-10T01:36:43.778Z,1554860203.778 [Default](DEBUG): Aggregate::uninitialize Default
2019-04-10T01:36:43.778Z,1554860203.778 [Default:B.GoToSurface] Stopped
2019-04-10T01:36:43.778Z,1554860203.778 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-10T01:36:43.778Z,1554860203.778 [Default:CheckIn] Stopped
2019-04-10T01:36:43.779Z,1554860203.779 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-10T01:36:43.779Z,1554860203.779 [Default:CheckIn:Read_GPS] Stopped
2019-04-10T01:36:43.781Z,1554860203.781 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-04-10T01:36:43.781Z,1554860203.781 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-04-10T01:36:43.781Z,1554860203.781 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-04-10T01:36:43.782Z,1554860203.782 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-04-10T01:36:43.782Z,1554860203.782 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-04-10T01:36:43.782Z,1554860203.782 [BuoyancyServo](INFO): Powering down
2019-04-10T01:36:43.794Z,1554860203.794 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-10T01:36:43.794Z,1554860203.794 [ElevatorServo](INFO): Powering down
2019-04-10T01:36:43.795Z,1554860203.795 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-10T01:36:43.795Z,1554860203.795 [MassServo](INFO): Powering down
2019-04-10T01:36:43.796Z,1554860203.796 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-10T01:36:43.796Z,1554860203.796 [RudderServo](INFO): Powering down
2019-04-10T01:36:43.797Z,1554860203.797 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-04-10T01:36:43.797Z,1554860203.797 [ThrusterServo](INFO): Powering down
2019-04-10T01:36:43.798Z,1554860203.798 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-04-10T01:36:43.798Z,1554860203.798 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-04-10T01:36:43.798Z,1554860203.798 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-04-10T01:36:43.799Z,1554860203.799 [CBIT](DEBUG): Powering off loads.
2019-04-10T01:36:43.810Z,1554860203.810 [CBIT](DEBUG): Disabling WDT.
2019-04-10T01:36:43.822Z,1554860203.822 [CBIT](DEBUG): Opening all GF detection circuits.
2019-04-10T01:36:43.823Z,1554860203.823 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:36:43.870Z,1554860203.870 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:36:43.875Z,1554860203.875 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:36:43.942Z,1554860203.942 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:36:43.946Z,1554860203.946 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:36:44.003Z,1554860204.003 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:36:44.057Z,1554860204.057 [logger ThreadHandler](INFO): Thread cancelled.