2019-04-09T22:57:38.693Z,1554850658.693 [Supervisor](DEBUG): Initializing supervisor.
2019-04-09T22:57:38.696Z,1554850658.696 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-09T22:57:38.697Z,1554850658.697 [SyncHandler](INFO): Protected caller Thread ID is 7574
2019-04-09T22:57:38.697Z,1554850658.697 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-04-09T22:57:38.699Z,1554850658.699 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-04-09T22:57:38.699Z,1554850658.699 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7575
2019-04-09T22:57:38.702Z,1554850658.702 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-04-09T22:57:38.714Z,1554850658.714 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-04-09T22:57:38.715Z,1554850658.715 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-04-09T22:57:38.716Z,1554850658.716 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7576
2019-04-09T22:57:38.716Z,1554850658.716 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-04-09T22:57:38.717Z,1554850658.717 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-04-09T22:57:38.718Z,1554850658.718 [logger ThreadHandler](INFO): Protected caller Thread ID is 7577
2019-04-09T22:57:38.720Z,1554850658.720 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-04-09T22:57:38.720Z,1554850658.720 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-04-09T22:57:38.722Z,1554850658.722 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-04-09T22:57:38.819Z,1554850658.819 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-04-09T22:57:38.819Z,1554850658.819 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-04-09T22:57:39.022Z,1554850659.022 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-04-09T22:57:39.023Z,1554850659.023 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-04-09T22:57:39.161Z,1554850659.161 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-04-09T22:57:39.162Z,1554850659.162 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-04-09T22:57:39.625Z,1554850659.625 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-04-09T22:57:39.625Z,1554850659.625 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-04-09T22:57:40.073Z,1554850660.073 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-04-09T22:57:40.074Z,1554850660.074 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-04-09T22:57:40.661Z,1554850660.661 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-04-09T22:57:40.662Z,1554850660.662 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-04-09T22:57:41.116Z,1554850661.116 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-04-09T22:57:41.116Z,1554850661.116 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-04-09T22:57:41.464Z,1554850661.464 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-04-09T22:57:41.465Z,1554850661.465 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-04-09T22:57:41.863Z,1554850661.863 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-04-09T22:57:41.864Z,1554850661.864 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-04-09T22:57:42.015Z,1554850662.015 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-04-09T22:57:42.015Z,1554850662.015 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-04-09T22:57:42.124Z,1554850662.124 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-04-09T22:57:42.124Z,1554850662.124 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-04-09T22:57:42.206Z,1554850662.206 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-04-09T22:57:42.309Z,1554850662.309 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-04-09T22:57:42.309Z,1554850662.309 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-04-09T22:57:42.505Z,1554850662.505 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-04-09T22:57:42.506Z,1554850662.506 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-04-09T22:57:42.716Z,1554850662.716 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-04-09T22:57:42.717Z,1554850662.717 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-04-09T22:57:42.718Z,1554850662.718 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-04-09T22:57:42.803Z,1554850662.803 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-04-09T22:57:43.040Z,1554850663.040 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-04-09T22:57:43.040Z,1554850663.040 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-04-09T22:57:43.136Z,1554850663.136 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-04-09T22:57:43.311Z,1554850663.311 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-04-09T22:57:43.526Z,1554850663.526 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-04-09T22:57:43.612Z,1554850663.612 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-04-09T22:57:43.711Z,1554850663.711 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-04-09T22:57:43.805Z,1554850663.805 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-04-09T22:57:43.958Z,1554850663.958 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-04-09T22:57:44.064Z,1554850664.064 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-04-09T22:57:44.163Z,1554850664.163 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2019-04-09T22:57:44.164Z,1554850664.164 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-04-09T22:57:44.168Z,1554850664.168 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-04-09T22:57:44.274Z,1554850664.274 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-04-09T22:57:44.274Z,1554850664.274 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-04-09T22:57:44.405Z,1554850664.405 [BuoyancyServo] Loaded
2019-04-09T22:57:44.405Z,1554850664.405 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-04-09T22:57:44.421Z,1554850664.421 [ElevatorServo] Loaded
2019-04-09T22:57:44.421Z,1554850664.421 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-04-09T22:57:44.436Z,1554850664.436 [MassServo] Loaded
2019-04-09T22:57:44.436Z,1554850664.436 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-04-09T22:57:44.452Z,1554850664.452 [RudderServo] Loaded
2019-04-09T22:57:44.452Z,1554850664.452 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-04-09T22:57:44.466Z,1554850664.466 [ThrusterServo] Loaded
2019-04-09T22:57:44.467Z,1554850664.467 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-04-09T22:57:44.467Z,1554850664.467 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-04-09T22:57:44.468Z,1554850664.468 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-04-09T22:57:44.614Z,1554850664.614 [SBIT](DEBUG): Construct Startup Built In Test.
2019-04-09T22:57:44.643Z,1554850664.643 [SBIT] Loaded
2019-04-09T22:57:44.643Z,1554850664.643 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-04-09T22:57:44.644Z,1554850664.644 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-04-09T22:57:44.672Z,1554850664.672 [IBIT] Loaded
2019-04-09T22:57:44.672Z,1554850664.672 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-04-09T22:57:44.676Z,1554850664.676 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-04-09T22:57:45.077Z,1554850665.077 [CBIT] Loaded
2019-04-09T22:57:45.078Z,1554850665.078 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-04-09T22:57:45.078Z,1554850665.078 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-04-09T22:57:45.079Z,1554850665.079 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-04-09T22:57:45.274Z,1554850665.274 [Aanderaa_O2] Loaded
2019-04-09T22:57:45.274Z,1554850665.274 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-04-09T22:57:45.284Z,1554850665.284 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-04-09T22:57:45.289Z,1554850665.289 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-04-09T22:57:45.291Z,1554850665.291 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-04-09T22:57:45.296Z,1554850665.296 [CTD_Seabird](INFO): created writer for : depth
2019-04-09T22:57:45.296Z,1554850665.296 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-04-09T22:57:45.302Z,1554850665.302 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-04-09T22:57:45.303Z,1554850665.303 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-04-09T22:57:45.308Z,1554850665.308 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-04-09T22:57:45.309Z,1554850665.309 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-04-09T22:57:45.314Z,1554850665.314 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-04-09T22:57:45.315Z,1554850665.315 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-04-09T22:57:45.321Z,1554850665.321 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-04-09T22:57:45.321Z,1554850665.321 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-04-09T22:57:45.326Z,1554850665.326 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-04-09T22:57:45.354Z,1554850665.354 [CTD_Seabird] Loaded
2019-04-09T22:57:45.354Z,1554850665.354 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-04-09T22:57:45.355Z,1554850665.355 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406654E0
2019-04-09T22:57:45.356Z,1554850665.356 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7656
2019-04-09T22:57:45.382Z,1554850665.382 [ESPComponent] Loaded
2019-04-09T22:57:45.382Z,1554850665.382 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-04-09T22:57:45.396Z,1554850665.396 [PAR_Licor] Loaded
2019-04-09T22:57:45.397Z,1554850665.397 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-04-09T22:57:45.446Z,1554850665.446 [WetLabsBB2FL] Loaded
2019-04-09T22:57:45.446Z,1554850665.446 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-04-09T22:57:45.447Z,1554850665.447 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406954E0
2019-04-09T22:57:45.448Z,1554850665.448 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7657
2019-04-09T22:57:45.448Z,1554850665.448 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-04-09T22:57:45.449Z,1554850665.449 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-04-09T22:57:45.492Z,1554850665.492 [DepthRateCalculator] Loaded
2019-04-09T22:57:45.492Z,1554850665.492 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-04-09T22:57:45.498Z,1554850665.498 [PitchRateCalculator] Loaded
2019-04-09T22:57:45.498Z,1554850665.498 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-04-09T22:57:45.515Z,1554850665.515 [SpeedCalculator] Loaded
2019-04-09T22:57:45.515Z,1554850665.515 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-04-09T22:57:45.537Z,1554850665.537 [TempGradientCalculator] Loaded
2019-04-09T22:57:45.537Z,1554850665.537 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-04-09T22:57:45.543Z,1554850665.543 [YawRateCalculator] Loaded
2019-04-09T22:57:45.544Z,1554850665.544 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-04-09T22:57:45.577Z,1554850665.577 [ElevatorOffsetCalculator] Loaded
2019-04-09T22:57:45.578Z,1554850665.578 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-04-09T22:57:45.578Z,1554850665.578 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-04-09T22:57:45.579Z,1554850665.579 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-04-09T22:57:45.607Z,1554850665.607 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-04-09T22:57:45.607Z,1554850665.607 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-04-09T22:57:45.713Z,1554850665.713 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-04-09T22:57:45.714Z,1554850665.714 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-04-09T22:57:45.775Z,1554850665.775 [DeadReckonUsingSpeedCalculator] Loaded
2019-04-09T22:57:45.776Z,1554850665.776 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-04-09T22:57:45.838Z,1554850665.838 [DeadReckonWithRespectToSeafloor] Loaded
2019-04-09T22:57:45.838Z,1554850665.838 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-04-09T22:57:45.859Z,1554850665.859 [NavChart] Loaded
2019-04-09T22:57:45.859Z,1554850665.859 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-04-09T22:57:45.863Z,1554850665.863 [UniversalFixResidualReporter] Loaded
2019-04-09T22:57:45.864Z,1554850665.864 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-04-09T22:57:45.864Z,1554850665.864 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-04-09T22:57:45.865Z,1554850665.865 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-04-09T22:57:46.823Z,1554850666.823 [AcousticModem_Benthos_ATM900] Loaded
2019-04-09T22:57:46.823Z,1554850666.823 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-04-09T22:57:46.895Z,1554850666.895 [DataOverHttps] Loaded
2019-04-09T22:57:46.895Z,1554850666.895 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-04-09T22:57:46.896Z,1554850666.896 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409714E0
2019-04-09T22:57:46.896Z,1554850666.896 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7658
2019-04-09T22:57:46.910Z,1554850666.910 [Depth_Keller] Loaded
2019-04-09T22:57:46.910Z,1554850666.910 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-04-09T22:57:47.006Z,1554850667.006 [NAL9602] Loaded
2019-04-09T22:57:47.006Z,1554850667.006 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-04-09T22:57:47.012Z,1554850667.012 [Onboard] Loaded
2019-04-09T22:57:47.012Z,1554850667.012 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-04-09T22:57:47.015Z,1554850667.015 [Radio_Surface] Loaded
2019-04-09T22:57:47.015Z,1554850667.015 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-04-09T22:57:47.016Z,1554850667.016 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A14E0
2019-04-09T22:57:47.017Z,1554850667.017 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7659
2019-04-09T22:57:48.369Z,1554850668.369 [BPC1] Loaded
2019-04-09T22:57:48.370Z,1554850668.370 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-04-09T22:57:48.401Z,1554850668.401 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minrange
2019-04-09T22:57:48.403Z,1554850668.403 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.maxrange
2019-04-09T22:57:48.405Z,1554850668.405 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.soundspeed
2019-04-09T22:57:48.407Z,1554850668.407 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.automode
2019-04-09T22:57:48.409Z,1554850668.409 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.gainindex
2019-04-09T22:57:48.411Z,1554850668.411 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minconfidence
2019-04-09T22:57:48.413Z,1554850668.413 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.pingenable
2019-04-09T22:57:48.416Z,1554850668.416 [BR_Ping1D] Loaded
2019-04-09T22:57:48.416Z,1554850668.416 [ComponentRegistry](DEBUG): SyncComponent "BR_Ping1D" handled in the control thread.
2019-04-09T22:57:48.417Z,1554850668.417 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-04-09T22:57:48.417Z,1554850668.417 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-04-09T22:57:48.485Z,1554850668.485 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-04-09T22:57:48.486Z,1554850668.486 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-04-09T22:57:48.557Z,1554850668.557 [VerticalControl](DEBUG): Construct VerticalControl.
2019-04-09T22:57:48.643Z,1554850668.643 [VerticalControl] Loaded
2019-04-09T22:57:48.643Z,1554850668.643 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-04-09T22:57:48.644Z,1554850668.644 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-04-09T22:57:48.703Z,1554850668.703 [HorizontalControl] Loaded
2019-04-09T22:57:48.703Z,1554850668.703 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-04-09T22:57:48.704Z,1554850668.704 [SpeedControl](DEBUG): Construct SpeedControl.
2019-04-09T22:57:48.705Z,1554850668.705 [SpeedControl] Loaded
2019-04-09T22:57:48.706Z,1554850668.706 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-04-09T22:57:48.706Z,1554850668.706 [LoopControl](DEBUG): Construct LoopControl.
2019-04-09T22:57:48.707Z,1554850668.707 [LoopControl] Loaded
2019-04-09T22:57:48.707Z,1554850668.707 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-04-09T22:57:48.708Z,1554850668.708 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-04-09T22:57:48.708Z,1554850668.708 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-04-09T22:57:48.722Z,1554850668.722 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-04-09T22:57:48.722Z,1554850668.722 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-04-09T22:57:49.020Z,1554850669.020 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-04-09T22:57:49.024Z,1554850669.024 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-04-09T22:57:49.025Z,1554850669.025 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-04-09T22:57:49.032Z,1554850669.032 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-04-09T22:57:49.033Z,1554850669.033 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACE4E0
2019-04-09T22:57:49.033Z,1554850669.033 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7660
2019-04-09T22:57:49.038Z,1554850669.038 [Supervisor](INFO): Main Thread ID is 4347
2019-04-09T22:57:49.038Z,1554850669.038 [Supervisor](DEBUG): Running supervisor.
2019-04-09T22:57:49.039Z,1554850669.039 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7661
2019-04-09T22:57:49.041Z,1554850669.041 [controlThread ThreadHandler](INFO): Handler Thread ID is 7662
2019-04-09T22:57:49.042Z,1554850669.042 [controlThread](DEBUG): Initializing ControlThread
2019-04-09T22:57:49.043Z,1554850669.043 [SBIT](INFO): Initialize SBIT Component.
2019-04-09T22:57:49.044Z,1554850669.044 [SBIT](IMPORTANT): git: 2019-03-18-31-ge98d5dc
2019-04-09T22:57:49.044Z,1554850669.044 [SBIT](INFO): git hash: e98d5dc50cbc39e402f19b9946ff4dc0776c8725
2019-04-09T22:57:49.044Z,1554850669.044 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-04-09T22:57:49.046Z,1554850669.046 [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-09T22:57:49.047Z,1554850669.047 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-04-09T22:57:49.048Z,1554850669.048 [IBIT](INFO): Initialize IBIT Component.
2019-04-09T22:57:49.048Z,1554850669.048 [CBIT](DEBUG): Initialize CBIT Component.
2019-04-09T22:57:49.049Z,1554850669.049 [logger ThreadHandler](INFO): Handler Thread ID is 7663
2019-04-09T22:57:49.062Z,1554850669.062 [CBIT](DEBUG): Initialized mux pins.
2019-04-09T22:57:49.062Z,1554850669.062 [CBIT](DEBUG): Initializing the watchdog timer.
2019-04-09T22:57:49.070Z,1554850669.070 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7664
2019-04-09T22:57:49.071Z,1554850669.071 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T22:57:49.075Z,1554850669.075 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T22:57:49.076Z,1554850669.076 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7666
2019-04-09T22:57:49.077Z,1554850669.077 [WetLabsBB2FL](INFO): Powering down
2019-04-09T22:57:49.086Z,1554850669.086 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-04-09T22:57:49.086Z,1554850669.086 [CBIT](DEBUG): Initializing heartbeat.
2019-04-09T22:57:49.106Z,1554850669.106 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7667
2019-04-09T22:57:49.107Z,1554850669.107 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-04-09T22:57:49.115Z,1554850669.115 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7668
2019-04-09T22:57:49.134Z,1554850669.134 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7669
2019-04-09T22:57:49.137Z,1554850669.137 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-09T22:57:49.137Z,1554850669.137 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-04-09T22:57:49.138Z,1554850669.138 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-09T22:57:49.138Z,1554850669.138 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-04-09T22:57:49.138Z,1554850669.138 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-09T22:57:49.139Z,1554850669.139 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-04-09T22:57:49.139Z,1554850669.139 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-09T22:57:49.139Z,1554850669.139 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-04-09T22:57:49.139Z,1554850669.139 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-09T22:57:49.139Z,1554850669.139 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-04-09T22:57:49.139Z,1554850669.139 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-09T22:57:49.140Z,1554850669.140 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-04-09T22:57:49.140Z,1554850669.140 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-09T22:57:49.140Z,1554850669.140 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-04-09T22:57:49.140Z,1554850669.140 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-09T22:57:49.140Z,1554850669.140 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-04-09T22:57:49.158Z,1554850669.158 [CBIT](DEBUG): Deactivating GF circuits.
2019-04-09T22:57:49.158Z,1554850669.158 [CBIT](DEBUG): Deactivating emergency mode.
2019-04-09T22:57:49.194Z,1554850669.194 [CBIT](DEBUG): Backplane powered.
2019-04-09T22:57:49.199Z,1554850669.199 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-04-09T22:57:49.199Z,1554850669.199 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-04-09T22:57:49.199Z,1554850669.199 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-04-09T22:57:49.200Z,1554850669.200 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-04-09T22:57:49.200Z,1554850669.200 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-04-09T22:57:49.201Z,1554850669.201 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-04-09T22:57:49.202Z,1554850669.202 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T22:57:49.203Z,1554850669.203 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-04-09T22:57:49.203Z,1554850669.203 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-04-09T22:57:49.211Z,1554850669.211 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-04-09T22:57:49.213Z,1554850669.213 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-04-09T22:57:49.222Z,1554850669.222 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-04-09T22:57:49.222Z,1554850669.222 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-04-09T22:57:49.223Z,1554850669.223 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-04-09T22:57:49.255Z,1554850669.255 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T22:57:49.286Z,1554850669.286 [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-09T22:57:49.287Z,1554850669.287 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-04-09T22:57:49.375Z,1554850669.375 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-04-09T22:57:49.376Z,1554850669.376 [Default:A.Wait](DEBUG): Construct Wait.
2019-04-09T22:57:49.378Z,1554850669.378 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T22:57:49.438Z,1554850669.438 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-04-09T22:57:49.441Z,1554850669.441 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-04-09T22:57:49.463Z,1554850669.463 [Default:E.Execute](DEBUG): Construct Execute.
2019-04-09T22:57:49.466Z,1554850669.466 [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-09T22:57:49.490Z,1554850669.490 [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-09T22:57:49.512Z,1554850669.512 [ESPComponent](INFO): powering down ESP
2019-04-09T22:57:49.518Z,1554850669.518 [Radio_Surface](INFO): Powering up
2019-04-09T22:57:49.799Z,1554850669.799 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T22:57:49.800Z,1554850669.800 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T22:57:50.004Z,1554850670.004 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-04-09T22:57:50.010Z,1554850670.010 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-04-09T22:57:50.012Z,1554850670.012 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-09T22:57:50.018Z,1554850670.018 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-09T22:57:50.019Z,1554850670.019 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T22:57:50.026Z,1554850670.026 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T22:57:50.027Z,1554850670.027 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-09T22:57:50.034Z,1554850670.034 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-09T22:57:50.035Z,1554850670.035 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-04-09T22:57:50.042Z,1554850670.042 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-04-09T22:57:50.045Z,1554850670.045 [CommandLine](FAULT): Scheduling is paused
2019-04-09T22:57:50.045Z,1554850670.045 [CBIT](INFO): Critical error at 20190409T225748
2019-04-09T22:57:50.045Z,1554850670.045 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-04-09T22:57:50.594Z,1554850670.594 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-09T22:57:50.594Z,1554850670.594 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T22:57:50.594Z,1554850670.594 [PAR_Licor](ERROR): Data Fault
2019-04-09T22:57:50.641Z,1554850670.641 [BR_Ping1D](INFO): startup: 0
2019-04-09T22:57:50.641Z,1554850670.641 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T22:57:50.654Z,1554850670.654 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T22:57:50.654Z,1554850670.654 [BR_Ping1D](INFO): data 011E
2019-04-09T22:57:50.654Z,1554850670.654 [BR_Ping1D](INFO): 42520200EC030000011EA401
2019-04-09T22:57:50.655Z,1554850670.655 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T22:57:50.655Z,1554850670.655 [BR_Ping1D](INFO): 42520100EB030000840115
2019-04-09T22:57:50.655Z,1554850670.655 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T22:57:50.655Z,1554850670.655 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-09T22:57:50.656Z,1554850670.656 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T22:57:50.656Z,1554850670.656 [BR_Ping1D](INFO): 42520100EE030000870115
2019-04-09T22:57:51.005Z,1554850671.005 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T22:57:51.006Z,1554850671.006 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T22:57:51.006Z,1554850671.006 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T22:57:51.047Z,1554850671.047 [BR_Ping1D](INFO): Starting
2019-04-09T22:57:51.437Z,1554850671.437 [BR_Ping1D](INFO): Starting
2019-04-09T22:57:51.437Z,1554850671.437 [BR_Ping1D](INFO): readConfig
2019-04-09T22:57:51.438Z,1554850671.438 [BR_Ping1D](INFO): startup: 1
2019-04-09T22:57:51.438Z,1554850671.438 [BR_Ping1D](INFO): UART requesting status
2019-04-09T22:57:51.438Z,1554850671.438 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-09T22:57:51.446Z,1554850671.446 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-09T22:57:51.454Z,1554850671.454 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-09T22:57:51.970Z,1554850671.970 [PAR_Licor](INFO): A/D timeout, 9 tries over 150 ms

2019-04-09T22:57:51.970Z,1554850671.970 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T22:57:51.970Z,1554850671.970 [PAR_Licor](ERROR): Data Fault
2019-04-09T22:57:52.005Z,1554850672.005 [BR_Ping1D](INFO): Starting
2019-04-09T22:57:52.006Z,1554850672.006 [BR_Ping1D](INFO): readConfig
2019-04-09T22:57:52.006Z,1554850672.006 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:57:52.006Z,1554850672.006 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:57:52.006Z,1554850672.006 [BR_Ping1D](INFO): elapsed 1.3646
2019-04-09T22:57:52.007Z,1554850672.007 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:57:52.007Z,1554850672.007 [BR_Ping1D](INFO): No UART data
2019-04-09T22:57:52.007Z,1554850672.007 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:57:52.007Z,1554850672.007 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:57:52.050Z,1554850672.050 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T22:57:52.050Z,1554850672.050 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T22:57:52.050Z,1554850672.050 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T22:57:52.245Z,1554850672.245 [BR_Ping1D](INFO): Starting
2019-04-09T22:57:52.245Z,1554850672.245 [BR_Ping1D](INFO): readConfig
2019-04-09T22:57:52.246Z,1554850672.246 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:57:52.246Z,1554850672.246 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:57:52.246Z,1554850672.246 [BR_Ping1D](INFO): elapsed 1.6046
2019-04-09T22:57:52.246Z,1554850672.246 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:57:52.247Z,1554850672.247 [BR_Ping1D](INFO): No UART data
2019-04-09T22:57:52.247Z,1554850672.247 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:57:52.247Z,1554850672.247 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:57:52.650Z,1554850672.650 [BR_Ping1D](INFO): Starting
2019-04-09T22:57:52.650Z,1554850672.650 [BR_Ping1D](INFO): readConfig
2019-04-09T22:57:52.650Z,1554850672.650 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:57:52.651Z,1554850672.651 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:57:52.651Z,1554850672.651 [BR_Ping1D](INFO): elapsed 2.0091
2019-04-09T22:57:52.651Z,1554850672.651 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:57:52.651Z,1554850672.651 [BR_Ping1D](INFO): No UART data
2019-04-09T22:57:52.651Z,1554850672.651 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:57:52.652Z,1554850672.652 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:57:53.053Z,1554850673.053 [BR_Ping1D](INFO): Starting
2019-04-09T22:57:53.053Z,1554850673.053 [BR_Ping1D](INFO): readConfig
2019-04-09T22:57:53.054Z,1554850673.054 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:57:53.054Z,1554850673.054 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:57:53.054Z,1554850673.054 [BR_Ping1D](INFO): elapsed 2.4124
2019-04-09T22:57:53.054Z,1554850673.054 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:57:53.054Z,1554850673.054 [BR_Ping1D](INFO): No UART data
2019-04-09T22:57:53.055Z,1554850673.055 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:57:53.055Z,1554850673.055 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:57:53.458Z,1554850673.458 [BR_Ping1D](INFO): Starting
2019-04-09T22:57:53.458Z,1554850673.458 [BR_Ping1D](INFO): readConfig
2019-04-09T22:57:53.459Z,1554850673.459 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:57:53.459Z,1554850673.459 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:57:53.459Z,1554850673.459 [BR_Ping1D](INFO): elapsed 2.8173
2019-04-09T22:57:53.459Z,1554850673.459 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:57:53.459Z,1554850673.459 [BR_Ping1D](INFO): No UART data
2019-04-09T22:57:53.459Z,1554850673.459 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:57:53.460Z,1554850673.460 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:57:54.002Z,1554850674.002 [PAR_Licor](INFO): A/D timeout, 7 tries over 125 ms

2019-04-09T22:57:54.002Z,1554850674.002 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T22:57:54.002Z,1554850674.002 [PAR_Licor](ERROR): Data Fault
2019-04-09T22:57:54.036Z,1554850674.036 [BR_Ping1D](INFO): Starting
2019-04-09T22:57:54.036Z,1554850674.036 [BR_Ping1D](INFO): readConfig
2019-04-09T22:57:54.036Z,1554850674.036 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:57:54.036Z,1554850674.036 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:57:54.036Z,1554850674.036 [BR_Ping1D](INFO): elapsed 3.3948
2019-04-09T22:57:54.037Z,1554850674.037 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:57:54.037Z,1554850674.037 [BR_Ping1D](INFO): No UART data
2019-04-09T22:57:54.037Z,1554850674.037 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:57:54.037Z,1554850674.037 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:57:54.106Z,1554850674.106 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T22:57:54.106Z,1554850674.106 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T22:57:54.106Z,1554850674.106 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T22:57:54.306Z,1554850674.306 [BR_Ping1D](INFO): Starting
2019-04-09T22:57:54.306Z,1554850674.306 [BR_Ping1D](INFO): readConfig
2019-04-09T22:57:54.307Z,1554850674.307 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:57:54.307Z,1554850674.307 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:57:54.307Z,1554850674.307 [BR_Ping1D](INFO): elapsed 3.6654
2019-04-09T22:57:54.307Z,1554850674.307 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:57:54.307Z,1554850674.307 [BR_Ping1D](INFO): No UART data
2019-04-09T22:57:54.308Z,1554850674.308 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:57:54.308Z,1554850674.308 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:57:54.818Z,1554850674.818 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-09T22:57:54.818Z,1554850674.818 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T22:57:54.818Z,1554850674.818 [PAR_Licor](ERROR): Data Fault
2019-04-09T22:57:54.841Z,1554850674.841 [BR_Ping1D](INFO): Starting
2019-04-09T22:57:54.841Z,1554850674.841 [BR_Ping1D](INFO): readConfig
2019-04-09T22:57:54.841Z,1554850674.841 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:57:54.842Z,1554850674.842 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:57:54.842Z,1554850674.842 [BR_Ping1D](INFO): elapsed 4.2001
2019-04-09T22:57:54.842Z,1554850674.842 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:57:54.842Z,1554850674.842 [BR_Ping1D](INFO): No UART data
2019-04-09T22:57:54.842Z,1554850674.842 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:57:54.843Z,1554850674.843 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:57:54.906Z,1554850674.906 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T22:57:54.906Z,1554850674.906 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T22:57:54.906Z,1554850674.906 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T22:57:55.114Z,1554850675.114 [BR_Ping1D](INFO): Starting
2019-04-09T22:57:55.114Z,1554850675.114 [BR_Ping1D](INFO): readConfig
2019-04-09T22:57:55.115Z,1554850675.115 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:57:55.115Z,1554850675.115 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:57:55.115Z,1554850675.115 [BR_Ping1D](INFO): elapsed 4.4733
2019-04-09T22:57:55.115Z,1554850675.115 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:57:55.115Z,1554850675.115 [BR_Ping1D](INFO): No UART data
2019-04-09T22:57:55.116Z,1554850675.116 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:57:55.116Z,1554850675.116 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:57:55.513Z,1554850675.513 [BR_Ping1D](INFO): Starting
2019-04-09T22:57:55.513Z,1554850675.513 [BR_Ping1D](INFO): readConfig
2019-04-09T22:57:55.514Z,1554850675.514 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:57:55.514Z,1554850675.514 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:57:55.514Z,1554850675.514 [BR_Ping1D](INFO): elapsed 4.8724
2019-04-09T22:57:55.514Z,1554850675.514 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:57:55.515Z,1554850675.515 [BR_Ping1D](INFO): No UART data
2019-04-09T22:57:55.515Z,1554850675.515 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:57:55.515Z,1554850675.515 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:57:55.918Z,1554850675.918 [BR_Ping1D](INFO): Starting
2019-04-09T22:57:55.918Z,1554850675.918 [BR_Ping1D](INFO): readConfig
2019-04-09T22:57:55.918Z,1554850675.918 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:57:55.918Z,1554850675.918 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:57:55.918Z,1554850675.918 [BR_Ping1D](INFO): elapsed 5.2768
2019-04-09T22:57:55.919Z,1554850675.919 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:57:55.919Z,1554850675.919 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T22:57:55.919Z,1554850675.919 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-09T22:57:55.919Z,1554850675.919 [BR_Ping1D](ERROR): Data Fault
2019-04-09T22:57:56.200Z,1554850676.200 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T22:57:56.321Z,1554850676.321 [BR_Ping1D](INFO): Stop
2019-04-09T22:57:56.321Z,1554850676.321 [BR_Ping1D](INFO): uninitialize
2019-04-09T22:57:56.321Z,1554850676.321 [BR_Ping1D](INFO): Powering down
2019-04-09T22:57:56.725Z,1554850676.725 [BR_Ping1D](INFO): Stopping
2019-04-09T22:57:57.129Z,1554850677.129 [BR_Ping1D](INFO): Stopping
2019-04-09T22:57:57.646Z,1554850677.646 [PAR_Licor](INFO): A/D timeout, 9 tries over 135 ms

2019-04-09T22:57:57.647Z,1554850677.647 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T22:57:57.647Z,1554850677.647 [PAR_Licor](ERROR): Data Fault
2019-04-09T22:57:57.668Z,1554850677.668 [BR_Ping1D](INFO): Stopped
2019-04-09T22:57:57.763Z,1554850677.763 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T22:57:57.763Z,1554850677.763 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T22:57:57.763Z,1554850677.763 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T22:57:57.763Z,1554850677.763 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T22:57:57.763Z,1554850677.763 [BR_Ping1D] No Fault, FailCount= 1
2019-04-09T22:57:57.938Z,1554850677.938 [BR_Ping1D](INFO): Start
2019-04-09T22:57:58.341Z,1554850678.341 [BR_Ping1D](INFO): Starting
2019-04-09T22:57:58.858Z,1554850678.858 [PAR_Licor](INFO): A/D timeout, 8 tries over 131 ms

2019-04-09T22:57:58.858Z,1554850678.858 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T22:57:58.858Z,1554850678.858 [PAR_Licor](ERROR): Data Fault
2019-04-09T22:57:58.889Z,1554850678.889 [BR_Ping1D](INFO): Starting
2019-04-09T22:57:58.889Z,1554850678.889 [BR_Ping1D](INFO): readConfig
2019-04-09T22:57:58.890Z,1554850678.890 [BR_Ping1D](INFO): startup: 0
2019-04-09T22:57:58.890Z,1554850678.890 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T22:57:58.902Z,1554850678.902 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T22:57:58.902Z,1554850678.902 [BR_Ping1D](INFO): data 011E
2019-04-09T22:57:58.902Z,1554850678.902 [BR_Ping1D](INFO): 42520200EC030000011EA401
2019-04-09T22:57:58.903Z,1554850678.903 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T22:57:58.903Z,1554850678.903 [BR_Ping1D](INFO): 42520100EB030000840115
2019-04-09T22:57:58.903Z,1554850678.903 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T22:57:58.903Z,1554850678.903 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-09T22:57:58.903Z,1554850678.903 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T22:57:58.903Z,1554850678.903 [BR_Ping1D](INFO): 42520100EE030000870115
2019-04-09T22:57:59.094Z,1554850679.094 [CBIT](CRITICAL): Environmental Failure. Press:14.881536 PSI. Humidity:37%. Temp:25 C. ABORTING MISSION
2019-04-09T22:57:59.095Z,1554850679.095 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T22:57:59.095Z,1554850679.095 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T22:57:59.095Z,1554850679.095 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T22:57:59.158Z,1554850679.158 [BR_Ping1D](INFO): Starting
2019-04-09T22:57:59.197Z,1554850679.197 [CBIT](INFO): Critical error at 20190409T225759
2019-04-09T22:57:59.530Z,1554850679.530 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T22:57:59.530Z,1554850679.530 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T22:57:59.530Z,1554850679.530 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T22:57:59.666Z,1554850679.666 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-09T22:57:59.666Z,1554850679.666 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T22:57:59.666Z,1554850679.666 [PAR_Licor](ERROR): Data Fault
2019-04-09T22:57:59.713Z,1554850679.713 [BR_Ping1D](INFO): Starting
2019-04-09T22:57:59.713Z,1554850679.713 [BR_Ping1D](INFO): readConfig
2019-04-09T22:57:59.713Z,1554850679.713 [BR_Ping1D](INFO): startup: 1
2019-04-09T22:57:59.713Z,1554850679.713 [BR_Ping1D](INFO): UART requesting status
2019-04-09T22:57:59.714Z,1554850679.714 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-09T22:57:59.722Z,1554850679.722 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-09T22:57:59.730Z,1554850679.730 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-09T22:57:59.763Z,1554850679.763 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T22:57:59.763Z,1554850679.763 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T22:57:59.763Z,1554850679.763 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T22:57:59.763Z,1554850679.763 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T22:58:00.054Z,1554850680.054 [Aanderaa_O2](INFO): Powering down
2019-04-09T22:58:00.128Z,1554850680.128 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:00.128Z,1554850680.128 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:00.128Z,1554850680.128 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:00.128Z,1554850680.128 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:00.129Z,1554850680.129 [BR_Ping1D](INFO): elapsed 1.2387
2019-04-09T22:58:00.129Z,1554850680.129 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:00.129Z,1554850680.129 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:00.129Z,1554850680.129 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:00.129Z,1554850680.129 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:00.361Z,1554850680.361 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:00.361Z,1554850680.361 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:00.362Z,1554850680.362 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:00.362Z,1554850680.362 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:00.362Z,1554850680.362 [BR_Ping1D](INFO): elapsed 1.4721
2019-04-09T22:58:00.362Z,1554850680.362 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:00.363Z,1554850680.363 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:00.363Z,1554850680.363 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:00.363Z,1554850680.363 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:00.882Z,1554850680.882 [PAR_Licor](INFO): A/D timeout, 8 tries over 138 ms

2019-04-09T22:58:00.882Z,1554850680.882 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T22:58:00.882Z,1554850680.882 [PAR_Licor](ERROR): Data Fault
2019-04-09T22:58:00.918Z,1554850680.918 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:00.918Z,1554850680.918 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:00.918Z,1554850680.918 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:00.918Z,1554850680.918 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:00.918Z,1554850680.918 [BR_Ping1D](INFO): elapsed 2.0285
2019-04-09T22:58:00.919Z,1554850680.919 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:00.919Z,1554850680.919 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:00.919Z,1554850680.919 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:00.919Z,1554850680.919 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:00.967Z,1554850680.967 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T22:58:00.967Z,1554850680.967 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-09T22:58:00.967Z,1554850680.967 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T22:58:00.967Z,1554850680.967 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T22:58:00.967Z,1554850680.967 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T22:58:01.165Z,1554850681.165 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:01.165Z,1554850681.165 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:01.165Z,1554850681.165 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:01.165Z,1554850681.165 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:01.165Z,1554850681.165 [BR_Ping1D](INFO): elapsed 2.2755
2019-04-09T22:58:01.166Z,1554850681.166 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:01.166Z,1554850681.166 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:01.166Z,1554850681.166 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:01.166Z,1554850681.166 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:01.587Z,1554850681.587 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:01.587Z,1554850681.587 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:01.587Z,1554850681.587 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:01.587Z,1554850681.587 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:01.587Z,1554850681.587 [BR_Ping1D](INFO): elapsed 2.6975
2019-04-09T22:58:01.588Z,1554850681.588 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:01.588Z,1554850681.588 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:01.588Z,1554850681.588 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:01.588Z,1554850681.588 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:02.094Z,1554850682.094 [PAR_Licor](INFO): A/D timeout, 8 tries over 138 ms

2019-04-09T22:58:02.094Z,1554850682.094 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T22:58:02.094Z,1554850682.094 [PAR_Licor](ERROR): Data Fault
2019-04-09T22:58:02.126Z,1554850682.126 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T22:58:02.126Z,1554850682.126 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-09T22:58:02.127Z,1554850682.127 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T22:58:02.130Z,1554850682.130 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:02.130Z,1554850682.130 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:02.131Z,1554850682.131 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:02.131Z,1554850682.131 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:02.131Z,1554850682.131 [BR_Ping1D](INFO): elapsed 3.2410
2019-04-09T22:58:02.131Z,1554850682.131 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:02.131Z,1554850682.131 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:02.132Z,1554850682.132 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:02.132Z,1554850682.132 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:02.163Z,1554850682.163 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T22:58:02.163Z,1554850682.163 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T22:58:02.163Z,1554850682.163 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T22:58:02.163Z,1554850682.163 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T22:58:02.381Z,1554850682.381 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:02.382Z,1554850682.382 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:02.382Z,1554850682.382 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:02.382Z,1554850682.382 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:02.382Z,1554850682.382 [BR_Ping1D](INFO): elapsed 3.4923
2019-04-09T22:58:02.383Z,1554850682.383 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:02.383Z,1554850682.383 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:02.383Z,1554850682.383 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:02.383Z,1554850682.383 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:02.530Z,1554850682.530 [CTD_Seabird](INFO): Powering down
2019-04-09T22:58:02.785Z,1554850682.785 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:02.785Z,1554850682.785 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:02.785Z,1554850682.785 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:02.786Z,1554850682.786 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:02.786Z,1554850682.786 [BR_Ping1D](INFO): elapsed 3.8958
2019-04-09T22:58:02.786Z,1554850682.786 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:02.786Z,1554850682.786 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:02.786Z,1554850682.786 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:02.787Z,1554850682.787 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:03.306Z,1554850683.306 [PAR_Licor](INFO): A/D timeout, 8 tries over 138 ms

2019-04-09T22:58:03.306Z,1554850683.306 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T22:58:03.306Z,1554850683.306 [PAR_Licor](ERROR): Data Fault
2019-04-09T22:58:03.341Z,1554850683.341 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:03.342Z,1554850683.342 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:03.342Z,1554850683.342 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:03.342Z,1554850683.342 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:03.342Z,1554850683.342 [BR_Ping1D](INFO): elapsed 4.4523
2019-04-09T22:58:03.342Z,1554850683.342 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:03.343Z,1554850683.343 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:03.343Z,1554850683.343 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:03.343Z,1554850683.343 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:03.391Z,1554850683.391 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T22:58:03.391Z,1554850683.391 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T22:58:03.391Z,1554850683.391 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T22:58:03.593Z,1554850683.593 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:03.593Z,1554850683.593 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:03.593Z,1554850683.593 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:03.594Z,1554850683.594 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:03.594Z,1554850683.594 [BR_Ping1D](INFO): elapsed 4.7039
2019-04-09T22:58:03.594Z,1554850683.594 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:03.594Z,1554850683.594 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:03.594Z,1554850683.594 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:03.595Z,1554850683.595 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:03.627Z,1554850683.627 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T22:58:03.627Z,1554850683.627 [CTD_Seabird] No Fault, FailCount= 1
2019-04-09T22:58:03.906Z,1554850683.906 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T22:58:03.908Z,1554850683.908 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T22:58:03.997Z,1554850683.997 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:03.998Z,1554850683.998 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:03.998Z,1554850683.998 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:03.998Z,1554850683.998 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:03.998Z,1554850683.998 [BR_Ping1D](INFO): elapsed 5.1083
2019-04-09T22:58:03.998Z,1554850683.998 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:03.999Z,1554850683.999 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T22:58:03.999Z,1554850683.999 [BR_Ping1D] Data Fault, FailCount= 2
2019-04-09T22:58:03.999Z,1554850683.999 [BR_Ping1D](ERROR): Data Fault
2019-04-09T22:58:04.035Z,1554850684.035 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T22:58:04.518Z,1554850684.518 [PAR_Licor](INFO): A/D timeout, 8 tries over 138 ms

2019-04-09T22:58:04.518Z,1554850684.518 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T22:58:04.518Z,1554850684.518 [PAR_Licor](ERROR): Data Fault
2019-04-09T22:58:04.553Z,1554850684.553 [BR_Ping1D](INFO): Stop
2019-04-09T22:58:04.553Z,1554850684.553 [BR_Ping1D](INFO): uninitialize
2019-04-09T22:58:04.554Z,1554850684.554 [BR_Ping1D](INFO): Powering down
2019-04-09T22:58:04.643Z,1554850684.643 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T22:58:04.643Z,1554850684.643 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T22:58:04.643Z,1554850684.643 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T22:58:04.806Z,1554850684.806 [BR_Ping1D](INFO): Stopping
2019-04-09T22:58:05.322Z,1554850685.322 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-09T22:58:05.322Z,1554850685.322 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T22:58:05.322Z,1554850685.322 [PAR_Licor](ERROR): Data Fault
2019-04-09T22:58:05.352Z,1554850685.352 [BR_Ping1D](INFO): Stopping
2019-04-09T22:58:05.383Z,1554850685.383 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T22:58:05.383Z,1554850685.383 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T22:58:05.383Z,1554850685.383 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T22:58:05.613Z,1554850685.613 [BR_Ping1D](INFO): Stopped
2019-04-09T22:58:05.663Z,1554850685.663 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T22:58:05.663Z,1554850685.663 [BR_Ping1D] No Fault, FailCount= 2
2019-04-09T22:58:06.126Z,1554850686.126 [PAR_Licor](INFO): A/D timeout, 7 tries over 130 ms

2019-04-09T22:58:06.126Z,1554850686.126 [PAR_Licor] Data Fault, FailCount= 3
2019-04-09T22:58:06.126Z,1554850686.126 [PAR_Licor](ERROR): Data Fault
2019-04-09T22:58:06.147Z,1554850686.147 [BR_Ping1D](INFO): Start
2019-04-09T22:58:06.212Z,1554850686.212 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T22:58:06.212Z,1554850686.212 [CBIT](CRITICAL): Data Fault in component: PAR_Licor
2019-04-09T22:58:06.418Z,1554850686.418 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:06.448Z,1554850686.448 [CBIT](INFO): Critical error at 20190409T225806
2019-04-09T22:58:06.822Z,1554850686.822 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:06.822Z,1554850686.822 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:06.822Z,1554850686.822 [BR_Ping1D](INFO): startup: 0
2019-04-09T22:58:06.822Z,1554850686.822 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T22:58:06.834Z,1554850686.834 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T22:58:06.834Z,1554850686.834 [BR_Ping1D](INFO): data 011E
2019-04-09T22:58:06.834Z,1554850686.834 [BR_Ping1D](INFO): 42520200EC030000011EA401
2019-04-09T22:58:06.835Z,1554850686.835 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T22:58:06.835Z,1554850686.835 [BR_Ping1D](INFO): 42520100EB030000840115
2019-04-09T22:58:06.835Z,1554850686.835 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T22:58:06.835Z,1554850686.835 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-09T22:58:06.835Z,1554850686.835 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T22:58:06.835Z,1554850686.835 [BR_Ping1D](INFO): 42520100EE030000870115
2019-04-09T22:58:07.227Z,1554850687.227 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:07.630Z,1554850687.630 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:07.630Z,1554850687.630 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:07.630Z,1554850687.630 [BR_Ping1D](INFO): startup: 1
2019-04-09T22:58:07.630Z,1554850687.630 [BR_Ping1D](INFO): UART requesting status
2019-04-09T22:58:07.631Z,1554850687.631 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-09T22:58:07.638Z,1554850687.638 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-09T22:58:07.646Z,1554850687.646 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-09T22:58:08.034Z,1554850688.034 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:08.034Z,1554850688.034 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:08.034Z,1554850688.034 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:08.034Z,1554850688.034 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:08.034Z,1554850688.034 [BR_Ping1D](INFO): elapsed 1.2118
2019-04-09T22:58:08.035Z,1554850688.035 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:08.035Z,1554850688.035 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:08.035Z,1554850688.035 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:08.035Z,1554850688.035 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:08.437Z,1554850688.437 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:08.438Z,1554850688.438 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:08.438Z,1554850688.438 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:08.438Z,1554850688.438 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:08.438Z,1554850688.438 [BR_Ping1D](INFO): elapsed 1.6156
2019-04-09T22:58:08.438Z,1554850688.438 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:08.439Z,1554850688.439 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:08.439Z,1554850688.439 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:08.439Z,1554850688.439 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:08.841Z,1554850688.841 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:08.842Z,1554850688.842 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:08.842Z,1554850688.842 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:08.842Z,1554850688.842 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:08.842Z,1554850688.842 [BR_Ping1D](INFO): elapsed 2.0197
2019-04-09T22:58:08.842Z,1554850688.842 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:08.843Z,1554850688.843 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:08.843Z,1554850688.843 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:08.843Z,1554850688.843 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:09.247Z,1554850689.247 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:09.247Z,1554850689.247 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:09.247Z,1554850689.247 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:09.247Z,1554850689.247 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:09.248Z,1554850689.248 [BR_Ping1D](INFO): elapsed 2.4250
2019-04-09T22:58:09.248Z,1554850689.248 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:09.248Z,1554850689.248 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:09.248Z,1554850689.248 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:09.248Z,1554850689.248 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:09.651Z,1554850689.651 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:09.651Z,1554850689.651 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:09.651Z,1554850689.651 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:09.651Z,1554850689.651 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:09.651Z,1554850689.651 [BR_Ping1D](INFO): elapsed 2.8287
2019-04-09T22:58:09.652Z,1554850689.652 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:09.652Z,1554850689.652 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:09.652Z,1554850689.652 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:09.652Z,1554850689.652 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:10.035Z,1554850690.035 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T22:58:10.035Z,1554850690.035 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-04-09T22:58:10.035Z,1554850690.035 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T22:58:10.054Z,1554850690.054 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:10.055Z,1554850690.055 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:10.055Z,1554850690.055 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:10.055Z,1554850690.055 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:10.055Z,1554850690.055 [BR_Ping1D](INFO): elapsed 3.2326
2019-04-09T22:58:10.055Z,1554850690.055 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:10.056Z,1554850690.056 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:10.056Z,1554850690.056 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:10.056Z,1554850690.056 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:10.071Z,1554850690.071 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T22:58:10.439Z,1554850690.439 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T22:58:10.598Z,1554850690.598 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:10.598Z,1554850690.598 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:10.598Z,1554850690.598 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:10.598Z,1554850690.598 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:10.599Z,1554850690.599 [BR_Ping1D](INFO): elapsed 3.7761
2019-04-09T22:58:10.599Z,1554850690.599 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:10.599Z,1554850690.599 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:10.599Z,1554850690.599 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:10.599Z,1554850690.599 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:10.862Z,1554850690.862 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:10.862Z,1554850690.862 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:10.863Z,1554850690.863 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:10.863Z,1554850690.863 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:10.863Z,1554850690.863 [BR_Ping1D](INFO): elapsed 4.0403
2019-04-09T22:58:10.863Z,1554850690.863 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:10.863Z,1554850690.863 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:10.863Z,1554850690.863 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:10.864Z,1554850690.864 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:11.246Z,1554850691.246 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T22:58:11.246Z,1554850691.246 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-09T22:58:11.246Z,1554850691.246 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T22:58:11.271Z,1554850691.271 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:11.271Z,1554850691.271 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:11.271Z,1554850691.271 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:11.271Z,1554850691.271 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:11.271Z,1554850691.271 [BR_Ping1D](INFO): elapsed 4.4488
2019-04-09T22:58:11.272Z,1554850691.272 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:11.272Z,1554850691.272 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:11.272Z,1554850691.272 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:11.272Z,1554850691.272 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:11.287Z,1554850691.287 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T22:58:11.287Z,1554850691.287 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T22:58:11.287Z,1554850691.287 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-04-09T22:58:11.770Z,1554850691.770 [Aanderaa_O2](INFO): Powering down
2019-04-09T22:58:11.790Z,1554850691.790 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:11.790Z,1554850691.790 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:11.791Z,1554850691.791 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:11.791Z,1554850691.791 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:11.791Z,1554850691.791 [BR_Ping1D](INFO): elapsed 4.9683
2019-04-09T22:58:11.791Z,1554850691.791 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:11.791Z,1554850691.791 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:11.791Z,1554850691.791 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:11.792Z,1554850691.792 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:12.074Z,1554850692.074 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:12.074Z,1554850692.074 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:12.074Z,1554850692.074 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:12.074Z,1554850692.074 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:12.075Z,1554850692.075 [BR_Ping1D](INFO): elapsed 5.2521
2019-04-09T22:58:12.075Z,1554850692.075 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:12.075Z,1554850692.075 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T22:58:12.075Z,1554850692.075 [BR_Ping1D] Data Fault, FailCount= 3
2019-04-09T22:58:12.075Z,1554850692.075 [BR_Ping1D](ERROR): Data Fault
2019-04-09T22:58:12.091Z,1554850692.091 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T22:58:12.477Z,1554850692.477 [BR_Ping1D](INFO): Stop
2019-04-09T22:58:12.477Z,1554850692.477 [BR_Ping1D](INFO): uninitialize
2019-04-09T22:58:12.478Z,1554850692.478 [BR_Ping1D](INFO): Powering down
2019-04-09T22:58:12.563Z,1554850692.563 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T22:58:12.563Z,1554850692.563 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-09T22:58:12.883Z,1554850692.883 [BR_Ping1D](INFO): Stopping
2019-04-09T22:58:13.303Z,1554850693.303 [BR_Ping1D](INFO): Stopping
2019-04-09T22:58:13.671Z,1554850693.671 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T22:58:13.671Z,1554850693.671 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T22:58:13.705Z,1554850693.705 [BR_Ping1D](INFO): Stopped
2019-04-09T22:58:13.732Z,1554850693.732 [SBIT](IMPORTANT): Beginning Startup BIT
2019-04-09T22:58:13.736Z,1554850693.736 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T22:58:13.736Z,1554850693.736 [BR_Ping1D] No Fault, FailCount= 3
2019-04-09T22:58:13.737Z,1554850693.737 [CBIT](IMPORTANT): Beginning ground fault scan
2019-04-09T22:58:14.093Z,1554850694.093 [BR_Ping1D](INFO): Start
2019-04-09T22:58:14.495Z,1554850694.495 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:14.901Z,1554850694.901 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:14.901Z,1554850694.901 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:14.902Z,1554850694.902 [BR_Ping1D](INFO): startup: 0
2019-04-09T22:58:14.902Z,1554850694.902 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T22:58:14.914Z,1554850694.914 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T22:58:14.914Z,1554850694.914 [BR_Ping1D](INFO): data 011E
2019-04-09T22:58:14.914Z,1554850694.914 [BR_Ping1D](INFO): 42520200EC030000011EA401
2019-04-09T22:58:14.914Z,1554850694.914 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T22:58:14.915Z,1554850694.915 [BR_Ping1D](INFO): 42520100EB030000840115
2019-04-09T22:58:14.915Z,1554850694.915 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T22:58:14.915Z,1554850694.915 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-09T22:58:14.915Z,1554850694.915 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T22:58:14.915Z,1554850694.915 [BR_Ping1D](INFO): 42520100EE030000870115
2019-04-09T22:58:15.303Z,1554850695.303 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:15.710Z,1554850695.710 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:15.711Z,1554850695.711 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:15.711Z,1554850695.711 [BR_Ping1D](INFO): startup: 1
2019-04-09T22:58:15.711Z,1554850695.711 [BR_Ping1D](INFO): UART requesting status
2019-04-09T22:58:15.711Z,1554850695.711 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-09T22:58:15.718Z,1554850695.718 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-09T22:58:15.726Z,1554850695.726 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-09T22:58:16.207Z,1554850696.207 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:16.207Z,1554850696.207 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:16.207Z,1554850696.207 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:16.207Z,1554850696.207 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:16.208Z,1554850696.208 [BR_Ping1D](INFO): elapsed 1.3056
2019-04-09T22:58:16.208Z,1554850696.208 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:16.208Z,1554850696.208 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:16.208Z,1554850696.208 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:16.208Z,1554850696.208 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:16.605Z,1554850696.605 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:16.605Z,1554850696.605 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:16.606Z,1554850696.606 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:16.606Z,1554850696.606 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:16.606Z,1554850696.606 [BR_Ping1D](INFO): elapsed 1.7041
2019-04-09T22:58:16.606Z,1554850696.606 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:16.606Z,1554850696.606 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:16.607Z,1554850696.607 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:16.607Z,1554850696.607 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:16.958Z,1554850696.958 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T22:58:16.958Z,1554850696.958 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-09T22:58:16.958Z,1554850696.958 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T22:58:17.008Z,1554850697.008 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:17.008Z,1554850697.008 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:17.009Z,1554850697.009 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:17.009Z,1554850697.009 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:17.009Z,1554850697.009 [BR_Ping1D](INFO): elapsed 2.1069
2019-04-09T22:58:17.009Z,1554850697.009 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:17.009Z,1554850697.009 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:17.009Z,1554850697.009 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:17.010Z,1554850697.010 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:17.159Z,1554850697.159 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T22:58:17.362Z,1554850697.362 [CTD_Seabird](INFO): Powering down
2019-04-09T22:58:17.403Z,1554850697.403 [NAL9602](INFO): Powering up NAL9602
2019-04-09T22:58:17.419Z,1554850697.419 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:17.419Z,1554850697.419 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:17.419Z,1554850697.419 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:17.419Z,1554850697.419 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:17.420Z,1554850697.420 [BR_Ping1D](INFO): elapsed 2.5176
2019-04-09T22:58:17.420Z,1554850697.420 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:17.420Z,1554850697.420 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:17.420Z,1554850697.420 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:17.420Z,1554850697.420 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:17.817Z,1554850697.817 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:17.817Z,1554850697.817 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:17.817Z,1554850697.817 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:17.817Z,1554850697.817 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:17.817Z,1554850697.817 [BR_Ping1D](INFO): elapsed 2.9155
2019-04-09T22:58:17.818Z,1554850697.818 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:17.818Z,1554850697.818 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:17.818Z,1554850697.818 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:17.818Z,1554850697.818 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:18.219Z,1554850698.219 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:18.220Z,1554850698.220 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:18.220Z,1554850698.220 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:18.220Z,1554850698.220 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:18.220Z,1554850698.220 [BR_Ping1D](INFO): elapsed 3.3183
2019-04-09T22:58:18.220Z,1554850698.220 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:18.221Z,1554850698.221 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:18.221Z,1554850698.221 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:18.221Z,1554850698.221 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:18.335Z,1554850698.335 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T22:58:18.335Z,1554850698.335 [CTD_Seabird] No Fault, FailCount= 2
2019-04-09T22:58:18.626Z,1554850698.626 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:18.627Z,1554850698.627 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:18.627Z,1554850698.627 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:18.627Z,1554850698.627 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:18.627Z,1554850698.627 [BR_Ping1D](INFO): elapsed 3.7253
2019-04-09T22:58:18.627Z,1554850698.627 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:18.628Z,1554850698.628 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:18.628Z,1554850698.628 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:18.628Z,1554850698.628 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:18.710Z,1554850698.710 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T22:58:18.712Z,1554850698.712 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T22:58:19.033Z,1554850699.033 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:19.033Z,1554850699.033 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:19.033Z,1554850699.033 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:19.034Z,1554850699.034 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:19.034Z,1554850699.034 [BR_Ping1D](INFO): elapsed 4.1319
2019-04-09T22:58:19.034Z,1554850699.034 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:19.034Z,1554850699.034 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:19.034Z,1554850699.034 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:19.035Z,1554850699.035 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:19.436Z,1554850699.436 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:19.436Z,1554850699.436 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:19.436Z,1554850699.436 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:19.436Z,1554850699.436 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:19.436Z,1554850699.436 [BR_Ping1D](INFO): elapsed 4.5345
2019-04-09T22:58:19.437Z,1554850699.437 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:19.437Z,1554850699.437 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:19.437Z,1554850699.437 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:19.437Z,1554850699.437 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:19.836Z,1554850699.836 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:19.836Z,1554850699.836 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:19.837Z,1554850699.837 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:19.837Z,1554850699.837 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:19.837Z,1554850699.837 [BR_Ping1D](INFO): elapsed 4.9351
2019-04-09T22:58:19.837Z,1554850699.837 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:19.837Z,1554850699.837 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:19.838Z,1554850699.838 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:19.838Z,1554850699.838 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:20.240Z,1554850700.240 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:20.240Z,1554850700.240 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:20.240Z,1554850700.240 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:20.240Z,1554850700.240 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:20.241Z,1554850700.241 [BR_Ping1D](INFO): elapsed 5.3387
2019-04-09T22:58:20.241Z,1554850700.241 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:20.241Z,1554850700.241 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T22:58:20.241Z,1554850700.241 [BR_Ping1D] Data Fault, FailCount= 4
2019-04-09T22:58:20.241Z,1554850700.241 [BR_Ping1D](ERROR): Data Fault
2019-04-09T22:58:20.311Z,1554850700.311 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T22:58:20.647Z,1554850700.647 [BR_Ping1D](INFO): Stop
2019-04-09T22:58:20.647Z,1554850700.647 [BR_Ping1D](INFO): uninitialize
2019-04-09T22:58:20.647Z,1554850700.647 [BR_Ping1D](INFO): Powering down
2019-04-09T22:58:21.050Z,1554850701.050 [BR_Ping1D](INFO): Stopping
2019-04-09T22:58:21.454Z,1554850701.454 [BR_Ping1D](INFO): Stopping
2019-04-09T22:58:21.860Z,1554850701.860 [BR_Ping1D](INFO): Stopped
2019-04-09T22:58:21.919Z,1554850701.919 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T22:58:21.919Z,1554850701.919 [BR_Ping1D] No Fault, FailCount= 4
2019-04-09T22:58:22.262Z,1554850702.262 [BR_Ping1D](INFO): Start
2019-04-09T22:58:22.669Z,1554850702.669 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:23.050Z,1554850703.050 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T22:58:23.050Z,1554850703.050 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-09T22:58:23.050Z,1554850703.050 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T22:58:23.084Z,1554850703.084 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:23.084Z,1554850703.084 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:23.084Z,1554850703.084 [BR_Ping1D](INFO): startup: 0
2019-04-09T22:58:23.084Z,1554850703.084 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T22:58:23.098Z,1554850703.098 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T22:58:23.098Z,1554850703.098 [BR_Ping1D](INFO): data 011E
2019-04-09T22:58:23.098Z,1554850703.098 [BR_Ping1D](INFO): 42520200EC030000011EA401
2019-04-09T22:58:23.099Z,1554850703.099 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T22:58:23.099Z,1554850703.099 [BR_Ping1D](INFO): 42520100EB030000840115
2019-04-09T22:58:23.099Z,1554850703.099 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T22:58:23.099Z,1554850703.099 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-09T22:58:23.099Z,1554850703.099 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T22:58:23.099Z,1554850703.099 [BR_Ping1D](INFO): 42520100EE030000870115
2019-04-09T22:58:23.175Z,1554850703.175 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T22:58:23.176Z,1554850703.176 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-09T22:58:23.574Z,1554850703.574 [Aanderaa_O2](INFO): Powering down
2019-04-09T22:58:23.606Z,1554850703.606 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:23.606Z,1554850703.606 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:23.606Z,1554850703.606 [BR_Ping1D](INFO): startup: 1
2019-04-09T22:58:23.606Z,1554850703.606 [BR_Ping1D](INFO): UART requesting status
2019-04-09T22:58:23.607Z,1554850703.607 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-09T22:58:23.614Z,1554850703.614 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-09T22:58:23.622Z,1554850703.622 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-09T22:58:23.879Z,1554850703.879 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:23.879Z,1554850703.879 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:23.880Z,1554850703.880 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:23.880Z,1554850703.880 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:23.880Z,1554850703.880 [BR_Ping1D](INFO): elapsed 0.7955
2019-04-09T22:58:23.880Z,1554850703.880 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:23.881Z,1554850703.881 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:23.881Z,1554850703.881 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:23.881Z,1554850703.881 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:24.283Z,1554850704.283 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:24.284Z,1554850704.284 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:24.284Z,1554850704.284 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:24.284Z,1554850704.284 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:24.284Z,1554850704.284 [BR_Ping1D](INFO): elapsed 1.1995
2019-04-09T22:58:24.284Z,1554850704.284 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:24.285Z,1554850704.285 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:24.285Z,1554850704.285 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:24.285Z,1554850704.285 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:24.686Z,1554850704.686 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:24.686Z,1554850704.686 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:24.687Z,1554850704.687 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:24.687Z,1554850704.687 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:24.687Z,1554850704.687 [BR_Ping1D](INFO): elapsed 1.6023
2019-04-09T22:58:24.687Z,1554850704.687 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:24.687Z,1554850704.687 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:24.687Z,1554850704.687 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:24.688Z,1554850704.688 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:24.763Z,1554850704.763 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.006672
CHAN A1 (24V): -0.127691
CHAN A2 (12V): -0.005105
CHAN A3 (5V): -0.002951
CHAN B0 (3.3V): -0.001046
CHAN B1 (3.15aV): -0.001364
CHAN B2 (3.15bV): -0.001256
CHAN B3 (GND): -0.000077
OPEN: -0.000951
Full Scale Calc: 4.765 mA, -1.589 mA
2019-04-09T22:58:25.091Z,1554850705.091 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:25.091Z,1554850705.091 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:25.092Z,1554850705.092 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:25.092Z,1554850705.092 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:25.092Z,1554850705.092 [BR_Ping1D](INFO): elapsed 2.0073
2019-04-09T22:58:25.092Z,1554850705.092 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:25.092Z,1554850705.092 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:25.092Z,1554850705.092 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:25.093Z,1554850705.093 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:25.495Z,1554850705.495 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:25.496Z,1554850705.496 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:25.496Z,1554850705.496 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:25.496Z,1554850705.496 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:25.496Z,1554850705.496 [BR_Ping1D](INFO): elapsed 2.4115
2019-04-09T22:58:25.496Z,1554850705.496 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:25.497Z,1554850705.497 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:25.497Z,1554850705.497 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:25.497Z,1554850705.497 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:25.896Z,1554850705.896 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:25.896Z,1554850705.896 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:25.897Z,1554850705.897 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:25.897Z,1554850705.897 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:25.897Z,1554850705.897 [BR_Ping1D](INFO): elapsed 2.8124
2019-04-09T22:58:25.897Z,1554850705.897 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:25.897Z,1554850705.897 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:25.898Z,1554850705.898 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:25.898Z,1554850705.898 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:26.303Z,1554850706.303 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:26.303Z,1554850706.303 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:26.303Z,1554850706.303 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:26.303Z,1554850706.303 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:26.303Z,1554850706.303 [BR_Ping1D](INFO): elapsed 3.2188
2019-04-09T22:58:26.304Z,1554850706.304 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:26.304Z,1554850706.304 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:26.304Z,1554850706.304 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:26.304Z,1554850706.304 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:26.704Z,1554850706.704 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:26.704Z,1554850706.704 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:26.704Z,1554850706.704 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:26.704Z,1554850706.704 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:26.705Z,1554850706.705 [BR_Ping1D](INFO): elapsed 3.6200
2019-04-09T22:58:26.705Z,1554850706.705 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:26.705Z,1554850706.705 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:26.705Z,1554850706.705 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:26.705Z,1554850706.705 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:27.107Z,1554850707.107 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:27.107Z,1554850707.107 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:27.107Z,1554850707.107 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:27.107Z,1554850707.107 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:27.107Z,1554850707.107 [BR_Ping1D](INFO): elapsed 4.0226
2019-04-09T22:58:27.107Z,1554850707.107 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:27.108Z,1554850707.108 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:27.108Z,1554850707.108 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:27.108Z,1554850707.108 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:27.518Z,1554850707.518 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:27.518Z,1554850707.518 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:27.518Z,1554850707.518 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:27.518Z,1554850707.518 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:27.518Z,1554850707.518 [BR_Ping1D](INFO): elapsed 4.4338
2019-04-09T22:58:27.519Z,1554850707.519 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:27.519Z,1554850707.519 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:27.519Z,1554850707.519 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:27.519Z,1554850707.519 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:27.933Z,1554850707.933 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:27.933Z,1554850707.933 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:27.933Z,1554850707.933 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:27.933Z,1554850707.933 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:27.933Z,1554850707.933 [BR_Ping1D](INFO): elapsed 4.8487
2019-04-09T22:58:27.934Z,1554850707.934 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:27.934Z,1554850707.934 [BR_Ping1D](INFO): No UART data
2019-04-09T22:58:27.934Z,1554850707.934 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T22:58:27.934Z,1554850707.934 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T22:58:28.308Z,1554850708.308 [NAL9602](INFO): NAL9602 initialized
2019-04-09T22:58:28.330Z,1554850708.330 [BR_Ping1D](INFO): Starting
2019-04-09T22:58:28.330Z,1554850708.330 [BR_Ping1D](INFO): readConfig
2019-04-09T22:58:28.331Z,1554850708.331 [BR_Ping1D](INFO): startup: 2
2019-04-09T22:58:28.331Z,1554850708.331 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T22:58:28.331Z,1554850708.331 [BR_Ping1D](INFO): elapsed 5.2464
2019-04-09T22:58:28.331Z,1554850708.331 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T22:58:28.331Z,1554850708.331 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T22:58:28.331Z,1554850708.331 [BR_Ping1D] Data Fault, FailCount= 5
2019-04-09T22:58:28.332Z,1554850708.332 [BR_Ping1D](ERROR): Data Fault
2019-04-09T22:58:28.403Z,1554850708.403 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T22:58:28.403Z,1554850708.403 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-04-09T22:58:28.761Z,1554850708.761 [BR_Ping1D](INFO): Stop
2019-04-09T22:58:28.761Z,1554850708.761 [BR_Ping1D](INFO): uninitialize
2019-04-09T22:58:28.761Z,1554850708.761 [BR_Ping1D](INFO): Powering down
2019-04-09T22:58:29.384Z,1554850709.384 [CBIT](INFO): Critical error at 20190409T225828
2019-04-09T22:58:29.406Z,1554850709.406 [NAL9602](DEBUG): Fix Requested
2019-04-09T22:58:29.421Z,1554850709.421 [BR_Ping1D](INFO): Stopping
2019-04-09T22:58:29.829Z,1554850709.829 [BR_Ping1D](INFO): Stopped
2019-04-09T22:58:31.766Z,1554850711.766 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T22:58:31.766Z,1554850711.766 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-09T22:58:31.766Z,1554850711.766 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T22:58:31.948Z,1554850711.948 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T22:58:31.948Z,1554850711.948 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-09T22:58:32.170Z,1554850712.170 [CTD_Seabird](INFO): Powering down
2019-04-09T22:58:33.834Z,1554850713.834 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T22:58:33.834Z,1554850713.834 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2019-04-09T22:58:33.834Z,1554850713.834 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T22:58:33.975Z,1554850713.975 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T22:58:34.238Z,1554850714.238 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T22:58:35.136Z,1554850715.136 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T22:58:35.136Z,1554850715.136 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2019-04-09T22:58:37.470Z,1554850717.470 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T22:58:37.470Z,1554850717.470 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T22:58:57.747Z,1554850737.747 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T22:58:57.747Z,1554850737.747 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2019-04-09T22:58:57.747Z,1554850737.747 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T22:58:57.899Z,1554850737.899 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T22:58:58.150Z,1554850738.150 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T22:58:59.043Z,1554850739.043 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T22:58:59.043Z,1554850739.043 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2019-04-09T22:59:01.382Z,1554850741.382 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T22:59:01.383Z,1554850741.383 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T22:59:03.033Z,1554850743.033 [BPC1](ERROR): BPC1A: No match for serial number 04CC in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2019-04-09T22:59:03.451Z,1554850743.451 [BPC1](ERROR): BPC1B: No match for serial number 018D in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2019-04-09T22:59:03.451Z,1554850743.451 [BPC1](FAULT): Failed to parse data from all battery packs.
2019-04-09T22:59:03.451Z,1554850743.451 [BPC1] Data Fault, FailCount= 1
2019-04-09T22:59:03.451Z,1554850743.451 [BPC1](ERROR): Data Fault
2019-04-09T22:59:03.469Z,1554850743.469 [CBIT](ERROR): Data Fault in component: BPC1
2019-04-09T22:59:07.476Z,1554850747.476 [SBIT](IMPORTANT): SBIT PASSED
2019-04-09T22:59:07.543Z,1554850747.543 [CommandLine](IMPORTANT): got command configSet list
2019-04-09T22:59:07.543Z,1554850747.543 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-04-09T22:59:07.544Z,1554850747.544 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-04-09T22:59:07.544Z,1554850747.544 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2019-04-09T22:59:07.885Z,1554850747.885 [MissionManager](IMPORTANT): Started mission Startup
2019-04-09T22:59:07.885Z,1554850747.885 [Startup] Running Loop=1
2019-04-09T22:59:07.885Z,1554850747.885 [Startup](DEBUG): Aggregate::initialize Startup
2019-04-09T22:59:07.886Z,1554850747.886 [Startup:A.GoToSurface] Running Loop=1
2019-04-09T22:59:07.886Z,1554850747.886 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-09T22:59:07.886Z,1554850747.886 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-09T22:59:07.887Z,1554850747.887 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-09T22:59:07.887Z,1554850747.887 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-09T22:59:07.888Z,1554850747.888 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-09T22:59:07.888Z,1554850747.888 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-09T22:59:07.890Z,1554850747.890 [Startup:StartupSatComms] Running Loop=1
2019-04-09T22:59:07.890Z,1554850747.890 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-04-09T22:59:07.890Z,1554850747.890 [Startup:StartupSatComms:A] Running Loop=1
2019-04-09T22:59:08.292Z,1554850748.292 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-04-09T22:59:21.582Z,1554850761.582 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T22:59:21.583Z,1554850761.583 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2019-04-09T22:59:21.583Z,1554850761.583 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T22:59:21.634Z,1554850761.634 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T22:59:21.986Z,1554850761.986 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T22:59:22.832Z,1554850762.832 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T22:59:22.832Z,1554850762.832 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2019-04-09T22:59:25.218Z,1554850765.218 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T22:59:25.219Z,1554850765.219 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T22:59:45.418Z,1554850785.418 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T22:59:45.419Z,1554850785.419 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2019-04-09T22:59:45.419Z,1554850785.419 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T22:59:45.484Z,1554850785.484 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T22:59:45.822Z,1554850785.822 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T22:59:46.668Z,1554850786.668 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T22:59:46.668Z,1554850786.668 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2019-04-09T22:59:49.054Z,1554850789.054 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T22:59:49.055Z,1554850789.055 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T22:59:49.486Z,1554850789.486 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T22:59:49.486Z,1554850789.486 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-04-09T22:59:49.486Z,1554850789.486 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T22:59:49.487Z,1554850789.487 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T22:59:49.487Z,1554850789.487 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-04-09T22:59:49.487Z,1554850789.487 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T22:59:49.489Z,1554850789.489 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T22:59:49.489Z,1554850789.489 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-04-09T22:59:49.489Z,1554850789.489 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T22:59:49.545Z,1554850789.545 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T22:59:49.545Z,1554850789.545 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T22:59:49.545Z,1554850789.545 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T22:59:49.921Z,1554850789.921 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T22:59:49.921Z,1554850789.921 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-04-09T22:59:49.922Z,1554850789.922 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T22:59:49.922Z,1554850789.922 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-04-09T22:59:49.923Z,1554850789.923 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T22:59:49.923Z,1554850789.923 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-04-09T22:59:50.305Z,1554850790.305 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T23:00:08.065Z,1554850808.065 [Startup:StartupSatComms:A](INFO): Timed out from 2019-04-09T22:59:07.9Z
2019-04-09T23:00:08.065Z,1554850808.065 [Startup:StartupSatComms:A] Stopped
2019-04-09T23:00:08.065Z,1554850808.065 [Startup:StartupSatComms:B] Running Loop=1
2019-04-09T23:00:08.479Z,1554850808.479 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-09T23:00:09.254Z,1554850809.254 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T23:00:09.255Z,1554850809.255 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2019-04-09T23:00:09.255Z,1554850809.255 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T23:00:09.301Z,1554850809.301 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T23:00:09.658Z,1554850809.658 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T23:00:10.577Z,1554850810.577 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T23:00:10.577Z,1554850810.577 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2019-04-09T23:00:12.890Z,1554850812.890 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T23:00:12.891Z,1554850812.891 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T23:00:30.936Z,1554850830.936 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004795
2019-04-09T23:00:33.090Z,1554850833.090 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T23:00:33.090Z,1554850833.090 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7
2019-04-09T23:00:33.090Z,1554850833.090 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T23:00:33.149Z,1554850833.149 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T23:00:33.495Z,1554850833.495 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T23:00:34.340Z,1554850834.340 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T23:00:34.340Z,1554850834.340 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7
2019-04-09T23:00:35.659Z,1554850835.659 [DataOverHttps](INFO): Sending 590 bytes from file Logs/20190409T224803/Courier0000.lzma
2019-04-09T23:00:36.464Z,1554850836.464 [DataOverHttps](INFO): Moved sent file to Logs/20190409T224803/Courier0000.lzma.bak
2019-04-09T23:00:36.464Z,1554850836.464 [DataOverHttps](INFO): SBD MOMSN=10520547
2019-04-09T23:00:36.726Z,1554850836.726 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T23:00:36.727Z,1554850836.727 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T23:00:53.336Z,1554850853.336 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T23:00:53.336Z,1554850853.336 [Aanderaa_O2] No Fault, FailCount= 3
2019-04-09T23:00:55.042Z,1554850855.042 [DataOverHttps](INFO): Sending 345 bytes from file Logs/20190409T224803/Courier0004.lzma
2019-04-09T23:00:55.848Z,1554850855.848 [DataOverHttps](INFO): Moved sent file to Logs/20190409T224803/Courier0004.lzma.bak
2019-04-09T23:00:55.848Z,1554850855.848 [DataOverHttps](INFO): SBD MOMSN=10520575
2019-04-09T23:00:56.927Z,1554850856.927 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T23:00:56.927Z,1554850856.927 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 8
2019-04-09T23:00:56.927Z,1554850856.927 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T23:00:56.980Z,1554850856.980 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T23:00:56.980Z,1554850856.980 [CBIT](FAULT): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T23:00:57.330Z,1554850857.330 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T23:01:01.812Z,1554850861.812 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T23:01:01.812Z,1554850861.812 [CTD_Seabird] No Fault, FailCount= 3
2019-04-09T23:01:02.186Z,1554850862.186 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T23:01:02.188Z,1554850862.188 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T23:01:03.794Z,1554850863.794 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T23:01:03.794Z,1554850863.794 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T23:01:03.794Z,1554850863.794 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T23:01:03.852Z,1554850863.852 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T23:01:04.318Z,1554850864.318 [Aanderaa_O2](INFO): Powering down
2019-04-09T23:01:05.069Z,1554850865.069 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T23:01:05.069Z,1554850865.069 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-09T23:01:05.962Z,1554850865.962 [DataOverHttps](INFO): Sending 314 bytes from file Logs/20190409T225340/Courier0000.lzma
2019-04-09T23:01:06.767Z,1554850866.767 [DataOverHttps](INFO): Moved sent file to Logs/20190409T225340/Courier0000.lzma.bak
2019-04-09T23:01:06.768Z,1554850866.768 [DataOverHttps](INFO): SBD MOMSN=10520593
2019-04-09T23:01:08.287Z,1554850868.287 [Startup:StartupSatComms:B](INFO): Timed out from 2019-04-09T23:00:08.1Z
2019-04-09T23:01:08.287Z,1554850868.287 [Startup:StartupSatComms:B] Stopped
2019-04-09T23:01:08.288Z,1554850868.288 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-04-09T23:01:08.288Z,1554850868.288 [Startup:StartupSatComms] Stopped
2019-04-09T23:01:08.288Z,1554850868.288 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-04-09T23:01:08.289Z,1554850868.289 [Startup](INFO): Completed Startup
2019-04-09T23:01:08.289Z,1554850868.289 [MissionManager](INFO): Startup is completed.
2019-04-09T23:01:08.289Z,1554850868.289 [MissionManager](INFO): Uninitializing Mission Startup
2019-04-09T23:01:08.289Z,1554850868.289 [Startup] Stopped
2019-04-09T23:01:08.289Z,1554850868.289 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-04-09T23:01:08.289Z,1554850868.289 [Startup:A.GoToSurface] Stopped
2019-04-09T23:01:08.289Z,1554850868.289 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-09T23:01:08.689Z,1554850868.689 [MissionManager](IMPORTANT): Started mission Default
2019-04-09T23:01:08.690Z,1554850868.690 [Default] Running Loop=1
2019-04-09T23:01:08.690Z,1554850868.690 [Default](DEBUG): Aggregate::initialize Default
2019-04-09T23:01:08.690Z,1554850868.690 [Default:B.GoToSurface] Running Loop=1
2019-04-09T23:01:08.690Z,1554850868.690 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-09T23:01:08.690Z,1554850868.690 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-09T23:01:08.691Z,1554850868.691 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-09T23:01:08.691Z,1554850868.691 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-09T23:01:08.691Z,1554850868.691 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-09T23:01:08.692Z,1554850868.692 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-09T23:01:08.692Z,1554850868.692 [Default:A.Wait] Running Loop=1
2019-04-09T23:01:08.692Z,1554850868.692 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-04-09T23:01:15.245Z,1554850875.245 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T23:01:15.245Z,1554850875.245 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-09T23:01:15.245Z,1554850875.245 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T23:01:15.510Z,1554850875.510 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T23:01:15.510Z,1554850875.510 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-09T23:01:15.510Z,1554850875.510 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T23:01:15.572Z,1554850875.572 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T23:01:15.572Z,1554850875.572 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T23:01:15.646Z,1554850875.646 [CTD_Seabird](INFO): Powering down
2019-04-09T23:01:16.034Z,1554850876.034 [Aanderaa_O2](INFO): Powering down
2019-04-09T23:01:16.760Z,1554850876.760 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T23:01:16.760Z,1554850876.760 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-09T23:01:16.760Z,1554850876.760 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T23:01:16.760Z,1554850876.760 [CTD_Seabird] No Fault, FailCount= 1
2019-04-09T23:01:16.990Z,1554850876.990 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T23:01:16.992Z,1554850876.992 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T23:01:21.000Z,1554850882.000 [Default:A.Wait](INFO): Done Waiting.
2019-04-09T23:01:21.000Z,1554850882.000 [Default:A.Wait] Stopped
2019-04-09T23:01:21.000Z,1554850882.000 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-04-09T23:01:22.404Z,1554850882.404 [Default:CheckIn] Running Loop=1
2019-04-09T23:01:22.404Z,1554850882.404 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-09T23:01:22.404Z,1554850882.404 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-09T23:01:22.811Z,1554850882.811 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-04-09T23:01:24.978Z,1554850884.978 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190409T225340/Courier0004.lzma
2019-04-09T23:01:25.783Z,1554850885.783 [DataOverHttps](INFO): Moved sent file to Logs/20190409T225340/Courier0004.lzma.bak
2019-04-09T23:01:25.783Z,1554850885.783 [DataOverHttps](INFO): SBD MOMSN=10520606
2019-04-09T23:01:27.226Z,1554850887.226 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T23:01:27.226Z,1554850887.226 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-09T23:01:27.226Z,1554850887.226 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T23:01:27.264Z,1554850887.264 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T23:01:27.264Z,1554850887.264 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-09T23:01:27.750Z,1554850887.750 [Aanderaa_O2](INFO): Powering down
2019-04-09T23:01:30.042Z,1554850890.042 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T23:01:30.042Z,1554850890.042 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-09T23:01:30.042Z,1554850890.042 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T23:01:30.102Z,1554850890.102 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T23:01:30.446Z,1554850890.446 [CTD_Seabird](INFO): Powering down
2019-04-09T23:01:31.710Z,1554850891.710 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T23:01:31.710Z,1554850891.710 [CTD_Seabird] No Fault, FailCount= 2
2019-04-09T23:01:31.786Z,1554850891.786 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T23:01:31.788Z,1554850891.788 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T23:01:44.838Z,1554850904.838 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T23:01:44.838Z,1554850904.838 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-09T23:01:44.838Z,1554850904.838 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T23:01:45.052Z,1554850905.052 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T23:01:45.052Z,1554850905.052 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-09T23:01:45.242Z,1554850905.242 [CTD_Seabird](INFO): Powering down
2019-04-09T23:01:50.693Z,1554850910.693 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T23:01:50.693Z,1554850910.693 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-04-09T23:01:50.693Z,1554850910.693 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T23:01:50.695Z,1554850910.695 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T23:01:50.695Z,1554850910.695 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-04-09T23:01:50.695Z,1554850910.695 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T23:01:50.696Z,1554850910.696 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T23:01:50.696Z,1554850910.696 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-04-09T23:01:50.696Z,1554850910.696 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T23:01:50.715Z,1554850910.715 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T23:01:50.715Z,1554850910.715 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T23:01:50.715Z,1554850910.715 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T23:01:51.115Z,1554850911.115 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T23:01:51.115Z,1554850911.115 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-04-09T23:01:51.116Z,1554850911.116 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T23:01:51.116Z,1554850911.116 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-04-09T23:01:51.116Z,1554850911.116 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T23:01:51.116Z,1554850911.116 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-04-09T23:01:51.493Z,1554850911.493 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T23:03:28.465Z,1554851008.465 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T23:03:28.465Z,1554851008.465 [BR_Ping1D] No Fault, FailCount= 5
2019-04-09T23:03:28.854Z,1554851008.854 [BR_Ping1D](INFO): Start
2019-04-09T23:03:29.251Z,1554851009.251 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:29.668Z,1554851009.668 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:29.668Z,1554851009.668 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:29.669Z,1554851009.669 [BR_Ping1D](INFO): startup: 0
2019-04-09T23:03:29.669Z,1554851009.669 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T23:03:29.682Z,1554851009.682 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T23:03:29.682Z,1554851009.682 [BR_Ping1D](INFO): data 011E
2019-04-09T23:03:29.682Z,1554851009.682 [BR_Ping1D](INFO): 42520200EC030000011EA401
2019-04-09T23:03:29.683Z,1554851009.683 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T23:03:29.683Z,1554851009.683 [BR_Ping1D](INFO): 42520100EB030000840115
2019-04-09T23:03:29.684Z,1554851009.684 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T23:03:29.685Z,1554851009.685 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-09T23:03:29.685Z,1554851009.685 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T23:03:29.686Z,1554851009.686 [BR_Ping1D](INFO): 42520100EE030000870115
2019-04-09T23:03:30.072Z,1554851010.072 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:30.477Z,1554851010.477 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:30.477Z,1554851010.477 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:30.478Z,1554851010.478 [BR_Ping1D](INFO): startup: 1
2019-04-09T23:03:30.478Z,1554851010.478 [BR_Ping1D](INFO): UART requesting status
2019-04-09T23:03:30.478Z,1554851010.478 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-09T23:03:30.486Z,1554851010.486 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-09T23:03:30.494Z,1554851010.494 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-09T23:03:30.882Z,1554851010.882 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:30.883Z,1554851010.883 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:30.883Z,1554851010.883 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:30.883Z,1554851010.883 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:30.883Z,1554851010.883 [BR_Ping1D](INFO): elapsed 1.2143
2019-04-09T23:03:30.883Z,1554851010.883 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:30.884Z,1554851010.884 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:30.884Z,1554851010.884 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:30.884Z,1554851010.884 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:31.274Z,1554851011.274 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:31.274Z,1554851011.274 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:31.274Z,1554851011.274 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:31.275Z,1554851011.275 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:31.275Z,1554851011.275 [BR_Ping1D](INFO): elapsed 1.6059
2019-04-09T23:03:31.275Z,1554851011.275 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:31.275Z,1554851011.275 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:31.275Z,1554851011.275 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:31.275Z,1554851011.275 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:31.678Z,1554851011.678 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:31.679Z,1554851011.679 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:31.679Z,1554851011.679 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:31.679Z,1554851011.679 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:31.679Z,1554851011.679 [BR_Ping1D](INFO): elapsed 2.0104
2019-04-09T23:03:31.679Z,1554851011.679 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:31.680Z,1554851011.680 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:31.680Z,1554851011.680 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:31.680Z,1554851011.680 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:32.067Z,1554851012.067 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-04-09T23:03:32.082Z,1554851012.082 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:32.082Z,1554851012.082 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:32.082Z,1554851012.082 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:32.082Z,1554851012.082 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:32.083Z,1554851012.083 [BR_Ping1D](INFO): elapsed 2.4137
2019-04-09T23:03:32.083Z,1554851012.083 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:32.083Z,1554851012.083 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:32.083Z,1554851012.083 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:32.083Z,1554851012.083 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:32.487Z,1554851012.487 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:32.487Z,1554851012.487 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:32.487Z,1554851012.487 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:32.488Z,1554851012.488 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:32.488Z,1554851012.488 [BR_Ping1D](INFO): elapsed 2.8189
2019-04-09T23:03:32.488Z,1554851012.488 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:32.488Z,1554851012.488 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:32.488Z,1554851012.488 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:32.489Z,1554851012.489 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:32.886Z,1554851012.886 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:03:32.900Z,1554851012.900 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:32.900Z,1554851012.900 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:32.901Z,1554851012.901 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:32.901Z,1554851012.901 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:32.901Z,1554851012.901 [BR_Ping1D](INFO): elapsed 3.2322
2019-04-09T23:03:32.901Z,1554851012.901 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:32.901Z,1554851012.901 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:32.902Z,1554851012.902 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:32.902Z,1554851012.902 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:33.294Z,1554851013.294 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:33.294Z,1554851013.294 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:33.295Z,1554851013.295 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:33.295Z,1554851013.295 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:33.295Z,1554851013.295 [BR_Ping1D](INFO): elapsed 3.6260
2019-04-09T23:03:33.295Z,1554851013.295 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:33.295Z,1554851013.295 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:33.295Z,1554851013.295 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:33.296Z,1554851013.296 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:33.698Z,1554851013.698 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:33.698Z,1554851013.698 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:33.698Z,1554851013.698 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:33.699Z,1554851013.699 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:33.699Z,1554851013.699 [BR_Ping1D](INFO): elapsed 4.0299
2019-04-09T23:03:33.699Z,1554851013.699 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:33.699Z,1554851013.699 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:33.699Z,1554851013.699 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:33.699Z,1554851013.699 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:34.105Z,1554851014.105 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:34.106Z,1554851014.106 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:34.106Z,1554851014.106 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:34.106Z,1554851014.106 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:34.106Z,1554851014.106 [BR_Ping1D](INFO): elapsed 4.4375
2019-04-09T23:03:34.107Z,1554851014.107 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:34.107Z,1554851014.107 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:34.107Z,1554851014.107 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:34.107Z,1554851014.107 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:34.509Z,1554851014.509 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:34.509Z,1554851014.509 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:34.510Z,1554851014.510 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:34.510Z,1554851014.510 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:34.510Z,1554851014.510 [BR_Ping1D](INFO): elapsed 4.8411
2019-04-09T23:03:34.510Z,1554851014.510 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:34.510Z,1554851014.510 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:34.511Z,1554851014.511 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:34.511Z,1554851014.511 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:34.920Z,1554851014.920 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:34.920Z,1554851014.920 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:34.921Z,1554851014.921 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:34.921Z,1554851014.921 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:34.921Z,1554851014.921 [BR_Ping1D](INFO): elapsed 5.2522
2019-04-09T23:03:34.921Z,1554851014.921 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:34.921Z,1554851014.921 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T23:03:34.922Z,1554851014.922 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-09T23:03:34.922Z,1554851014.922 [BR_Ping1D](ERROR): Data Fault
2019-04-09T23:03:34.983Z,1554851014.983 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T23:03:35.325Z,1554851015.325 [BR_Ping1D](INFO): Stop
2019-04-09T23:03:35.325Z,1554851015.325 [BR_Ping1D](INFO): uninitialize
2019-04-09T23:03:35.325Z,1554851015.325 [BR_Ping1D](INFO): Powering down
2019-04-09T23:03:35.723Z,1554851015.723 [BR_Ping1D](INFO): Stopping
2019-04-09T23:03:36.126Z,1554851016.126 [BR_Ping1D](INFO): Stopping
2019-04-09T23:03:36.522Z,1554851016.522 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:03:36.537Z,1554851016.537 [BR_Ping1D](INFO): Stopped
2019-04-09T23:03:36.557Z,1554851016.557 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T23:03:36.557Z,1554851016.557 [BR_Ping1D] No Fault, FailCount= 1
2019-04-09T23:03:36.944Z,1554851016.944 [BR_Ping1D](INFO): Start
2019-04-09T23:03:37.349Z,1554851017.349 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:37.745Z,1554851017.745 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:37.745Z,1554851017.745 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:37.745Z,1554851017.745 [BR_Ping1D](INFO): startup: 0
2019-04-09T23:03:37.745Z,1554851017.745 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T23:03:37.758Z,1554851017.758 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T23:03:37.758Z,1554851017.758 [BR_Ping1D](INFO): data 011E
2019-04-09T23:03:37.758Z,1554851017.758 [BR_Ping1D](INFO): 42520200EC030000011EA401
2019-04-09T23:03:37.759Z,1554851017.759 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T23:03:37.760Z,1554851017.760 [BR_Ping1D](INFO): 42520100EB030000840115
2019-04-09T23:03:37.760Z,1554851017.760 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T23:03:37.761Z,1554851017.761 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-09T23:03:37.761Z,1554851017.761 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T23:03:37.762Z,1554851017.762 [BR_Ping1D](INFO): 42520100EE030000870115
2019-04-09T23:03:38.142Z,1554851018.142 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:38.550Z,1554851018.550 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:03:38.564Z,1554851018.564 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:38.564Z,1554851018.564 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:38.565Z,1554851018.565 [BR_Ping1D](INFO): startup: 1
2019-04-09T23:03:38.565Z,1554851018.565 [BR_Ping1D](INFO): UART requesting status
2019-04-09T23:03:38.565Z,1554851018.565 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-09T23:03:38.578Z,1554851018.578 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-09T23:03:38.586Z,1554851018.586 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-09T23:03:38.961Z,1554851018.961 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:38.961Z,1554851018.961 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:38.961Z,1554851018.961 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:38.961Z,1554851018.961 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:38.962Z,1554851018.962 [BR_Ping1D](INFO): elapsed 1.2161
2019-04-09T23:03:38.962Z,1554851018.962 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:38.962Z,1554851018.962 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:38.962Z,1554851018.962 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:38.963Z,1554851018.963 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:39.364Z,1554851019.364 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:39.364Z,1554851019.364 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:39.365Z,1554851019.365 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:39.365Z,1554851019.365 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:39.365Z,1554851019.365 [BR_Ping1D](INFO): elapsed 1.6193
2019-04-09T23:03:39.365Z,1554851019.365 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:39.365Z,1554851019.365 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:39.365Z,1554851019.365 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:39.366Z,1554851019.366 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:39.759Z,1554851019.759 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:39.759Z,1554851019.759 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:39.760Z,1554851019.760 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:39.760Z,1554851019.760 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:39.760Z,1554851019.760 [BR_Ping1D](INFO): elapsed 2.0144
2019-04-09T23:03:39.760Z,1554851019.760 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:39.760Z,1554851019.760 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:39.761Z,1554851019.761 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:39.761Z,1554851019.761 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:40.162Z,1554851020.162 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:40.162Z,1554851020.162 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:40.163Z,1554851020.163 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:40.163Z,1554851020.163 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:40.163Z,1554851020.163 [BR_Ping1D](INFO): elapsed 2.4175
2019-04-09T23:03:40.163Z,1554851020.163 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:40.164Z,1554851020.164 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:40.164Z,1554851020.164 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:40.164Z,1554851020.164 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:40.570Z,1554851020.570 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:03:40.585Z,1554851020.585 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:40.585Z,1554851020.585 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:40.585Z,1554851020.585 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:40.585Z,1554851020.585 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:40.585Z,1554851020.585 [BR_Ping1D](INFO): elapsed 2.8398
2019-04-09T23:03:40.586Z,1554851020.586 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:40.586Z,1554851020.586 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:40.586Z,1554851020.586 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:40.586Z,1554851020.586 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:40.988Z,1554851020.988 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:40.988Z,1554851020.988 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:40.988Z,1554851020.988 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:40.988Z,1554851020.988 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:40.989Z,1554851020.989 [BR_Ping1D](INFO): elapsed 3.2431
2019-04-09T23:03:40.989Z,1554851020.989 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:40.989Z,1554851020.989 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:40.989Z,1554851020.989 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:40.989Z,1554851020.989 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:41.384Z,1554851021.384 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:41.385Z,1554851021.385 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:41.385Z,1554851021.385 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:41.385Z,1554851021.385 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:41.385Z,1554851021.385 [BR_Ping1D](INFO): elapsed 3.6397
2019-04-09T23:03:41.385Z,1554851021.385 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:41.386Z,1554851021.386 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:41.386Z,1554851021.386 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:41.386Z,1554851021.386 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:41.777Z,1554851021.777 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:41.778Z,1554851021.778 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:41.778Z,1554851021.778 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:41.778Z,1554851021.778 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:41.778Z,1554851021.778 [BR_Ping1D](INFO): elapsed 4.0327
2019-04-09T23:03:41.778Z,1554851021.778 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:41.779Z,1554851021.779 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:41.779Z,1554851021.779 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:41.779Z,1554851021.779 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:42.182Z,1554851022.182 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:42.182Z,1554851022.182 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:42.183Z,1554851022.183 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:42.183Z,1554851022.183 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:42.183Z,1554851022.183 [BR_Ping1D](INFO): elapsed 4.4375
2019-04-09T23:03:42.183Z,1554851022.183 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:42.183Z,1554851022.183 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:42.184Z,1554851022.184 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:42.184Z,1554851022.184 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:42.582Z,1554851022.582 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:03:42.596Z,1554851022.596 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:42.596Z,1554851022.596 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:42.596Z,1554851022.596 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:42.596Z,1554851022.596 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:42.596Z,1554851022.596 [BR_Ping1D](INFO): elapsed 4.8508
2019-04-09T23:03:42.597Z,1554851022.597 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:42.597Z,1554851022.597 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:42.597Z,1554851022.597 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:42.597Z,1554851022.597 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:43.004Z,1554851023.004 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:43.004Z,1554851023.004 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:43.005Z,1554851023.005 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:43.005Z,1554851023.005 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:43.005Z,1554851023.005 [BR_Ping1D](INFO): elapsed 5.2595
2019-04-09T23:03:43.005Z,1554851023.005 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:43.005Z,1554851023.005 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T23:03:43.005Z,1554851023.005 [BR_Ping1D] Data Fault, FailCount= 2
2019-04-09T23:03:43.006Z,1554851023.006 [BR_Ping1D](ERROR): Data Fault
2019-04-09T23:03:43.028Z,1554851023.028 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T23:03:43.404Z,1554851023.404 [BR_Ping1D](INFO): Stop
2019-04-09T23:03:43.404Z,1554851023.404 [BR_Ping1D](INFO): uninitialize
2019-04-09T23:03:43.405Z,1554851023.405 [BR_Ping1D](INFO): Powering down
2019-04-09T23:03:43.803Z,1554851023.803 [BR_Ping1D](INFO): Stopping
2019-04-09T23:03:44.204Z,1554851024.204 [BR_Ping1D](INFO): Stopping
2019-04-09T23:03:44.614Z,1554851024.614 [BR_Ping1D](INFO): Stopped
2019-04-09T23:03:44.629Z,1554851024.629 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T23:03:44.629Z,1554851024.629 [BR_Ping1D] No Fault, FailCount= 2
2019-04-09T23:03:45.011Z,1554851025.011 [BR_Ping1D](INFO): Start
2019-04-09T23:03:45.418Z,1554851025.418 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:45.818Z,1554851025.818 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:03:45.832Z,1554851025.832 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:45.832Z,1554851025.832 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:45.833Z,1554851025.833 [BR_Ping1D](INFO): startup: 0
2019-04-09T23:03:45.833Z,1554851025.833 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T23:03:45.846Z,1554851025.846 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T23:03:45.846Z,1554851025.846 [BR_Ping1D](INFO): data 011E
2019-04-09T23:03:45.846Z,1554851025.846 [BR_Ping1D](INFO): 42520200EC030000011EA401
2019-04-09T23:03:45.847Z,1554851025.847 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T23:03:45.847Z,1554851025.847 [BR_Ping1D](INFO): 42520100EB030000840115
2019-04-09T23:03:45.848Z,1554851025.848 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T23:03:45.849Z,1554851025.849 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-09T23:03:45.850Z,1554851025.850 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T23:03:45.850Z,1554851025.850 [BR_Ping1D](INFO): 42520100EE030000870115
2019-04-09T23:03:46.232Z,1554851026.232 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:46.640Z,1554851026.640 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:46.640Z,1554851026.640 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:46.640Z,1554851026.640 [BR_Ping1D](INFO): startup: 1
2019-04-09T23:03:46.641Z,1554851026.641 [BR_Ping1D](INFO): UART requesting status
2019-04-09T23:03:46.641Z,1554851026.641 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-09T23:03:46.650Z,1554851026.650 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-09T23:03:46.658Z,1554851026.658 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-09T23:03:47.030Z,1554851027.030 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:47.030Z,1554851027.030 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:47.031Z,1554851027.031 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:47.031Z,1554851027.031 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:47.031Z,1554851027.031 [BR_Ping1D](INFO): elapsed 1.1983
2019-04-09T23:03:47.031Z,1554851027.031 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:47.032Z,1554851027.032 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:47.032Z,1554851027.032 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:47.032Z,1554851027.032 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:47.435Z,1554851027.435 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:47.436Z,1554851027.436 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:47.436Z,1554851027.436 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:47.436Z,1554851027.436 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:47.436Z,1554851027.436 [BR_Ping1D](INFO): elapsed 1.6034
2019-04-09T23:03:47.436Z,1554851027.436 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:47.437Z,1554851027.437 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:47.437Z,1554851027.437 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:47.437Z,1554851027.437 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:47.842Z,1554851027.842 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:47.842Z,1554851027.842 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:47.842Z,1554851027.842 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:47.842Z,1554851027.842 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:47.843Z,1554851027.843 [BR_Ping1D](INFO): elapsed 2.0098
2019-04-09T23:03:47.843Z,1554851027.843 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:47.843Z,1554851027.843 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:47.843Z,1554851027.843 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:47.843Z,1554851027.843 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:48.242Z,1554851028.242 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:48.242Z,1554851028.242 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:48.242Z,1554851028.242 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:48.243Z,1554851028.243 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:48.243Z,1554851028.243 [BR_Ping1D](INFO): elapsed 2.4099
2019-04-09T23:03:48.243Z,1554851028.243 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:48.243Z,1554851028.243 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:48.243Z,1554851028.243 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:48.243Z,1554851028.243 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:48.646Z,1554851028.646 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:03:48.661Z,1554851028.661 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:48.661Z,1554851028.661 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:48.661Z,1554851028.661 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:48.661Z,1554851028.661 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:48.662Z,1554851028.662 [BR_Ping1D](INFO): elapsed 2.8287
2019-04-09T23:03:48.662Z,1554851028.662 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:48.662Z,1554851028.662 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:48.662Z,1554851028.662 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:48.662Z,1554851028.662 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:49.064Z,1554851029.064 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:49.064Z,1554851029.064 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:49.064Z,1554851029.064 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:49.064Z,1554851029.064 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:49.065Z,1554851029.065 [BR_Ping1D](INFO): elapsed 3.2319
2019-04-09T23:03:49.065Z,1554851029.065 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:49.065Z,1554851029.065 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:49.065Z,1554851029.065 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:49.065Z,1554851029.065 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:49.465Z,1554851029.465 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:49.465Z,1554851029.465 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:49.465Z,1554851029.465 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:49.465Z,1554851029.465 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:49.466Z,1554851029.466 [BR_Ping1D](INFO): elapsed 3.6327
2019-04-09T23:03:49.466Z,1554851029.466 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:49.466Z,1554851029.466 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:49.466Z,1554851029.466 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:49.466Z,1554851029.466 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:49.858Z,1554851029.858 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:49.858Z,1554851029.858 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:49.858Z,1554851029.858 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:49.859Z,1554851029.859 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:49.859Z,1554851029.859 [BR_Ping1D](INFO): elapsed 4.0260
2019-04-09T23:03:49.859Z,1554851029.859 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:49.859Z,1554851029.859 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:49.859Z,1554851029.859 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:49.860Z,1554851029.860 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:50.262Z,1554851030.262 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:50.262Z,1554851030.262 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:50.262Z,1554851030.262 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:50.263Z,1554851030.263 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:50.263Z,1554851030.263 [BR_Ping1D](INFO): elapsed 4.4299
2019-04-09T23:03:50.263Z,1554851030.263 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:50.263Z,1554851030.263 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:50.263Z,1554851030.263 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:50.263Z,1554851030.263 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:50.666Z,1554851030.666 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:50.666Z,1554851030.666 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:50.666Z,1554851030.666 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:50.667Z,1554851030.667 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:50.667Z,1554851030.667 [BR_Ping1D](INFO): elapsed 4.8339
2019-04-09T23:03:50.667Z,1554851030.667 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:50.667Z,1554851030.667 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:50.667Z,1554851030.667 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:50.667Z,1554851030.667 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:51.074Z,1554851031.074 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:51.074Z,1554851031.074 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:51.074Z,1554851031.074 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:51.074Z,1554851031.074 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:51.075Z,1554851031.075 [BR_Ping1D](INFO): elapsed 5.2417
2019-04-09T23:03:51.075Z,1554851031.075 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:51.075Z,1554851031.075 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T23:03:51.075Z,1554851031.075 [BR_Ping1D] Data Fault, FailCount= 3
2019-04-09T23:03:51.075Z,1554851031.075 [BR_Ping1D](ERROR): Data Fault
2019-04-09T23:03:51.090Z,1554851031.090 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T23:03:51.474Z,1554851031.474 [BR_Ping1D](INFO): Stop
2019-04-09T23:03:51.474Z,1554851031.474 [BR_Ping1D](INFO): uninitialize
2019-04-09T23:03:51.474Z,1554851031.474 [BR_Ping1D](INFO): Powering down
2019-04-09T23:03:51.557Z,1554851031.557 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T23:03:51.557Z,1554851031.557 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-04-09T23:03:51.557Z,1554851031.557 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T23:03:51.559Z,1554851031.559 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T23:03:51.559Z,1554851031.559 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-04-09T23:03:51.559Z,1554851031.559 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T23:03:51.560Z,1554851031.560 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T23:03:51.560Z,1554851031.560 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2019-04-09T23:03:51.560Z,1554851031.560 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T23:03:51.571Z,1554851031.571 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T23:03:51.571Z,1554851031.571 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T23:03:51.571Z,1554851031.571 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T23:03:51.874Z,1554851031.874 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:03:51.889Z,1554851031.889 [BR_Ping1D](INFO): Stopping
2019-04-09T23:03:51.916Z,1554851031.916 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T23:03:51.916Z,1554851031.916 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-04-09T23:03:51.917Z,1554851031.917 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T23:03:51.917Z,1554851031.917 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-04-09T23:03:51.918Z,1554851031.918 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T23:03:51.918Z,1554851031.918 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2019-04-09T23:03:52.292Z,1554851032.292 [BR_Ping1D](INFO): Stopping
2019-04-09T23:03:52.301Z,1554851032.301 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T23:03:52.700Z,1554851032.700 [BR_Ping1D](INFO): Stopped
2019-04-09T23:03:52.715Z,1554851032.715 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T23:03:52.715Z,1554851032.715 [BR_Ping1D] No Fault, FailCount= 3
2019-04-09T23:03:53.094Z,1554851033.094 [BR_Ping1D](INFO): Start
2019-04-09T23:03:53.491Z,1554851033.491 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:53.898Z,1554851033.898 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:53.898Z,1554851033.898 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:53.899Z,1554851033.899 [BR_Ping1D](INFO): startup: 0
2019-04-09T23:03:53.899Z,1554851033.899 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T23:03:53.910Z,1554851033.910 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T23:03:53.910Z,1554851033.910 [BR_Ping1D](INFO): data 011E
2019-04-09T23:03:53.910Z,1554851033.910 [BR_Ping1D](INFO): 42520200EC030000011EA401
2019-04-09T23:03:53.911Z,1554851033.911 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T23:03:53.911Z,1554851033.911 [BR_Ping1D](INFO): 42520100EB030000840115
2019-04-09T23:03:53.912Z,1554851033.912 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T23:03:53.913Z,1554851033.913 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-09T23:03:53.913Z,1554851033.913 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T23:03:53.914Z,1554851033.914 [BR_Ping1D](INFO): 42520100EE030000870115
2019-04-09T23:03:54.308Z,1554851034.308 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:54.706Z,1554851034.706 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:03:54.724Z,1554851034.724 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:54.724Z,1554851034.724 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:54.725Z,1554851034.725 [BR_Ping1D](INFO): startup: 1
2019-04-09T23:03:54.725Z,1554851034.725 [BR_Ping1D](INFO): UART requesting status
2019-04-09T23:03:54.725Z,1554851034.725 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-09T23:03:54.734Z,1554851034.734 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-09T23:03:54.742Z,1554851034.742 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-09T23:03:55.127Z,1554851035.127 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:55.127Z,1554851035.127 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:55.128Z,1554851035.128 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:55.128Z,1554851035.128 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:55.128Z,1554851035.128 [BR_Ping1D](INFO): elapsed 1.2291
2019-04-09T23:03:55.128Z,1554851035.128 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:55.129Z,1554851035.129 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:55.129Z,1554851035.129 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:55.129Z,1554851035.129 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:55.524Z,1554851035.524 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:55.524Z,1554851035.524 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:55.524Z,1554851035.524 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:55.525Z,1554851035.525 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:55.525Z,1554851035.525 [BR_Ping1D](INFO): elapsed 1.6258
2019-04-09T23:03:55.525Z,1554851035.525 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:55.525Z,1554851035.525 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:55.525Z,1554851035.525 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:55.525Z,1554851035.526 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:55.921Z,1554851035.921 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:55.921Z,1554851035.921 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:55.922Z,1554851035.922 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:55.922Z,1554851035.922 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:55.922Z,1554851035.922 [BR_Ping1D](INFO): elapsed 2.0230
2019-04-09T23:03:55.922Z,1554851035.922 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:55.922Z,1554851035.922 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:55.923Z,1554851035.923 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:55.923Z,1554851035.923 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:56.326Z,1554851036.326 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:56.326Z,1554851036.326 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:56.326Z,1554851036.326 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:56.326Z,1554851036.326 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:56.327Z,1554851036.327 [BR_Ping1D](INFO): elapsed 2.4277
2019-04-09T23:03:56.327Z,1554851036.327 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:56.327Z,1554851036.327 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:56.327Z,1554851036.327 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:56.327Z,1554851036.327 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:56.726Z,1554851036.726 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:56.726Z,1554851036.726 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:56.727Z,1554851036.727 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:56.727Z,1554851036.727 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:56.727Z,1554851036.727 [BR_Ping1D](INFO): elapsed 2.8281
2019-04-09T23:03:56.727Z,1554851036.727 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:56.728Z,1554851036.728 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:56.728Z,1554851036.728 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:56.728Z,1554851036.728 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:57.131Z,1554851037.131 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:57.131Z,1554851037.131 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:57.132Z,1554851037.132 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:57.132Z,1554851037.132 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:57.132Z,1554851037.132 [BR_Ping1D](INFO): elapsed 3.2329
2019-04-09T23:03:57.132Z,1554851037.132 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:57.132Z,1554851037.132 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:57.132Z,1554851037.132 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:57.133Z,1554851037.133 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:57.536Z,1554851037.536 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:57.536Z,1554851037.536 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:57.536Z,1554851037.536 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:57.536Z,1554851037.536 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:57.537Z,1554851037.537 [BR_Ping1D](INFO): elapsed 3.6376
2019-04-09T23:03:57.537Z,1554851037.537 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:57.537Z,1554851037.537 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:57.537Z,1554851037.537 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:57.537Z,1554851037.537 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:57.567Z,1554851037.567 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T23:03:57.568Z,1554851037.568 [Aanderaa_O2] No Fault, FailCount= 3
2019-04-09T23:03:57.934Z,1554851037.934 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:03:57.953Z,1554851037.953 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:57.953Z,1554851037.953 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:57.953Z,1554851037.953 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:57.954Z,1554851037.954 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:57.954Z,1554851037.954 [BR_Ping1D](INFO): elapsed 4.0549
2019-04-09T23:03:57.954Z,1554851037.954 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:57.954Z,1554851037.954 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:57.955Z,1554851037.955 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:57.955Z,1554851037.955 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:58.369Z,1554851038.369 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:58.369Z,1554851038.369 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:58.369Z,1554851038.369 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:58.369Z,1554851038.369 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:58.370Z,1554851038.370 [BR_Ping1D](INFO): elapsed 4.4706
2019-04-09T23:03:58.370Z,1554851038.370 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:58.370Z,1554851038.370 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:58.370Z,1554851038.370 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:58.370Z,1554851038.370 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:58.757Z,1554851038.757 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:58.757Z,1554851038.757 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:58.757Z,1554851038.757 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:58.757Z,1554851038.757 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:58.757Z,1554851038.757 [BR_Ping1D](INFO): elapsed 4.8583
2019-04-09T23:03:58.757Z,1554851038.757 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:58.758Z,1554851038.758 [BR_Ping1D](INFO): No UART data
2019-04-09T23:03:58.758Z,1554851038.758 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:03:58.758Z,1554851038.758 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:03:59.150Z,1554851039.150 [BR_Ping1D](INFO): Starting
2019-04-09T23:03:59.150Z,1554851039.150 [BR_Ping1D](INFO): readConfig
2019-04-09T23:03:59.150Z,1554851039.150 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:03:59.150Z,1554851039.150 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:03:59.150Z,1554851039.150 [BR_Ping1D](INFO): elapsed 5.2514
2019-04-09T23:03:59.151Z,1554851039.151 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:03:59.151Z,1554851039.151 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T23:03:59.151Z,1554851039.151 [BR_Ping1D] Data Fault, FailCount= 4
2019-04-09T23:03:59.151Z,1554851039.151 [BR_Ping1D](ERROR): Data Fault
2019-04-09T23:03:59.180Z,1554851039.180 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T23:03:59.555Z,1554851039.555 [BR_Ping1D](INFO): Stop
2019-04-09T23:03:59.555Z,1554851039.555 [BR_Ping1D](INFO): uninitialize
2019-04-09T23:03:59.555Z,1554851039.555 [BR_Ping1D](INFO): Powering down
2019-04-09T23:03:59.959Z,1554851039.959 [BR_Ping1D](INFO): Stopping
2019-04-09T23:04:00.362Z,1554851040.362 [BR_Ping1D](INFO): Stopping
2019-04-09T23:04:00.762Z,1554851040.762 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:04:00.776Z,1554851040.776 [BR_Ping1D](INFO): Stopped
2019-04-09T23:04:00.796Z,1554851040.796 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T23:04:00.797Z,1554851040.797 [BR_Ping1D] No Fault, FailCount= 4
2019-04-09T23:04:01.184Z,1554851041.184 [BR_Ping1D](INFO): Start
2019-04-09T23:04:01.593Z,1554851041.593 [BR_Ping1D](INFO): Starting
2019-04-09T23:04:01.975Z,1554851041.975 [BR_Ping1D](INFO): Starting
2019-04-09T23:04:01.975Z,1554851041.975 [BR_Ping1D](INFO): readConfig
2019-04-09T23:04:01.976Z,1554851041.976 [BR_Ping1D](INFO): startup: 0
2019-04-09T23:04:01.976Z,1554851041.976 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T23:04:01.990Z,1554851041.990 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T23:04:01.990Z,1554851041.990 [BR_Ping1D](INFO): data 011E
2019-04-09T23:04:01.990Z,1554851041.990 [BR_Ping1D](INFO): 42520200EC030000011EA401
2019-04-09T23:04:01.991Z,1554851041.991 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T23:04:01.992Z,1554851041.992 [BR_Ping1D](INFO): 42520100EB030000840115
2019-04-09T23:04:01.993Z,1554851041.993 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T23:04:01.993Z,1554851041.993 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-09T23:04:01.994Z,1554851041.994 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T23:04:01.995Z,1554851041.995 [BR_Ping1D](INFO): 42520100EE030000870115
2019-04-09T23:04:02.382Z,1554851042.382 [BR_Ping1D](INFO): Starting
2019-04-09T23:04:02.791Z,1554851042.791 [BR_Ping1D](INFO): Starting
2019-04-09T23:04:02.791Z,1554851042.791 [BR_Ping1D](INFO): readConfig
2019-04-09T23:04:02.791Z,1554851042.791 [BR_Ping1D](INFO): startup: 1
2019-04-09T23:04:02.791Z,1554851042.791 [BR_Ping1D](INFO): UART requesting status
2019-04-09T23:04:02.791Z,1554851042.791 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-09T23:04:02.798Z,1554851042.798 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-09T23:04:02.806Z,1554851042.806 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-09T23:04:03.191Z,1554851043.191 [BR_Ping1D](INFO): Starting
2019-04-09T23:04:03.191Z,1554851043.191 [BR_Ping1D](INFO): readConfig
2019-04-09T23:04:03.191Z,1554851043.191 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:04:03.191Z,1554851043.191 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:04:03.191Z,1554851043.191 [BR_Ping1D](INFO): elapsed 1.2155
2019-04-09T23:04:03.191Z,1554851043.191 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:04:03.192Z,1554851043.192 [BR_Ping1D](INFO): No UART data
2019-04-09T23:04:03.192Z,1554851043.192 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:04:03.192Z,1554851043.192 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:04:03.590Z,1554851043.590 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:04:03.604Z,1554851043.604 [BR_Ping1D](INFO): Starting
2019-04-09T23:04:03.604Z,1554851043.604 [BR_Ping1D](INFO): readConfig
2019-04-09T23:04:03.605Z,1554851043.605 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:04:03.605Z,1554851043.605 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:04:03.605Z,1554851043.605 [BR_Ping1D](INFO): elapsed 1.6291
2019-04-09T23:04:03.605Z,1554851043.605 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:04:03.605Z,1554851043.605 [BR_Ping1D](INFO): No UART data
2019-04-09T23:04:03.606Z,1554851043.606 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:04:03.606Z,1554851043.606 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:04:04.012Z,1554851044.012 [BR_Ping1D](INFO): Starting
2019-04-09T23:04:04.012Z,1554851044.012 [BR_Ping1D](INFO): readConfig
2019-04-09T23:04:04.013Z,1554851044.013 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:04:04.013Z,1554851044.013 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:04:04.013Z,1554851044.013 [BR_Ping1D](INFO): elapsed 2.0373
2019-04-09T23:04:04.013Z,1554851044.013 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:04:04.014Z,1554851044.014 [BR_Ping1D](INFO): No UART data
2019-04-09T23:04:04.014Z,1554851044.014 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:04:04.014Z,1554851044.014 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:04:04.415Z,1554851044.415 [BR_Ping1D](INFO): Starting
2019-04-09T23:04:04.415Z,1554851044.415 [BR_Ping1D](INFO): readConfig
2019-04-09T23:04:04.415Z,1554851044.415 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:04:04.416Z,1554851044.416 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:04:04.416Z,1554851044.416 [BR_Ping1D](INFO): elapsed 2.4400
2019-04-09T23:04:04.416Z,1554851044.416 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:04:04.416Z,1554851044.416 [BR_Ping1D](INFO): No UART data
2019-04-09T23:04:04.416Z,1554851044.416 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:04:04.417Z,1554851044.417 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:04:04.807Z,1554851044.807 [BR_Ping1D](INFO): Starting
2019-04-09T23:04:04.807Z,1554851044.807 [BR_Ping1D](INFO): readConfig
2019-04-09T23:04:04.807Z,1554851044.807 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:04:04.807Z,1554851044.807 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:04:04.808Z,1554851044.808 [BR_Ping1D](INFO): elapsed 2.8317
2019-04-09T23:04:04.808Z,1554851044.808 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:04:04.808Z,1554851044.808 [BR_Ping1D](INFO): No UART data
2019-04-09T23:04:04.808Z,1554851044.808 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:04:04.808Z,1554851044.808 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:04:05.214Z,1554851045.214 [BR_Ping1D](INFO): Starting
2019-04-09T23:04:05.214Z,1554851045.214 [BR_Ping1D](INFO): readConfig
2019-04-09T23:04:05.214Z,1554851045.214 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:04:05.215Z,1554851045.215 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:04:05.215Z,1554851045.215 [BR_Ping1D](INFO): elapsed 3.2389
2019-04-09T23:04:05.215Z,1554851045.215 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:04:05.215Z,1554851045.215 [BR_Ping1D](INFO): No UART data
2019-04-09T23:04:05.215Z,1554851045.215 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:04:05.215Z,1554851045.215 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:04:05.610Z,1554851045.610 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:04:05.625Z,1554851045.625 [BR_Ping1D](INFO): Starting
2019-04-09T23:04:05.625Z,1554851045.625 [BR_Ping1D](INFO): readConfig
2019-04-09T23:04:05.625Z,1554851045.625 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:04:05.626Z,1554851045.626 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:04:05.626Z,1554851045.626 [BR_Ping1D](INFO): elapsed 3.6501
2019-04-09T23:04:05.626Z,1554851045.626 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:04:05.626Z,1554851045.626 [BR_Ping1D](INFO): No UART data
2019-04-09T23:04:05.627Z,1554851045.627 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:04:05.627Z,1554851045.627 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:04:06.032Z,1554851046.032 [BR_Ping1D](INFO): Starting
2019-04-09T23:04:06.032Z,1554851046.032 [BR_Ping1D](INFO): readConfig
2019-04-09T23:04:06.032Z,1554851046.032 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:04:06.033Z,1554851046.033 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:04:06.033Z,1554851046.033 [BR_Ping1D](INFO): elapsed 4.0569
2019-04-09T23:04:06.033Z,1554851046.033 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:04:06.033Z,1554851046.033 [BR_Ping1D](INFO): No UART data
2019-04-09T23:04:06.033Z,1554851046.033 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:04:06.033Z,1554851046.033 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:04:06.433Z,1554851046.433 [BR_Ping1D](INFO): Starting
2019-04-09T23:04:06.433Z,1554851046.433 [BR_Ping1D](INFO): readConfig
2019-04-09T23:04:06.433Z,1554851046.433 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:04:06.433Z,1554851046.433 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:04:06.434Z,1554851046.434 [BR_Ping1D](INFO): elapsed 4.4579
2019-04-09T23:04:06.434Z,1554851046.434 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:04:06.434Z,1554851046.434 [BR_Ping1D](INFO): No UART data
2019-04-09T23:04:06.434Z,1554851046.434 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:04:06.434Z,1554851046.434 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:04:06.827Z,1554851046.827 [BR_Ping1D](INFO): Starting
2019-04-09T23:04:06.827Z,1554851046.827 [BR_Ping1D](INFO): readConfig
2019-04-09T23:04:06.827Z,1554851046.827 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:04:06.828Z,1554851046.828 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:04:06.828Z,1554851046.828 [BR_Ping1D](INFO): elapsed 4.8519
2019-04-09T23:04:06.828Z,1554851046.828 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:04:06.828Z,1554851046.828 [BR_Ping1D](INFO): No UART data
2019-04-09T23:04:06.828Z,1554851046.828 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T23:04:06.828Z,1554851046.828 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T23:04:07.230Z,1554851047.230 [BR_Ping1D](INFO): Starting
2019-04-09T23:04:07.230Z,1554851047.230 [BR_Ping1D](INFO): readConfig
2019-04-09T23:04:07.230Z,1554851047.230 [BR_Ping1D](INFO): startup: 2
2019-04-09T23:04:07.231Z,1554851047.231 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T23:04:07.231Z,1554851047.231 [BR_Ping1D](INFO): elapsed 5.2550
2019-04-09T23:04:07.231Z,1554851047.231 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T23:04:07.231Z,1554851047.231 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T23:04:07.231Z,1554851047.231 [BR_Ping1D] Data Fault, FailCount= 5
2019-04-09T23:04:07.231Z,1554851047.231 [BR_Ping1D](ERROR): Data Fault
2019-04-09T23:04:07.252Z,1554851047.252 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T23:04:07.252Z,1554851047.252 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-04-09T23:04:07.630Z,1554851047.630 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:04:07.644Z,1554851047.644 [BR_Ping1D](INFO): Stop
2019-04-09T23:04:07.644Z,1554851047.644 [BR_Ping1D](INFO): uninitialize
2019-04-09T23:04:07.645Z,1554851047.645 [BR_Ping1D](INFO): Powering down
2019-04-09T23:04:07.749Z,1554851047.749 [CBIT](INFO): Critical error at 20190409T230407
2019-04-09T23:04:08.018Z,1554851048.018 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T23:04:08.018Z,1554851048.018 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T23:04:08.018Z,1554851048.018 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T23:04:08.059Z,1554851048.059 [BR_Ping1D](INFO): Stopping
2019-04-09T23:04:08.084Z,1554851048.084 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T23:04:08.542Z,1554851048.542 [Aanderaa_O2](INFO): Powering down
2019-04-09T23:04:08.581Z,1554851048.581 [BR_Ping1D](INFO): Stopping
2019-04-09T23:04:08.846Z,1554851048.846 [BR_Ping1D](INFO): Stopped
2019-04-09T23:04:09.271Z,1554851049.271 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T23:04:09.271Z,1554851049.271 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-09T23:04:10.866Z,1554851050.866 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:04:13.694Z,1554851053.694 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:04:14.927Z,1554851054.927 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T23:04:14.927Z,1554851054.927 [CTD_Seabird] No Fault, FailCount= 3
2019-04-09T23:04:15.258Z,1554851055.258 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T23:04:15.260Z,1554851055.260 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T23:04:16.926Z,1554851056.926 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:04:19.734Z,1554851059.734 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T23:04:19.734Z,1554851059.734 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-09T23:04:19.734Z,1554851059.734 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T23:04:19.754Z,1554851059.754 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:04:19.788Z,1554851059.788 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T23:04:20.258Z,1554851060.258 [Aanderaa_O2](INFO): Powering down
2019-04-09T23:04:20.984Z,1554851060.984 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T23:04:20.984Z,1554851060.984 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-09T23:04:22.982Z,1554851062.982 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:04:25.810Z,1554851065.810 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:04:28.330Z,1554851068.330 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T23:04:28.330Z,1554851068.330 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-09T23:04:28.330Z,1554851068.330 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T23:04:28.646Z,1554851068.646 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:04:28.674Z,1554851068.674 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T23:04:28.734Z,1554851068.734 [CTD_Seabird](INFO): Powering down
2019-04-09T23:04:29.873Z,1554851069.873 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T23:04:29.873Z,1554851069.873 [CTD_Seabird] No Fault, FailCount= 1
2019-04-09T23:04:30.078Z,1554851070.078 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T23:04:30.080Z,1554851070.080 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T23:04:30.662Z,1554851070.662 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:04:31.450Z,1554851071.450 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T23:04:31.450Z,1554851071.450 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-09T23:04:31.450Z,1554851071.450 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T23:04:31.505Z,1554851071.505 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T23:04:31.506Z,1554851071.506 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-09T23:04:31.974Z,1554851071.974 [Aanderaa_O2](INFO): Powering down
2019-04-09T23:04:33.894Z,1554851073.894 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:04:36.722Z,1554851076.722 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:04:39.954Z,1554851079.954 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:04:42.782Z,1554851082.782 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:04:43.130Z,1554851083.130 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T23:04:43.130Z,1554851083.130 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-09T23:04:43.130Z,1554851083.130 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T23:04:43.261Z,1554851083.261 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T23:04:43.534Z,1554851083.534 [CTD_Seabird](INFO): Powering down
2019-04-09T23:04:44.820Z,1554851084.820 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T23:04:44.820Z,1554851084.820 [CTD_Seabird] No Fault, FailCount= 2
2019-04-09T23:04:44.878Z,1554851084.878 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T23:04:44.880Z,1554851084.880 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T23:04:46.014Z,1554851086.014 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:04:48.838Z,1554851088.838 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:04:51.666Z,1554851091.666 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:04:53.686Z,1554851093.686 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:04:56.918Z,1554851096.918 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:04:57.931Z,1554851097.931 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T23:04:57.932Z,1554851097.932 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-09T23:04:57.932Z,1554851097.932 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T23:04:58.152Z,1554851098.152 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T23:04:58.152Z,1554851098.152 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-09T23:04:58.335Z,1554851098.335 [CTD_Seabird](INFO): Powering down
2019-04-09T23:04:59.746Z,1554851099.746 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:05:02.986Z,1554851102.986 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:05:05.810Z,1554851105.810 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:05:09.038Z,1554851109.038 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:05:11.870Z,1554851111.870 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:05:14.698Z,1554851114.698 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:05:16.718Z,1554851116.718 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:05:18.734Z,1554851118.734 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:05:21.966Z,1554851121.966 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:05:24.794Z,1554851124.794 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:05:28.026Z,1554851128.026 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:05:30.858Z,1554851130.858 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:05:34.086Z,1554851134.086 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:05:36.918Z,1554851136.918 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:05:39.750Z,1554851139.750 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:05:41.766Z,1554851141.766 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:05:44.998Z,1554851144.998 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:05:47.826Z,1554851147.826 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:05:51.054Z,1554851151.054 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:05:52.681Z,1554851152.681 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T23:05:52.681Z,1554851152.681 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-04-09T23:05:52.681Z,1554851152.681 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T23:05:52.683Z,1554851152.683 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T23:05:52.683Z,1554851152.683 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2019-04-09T23:05:52.683Z,1554851152.683 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T23:05:52.684Z,1554851152.684 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T23:05:52.684Z,1554851152.684 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2019-04-09T23:05:52.684Z,1554851152.684 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T23:05:52.706Z,1554851152.706 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T23:05:52.707Z,1554851152.707 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T23:05:52.707Z,1554851152.707 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T23:05:53.095Z,1554851153.095 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T23:05:53.095Z,1554851153.095 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-04-09T23:05:53.095Z,1554851153.095 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T23:05:53.095Z,1554851153.095 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2019-04-09T23:05:53.096Z,1554851153.096 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T23:05:53.096Z,1554851153.096 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2019-04-09T23:05:53.489Z,1554851153.489 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T23:05:53.882Z,1554851153.882 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:05:57.114Z,1554851157.114 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:05:57.149Z,1554851157.149 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T23:05:57.149Z,1554851157.149 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 8
2019-04-09T23:05:57.502Z,1554851157.502 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T23:05:57.503Z,1554851157.503 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T23:05:59.946Z,1554851159.946 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:06:02.774Z,1554851162.774 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:06:04.794Z,1554851164.794 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:06:06.814Z,1554851166.814 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:06:10.046Z,1554851170.046 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:06:12.870Z,1554851172.870 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:06:16.106Z,1554851176.106 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:06:17.702Z,1554851177.702 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T23:06:17.703Z,1554851177.703 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-04-09T23:06:17.703Z,1554851177.703 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T23:06:17.752Z,1554851177.752 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T23:06:18.106Z,1554851178.106 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T23:06:18.934Z,1554851178.934 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:06:18.976Z,1554851178.976 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T23:06:18.976Z,1554851178.976 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-04-09T23:06:21.339Z,1554851181.339 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T23:06:21.339Z,1554851181.339 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T23:06:22.194Z,1554851182.194 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:06:22.611Z,1554851182.611 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-04-09T23:01:22.4Z
2019-04-09T23:06:22.612Z,1554851182.612 [Default:CheckIn:Read_GPS] Stopped
2019-04-09T23:06:22.612Z,1554851182.612 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-04-09T23:06:23.003Z,1554851183.003 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-09T23:06:24.838Z,1554851184.838 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:06:28.078Z,1554851188.078 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:06:28.365Z,1554851188.365 [DataOverHttps](INFO): Sending 312 bytes from file Logs/20190409T225738/Courier0000.lzma
2019-04-09T23:06:29.168Z,1554851189.168 [DataOverHttps](INFO): Moved sent file to Logs/20190409T225738/Courier0000.lzma.bak
2019-04-09T23:06:29.169Z,1554851189.169 [DataOverHttps](INFO): SBD MOMSN=10520617
2019-04-09T23:06:30.902Z,1554851190.902 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:06:33.508Z,1554851193.508 [CommandLine](IMPORTANT): got command restart application
2019-04-09T23:06:34.134Z,1554851194.134 [NAL9602](DEBUG): Fix Requested
2019-04-09T23:06:34.515Z,1554851194.515 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T23:06:34.515Z,1554851194.515 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T23:06:34.670Z,1554851194.670 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-04-09T23:06:34.670Z,1554851194.670 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T23:06:34.671Z,1554851194.671 [CommandLine](INFO): Join timeout helper Thread ID is 7734
2019-04-09T23:06:34.671Z,1554851194.671 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-04-09T23:06:34.671Z,1554851194.671 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T23:06:34.672Z,1554851194.672 [NavChartDb](INFO): Join timeout helper Thread ID is 7735
2019-04-09T23:06:34.710Z,1554851194.710 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T23:06:34.710Z,1554851194.710 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T23:06:34.730Z,1554851194.730 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-04-09T23:06:34.730Z,1554851194.730 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T23:06:34.731Z,1554851194.731 [Radio_Surface](INFO): Join timeout helper Thread ID is 7736
2019-04-09T23:06:34.846Z,1554851194.846 [Radio_Surface](INFO): Powering down
2019-04-09T23:06:34.847Z,1554851194.847 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T23:06:34.847Z,1554851194.847 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T23:06:34.850Z,1554851194.850 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-04-09T23:06:34.850Z,1554851194.850 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T23:06:34.850Z,1554851194.850 [DataOverHttps](INFO): Join timeout helper Thread ID is 7737
2019-04-09T23:06:35.222Z,1554851195.222 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T23:06:35.223Z,1554851195.223 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T23:06:35.224Z,1554851195.224 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-04-09T23:06:35.224Z,1554851195.224 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T23:06:35.224Z,1554851195.224 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7738
2019-04-09T23:06:35.466Z,1554851195.466 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T23:06:35.467Z,1554851195.467 [WetLabsBB2FL](INFO): Powering down
2019-04-09T23:06:35.467Z,1554851195.467 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T23:06:35.471Z,1554851195.471 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-04-09T23:06:35.471Z,1554851195.471 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T23:06:35.471Z,1554851195.471 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7739
2019-04-09T23:06:35.838Z,1554851195.838 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T23:06:35.838Z,1554851195.838 [CTD_Seabird](INFO): Powering down
2019-04-09T23:06:35.850Z,1554851195.850 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T23:06:35.855Z,1554851195.855 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-04-09T23:06:35.855Z,1554851195.855 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T23:06:35.855Z,1554851195.855 [logger](INFO): Join timeout helper Thread ID is 7740
2019-04-09T23:06:35.866Z,1554851195.866 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T23:06:35.866Z,1554851195.866 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T23:06:35.875Z,1554851195.875 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-04-09T23:06:35.875Z,1554851195.875 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T23:06:35.875Z,1554851195.875 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-04-09T23:06:35.875Z,1554851195.875 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T23:06:35.875Z,1554851195.875 [controlThread](INFO): Join timeout helper Thread ID is 7741
2019-04-09T23:06:36.130Z,1554851196.130 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T23:06:36.130Z,1554851196.130 [controlThread](DEBUG): Uninitializing ControlThread
2019-04-09T23:06:36.130Z,1554851196.130 [Aanderaa_O2](INFO): Powering down
2019-04-09T23:06:36.132Z,1554851196.132 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T23:06:36.250Z,1554851196.250 [NAL9602](INFO): Powering down
2019-04-09T23:06:36.252Z,1554851196.252 [BR_Ping1D](INFO): uninitialize
2019-04-09T23:06:36.252Z,1554851196.252 [BR_Ping1D](INFO): Powering down
2019-04-09T23:06:36.253Z,1554851196.253 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-04-09T23:06:36.254Z,1554851196.254 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-04-09T23:06:36.255Z,1554851196.255 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-04-09T23:06:36.256Z,1554851196.256 [MissionManager](INFO): Uninitializing Mission Default
2019-04-09T23:06:36.256Z,1554851196.256 [Default] Stopped
2019-04-09T23:06:36.256Z,1554851196.256 [Default](DEBUG): Aggregate::uninitialize Default
2019-04-09T23:06:36.256Z,1554851196.256 [Default:B.GoToSurface] Stopped
2019-04-09T23:06:36.256Z,1554851196.256 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-09T23:06:36.256Z,1554851196.256 [Default:CheckIn] Stopped
2019-04-09T23:06:36.256Z,1554851196.256 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-09T23:06:36.256Z,1554851196.256 [Default:CheckIn:Read_Iridium] Stopped
2019-04-09T23:06:36.259Z,1554851196.259 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-04-09T23:06:36.259Z,1554851196.259 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-04-09T23:06:36.259Z,1554851196.259 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-04-09T23:06:36.260Z,1554851196.260 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-04-09T23:06:36.260Z,1554851196.260 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-04-09T23:06:36.260Z,1554851196.260 [BuoyancyServo](INFO): Powering down
2019-04-09T23:06:36.274Z,1554851196.274 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-09T23:06:36.274Z,1554851196.274 [ElevatorServo](INFO): Powering down
2019-04-09T23:06:36.275Z,1554851196.275 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T23:06:36.275Z,1554851196.275 [MassServo](INFO): Powering down
2019-04-09T23:06:36.276Z,1554851196.276 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-09T23:06:36.276Z,1554851196.276 [RudderServo](INFO): Powering down
2019-04-09T23:06:36.277Z,1554851196.277 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-04-09T23:06:36.277Z,1554851196.277 [ThrusterServo](INFO): Powering down
2019-04-09T23:06:36.278Z,1554851196.278 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-04-09T23:06:36.278Z,1554851196.278 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-04-09T23:06:36.279Z,1554851196.279 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-04-09T23:06:36.279Z,1554851196.279 [CBIT](DEBUG): Powering off loads.
2019-04-09T23:06:36.290Z,1554851196.290 [CBIT](DEBUG): Disabling WDT.
2019-04-09T23:06:36.302Z,1554851196.302 [CBIT](DEBUG): Opening all GF detection circuits.
2019-04-09T23:06:36.303Z,1554851196.303 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T23:06:36.340Z,1554851196.340 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T23:06:36.345Z,1554851196.345 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T23:06:36.542Z,1554851196.542 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T23:06:36.547Z,1554851196.547 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T23:06:36.598Z,1554851196.598 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T23:06:36.656Z,1554851196.656 [logger ThreadHandler](INFO): Thread cancelled.