2019-04-09T16:08:56.603Z,1554826136.603 [Supervisor](DEBUG): Initializing supervisor.
2019-04-09T16:08:56.606Z,1554826136.606 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-09T16:08:56.606Z,1554826136.606 [SyncHandler](INFO): Protected caller Thread ID is 4045
2019-04-09T16:08:56.607Z,1554826136.607 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-04-09T16:08:56.608Z,1554826136.608 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-04-09T16:08:56.608Z,1554826136.608 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4046
2019-04-09T16:08:56.612Z,1554826136.612 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-04-09T16:08:56.624Z,1554826136.624 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-04-09T16:08:56.625Z,1554826136.625 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-04-09T16:08:56.625Z,1554826136.625 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4047
2019-04-09T16:08:56.626Z,1554826136.626 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-04-09T16:08:56.627Z,1554826136.627 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-04-09T16:08:56.627Z,1554826136.627 [logger ThreadHandler](INFO): Protected caller Thread ID is 4048
2019-04-09T16:08:56.629Z,1554826136.629 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-04-09T16:08:56.630Z,1554826136.630 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-04-09T16:08:56.631Z,1554826136.631 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-04-09T16:08:56.727Z,1554826136.727 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-04-09T16:08:56.728Z,1554826136.728 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-04-09T16:08:56.931Z,1554826136.931 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-04-09T16:08:56.932Z,1554826136.932 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-04-09T16:08:57.070Z,1554826137.070 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-04-09T16:08:57.071Z,1554826137.071 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-04-09T16:08:57.538Z,1554826137.538 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-04-09T16:08:57.538Z,1554826137.538 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-04-09T16:08:57.988Z,1554826137.988 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-04-09T16:08:57.989Z,1554826137.989 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-04-09T16:08:58.465Z,1554826138.465 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-04-09T16:08:58.465Z,1554826138.465 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-04-09T16:08:58.776Z,1554826138.776 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-04-09T16:08:58.777Z,1554826138.777 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-04-09T16:08:59.114Z,1554826139.114 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-04-09T16:08:59.114Z,1554826139.114 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-04-09T16:08:59.517Z,1554826139.517 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-04-09T16:08:59.518Z,1554826139.518 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-04-09T16:08:59.669Z,1554826139.669 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-04-09T16:08:59.670Z,1554826139.670 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-04-09T16:08:59.776Z,1554826139.776 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-04-09T16:08:59.776Z,1554826139.776 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-04-09T16:08:59.857Z,1554826139.857 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-04-09T16:08:59.960Z,1554826139.960 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-04-09T16:08:59.961Z,1554826139.961 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-04-09T16:09:00.157Z,1554826140.157 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-04-09T16:09:00.157Z,1554826140.157 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-04-09T16:09:00.368Z,1554826140.368 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-04-09T16:09:00.370Z,1554826140.370 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-04-09T16:09:00.371Z,1554826140.371 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-04-09T16:09:00.454Z,1554826140.454 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-04-09T16:09:00.691Z,1554826140.691 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-04-09T16:09:00.691Z,1554826140.691 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-04-09T16:09:00.787Z,1554826140.787 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-04-09T16:09:00.963Z,1554826140.963 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-04-09T16:09:01.178Z,1554826141.178 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-04-09T16:09:01.264Z,1554826141.264 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-04-09T16:09:01.363Z,1554826141.363 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-04-09T16:09:01.456Z,1554826141.456 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-04-09T16:09:01.621Z,1554826141.621 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-04-09T16:09:01.788Z,1554826141.788 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-04-09T16:09:02.097Z,1554826142.097 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2019-04-09T16:09:02.097Z,1554826142.097 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-04-09T16:09:02.102Z,1554826142.102 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-04-09T16:09:02.262Z,1554826142.262 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-04-09T16:09:02.262Z,1554826142.262 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-04-09T16:09:02.393Z,1554826142.393 [BuoyancyServo] Loaded
2019-04-09T16:09:02.393Z,1554826142.393 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-04-09T16:09:02.408Z,1554826142.408 [ElevatorServo] Loaded
2019-04-09T16:09:02.409Z,1554826142.409 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-04-09T16:09:02.424Z,1554826142.424 [MassServo] Loaded
2019-04-09T16:09:02.424Z,1554826142.424 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-04-09T16:09:02.440Z,1554826142.440 [RudderServo] Loaded
2019-04-09T16:09:02.440Z,1554826142.440 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-04-09T16:09:02.454Z,1554826142.454 [ThrusterServo] Loaded
2019-04-09T16:09:02.455Z,1554826142.455 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-04-09T16:09:02.455Z,1554826142.455 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-04-09T16:09:02.456Z,1554826142.456 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-04-09T16:09:02.595Z,1554826142.595 [SBIT](DEBUG): Construct Startup Built In Test.
2019-04-09T16:09:02.624Z,1554826142.624 [SBIT] Loaded
2019-04-09T16:09:02.624Z,1554826142.624 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-04-09T16:09:02.625Z,1554826142.625 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-04-09T16:09:02.653Z,1554826142.653 [IBIT] Loaded
2019-04-09T16:09:02.654Z,1554826142.654 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-04-09T16:09:02.657Z,1554826142.657 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-04-09T16:09:03.052Z,1554826143.052 [CBIT] Loaded
2019-04-09T16:09:03.052Z,1554826143.052 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-04-09T16:09:03.053Z,1554826143.053 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-04-09T16:09:03.053Z,1554826143.053 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-04-09T16:09:03.241Z,1554826143.241 [Aanderaa_O2] Loaded
2019-04-09T16:09:03.241Z,1554826143.241 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-04-09T16:09:03.251Z,1554826143.251 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-04-09T16:09:03.256Z,1554826143.256 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-04-09T16:09:03.258Z,1554826143.258 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-04-09T16:09:03.263Z,1554826143.263 [CTD_Seabird](INFO): created writer for : depth
2019-04-09T16:09:03.264Z,1554826143.264 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-04-09T16:09:03.269Z,1554826143.269 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-04-09T16:09:03.270Z,1554826143.270 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-04-09T16:09:03.275Z,1554826143.275 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-04-09T16:09:03.275Z,1554826143.275 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-04-09T16:09:03.281Z,1554826143.281 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-04-09T16:09:03.282Z,1554826143.282 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-04-09T16:09:03.287Z,1554826143.287 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-04-09T16:09:03.288Z,1554826143.288 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-04-09T16:09:03.293Z,1554826143.293 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-04-09T16:09:03.320Z,1554826143.320 [CTD_Seabird] Loaded
2019-04-09T16:09:03.321Z,1554826143.321 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-04-09T16:09:03.322Z,1554826143.322 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406654E0
2019-04-09T16:09:03.322Z,1554826143.322 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4127
2019-04-09T16:09:03.348Z,1554826143.348 [ESPComponent] Loaded
2019-04-09T16:09:03.348Z,1554826143.348 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-04-09T16:09:03.363Z,1554826143.363 [PAR_Licor] Loaded
2019-04-09T16:09:03.363Z,1554826143.363 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-04-09T16:09:03.412Z,1554826143.412 [WetLabsBB2FL] Loaded
2019-04-09T16:09:03.412Z,1554826143.412 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-04-09T16:09:03.413Z,1554826143.413 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406954E0
2019-04-09T16:09:03.414Z,1554826143.414 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4128
2019-04-09T16:09:03.414Z,1554826143.414 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-04-09T16:09:03.415Z,1554826143.415 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-04-09T16:09:03.456Z,1554826143.456 [DepthRateCalculator] Loaded
2019-04-09T16:09:03.457Z,1554826143.457 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-04-09T16:09:03.462Z,1554826143.462 [PitchRateCalculator] Loaded
2019-04-09T16:09:03.463Z,1554826143.463 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-04-09T16:09:03.479Z,1554826143.479 [SpeedCalculator] Loaded
2019-04-09T16:09:03.479Z,1554826143.479 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-04-09T16:09:03.501Z,1554826143.501 [TempGradientCalculator] Loaded
2019-04-09T16:09:03.501Z,1554826143.501 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-04-09T16:09:03.508Z,1554826143.508 [YawRateCalculator] Loaded
2019-04-09T16:09:03.508Z,1554826143.508 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-04-09T16:09:03.541Z,1554826143.541 [ElevatorOffsetCalculator] Loaded
2019-04-09T16:09:03.542Z,1554826143.542 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-04-09T16:09:03.542Z,1554826143.542 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-04-09T16:09:03.543Z,1554826143.543 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-04-09T16:09:03.570Z,1554826143.570 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-04-09T16:09:03.570Z,1554826143.570 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-04-09T16:09:03.675Z,1554826143.675 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-04-09T16:09:03.675Z,1554826143.675 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-04-09T16:09:03.736Z,1554826143.736 [DeadReckonUsingSpeedCalculator] Loaded
2019-04-09T16:09:03.736Z,1554826143.736 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-04-09T16:09:03.797Z,1554826143.797 [DeadReckonWithRespectToSeafloor] Loaded
2019-04-09T16:09:03.798Z,1554826143.798 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-04-09T16:09:03.819Z,1554826143.819 [NavChart] Loaded
2019-04-09T16:09:03.819Z,1554826143.819 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-04-09T16:09:03.823Z,1554826143.823 [UniversalFixResidualReporter] Loaded
2019-04-09T16:09:03.823Z,1554826143.823 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-04-09T16:09:03.824Z,1554826143.824 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-04-09T16:09:03.824Z,1554826143.824 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-04-09T16:09:04.142Z,1554826144.142 [AcousticModem_Benthos_ATM900] Loaded
2019-04-09T16:09:04.142Z,1554826144.142 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-04-09T16:09:04.214Z,1554826144.214 [DataOverHttps] Loaded
2019-04-09T16:09:04.214Z,1554826144.214 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-04-09T16:09:04.215Z,1554826144.215 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409714E0
2019-04-09T16:09:04.216Z,1554826144.216 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4129
2019-04-09T16:09:04.229Z,1554826144.229 [Depth_Keller] Loaded
2019-04-09T16:09:04.230Z,1554826144.230 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-04-09T16:09:04.324Z,1554826144.324 [NAL9602] Loaded
2019-04-09T16:09:04.324Z,1554826144.324 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-04-09T16:09:04.329Z,1554826144.329 [Onboard] Loaded
2019-04-09T16:09:04.330Z,1554826144.330 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-04-09T16:09:04.333Z,1554826144.333 [Radio_Surface] Loaded
2019-04-09T16:09:04.333Z,1554826144.333 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-04-09T16:09:04.334Z,1554826144.334 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A14E0
2019-04-09T16:09:04.335Z,1554826144.335 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4130
2019-04-09T16:09:05.669Z,1554826145.668 [BPC1] Loaded
2019-04-09T16:09:05.669Z,1554826145.669 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-04-09T16:09:05.700Z,1554826145.700 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minrange
2019-04-09T16:09:05.702Z,1554826145.702 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.maxrange
2019-04-09T16:09:05.704Z,1554826145.704 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.soundspeed
2019-04-09T16:09:05.706Z,1554826145.706 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.automode
2019-04-09T16:09:05.708Z,1554826145.708 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.gainindex
2019-04-09T16:09:05.710Z,1554826145.710 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minconfidence
2019-04-09T16:09:05.712Z,1554826145.712 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.pingenable
2019-04-09T16:09:05.715Z,1554826145.715 [BR_Ping1D] Loaded
2019-04-09T16:09:05.715Z,1554826145.715 [ComponentRegistry](DEBUG): SyncComponent "BR_Ping1D" handled in the control thread.
2019-04-09T16:09:05.716Z,1554826145.716 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-04-09T16:09:05.716Z,1554826145.716 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-04-09T16:09:05.781Z,1554826145.781 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-04-09T16:09:05.781Z,1554826145.781 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-04-09T16:09:05.849Z,1554826145.849 [VerticalControl](DEBUG): Construct VerticalControl.
2019-04-09T16:09:05.935Z,1554826145.935 [VerticalControl] Loaded
2019-04-09T16:09:05.935Z,1554826145.935 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-04-09T16:09:05.936Z,1554826145.936 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-04-09T16:09:05.994Z,1554826145.994 [HorizontalControl] Loaded
2019-04-09T16:09:05.994Z,1554826145.994 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-04-09T16:09:05.995Z,1554826145.995 [SpeedControl](DEBUG): Construct SpeedControl.
2019-04-09T16:09:05.997Z,1554826145.997 [SpeedControl] Loaded
2019-04-09T16:09:05.997Z,1554826145.997 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-04-09T16:09:05.998Z,1554826145.998 [LoopControl](DEBUG): Construct LoopControl.
2019-04-09T16:09:05.998Z,1554826145.998 [LoopControl] Loaded
2019-04-09T16:09:05.999Z,1554826145.999 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-04-09T16:09:05.999Z,1554826145.999 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-04-09T16:09:05.000Z,1554826146.000 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-04-09T16:09:06.013Z,1554826146.013 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-04-09T16:09:06.014Z,1554826146.014 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-04-09T16:09:06.299Z,1554826146.299 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-04-09T16:09:06.302Z,1554826146.302 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-04-09T16:09:06.303Z,1554826146.303 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-04-09T16:09:06.310Z,1554826146.310 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-04-09T16:09:06.311Z,1554826146.311 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACE4E0
2019-04-09T16:09:06.311Z,1554826146.311 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4131
2019-04-09T16:09:06.316Z,1554826146.316 [Supervisor](INFO): Main Thread ID is 4347
2019-04-09T16:09:06.316Z,1554826146.316 [Supervisor](DEBUG): Running supervisor.
2019-04-09T16:09:06.317Z,1554826146.317 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4132
2019-04-09T16:09:06.320Z,1554826146.320 [controlThread ThreadHandler](INFO): Handler Thread ID is 4133
2019-04-09T16:09:06.320Z,1554826146.320 [controlThread](DEBUG): Initializing ControlThread
2019-04-09T16:09:06.322Z,1554826146.322 [SBIT](INFO): Initialize SBIT Component.
2019-04-09T16:09:06.322Z,1554826146.322 [SBIT](IMPORTANT): git: 2019-03-18-31-ge98d5dc
2019-04-09T16:09:06.322Z,1554826146.322 [SBIT](INFO): git hash: e98d5dc50cbc39e402f19b9946ff4dc0776c8725
2019-04-09T16:09:06.323Z,1554826146.323 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-04-09T16:09:06.324Z,1554826146.324 [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-09T16:09:06.325Z,1554826146.325 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-04-09T16:09:06.326Z,1554826146.326 [IBIT](INFO): Initialize IBIT Component.
2019-04-09T16:09:06.327Z,1554826146.327 [CBIT](DEBUG): Initialize CBIT Component.
2019-04-09T16:09:06.328Z,1554826146.328 [logger ThreadHandler](INFO): Handler Thread ID is 4134
2019-04-09T16:09:06.338Z,1554826146.338 [CBIT](DEBUG): Initialized mux pins.
2019-04-09T16:09:06.338Z,1554826146.338 [CBIT](DEBUG): Initializing the watchdog timer.
2019-04-09T16:09:06.346Z,1554826146.346 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4135
2019-04-09T16:09:06.347Z,1554826146.347 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T16:09:06.351Z,1554826146.351 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T16:09:06.352Z,1554826146.352 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4137
2019-04-09T16:09:06.353Z,1554826146.353 [WetLabsBB2FL](INFO): Powering down
2019-04-09T16:09:06.362Z,1554826146.362 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-04-09T16:09:06.363Z,1554826146.363 [CBIT](DEBUG): Initializing heartbeat.
2019-04-09T16:09:06.382Z,1554826146.382 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4138
2019-04-09T16:09:06.383Z,1554826146.383 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-04-09T16:09:06.392Z,1554826146.392 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4139
2019-04-09T16:09:06.410Z,1554826146.410 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4140
2019-04-09T16:09:06.413Z,1554826146.413 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-09T16:09:06.413Z,1554826146.413 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-04-09T16:09:06.414Z,1554826146.414 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-09T16:09:06.414Z,1554826146.414 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-04-09T16:09:06.414Z,1554826146.414 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-09T16:09:06.414Z,1554826146.414 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-04-09T16:09:06.415Z,1554826146.415 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-09T16:09:06.415Z,1554826146.415 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-04-09T16:09:06.415Z,1554826146.415 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-09T16:09:06.415Z,1554826146.415 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-04-09T16:09:06.415Z,1554826146.415 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-09T16:09:06.415Z,1554826146.415 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-04-09T16:09:06.416Z,1554826146.416 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-09T16:09:06.416Z,1554826146.416 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-04-09T16:09:06.416Z,1554826146.416 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-09T16:09:06.416Z,1554826146.416 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-04-09T16:09:06.434Z,1554826146.434 [CBIT](DEBUG): Deactivating GF circuits.
2019-04-09T16:09:06.434Z,1554826146.434 [CBIT](DEBUG): Deactivating emergency mode.
2019-04-09T16:09:06.470Z,1554826146.470 [CBIT](DEBUG): Backplane powered.
2019-04-09T16:09:06.474Z,1554826146.474 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-04-09T16:09:06.475Z,1554826146.475 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-04-09T16:09:06.475Z,1554826146.475 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-04-09T16:09:06.475Z,1554826146.475 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-04-09T16:09:06.476Z,1554826146.476 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-04-09T16:09:06.476Z,1554826146.476 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-04-09T16:09:06.477Z,1554826146.477 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T16:09:06.478Z,1554826146.478 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-04-09T16:09:06.479Z,1554826146.479 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-04-09T16:09:06.487Z,1554826146.487 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-04-09T16:09:06.489Z,1554826146.489 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-04-09T16:09:06.490Z,1554826146.490 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-04-09T16:09:06.491Z,1554826146.491 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-04-09T16:09:06.491Z,1554826146.491 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-04-09T16:09:06.517Z,1554826146.517 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T16:09:06.553Z,1554826146.553 [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-09T16:09:06.562Z,1554826146.562 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-04-09T16:09:06.640Z,1554826146.640 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-04-09T16:09:06.641Z,1554826146.641 [Default:A.Wait](DEBUG): Construct Wait.
2019-04-09T16:09:06.659Z,1554826146.659 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T16:09:06.686Z,1554826146.686 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-04-09T16:09:06.689Z,1554826146.689 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-04-09T16:09:06.710Z,1554826146.710 [Default:E.Execute](DEBUG): Construct Execute.
2019-04-09T16:09:06.714Z,1554826146.714 [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-09T16:09:06.738Z,1554826146.738 [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-09T16:09:06.794Z,1554826146.794 [Radio_Surface](INFO): Powering up
2019-04-09T16:09:06.799Z,1554826146.799 [ESPComponent](INFO): powering down ESP
2019-04-09T16:09:07.306Z,1554826147.306 [PAR_Licor](INFO): A/D timeout, 8 tries over 144 ms

2019-04-09T16:09:07.306Z,1554826147.306 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T16:09:07.307Z,1554826147.307 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:09:07.307Z,1554826147.307 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:09:07.307Z,1554826147.307 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:09:07.504Z,1554826147.504 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:09:07.510Z,1554826147.510 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-04-09T16:09:07.511Z,1554826147.511 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:09:07.518Z,1554826147.518 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-09T16:09:07.519Z,1554826147.519 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:09:07.526Z,1554826147.526 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:09:07.527Z,1554826147.527 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:09:07.534Z,1554826147.534 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-09T16:09:07.535Z,1554826147.535 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:09:07.542Z,1554826147.542 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-04-09T16:09:07.545Z,1554826147.545 [CommandLine](FAULT): Scheduling is paused
2019-04-09T16:09:07.545Z,1554826147.545 [CBIT](INFO): Critical error at 20190409T160905
2019-04-09T16:09:07.545Z,1554826147.545 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-04-09T16:09:07.546Z,1554826147.546 [CBIT](FAULT): Main Battery Failure. Count: 1
2019-04-09T16:09:07.550Z,1554826147.550 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:09:07.550Z,1554826147.550 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T16:09:07.550Z,1554826147.550 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T16:09:08.094Z,1554826148.094 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-09T16:09:08.094Z,1554826148.094 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T16:09:08.094Z,1554826148.094 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:09:08.132Z,1554826148.132 [BR_Ping1D](INFO): startup: 0
2019-04-09T16:09:08.133Z,1554826148.133 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T16:09:08.146Z,1554826148.146 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T16:09:08.146Z,1554826148.146 [BR_Ping1D](INFO): 66
2019-04-09T16:09:08.146Z,1554826148.146 [BR_Ping1D](INFO): 82
2019-04-09T16:09:08.146Z,1554826148.146 [BR_Ping1D](INFO): 2
2019-04-09T16:09:08.147Z,1554826148.147 [BR_Ping1D](INFO): 0
2019-04-09T16:09:08.147Z,1554826148.147 [BR_Ping1D](INFO): 236
2019-04-09T16:09:08.147Z,1554826148.147 [BR_Ping1D](INFO): 3
2019-04-09T16:09:08.147Z,1554826148.147 [BR_Ping1D](INFO): 0
2019-04-09T16:09:08.147Z,1554826148.147 [BR_Ping1D](INFO): 0
2019-04-09T16:09:08.147Z,1554826148.147 [BR_Ping1D](INFO): 1
2019-04-09T16:09:08.147Z,1554826148.147 [BR_Ping1D](INFO): 30
2019-04-09T16:09:08.147Z,1554826148.147 [BR_Ping1D](INFO): 164
2019-04-09T16:09:08.147Z,1554826148.147 [BR_Ping1D](INFO): 1
2019-04-09T16:09:08.148Z,1554826148.148 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T16:09:08.148Z,1554826148.148 [BR_Ping1D](INFO): 66
2019-04-09T16:09:08.148Z,1554826148.148 [BR_Ping1D](INFO): 82
2019-04-09T16:09:08.148Z,1554826148.148 [BR_Ping1D](INFO): 1
2019-04-09T16:09:08.148Z,1554826148.148 [BR_Ping1D](INFO): 0
2019-04-09T16:09:08.148Z,1554826148.148 [BR_Ping1D](INFO): 235
2019-04-09T16:09:08.148Z,1554826148.148 [BR_Ping1D](INFO): 3
2019-04-09T16:09:08.148Z,1554826148.148 [BR_Ping1D](INFO): 0
2019-04-09T16:09:08.149Z,1554826148.149 [BR_Ping1D](INFO): 0
2019-04-09T16:09:08.149Z,1554826148.149 [BR_Ping1D](INFO): 132
2019-04-09T16:09:08.149Z,1554826148.149 [BR_Ping1D](INFO): 1
2019-04-09T16:09:08.149Z,1554826148.149 [BR_Ping1D](INFO): 21
2019-04-09T16:09:08.149Z,1554826148.149 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T16:09:08.149Z,1554826148.149 [BR_Ping1D](INFO): 66
2019-04-09T16:09:08.149Z,1554826148.149 [BR_Ping1D](INFO): 82
2019-04-09T16:09:08.149Z,1554826148.149 [BR_Ping1D](INFO): 4
2019-04-09T16:09:08.150Z,1554826148.150 [BR_Ping1D](INFO): 0
2019-04-09T16:09:08.150Z,1554826148.150 [BR_Ping1D](INFO): 234
2019-04-09T16:09:08.151Z,1554826148.151 [BR_Ping1D](INFO): 3
2019-04-09T16:09:08.151Z,1554826148.151 [BR_Ping1D](INFO): 0
2019-04-09T16:09:08.152Z,1554826148.152 [BR_Ping1D](INFO): 0
2019-04-09T16:09:08.152Z,1554826148.152 [BR_Ping1D](INFO): 96
2019-04-09T16:09:08.153Z,1554826148.153 [BR_Ping1D](INFO): 227
2019-04-09T16:09:08.153Z,1554826148.153 [BR_Ping1D](INFO): 22
2019-04-09T16:09:08.154Z,1554826148.154 [BR_Ping1D](INFO): 0
2019-04-09T16:09:08.155Z,1554826148.155 [BR_Ping1D](INFO): 222
2019-04-09T16:09:08.155Z,1554826148.155 [BR_Ping1D](INFO): 2
2019-04-09T16:09:08.156Z,1554826148.156 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T16:09:08.157Z,1554826148.157 [BR_Ping1D](INFO): 66
2019-04-09T16:09:08.157Z,1554826148.157 [BR_Ping1D](INFO): 82
2019-04-09T16:09:08.174Z,1554826148.174 [BR_Ping1D](INFO): 1
2019-04-09T16:09:08.174Z,1554826148.174 [BR_Ping1D](INFO): 0
2019-04-09T16:09:08.174Z,1554826148.174 [BR_Ping1D](INFO): 238
2019-04-09T16:09:08.174Z,1554826148.174 [BR_Ping1D](INFO): 3
2019-04-09T16:09:08.174Z,1554826148.174 [BR_Ping1D](INFO): 0
2019-04-09T16:09:08.174Z,1554826148.174 [BR_Ping1D](INFO): 0
2019-04-09T16:09:08.174Z,1554826148.174 [BR_Ping1D](INFO): 135
2019-04-09T16:09:08.175Z,1554826148.175 [BR_Ping1D](INFO): 1
2019-04-09T16:09:08.175Z,1554826148.175 [BR_Ping1D](INFO): 21
2019-04-09T16:09:08.509Z,1554826148.509 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:09:08.510Z,1554826148.510 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T16:09:08.510Z,1554826148.510 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T16:09:08.553Z,1554826148.553 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:08.941Z,1554826148.941 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:08.941Z,1554826148.941 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:08.942Z,1554826148.942 [BR_Ping1D](INFO): startup: 1
2019-04-09T16:09:08.942Z,1554826148.942 [BR_Ping1D](INFO): UART requesting status
2019-04-09T16:09:08.942Z,1554826148.942 [BR_Ping1D](INFO): 66
2019-04-09T16:09:08.942Z,1554826148.942 [BR_Ping1D](INFO): 82
2019-04-09T16:09:08.942Z,1554826148.942 [BR_Ping1D](INFO): 0
2019-04-09T16:09:08.942Z,1554826148.942 [BR_Ping1D](INFO): 0
2019-04-09T16:09:08.942Z,1554826148.942 [BR_Ping1D](INFO): 186
2019-04-09T16:09:08.943Z,1554826148.943 [BR_Ping1D](INFO): 4
2019-04-09T16:09:08.943Z,1554826148.943 [BR_Ping1D](INFO): 0
2019-04-09T16:09:08.943Z,1554826148.943 [BR_Ping1D](INFO): 0
2019-04-09T16:09:08.943Z,1554826148.943 [BR_Ping1D](INFO): 82
2019-04-09T16:09:08.943Z,1554826148.943 [BR_Ping1D](INFO): 1
2019-04-09T16:09:08.950Z,1554826148.950 [BR_Ping1D](INFO): 66
2019-04-09T16:09:08.950Z,1554826148.950 [BR_Ping1D](INFO): 82
2019-04-09T16:09:08.950Z,1554826148.950 [BR_Ping1D](INFO): 0
2019-04-09T16:09:08.950Z,1554826148.950 [BR_Ping1D](INFO): 0
2019-04-09T16:09:08.950Z,1554826148.950 [BR_Ping1D](INFO): 191
2019-04-09T16:09:08.950Z,1554826148.950 [BR_Ping1D](INFO): 4
2019-04-09T16:09:08.950Z,1554826148.950 [BR_Ping1D](INFO): 0
2019-04-09T16:09:08.951Z,1554826148.951 [BR_Ping1D](INFO): 0
2019-04-09T16:09:08.951Z,1554826148.951 [BR_Ping1D](INFO): 87
2019-04-09T16:09:08.951Z,1554826148.951 [BR_Ping1D](INFO): 1
2019-04-09T16:09:08.958Z,1554826148.958 [BR_Ping1D](INFO): 66
2019-04-09T16:09:08.958Z,1554826148.958 [BR_Ping1D](INFO): 82
2019-04-09T16:09:08.958Z,1554826148.958 [BR_Ping1D](INFO): 0
2019-04-09T16:09:08.958Z,1554826148.958 [BR_Ping1D](INFO): 0
2019-04-09T16:09:08.958Z,1554826148.958 [BR_Ping1D](INFO): 179
2019-04-09T16:09:08.958Z,1554826148.958 [BR_Ping1D](INFO): 4
2019-04-09T16:09:08.958Z,1554826148.958 [BR_Ping1D](INFO): 0
2019-04-09T16:09:08.959Z,1554826148.959 [BR_Ping1D](INFO): 0
2019-04-09T16:09:08.959Z,1554826148.959 [BR_Ping1D](INFO): 75
2019-04-09T16:09:08.959Z,1554826148.959 [BR_Ping1D](INFO): 1
2019-04-09T16:09:09.462Z,1554826149.462 [PAR_Licor](INFO): A/D timeout, 8 tries over 138 ms

2019-04-09T16:09:09.462Z,1554826149.462 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T16:09:09.462Z,1554826149.462 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:09:09.498Z,1554826149.498 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:09.498Z,1554826149.498 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:09.498Z,1554826149.498 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:09.499Z,1554826149.499 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:09.499Z,1554826149.499 [BR_Ping1D](INFO): elapsed 1.3662
2019-04-09T16:09:09.499Z,1554826149.499 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:09.499Z,1554826149.499 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:09.500Z,1554826149.500 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:09.500Z,1554826149.500 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:09.534Z,1554826149.534 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:09:09.534Z,1554826149.534 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T16:09:09.534Z,1554826149.534 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T16:09:09.749Z,1554826149.749 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:09.749Z,1554826149.749 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:09.749Z,1554826149.749 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:09.750Z,1554826149.750 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:09.750Z,1554826149.750 [BR_Ping1D](INFO): elapsed 1.6171
2019-04-09T16:09:09.750Z,1554826149.750 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:09.750Z,1554826149.750 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:09.750Z,1554826149.750 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:09.751Z,1554826149.751 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:10.098Z,1554826150.098 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T16:09:10.098Z,1554826150.098 [MassServo] Communications Fault, FailCount= 1
2019-04-09T16:09:10.098Z,1554826150.098 [MassServo](ERROR): Communications Fault
2019-04-09T16:09:10.150Z,1554826150.150 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T16:09:10.199Z,1554826150.199 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:10.199Z,1554826150.199 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:10.199Z,1554826150.199 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:10.199Z,1554826150.199 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:10.199Z,1554826150.199 [BR_Ping1D](INFO): elapsed 2.0666
2019-04-09T16:09:10.199Z,1554826150.199 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:10.200Z,1554826150.200 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:10.200Z,1554826150.200 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:10.200Z,1554826150.200 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:10.278Z,1554826150.278 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:09:10.278Z,1554826150.278 [MassServo](INFO): Powering down
2019-04-09T16:09:10.694Z,1554826150.694 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-09T16:09:10.694Z,1554826150.694 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T16:09:10.694Z,1554826150.694 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:09:10.732Z,1554826150.732 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:10.732Z,1554826150.732 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:10.733Z,1554826150.733 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:10.733Z,1554826150.733 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:10.733Z,1554826150.733 [BR_Ping1D](INFO): elapsed 2.6003
2019-04-09T16:09:10.733Z,1554826150.733 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:10.733Z,1554826150.733 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:10.734Z,1554826150.734 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:10.734Z,1554826150.734 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:10.866Z,1554826150.866 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:09:10.866Z,1554826150.866 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T16:09:10.866Z,1554826150.866 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T16:09:10.986Z,1554826150.986 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:10.986Z,1554826150.986 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:10.987Z,1554826150.987 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:10.987Z,1554826150.987 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:10.987Z,1554826150.987 [BR_Ping1D](INFO): elapsed 2.8542
2019-04-09T16:09:10.987Z,1554826150.987 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:10.987Z,1554826150.987 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:10.987Z,1554826150.987 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:10.988Z,1554826150.988 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:11.072Z,1554826151.072 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:09:11.191Z,1554826151.191 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:09:11.510Z,1554826151.510 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T16:09:11.510Z,1554826151.510 [MassServo] No Fault, FailCount= 1
2019-04-09T16:09:11.642Z,1554826151.642 [PAR_Licor](INFO): A/D timeout, 6 tries over 126 ms

2019-04-09T16:09:11.643Z,1554826151.643 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T16:09:11.643Z,1554826151.643 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:09:11.674Z,1554826151.674 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:11.674Z,1554826151.674 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:11.675Z,1554826151.675 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:11.675Z,1554826151.675 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:11.675Z,1554826151.675 [BR_Ping1D](INFO): elapsed 3.5422
2019-04-09T16:09:11.675Z,1554826151.675 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:11.675Z,1554826151.675 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:11.675Z,1554826151.675 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:11.676Z,1554826151.676 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:11.724Z,1554826151.724 [CBIT](FAULT): Main Battery Failure. Count: 11
2019-04-09T16:09:11.727Z,1554826151.727 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:09:11.727Z,1554826151.727 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T16:09:11.727Z,1554826151.727 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T16:09:11.942Z,1554826151.942 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:11.942Z,1554826151.942 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:11.943Z,1554826151.943 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:11.943Z,1554826151.943 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:11.943Z,1554826151.943 [BR_Ping1D](INFO): elapsed 3.8103
2019-04-09T16:09:11.943Z,1554826151.943 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:11.943Z,1554826151.943 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:11.944Z,1554826151.944 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:11.944Z,1554826151.944 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:12.458Z,1554826152.458 [PAR_Licor](INFO): A/D timeout, 9 tries over 134 ms

2019-04-09T16:09:12.458Z,1554826152.458 [PAR_Licor] Data Fault, FailCount= 3
2019-04-09T16:09:12.458Z,1554826152.458 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:09:12.485Z,1554826152.485 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:12.485Z,1554826152.485 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:12.486Z,1554826152.486 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:12.486Z,1554826152.486 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:12.486Z,1554826152.486 [BR_Ping1D](INFO): elapsed 4.3534
2019-04-09T16:09:12.486Z,1554826152.486 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:12.486Z,1554826152.486 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:12.487Z,1554826152.487 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:12.487Z,1554826152.487 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:12.557Z,1554826152.557 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:09:12.566Z,1554826152.566 [CBIT](CRITICAL): Data Fault in component: PAR_Licor
2019-04-09T16:09:12.746Z,1554826152.746 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:12.746Z,1554826152.746 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:12.746Z,1554826152.746 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:12.746Z,1554826152.746 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:12.747Z,1554826152.747 [BR_Ping1D](INFO): elapsed 4.6139
2019-04-09T16:09:12.747Z,1554826152.747 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:12.747Z,1554826152.747 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:12.747Z,1554826152.747 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:12.747Z,1554826152.747 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:12.760Z,1554826152.760 [CBIT](INFO): Critical error at 20190409T160912
2019-04-09T16:09:13.149Z,1554826153.149 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:13.149Z,1554826153.149 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:13.150Z,1554826153.150 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:13.150Z,1554826153.150 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:13.150Z,1554826153.150 [BR_Ping1D](INFO): elapsed 5.0174
2019-04-09T16:09:13.150Z,1554826153.150 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:13.150Z,1554826153.150 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T16:09:13.150Z,1554826153.150 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-09T16:09:13.151Z,1554826153.151 [BR_Ping1D](ERROR): Data Fault
2019-04-09T16:09:13.167Z,1554826153.167 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T16:09:13.555Z,1554826153.555 [BR_Ping1D](INFO): Stop
2019-04-09T16:09:13.555Z,1554826153.555 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:09:13.555Z,1554826153.555 [BR_Ping1D](INFO): Powering down
2019-04-09T16:09:14.154Z,1554826154.154 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T16:09:14.154Z,1554826154.154 [MassServo] Communications Fault, FailCount= 2
2019-04-09T16:09:14.154Z,1554826154.154 [MassServo](ERROR): Communications Fault
2019-04-09T16:09:14.158Z,1554826154.158 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T16:09:14.186Z,1554826154.186 [BR_Ping1D](INFO): Stopping
2019-04-09T16:09:14.212Z,1554826154.212 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:09:14.212Z,1554826154.212 [MassServo](INFO): Powering down
2019-04-09T16:09:14.588Z,1554826154.588 [BR_Ping1D](INFO): Stopped
2019-04-09T16:09:14.605Z,1554826154.605 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T16:09:14.605Z,1554826154.605 [BR_Ping1D] No Fault, FailCount= 1
2019-04-09T16:09:14.990Z,1554826154.990 [BR_Ping1D](INFO): Start
2019-04-09T16:09:15.027Z,1554826155.027 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:09:15.147Z,1554826155.147 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:09:15.151Z,1554826155.151 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T16:09:15.151Z,1554826155.151 [MassServo] No Fault, FailCount= 2
2019-04-09T16:09:15.394Z,1554826155.394 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:15.797Z,1554826155.797 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:15.797Z,1554826155.797 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:15.798Z,1554826155.798 [BR_Ping1D](INFO): startup: 0
2019-04-09T16:09:15.798Z,1554826155.798 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T16:09:15.810Z,1554826155.810 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T16:09:15.810Z,1554826155.810 [BR_Ping1D](INFO): 66
2019-04-09T16:09:15.810Z,1554826155.810 [BR_Ping1D](INFO): 82
2019-04-09T16:09:15.810Z,1554826155.810 [BR_Ping1D](INFO): 2
2019-04-09T16:09:15.810Z,1554826155.810 [BR_Ping1D](INFO): 0
2019-04-09T16:09:15.810Z,1554826155.810 [BR_Ping1D](INFO): 236
2019-04-09T16:09:15.811Z,1554826155.810 [BR_Ping1D](INFO): 3
2019-04-09T16:09:15.811Z,1554826155.811 [BR_Ping1D](INFO): 0
2019-04-09T16:09:15.811Z,1554826155.811 [BR_Ping1D](INFO): 0
2019-04-09T16:09:15.811Z,1554826155.811 [BR_Ping1D](INFO): 1
2019-04-09T16:09:15.811Z,1554826155.811 [BR_Ping1D](INFO): 30
2019-04-09T16:09:15.811Z,1554826155.811 [BR_Ping1D](INFO): 164
2019-04-09T16:09:15.811Z,1554826155.811 [BR_Ping1D](INFO): 1
2019-04-09T16:09:15.811Z,1554826155.811 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T16:09:15.811Z,1554826155.811 [BR_Ping1D](INFO): 66
2019-04-09T16:09:15.812Z,1554826155.812 [BR_Ping1D](INFO): 82
2019-04-09T16:09:15.812Z,1554826155.812 [BR_Ping1D](INFO): 1
2019-04-09T16:09:15.812Z,1554826155.812 [BR_Ping1D](INFO): 0
2019-04-09T16:09:15.812Z,1554826155.812 [BR_Ping1D](INFO): 235
2019-04-09T16:09:15.812Z,1554826155.812 [BR_Ping1D](INFO): 3
2019-04-09T16:09:15.812Z,1554826155.812 [BR_Ping1D](INFO): 0
2019-04-09T16:09:15.812Z,1554826155.812 [BR_Ping1D](INFO): 0
2019-04-09T16:09:15.812Z,1554826155.812 [BR_Ping1D](INFO): 132
2019-04-09T16:09:15.812Z,1554826155.812 [BR_Ping1D](INFO): 1
2019-04-09T16:09:15.812Z,1554826155.812 [BR_Ping1D](INFO): 21
2019-04-09T16:09:15.813Z,1554826155.813 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T16:09:15.813Z,1554826155.813 [BR_Ping1D](INFO): 66
2019-04-09T16:09:15.813Z,1554826155.813 [BR_Ping1D](INFO): 82
2019-04-09T16:09:15.813Z,1554826155.813 [BR_Ping1D](INFO): 4
2019-04-09T16:09:15.813Z,1554826155.813 [BR_Ping1D](INFO): 0
2019-04-09T16:09:15.813Z,1554826155.813 [BR_Ping1D](INFO): 234
2019-04-09T16:09:15.813Z,1554826155.813 [BR_Ping1D](INFO): 3
2019-04-09T16:09:15.813Z,1554826155.813 [BR_Ping1D](INFO): 0
2019-04-09T16:09:15.813Z,1554826155.813 [BR_Ping1D](INFO): 0
2019-04-09T16:09:15.814Z,1554826155.814 [BR_Ping1D](INFO): 96
2019-04-09T16:09:15.814Z,1554826155.814 [BR_Ping1D](INFO): 227
2019-04-09T16:09:15.815Z,1554826155.815 [BR_Ping1D](INFO): 22
2019-04-09T16:09:15.815Z,1554826155.815 [BR_Ping1D](INFO): 0
2019-04-09T16:09:15.816Z,1554826155.816 [BR_Ping1D](INFO): 222
2019-04-09T16:09:15.816Z,1554826155.816 [BR_Ping1D](INFO): 2
2019-04-09T16:09:15.817Z,1554826155.817 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T16:09:15.826Z,1554826155.826 [BR_Ping1D](INFO): 66
2019-04-09T16:09:15.826Z,1554826155.826 [BR_Ping1D](INFO): 82
2019-04-09T16:09:15.826Z,1554826155.826 [BR_Ping1D](INFO): 1
2019-04-09T16:09:15.826Z,1554826155.826 [BR_Ping1D](INFO): 0
2019-04-09T16:09:15.826Z,1554826155.826 [BR_Ping1D](INFO): 238
2019-04-09T16:09:15.826Z,1554826155.826 [BR_Ping1D](INFO): 3
2019-04-09T16:09:15.826Z,1554826155.826 [BR_Ping1D](INFO): 0
2019-04-09T16:09:15.826Z,1554826155.826 [BR_Ping1D](INFO): 0
2019-04-09T16:09:15.827Z,1554826155.827 [BR_Ping1D](INFO): 135
2019-04-09T16:09:15.827Z,1554826155.827 [BR_Ping1D](INFO): 1
2019-04-09T16:09:15.827Z,1554826155.827 [BR_Ping1D](INFO): 21
2019-04-09T16:09:15.856Z,1554826155.856 [CBIT](FAULT): Main Battery Failure. Count: 21
2019-04-09T16:09:16.202Z,1554826156.202 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:16.605Z,1554826156.605 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:16.605Z,1554826156.605 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:16.605Z,1554826156.605 [BR_Ping1D](INFO): startup: 1
2019-04-09T16:09:16.606Z,1554826156.606 [BR_Ping1D](INFO): UART requesting status
2019-04-09T16:09:16.606Z,1554826156.606 [BR_Ping1D](INFO): 66
2019-04-09T16:09:16.606Z,1554826156.606 [BR_Ping1D](INFO): 82
2019-04-09T16:09:16.606Z,1554826156.606 [BR_Ping1D](INFO): 0
2019-04-09T16:09:16.606Z,1554826156.606 [BR_Ping1D](INFO): 0
2019-04-09T16:09:16.606Z,1554826156.606 [BR_Ping1D](INFO): 186
2019-04-09T16:09:16.606Z,1554826156.606 [BR_Ping1D](INFO): 4
2019-04-09T16:09:16.606Z,1554826156.606 [BR_Ping1D](INFO): 0
2019-04-09T16:09:16.606Z,1554826156.606 [BR_Ping1D](INFO): 0
2019-04-09T16:09:16.607Z,1554826156.607 [BR_Ping1D](INFO): 82
2019-04-09T16:09:16.607Z,1554826156.607 [BR_Ping1D](INFO): 1
2019-04-09T16:09:16.615Z,1554826156.615 [BR_Ping1D](INFO): 66
2019-04-09T16:09:16.615Z,1554826156.615 [BR_Ping1D](INFO): 82
2019-04-09T16:09:16.615Z,1554826156.615 [BR_Ping1D](INFO): 0
2019-04-09T16:09:16.615Z,1554826156.615 [BR_Ping1D](INFO): 0
2019-04-09T16:09:16.615Z,1554826156.615 [BR_Ping1D](INFO): 191
2019-04-09T16:09:16.615Z,1554826156.615 [BR_Ping1D](INFO): 4
2019-04-09T16:09:16.615Z,1554826156.615 [BR_Ping1D](INFO): 0
2019-04-09T16:09:16.616Z,1554826156.616 [BR_Ping1D](INFO): 0
2019-04-09T16:09:16.616Z,1554826156.616 [BR_Ping1D](INFO): 87
2019-04-09T16:09:16.616Z,1554826156.616 [BR_Ping1D](INFO): 1
2019-04-09T16:09:16.622Z,1554826156.622 [BR_Ping1D](INFO): 66
2019-04-09T16:09:16.622Z,1554826156.622 [BR_Ping1D](INFO): 82
2019-04-09T16:09:16.622Z,1554826156.622 [BR_Ping1D](INFO): 0
2019-04-09T16:09:16.622Z,1554826156.622 [BR_Ping1D](INFO): 0
2019-04-09T16:09:16.622Z,1554826156.622 [BR_Ping1D](INFO): 179
2019-04-09T16:09:16.622Z,1554826156.622 [BR_Ping1D](INFO): 4
2019-04-09T16:09:16.622Z,1554826156.622 [BR_Ping1D](INFO): 0
2019-04-09T16:09:16.623Z,1554826156.623 [BR_Ping1D](INFO): 0
2019-04-09T16:09:16.623Z,1554826156.623 [BR_Ping1D](INFO): 75
2019-04-09T16:09:16.623Z,1554826156.623 [BR_Ping1D](INFO): 1
2019-04-09T16:09:16.639Z,1554826156.639 [CBIT](CRITICAL): Environmental Failure. Press:14.883629 PSI. Humidity:35%. Temp:26 C. ABORTING MISSION
2019-04-09T16:09:16.991Z,1554826156.991 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T16:09:16.991Z,1554826156.991 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T16:09:16.991Z,1554826156.991 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T16:09:17.010Z,1554826157.010 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:17.010Z,1554826157.010 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:17.010Z,1554826157.010 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:17.010Z,1554826157.010 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:17.010Z,1554826157.010 [BR_Ping1D](INFO): elapsed 1.2125
2019-04-09T16:09:17.011Z,1554826157.011 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:17.011Z,1554826157.011 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:17.011Z,1554826157.011 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:17.011Z,1554826157.011 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:17.025Z,1554826157.025 [CBIT](INFO): Critical error at 20190409T160916
2019-04-09T16:09:17.027Z,1554826157.027 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T16:09:17.514Z,1554826157.514 [Aanderaa_O2](INFO): Powering down
2019-04-09T16:09:17.620Z,1554826157.620 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:17.620Z,1554826157.620 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:17.620Z,1554826157.620 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:17.620Z,1554826157.620 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:17.621Z,1554826157.621 [BR_Ping1D](INFO): elapsed 1.8225
2019-04-09T16:09:17.621Z,1554826157.621 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:17.621Z,1554826157.621 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:17.621Z,1554826157.621 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:17.621Z,1554826157.621 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:17.958Z,1554826157.958 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T16:09:17.958Z,1554826157.958 [MassServo] Communications Fault, FailCount= 3
2019-04-09T16:09:17.958Z,1554826157.958 [MassServo](ERROR): Communications Fault
2019-04-09T16:09:17.962Z,1554826157.962 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T16:09:17.989Z,1554826157.989 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:17.989Z,1554826157.989 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:17.989Z,1554826157.989 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:17.989Z,1554826157.989 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:17.989Z,1554826157.989 [BR_Ping1D](INFO): elapsed 2.1915
2019-04-09T16:09:17.990Z,1554826157.990 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:17.990Z,1554826157.990 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:17.990Z,1554826157.990 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:17.990Z,1554826157.990 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:18.019Z,1554826158.019 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:09:18.019Z,1554826158.019 [MassServo](INFO): Powering down
2019-04-09T16:09:18.389Z,1554826158.389 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:18.389Z,1554826158.389 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:18.390Z,1554826158.390 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:18.390Z,1554826158.390 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:18.390Z,1554826158.390 [BR_Ping1D](INFO): elapsed 2.5920
2019-04-09T16:09:18.390Z,1554826158.390 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:18.390Z,1554826158.390 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:18.391Z,1554826158.391 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:18.391Z,1554826158.391 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:18.423Z,1554826158.423 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T16:09:18.423Z,1554826158.423 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-09T16:09:18.794Z,1554826158.794 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:18.794Z,1554826158.794 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:18.794Z,1554826158.794 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:18.794Z,1554826158.794 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:18.795Z,1554826158.795 [BR_Ping1D](INFO): elapsed 2.9967
2019-04-09T16:09:18.795Z,1554826158.795 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:18.795Z,1554826158.795 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:18.795Z,1554826158.795 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:18.795Z,1554826158.795 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:18.824Z,1554826158.824 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:09:18.943Z,1554826158.943 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:09:18.947Z,1554826158.947 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T16:09:18.947Z,1554826158.947 [MassServo] No Fault, FailCount= 3
2019-04-09T16:09:19.209Z,1554826159.209 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:19.209Z,1554826159.209 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:19.210Z,1554826159.210 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:19.210Z,1554826159.210 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:19.210Z,1554826159.210 [BR_Ping1D](INFO): elapsed 3.4122
2019-04-09T16:09:19.211Z,1554826159.211 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:19.211Z,1554826159.211 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:19.211Z,1554826159.211 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:19.211Z,1554826159.211 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:19.402Z,1554826159.402 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T16:09:19.402Z,1554826159.402 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-09T16:09:19.402Z,1554826159.402 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T16:09:19.601Z,1554826159.601 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:19.602Z,1554826159.602 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:19.602Z,1554826159.602 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:19.602Z,1554826159.602 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:19.602Z,1554826159.602 [BR_Ping1D](INFO): elapsed 3.8043
2019-04-09T16:09:19.603Z,1554826159.603 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:19.603Z,1554826159.603 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:19.603Z,1554826159.603 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:19.603Z,1554826159.603 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:19.619Z,1554826159.619 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T16:09:19.806Z,1554826159.806 [CTD_Seabird](INFO): Powering down
2019-04-09T16:09:20.005Z,1554826160.005 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:20.005Z,1554826160.005 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:20.005Z,1554826160.005 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:20.006Z,1554826160.006 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:20.006Z,1554826160.006 [BR_Ping1D](INFO): elapsed 4.2079
2019-04-09T16:09:20.006Z,1554826160.006 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:20.006Z,1554826160.006 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:20.006Z,1554826160.006 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:20.007Z,1554826160.007 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:20.078Z,1554826160.078 [CBIT](FAULT): Main Battery Failure. Count: 31
2019-04-09T16:09:20.410Z,1554826160.410 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:20.410Z,1554826160.410 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:20.410Z,1554826160.410 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:20.410Z,1554826160.410 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:20.411Z,1554826160.411 [BR_Ping1D](INFO): elapsed 4.6126
2019-04-09T16:09:20.411Z,1554826160.411 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:20.411Z,1554826160.411 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:20.411Z,1554826160.411 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:20.411Z,1554826160.411 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:20.814Z,1554826160.814 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:20.814Z,1554826160.814 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:20.814Z,1554826160.814 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:20.814Z,1554826160.814 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:20.814Z,1554826160.814 [BR_Ping1D](INFO): elapsed 5.0165
2019-04-09T16:09:20.815Z,1554826160.815 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:20.815Z,1554826160.815 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T16:09:20.815Z,1554826160.815 [BR_Ping1D] Data Fault, FailCount= 2
2019-04-09T16:09:20.815Z,1554826160.815 [BR_Ping1D](ERROR): Data Fault
2019-04-09T16:09:20.852Z,1554826160.852 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T16:09:20.852Z,1554826160.852 [CTD_Seabird] No Fault, FailCount= 1
2019-04-09T16:09:20.852Z,1554826160.852 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T16:09:21.178Z,1554826161.178 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T16:09:21.180Z,1554826161.180 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T16:09:21.238Z,1554826161.238 [BR_Ping1D](INFO): Stop
2019-04-09T16:09:21.238Z,1554826161.238 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:09:21.238Z,1554826161.238 [BR_Ping1D](INFO): Powering down
2019-04-09T16:09:21.634Z,1554826161.634 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T16:09:21.634Z,1554826161.634 [MassServo] Communications Fault, FailCount= 4
2019-04-09T16:09:21.634Z,1554826161.634 [MassServo](ERROR): Communications Fault
2019-04-09T16:09:21.639Z,1554826161.639 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T16:09:21.663Z,1554826161.663 [BR_Ping1D](INFO): Stopping
2019-04-09T16:09:21.690Z,1554826161.690 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:09:21.691Z,1554826161.691 [MassServo](INFO): Powering down
2019-04-09T16:09:22.066Z,1554826162.066 [BR_Ping1D](INFO): Stopping
2019-04-09T16:09:22.470Z,1554826162.470 [BR_Ping1D](INFO): Stopped
2019-04-09T16:09:22.484Z,1554826162.484 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:09:22.603Z,1554826162.603 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:09:22.607Z,1554826162.607 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T16:09:22.607Z,1554826162.607 [MassServo] No Fault, FailCount= 4
2019-04-09T16:09:22.608Z,1554826162.608 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T16:09:22.608Z,1554826162.608 [BR_Ping1D] No Fault, FailCount= 2
2019-04-09T16:09:22.874Z,1554826162.874 [BR_Ping1D](INFO): Start
2019-04-09T16:09:23.278Z,1554826163.278 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:23.682Z,1554826163.682 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:23.682Z,1554826163.682 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:23.682Z,1554826163.682 [BR_Ping1D](INFO): startup: 0
2019-04-09T16:09:23.682Z,1554826163.682 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T16:09:23.694Z,1554826163.694 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T16:09:23.694Z,1554826163.694 [BR_Ping1D](INFO): 66
2019-04-09T16:09:23.694Z,1554826163.694 [BR_Ping1D](INFO): 82
2019-04-09T16:09:23.694Z,1554826163.694 [BR_Ping1D](INFO): 2
2019-04-09T16:09:23.694Z,1554826163.694 [BR_Ping1D](INFO): 0
2019-04-09T16:09:23.694Z,1554826163.694 [BR_Ping1D](INFO): 236
2019-04-09T16:09:23.695Z,1554826163.695 [BR_Ping1D](INFO): 3
2019-04-09T16:09:23.695Z,1554826163.695 [BR_Ping1D](INFO): 0
2019-04-09T16:09:23.695Z,1554826163.695 [BR_Ping1D](INFO): 0
2019-04-09T16:09:23.695Z,1554826163.695 [BR_Ping1D](INFO): 1
2019-04-09T16:09:23.695Z,1554826163.695 [BR_Ping1D](INFO): 30
2019-04-09T16:09:23.695Z,1554826163.695 [BR_Ping1D](INFO): 164
2019-04-09T16:09:23.695Z,1554826163.695 [BR_Ping1D](INFO): 1
2019-04-09T16:09:23.695Z,1554826163.695 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T16:09:23.696Z,1554826163.696 [BR_Ping1D](INFO): 66
2019-04-09T16:09:23.696Z,1554826163.696 [BR_Ping1D](INFO): 82
2019-04-09T16:09:23.696Z,1554826163.696 [BR_Ping1D](INFO): 1
2019-04-09T16:09:23.696Z,1554826163.696 [BR_Ping1D](INFO): 0
2019-04-09T16:09:23.696Z,1554826163.696 [BR_Ping1D](INFO): 235
2019-04-09T16:09:23.696Z,1554826163.696 [BR_Ping1D](INFO): 3
2019-04-09T16:09:23.696Z,1554826163.696 [BR_Ping1D](INFO): 0
2019-04-09T16:09:23.696Z,1554826163.696 [BR_Ping1D](INFO): 0
2019-04-09T16:09:23.696Z,1554826163.696 [BR_Ping1D](INFO): 132
2019-04-09T16:09:23.696Z,1554826163.696 [BR_Ping1D](INFO): 1
2019-04-09T16:09:23.697Z,1554826163.697 [BR_Ping1D](INFO): 21
2019-04-09T16:09:23.697Z,1554826163.697 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T16:09:23.697Z,1554826163.697 [BR_Ping1D](INFO): 66
2019-04-09T16:09:23.697Z,1554826163.697 [BR_Ping1D](INFO): 82
2019-04-09T16:09:23.697Z,1554826163.697 [BR_Ping1D](INFO): 4
2019-04-09T16:09:23.697Z,1554826163.697 [BR_Ping1D](INFO): 0
2019-04-09T16:09:23.697Z,1554826163.697 [BR_Ping1D](INFO): 234
2019-04-09T16:09:23.697Z,1554826163.697 [BR_Ping1D](INFO): 3
2019-04-09T16:09:23.697Z,1554826163.697 [BR_Ping1D](INFO): 0
2019-04-09T16:09:23.698Z,1554826163.698 [BR_Ping1D](INFO): 0
2019-04-09T16:09:23.698Z,1554826163.698 [BR_Ping1D](INFO): 96
2019-04-09T16:09:23.699Z,1554826163.699 [BR_Ping1D](INFO): 227
2019-04-09T16:09:23.699Z,1554826163.699 [BR_Ping1D](INFO): 22
2019-04-09T16:09:23.700Z,1554826163.700 [BR_Ping1D](INFO): 0
2019-04-09T16:09:23.700Z,1554826163.700 [BR_Ping1D](INFO): 222
2019-04-09T16:09:23.701Z,1554826163.701 [BR_Ping1D](INFO): 2
2019-04-09T16:09:23.710Z,1554826163.710 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T16:09:23.710Z,1554826163.710 [BR_Ping1D](INFO): 66
2019-04-09T16:09:23.710Z,1554826163.710 [BR_Ping1D](INFO): 82
2019-04-09T16:09:23.710Z,1554826163.710 [BR_Ping1D](INFO): 1
2019-04-09T16:09:23.710Z,1554826163.710 [BR_Ping1D](INFO): 0
2019-04-09T16:09:23.710Z,1554826163.710 [BR_Ping1D](INFO): 238
2019-04-09T16:09:23.710Z,1554826163.710 [BR_Ping1D](INFO): 3
2019-04-09T16:09:23.711Z,1554826163.711 [BR_Ping1D](INFO): 0
2019-04-09T16:09:23.711Z,1554826163.711 [BR_Ping1D](INFO): 0
2019-04-09T16:09:23.711Z,1554826163.711 [BR_Ping1D](INFO): 135
2019-04-09T16:09:23.711Z,1554826163.711 [BR_Ping1D](INFO): 1
2019-04-09T16:09:23.711Z,1554826163.711 [BR_Ping1D](INFO): 21
2019-04-09T16:09:24.087Z,1554826164.087 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:24.113Z,1554826164.113 [CBIT](FAULT): Main Battery Failure. Count: 41
2019-04-09T16:09:24.490Z,1554826164.490 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:24.490Z,1554826164.490 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:24.490Z,1554826164.490 [BR_Ping1D](INFO): startup: 1
2019-04-09T16:09:24.490Z,1554826164.490 [BR_Ping1D](INFO): UART requesting status
2019-04-09T16:09:24.490Z,1554826164.490 [BR_Ping1D](INFO): 66
2019-04-09T16:09:24.490Z,1554826164.490 [BR_Ping1D](INFO): 82
2019-04-09T16:09:24.490Z,1554826164.490 [BR_Ping1D](INFO): 0
2019-04-09T16:09:24.491Z,1554826164.491 [BR_Ping1D](INFO): 0
2019-04-09T16:09:24.491Z,1554826164.491 [BR_Ping1D](INFO): 186
2019-04-09T16:09:24.491Z,1554826164.491 [BR_Ping1D](INFO): 4
2019-04-09T16:09:24.491Z,1554826164.491 [BR_Ping1D](INFO): 0
2019-04-09T16:09:24.491Z,1554826164.491 [BR_Ping1D](INFO): 0
2019-04-09T16:09:24.491Z,1554826164.491 [BR_Ping1D](INFO): 82
2019-04-09T16:09:24.491Z,1554826164.491 [BR_Ping1D](INFO): 1
2019-04-09T16:09:24.498Z,1554826164.498 [BR_Ping1D](INFO): 66
2019-04-09T16:09:24.498Z,1554826164.498 [BR_Ping1D](INFO): 82
2019-04-09T16:09:24.498Z,1554826164.498 [BR_Ping1D](INFO): 0
2019-04-09T16:09:24.498Z,1554826164.498 [BR_Ping1D](INFO): 0
2019-04-09T16:09:24.498Z,1554826164.498 [BR_Ping1D](INFO): 191
2019-04-09T16:09:24.498Z,1554826164.498 [BR_Ping1D](INFO): 4
2019-04-09T16:09:24.498Z,1554826164.498 [BR_Ping1D](INFO): 0
2019-04-09T16:09:24.499Z,1554826164.499 [BR_Ping1D](INFO): 0
2019-04-09T16:09:24.499Z,1554826164.499 [BR_Ping1D](INFO): 87
2019-04-09T16:09:24.499Z,1554826164.499 [BR_Ping1D](INFO): 1
2019-04-09T16:09:24.506Z,1554826164.506 [BR_Ping1D](INFO): 66
2019-04-09T16:09:24.506Z,1554826164.506 [BR_Ping1D](INFO): 82
2019-04-09T16:09:24.506Z,1554826164.506 [BR_Ping1D](INFO): 0
2019-04-09T16:09:24.506Z,1554826164.506 [BR_Ping1D](INFO): 0
2019-04-09T16:09:24.506Z,1554826164.506 [BR_Ping1D](INFO): 179
2019-04-09T16:09:24.506Z,1554826164.506 [BR_Ping1D](INFO): 4
2019-04-09T16:09:24.507Z,1554826164.507 [BR_Ping1D](INFO): 0
2019-04-09T16:09:24.507Z,1554826164.507 [BR_Ping1D](INFO): 0
2019-04-09T16:09:24.507Z,1554826164.507 [BR_Ping1D](INFO): 75
2019-04-09T16:09:24.507Z,1554826164.507 [BR_Ping1D](INFO): 1
2019-04-09T16:09:24.894Z,1554826164.894 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:24.894Z,1554826164.894 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:24.894Z,1554826164.894 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:24.894Z,1554826164.894 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:24.895Z,1554826164.895 [BR_Ping1D](INFO): elapsed 1.2123
2019-04-09T16:09:24.895Z,1554826164.895 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:24.895Z,1554826164.895 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:24.895Z,1554826164.895 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:24.895Z,1554826164.895 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:25.234Z,1554826165.234 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T16:09:25.234Z,1554826165.234 [MassServo] Communications Fault, FailCount= 5
2019-04-09T16:09:25.234Z,1554826165.234 [MassServo](ERROR): Communications Fault
2019-04-09T16:09:25.238Z,1554826165.238 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T16:09:25.239Z,1554826165.239 [CBIT](CRITICAL): Communications Fault in component: MassServo
2019-04-09T16:09:25.295Z,1554826165.295 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:25.295Z,1554826165.295 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:25.295Z,1554826165.295 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:25.296Z,1554826165.296 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:25.296Z,1554826165.296 [BR_Ping1D](INFO): elapsed 1.6134
2019-04-09T16:09:25.296Z,1554826165.296 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:25.296Z,1554826165.296 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:25.296Z,1554826165.296 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:25.296Z,1554826165.296 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:25.307Z,1554826165.307 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:09:25.307Z,1554826165.307 [MassServo](INFO): Powering down
2019-04-09T16:09:25.309Z,1554826165.309 [CBIT](INFO): Critical error at 20190409T160925
2019-04-09T16:09:25.701Z,1554826165.701 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:25.701Z,1554826165.701 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:25.702Z,1554826165.702 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:25.702Z,1554826165.702 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:25.702Z,1554826165.702 [BR_Ping1D](INFO): elapsed 2.0198
2019-04-09T16:09:25.702Z,1554826165.702 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:25.703Z,1554826165.703 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:25.703Z,1554826165.703 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:25.703Z,1554826165.703 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:26.105Z,1554826166.105 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:26.106Z,1554826166.106 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:26.106Z,1554826166.106 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:26.106Z,1554826166.106 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:26.106Z,1554826166.106 [BR_Ping1D](INFO): elapsed 2.4239
2019-04-09T16:09:26.106Z,1554826166.106 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:26.107Z,1554826166.107 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:26.107Z,1554826166.107 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:26.107Z,1554826166.107 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:26.135Z,1554826166.135 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:09:26.255Z,1554826166.255 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:09:26.511Z,1554826166.510 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:26.511Z,1554826166.511 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:26.511Z,1554826166.511 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:26.511Z,1554826166.511 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:26.511Z,1554826166.511 [BR_Ping1D](INFO): elapsed 2.8289
2019-04-09T16:09:26.511Z,1554826166.511 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:26.512Z,1554826166.512 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:26.512Z,1554826166.512 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:26.512Z,1554826166.512 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:26.914Z,1554826166.914 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:26.914Z,1554826166.914 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:26.915Z,1554826166.915 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:26.915Z,1554826166.915 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:26.915Z,1554826166.915 [BR_Ping1D](INFO): elapsed 3.2327
2019-04-09T16:09:26.915Z,1554826166.915 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:26.916Z,1554826166.916 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:26.916Z,1554826166.916 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:26.916Z,1554826166.916 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:27.318Z,1554826167.318 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:27.318Z,1554826167.318 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:27.319Z,1554826167.319 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:27.319Z,1554826167.319 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:27.319Z,1554826167.319 [BR_Ping1D](INFO): elapsed 3.6366
2019-04-09T16:09:27.319Z,1554826167.319 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:27.319Z,1554826167.319 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:27.319Z,1554826167.319 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:27.320Z,1554826167.320 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:27.703Z,1554826167.703 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:09:27.703Z,1554826167.703 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-04-09T16:09:27.703Z,1554826167.703 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:09:27.723Z,1554826167.723 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:27.723Z,1554826167.723 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:27.723Z,1554826167.723 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:27.723Z,1554826167.723 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:27.723Z,1554826167.723 [BR_Ping1D](INFO): elapsed 4.0409
2019-04-09T16:09:27.723Z,1554826167.723 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:27.724Z,1554826167.724 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:27.724Z,1554826167.724 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:27.724Z,1554826167.724 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:27.750Z,1554826167.750 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:09:28.107Z,1554826168.107 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:09:28.271Z,1554826168.271 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:28.271Z,1554826168.271 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:28.271Z,1554826168.271 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:28.271Z,1554826168.271 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:28.272Z,1554826168.272 [BR_Ping1D](INFO): elapsed 4.5894
2019-04-09T16:09:28.272Z,1554826168.272 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:28.272Z,1554826168.272 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:28.272Z,1554826168.272 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:28.272Z,1554826168.272 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:28.289Z,1554826168.289 [CBIT](FAULT): Main Battery Failure. Count: 51
2019-04-09T16:09:28.531Z,1554826168.531 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:28.531Z,1554826168.531 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:28.532Z,1554826168.532 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:28.532Z,1554826168.532 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:28.532Z,1554826168.532 [BR_Ping1D](INFO): elapsed 4.8496
2019-04-09T16:09:28.532Z,1554826168.532 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:28.532Z,1554826168.532 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:28.533Z,1554826168.533 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:28.533Z,1554826168.533 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:28.914Z,1554826168.914 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T16:09:28.915Z,1554826168.915 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-09T16:09:28.915Z,1554826168.915 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T16:09:28.934Z,1554826168.934 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:28.934Z,1554826168.934 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:28.934Z,1554826168.934 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:28.934Z,1554826168.934 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:28.934Z,1554826168.934 [BR_Ping1D](INFO): elapsed 5.2520
2019-04-09T16:09:28.935Z,1554826168.935 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:28.935Z,1554826168.935 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T16:09:28.935Z,1554826168.935 [BR_Ping1D] Data Fault, FailCount= 3
2019-04-09T16:09:28.935Z,1554826168.935 [BR_Ping1D](ERROR): Data Fault
2019-04-09T16:09:28.951Z,1554826168.951 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T16:09:28.951Z,1554826168.951 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:09:28.951Z,1554826168.951 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-04-09T16:09:28.951Z,1554826168.951 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T16:09:29.438Z,1554826169.438 [Aanderaa_O2](INFO): Powering down
2019-04-09T16:09:29.458Z,1554826169.458 [BR_Ping1D](INFO): Stop
2019-04-09T16:09:29.458Z,1554826169.458 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:09:29.459Z,1554826169.459 [BR_Ping1D](INFO): Powering down
2019-04-09T16:09:29.742Z,1554826169.742 [BR_Ping1D](INFO): Stopping
2019-04-09T16:09:30.147Z,1554826170.147 [BR_Ping1D](INFO): Stopping
2019-04-09T16:09:30.163Z,1554826170.163 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T16:09:30.163Z,1554826170.163 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-09T16:09:30.551Z,1554826170.551 [BR_Ping1D](INFO): Stopped
2019-04-09T16:09:30.567Z,1554826170.567 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T16:09:30.567Z,1554826170.567 [BR_Ping1D] No Fault, FailCount= 3
2019-04-09T16:09:30.965Z,1554826170.965 [BR_Ping1D](INFO): Start
2019-04-09T16:09:31.013Z,1554826171.013 [SBIT](IMPORTANT): Beginning Startup BIT
2019-04-09T16:09:31.017Z,1554826171.017 [CBIT](IMPORTANT): Beginning ground fault scan
2019-04-09T16:09:31.339Z,1554826171.339 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:09:31.339Z,1554826171.339 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:09:31.373Z,1554826171.373 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:31.762Z,1554826171.762 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:31.762Z,1554826171.762 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:31.762Z,1554826171.762 [BR_Ping1D](INFO): startup: 0
2019-04-09T16:09:31.762Z,1554826171.762 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T16:09:31.774Z,1554826171.774 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T16:09:31.774Z,1554826171.774 [BR_Ping1D](INFO): 66
2019-04-09T16:09:31.774Z,1554826171.774 [BR_Ping1D](INFO): 82
2019-04-09T16:09:31.774Z,1554826171.774 [BR_Ping1D](INFO): 2
2019-04-09T16:09:31.774Z,1554826171.774 [BR_Ping1D](INFO): 0
2019-04-09T16:09:31.774Z,1554826171.774 [BR_Ping1D](INFO): 236
2019-04-09T16:09:31.775Z,1554826171.775 [BR_Ping1D](INFO): 3
2019-04-09T16:09:31.775Z,1554826171.775 [BR_Ping1D](INFO): 0
2019-04-09T16:09:31.775Z,1554826171.775 [BR_Ping1D](INFO): 0
2019-04-09T16:09:31.775Z,1554826171.775 [BR_Ping1D](INFO): 1
2019-04-09T16:09:31.775Z,1554826171.775 [BR_Ping1D](INFO): 30
2019-04-09T16:09:31.775Z,1554826171.775 [BR_Ping1D](INFO): 164
2019-04-09T16:09:31.775Z,1554826171.775 [BR_Ping1D](INFO): 1
2019-04-09T16:09:31.775Z,1554826171.775 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T16:09:31.776Z,1554826171.776 [BR_Ping1D](INFO): 66
2019-04-09T16:09:31.776Z,1554826171.776 [BR_Ping1D](INFO): 82
2019-04-09T16:09:31.776Z,1554826171.776 [BR_Ping1D](INFO): 1
2019-04-09T16:09:31.776Z,1554826171.776 [BR_Ping1D](INFO): 0
2019-04-09T16:09:31.776Z,1554826171.776 [BR_Ping1D](INFO): 235
2019-04-09T16:09:31.776Z,1554826171.776 [BR_Ping1D](INFO): 3
2019-04-09T16:09:31.776Z,1554826171.776 [BR_Ping1D](INFO): 0
2019-04-09T16:09:31.776Z,1554826171.776 [BR_Ping1D](INFO): 0
2019-04-09T16:09:31.776Z,1554826171.776 [BR_Ping1D](INFO): 132
2019-04-09T16:09:31.776Z,1554826171.776 [BR_Ping1D](INFO): 1
2019-04-09T16:09:31.777Z,1554826171.777 [BR_Ping1D](INFO): 21
2019-04-09T16:09:31.777Z,1554826171.777 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T16:09:31.777Z,1554826171.777 [BR_Ping1D](INFO): 66
2019-04-09T16:09:31.777Z,1554826171.777 [BR_Ping1D](INFO): 82
2019-04-09T16:09:31.777Z,1554826171.777 [BR_Ping1D](INFO): 4
2019-04-09T16:09:31.777Z,1554826171.777 [BR_Ping1D](INFO): 0
2019-04-09T16:09:31.777Z,1554826171.777 [BR_Ping1D](INFO): 234
2019-04-09T16:09:31.777Z,1554826171.777 [BR_Ping1D](INFO): 3
2019-04-09T16:09:31.778Z,1554826171.778 [BR_Ping1D](INFO): 0
2019-04-09T16:09:31.778Z,1554826171.778 [BR_Ping1D](INFO): 0
2019-04-09T16:09:31.779Z,1554826171.779 [BR_Ping1D](INFO): 96
2019-04-09T16:09:31.779Z,1554826171.779 [BR_Ping1D](INFO): 227
2019-04-09T16:09:31.780Z,1554826171.780 [BR_Ping1D](INFO): 22
2019-04-09T16:09:31.780Z,1554826171.780 [BR_Ping1D](INFO): 0
2019-04-09T16:09:31.781Z,1554826171.781 [BR_Ping1D](INFO): 222
2019-04-09T16:09:31.781Z,1554826171.781 [BR_Ping1D](INFO): 2
2019-04-09T16:09:31.783Z,1554826171.783 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T16:09:31.783Z,1554826171.783 [BR_Ping1D](INFO): 66
2019-04-09T16:09:31.784Z,1554826171.784 [BR_Ping1D](INFO): 82
2019-04-09T16:09:31.784Z,1554826171.784 [BR_Ping1D](INFO): 1
2019-04-09T16:09:31.785Z,1554826171.785 [BR_Ping1D](INFO): 0
2019-04-09T16:09:31.785Z,1554826171.785 [BR_Ping1D](INFO): 238
2019-04-09T16:09:31.786Z,1554826171.786 [BR_Ping1D](INFO): 3
2019-04-09T16:09:31.786Z,1554826171.786 [BR_Ping1D](INFO): 0
2019-04-09T16:09:31.787Z,1554826171.787 [BR_Ping1D](INFO): 0
2019-04-09T16:09:31.787Z,1554826171.787 [BR_Ping1D](INFO): 135
2019-04-09T16:09:31.787Z,1554826171.787 [BR_Ping1D](INFO): 1
2019-04-09T16:09:31.787Z,1554826171.787 [BR_Ping1D](INFO): 21
2019-04-09T16:09:32.166Z,1554826172.166 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:32.245Z,1554826172.245 [CBIT](FAULT): Main Battery Failure. Count: 61
2019-04-09T16:09:32.569Z,1554826172.569 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:32.569Z,1554826172.569 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:32.569Z,1554826172.569 [BR_Ping1D](INFO): startup: 1
2019-04-09T16:09:32.569Z,1554826172.569 [BR_Ping1D](INFO): UART requesting status
2019-04-09T16:09:32.569Z,1554826172.569 [BR_Ping1D](INFO): 66
2019-04-09T16:09:32.569Z,1554826172.569 [BR_Ping1D](INFO): 82
2019-04-09T16:09:32.570Z,1554826172.570 [BR_Ping1D](INFO): 0
2019-04-09T16:09:32.570Z,1554826172.570 [BR_Ping1D](INFO): 0
2019-04-09T16:09:32.570Z,1554826172.570 [BR_Ping1D](INFO): 186
2019-04-09T16:09:32.570Z,1554826172.570 [BR_Ping1D](INFO): 4
2019-04-09T16:09:32.570Z,1554826172.570 [BR_Ping1D](INFO): 0
2019-04-09T16:09:32.570Z,1554826172.570 [BR_Ping1D](INFO): 0
2019-04-09T16:09:32.570Z,1554826172.570 [BR_Ping1D](INFO): 82
2019-04-09T16:09:32.570Z,1554826172.570 [BR_Ping1D](INFO): 1
2019-04-09T16:09:32.578Z,1554826172.578 [BR_Ping1D](INFO): 66
2019-04-09T16:09:32.578Z,1554826172.578 [BR_Ping1D](INFO): 82
2019-04-09T16:09:32.578Z,1554826172.578 [BR_Ping1D](INFO): 0
2019-04-09T16:09:32.578Z,1554826172.578 [BR_Ping1D](INFO): 0
2019-04-09T16:09:32.578Z,1554826172.578 [BR_Ping1D](INFO): 191
2019-04-09T16:09:32.578Z,1554826172.578 [BR_Ping1D](INFO): 4
2019-04-09T16:09:32.578Z,1554826172.578 [BR_Ping1D](INFO): 0
2019-04-09T16:09:32.579Z,1554826172.579 [BR_Ping1D](INFO): 0
2019-04-09T16:09:32.579Z,1554826172.579 [BR_Ping1D](INFO): 87
2019-04-09T16:09:32.579Z,1554826172.579 [BR_Ping1D](INFO): 1
2019-04-09T16:09:32.586Z,1554826172.586 [BR_Ping1D](INFO): 66
2019-04-09T16:09:32.586Z,1554826172.586 [BR_Ping1D](INFO): 82
2019-04-09T16:09:32.586Z,1554826172.586 [BR_Ping1D](INFO): 0
2019-04-09T16:09:32.586Z,1554826172.586 [BR_Ping1D](INFO): 0
2019-04-09T16:09:32.586Z,1554826172.586 [BR_Ping1D](INFO): 179
2019-04-09T16:09:32.586Z,1554826172.586 [BR_Ping1D](INFO): 4
2019-04-09T16:09:32.586Z,1554826172.586 [BR_Ping1D](INFO): 0
2019-04-09T16:09:32.587Z,1554826172.587 [BR_Ping1D](INFO): 0
2019-04-09T16:09:32.587Z,1554826172.587 [BR_Ping1D](INFO): 75
2019-04-09T16:09:32.587Z,1554826172.587 [BR_Ping1D](INFO): 1
2019-04-09T16:09:32.975Z,1554826172.975 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:32.976Z,1554826172.976 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:32.976Z,1554826172.976 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:32.976Z,1554826172.976 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:32.976Z,1554826172.976 [BR_Ping1D](INFO): elapsed 1.2138
2019-04-09T16:09:32.976Z,1554826172.976 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:32.977Z,1554826172.977 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:32.977Z,1554826172.977 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:32.977Z,1554826172.977 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:33.381Z,1554826173.381 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:33.382Z,1554826173.382 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:33.382Z,1554826173.382 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:33.382Z,1554826173.382 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:33.383Z,1554826173.383 [BR_Ping1D](INFO): elapsed 1.6201
2019-04-09T16:09:33.383Z,1554826173.383 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:33.383Z,1554826173.383 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:33.383Z,1554826173.383 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:33.383Z,1554826173.383 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:33.781Z,1554826173.781 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:33.781Z,1554826173.781 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:33.781Z,1554826173.781 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:33.782Z,1554826173.782 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:33.782Z,1554826173.782 [BR_Ping1D](INFO): elapsed 2.0195
2019-04-09T16:09:33.782Z,1554826173.782 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:33.782Z,1554826173.782 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:33.782Z,1554826173.782 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:33.783Z,1554826173.783 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:34.185Z,1554826174.185 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:34.186Z,1554826174.186 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:34.186Z,1554826174.186 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:34.186Z,1554826174.186 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:34.186Z,1554826174.186 [BR_Ping1D](INFO): elapsed 2.4239
2019-04-09T16:09:34.186Z,1554826174.186 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:34.187Z,1554826174.187 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:34.187Z,1554826174.187 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:34.187Z,1554826174.187 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:34.230Z,1554826174.230 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T16:09:34.230Z,1554826174.230 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-09T16:09:34.230Z,1554826174.230 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T16:09:34.335Z,1554826174.335 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T16:09:34.583Z,1554826174.583 [NAL9602](INFO): Powering up NAL9602
2019-04-09T16:09:34.599Z,1554826174.599 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:34.599Z,1554826174.599 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:34.599Z,1554826174.599 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:34.599Z,1554826174.599 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:34.600Z,1554826174.600 [BR_Ping1D](INFO): elapsed 2.8371
2019-04-09T16:09:34.600Z,1554826174.600 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:34.600Z,1554826174.600 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:34.600Z,1554826174.600 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:34.600Z,1554826174.600 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:34.634Z,1554826174.634 [CTD_Seabird](INFO): Powering down
2019-04-09T16:09:34.993Z,1554826174.993 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:34.994Z,1554826174.994 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:34.994Z,1554826174.994 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:34.994Z,1554826174.994 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:34.994Z,1554826174.994 [BR_Ping1D](INFO): elapsed 3.2319
2019-04-09T16:09:34.994Z,1554826174.994 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:34.995Z,1554826174.995 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:34.995Z,1554826174.995 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:34.995Z,1554826174.995 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:35.397Z,1554826175.397 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:35.397Z,1554826175.397 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:35.397Z,1554826175.397 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:35.398Z,1554826175.398 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:35.398Z,1554826175.398 [BR_Ping1D](INFO): elapsed 3.6354
2019-04-09T16:09:35.398Z,1554826175.398 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:35.398Z,1554826175.398 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:35.398Z,1554826175.398 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:35.399Z,1554826175.399 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:35.802Z,1554826175.802 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:35.802Z,1554826175.802 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:35.802Z,1554826175.802 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:35.802Z,1554826175.802 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:35.803Z,1554826175.803 [BR_Ping1D](INFO): elapsed 4.0402
2019-04-09T16:09:35.803Z,1554826175.803 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:35.803Z,1554826175.803 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:35.803Z,1554826175.803 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:35.803Z,1554826175.803 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:35.939Z,1554826175.939 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T16:09:35.939Z,1554826175.939 [CTD_Seabird] No Fault, FailCount= 2
2019-04-09T16:09:35.978Z,1554826175.978 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T16:09:35.980Z,1554826175.980 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T16:09:36.203Z,1554826176.203 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:36.203Z,1554826176.203 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:36.204Z,1554826176.204 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:36.204Z,1554826176.204 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:36.204Z,1554826176.204 [BR_Ping1D](INFO): elapsed 4.4416
2019-04-09T16:09:36.204Z,1554826176.204 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:36.204Z,1554826176.204 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:36.205Z,1554826176.205 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:36.205Z,1554826176.205 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:36.217Z,1554826176.217 [CBIT](FAULT): Main Battery Failure. Count: 71
2019-04-09T16:09:36.610Z,1554826176.610 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:36.611Z,1554826176.611 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:36.611Z,1554826176.611 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:36.611Z,1554826176.611 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:36.611Z,1554826176.611 [BR_Ping1D](INFO): elapsed 4.8488
2019-04-09T16:09:36.611Z,1554826176.611 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:36.612Z,1554826176.612 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:36.612Z,1554826176.612 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:36.612Z,1554826176.612 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:37.013Z,1554826177.013 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:37.013Z,1554826177.013 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:37.013Z,1554826177.013 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:37.013Z,1554826177.013 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:37.014Z,1554826177.014 [BR_Ping1D](INFO): elapsed 5.2513
2019-04-09T16:09:37.014Z,1554826177.014 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:37.014Z,1554826177.014 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T16:09:37.014Z,1554826177.014 [BR_Ping1D] Data Fault, FailCount= 4
2019-04-09T16:09:37.014Z,1554826177.014 [BR_Ping1D](ERROR): Data Fault
2019-04-09T16:09:37.073Z,1554826177.073 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T16:09:37.417Z,1554826177.417 [BR_Ping1D](INFO): Stop
2019-04-09T16:09:37.418Z,1554826177.418 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:09:37.418Z,1554826177.418 [BR_Ping1D](INFO): Powering down
2019-04-09T16:09:37.821Z,1554826177.821 [BR_Ping1D](INFO): Stopping
2019-04-09T16:09:38.225Z,1554826178.225 [BR_Ping1D](INFO): Stopping
2019-04-09T16:09:38.629Z,1554826178.629 [BR_Ping1D](INFO): Stopped
2019-04-09T16:09:38.643Z,1554826178.643 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T16:09:38.644Z,1554826178.644 [BR_Ping1D] No Fault, FailCount= 4
2019-04-09T16:09:39.035Z,1554826179.035 [BR_Ping1D](INFO): Start
2019-04-09T16:09:39.437Z,1554826179.437 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:39.843Z,1554826179.843 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:39.843Z,1554826179.843 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:39.843Z,1554826179.843 [BR_Ping1D](INFO): startup: 0
2019-04-09T16:09:39.843Z,1554826179.843 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T16:09:39.854Z,1554826179.854 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T16:09:39.854Z,1554826179.854 [BR_Ping1D](INFO): 66
2019-04-09T16:09:39.854Z,1554826179.854 [BR_Ping1D](INFO): 82
2019-04-09T16:09:39.854Z,1554826179.854 [BR_Ping1D](INFO): 2
2019-04-09T16:09:39.854Z,1554826179.854 [BR_Ping1D](INFO): 0
2019-04-09T16:09:39.854Z,1554826179.854 [BR_Ping1D](INFO): 236
2019-04-09T16:09:39.855Z,1554826179.855 [BR_Ping1D](INFO): 3
2019-04-09T16:09:39.855Z,1554826179.855 [BR_Ping1D](INFO): 0
2019-04-09T16:09:39.855Z,1554826179.855 [BR_Ping1D](INFO): 0
2019-04-09T16:09:39.855Z,1554826179.855 [BR_Ping1D](INFO): 1
2019-04-09T16:09:39.855Z,1554826179.855 [BR_Ping1D](INFO): 30
2019-04-09T16:09:39.855Z,1554826179.855 [BR_Ping1D](INFO): 164
2019-04-09T16:09:39.855Z,1554826179.855 [BR_Ping1D](INFO): 1
2019-04-09T16:09:39.855Z,1554826179.855 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T16:09:39.856Z,1554826179.856 [BR_Ping1D](INFO): 66
2019-04-09T16:09:39.856Z,1554826179.856 [BR_Ping1D](INFO): 82
2019-04-09T16:09:39.856Z,1554826179.856 [BR_Ping1D](INFO): 1
2019-04-09T16:09:39.856Z,1554826179.856 [BR_Ping1D](INFO): 0
2019-04-09T16:09:39.856Z,1554826179.856 [BR_Ping1D](INFO): 235
2019-04-09T16:09:39.856Z,1554826179.856 [BR_Ping1D](INFO): 3
2019-04-09T16:09:39.856Z,1554826179.856 [BR_Ping1D](INFO): 0
2019-04-09T16:09:39.856Z,1554826179.856 [BR_Ping1D](INFO): 0
2019-04-09T16:09:39.856Z,1554826179.856 [BR_Ping1D](INFO): 132
2019-04-09T16:09:39.856Z,1554826179.856 [BR_Ping1D](INFO): 1
2019-04-09T16:09:39.857Z,1554826179.857 [BR_Ping1D](INFO): 21
2019-04-09T16:09:39.857Z,1554826179.857 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T16:09:39.857Z,1554826179.857 [BR_Ping1D](INFO): 66
2019-04-09T16:09:39.857Z,1554826179.857 [BR_Ping1D](INFO): 82
2019-04-09T16:09:39.857Z,1554826179.857 [BR_Ping1D](INFO): 4
2019-04-09T16:09:39.857Z,1554826179.857 [BR_Ping1D](INFO): 0
2019-04-09T16:09:39.857Z,1554826179.857 [BR_Ping1D](INFO): 234
2019-04-09T16:09:39.857Z,1554826179.857 [BR_Ping1D](INFO): 3
2019-04-09T16:09:39.857Z,1554826179.857 [BR_Ping1D](INFO): 0
2019-04-09T16:09:39.858Z,1554826179.858 [BR_Ping1D](INFO): 0
2019-04-09T16:09:39.858Z,1554826179.858 [BR_Ping1D](INFO): 96
2019-04-09T16:09:39.859Z,1554826179.859 [BR_Ping1D](INFO): 227
2019-04-09T16:09:39.859Z,1554826179.859 [BR_Ping1D](INFO): 22
2019-04-09T16:09:39.860Z,1554826179.860 [BR_Ping1D](INFO): 0
2019-04-09T16:09:39.860Z,1554826179.860 [BR_Ping1D](INFO): 222
2019-04-09T16:09:39.861Z,1554826179.861 [BR_Ping1D](INFO): 2
2019-04-09T16:09:39.862Z,1554826179.862 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T16:09:39.863Z,1554826179.863 [BR_Ping1D](INFO): 66
2019-04-09T16:09:39.863Z,1554826179.863 [BR_Ping1D](INFO): 82
2019-04-09T16:09:39.864Z,1554826179.864 [BR_Ping1D](INFO): 1
2019-04-09T16:09:39.864Z,1554826179.864 [BR_Ping1D](INFO): 0
2019-04-09T16:09:39.865Z,1554826179.865 [BR_Ping1D](INFO): 238
2019-04-09T16:09:39.865Z,1554826179.865 [BR_Ping1D](INFO): 3
2019-04-09T16:09:39.866Z,1554826179.866 [BR_Ping1D](INFO): 0
2019-04-09T16:09:39.866Z,1554826179.866 [BR_Ping1D](INFO): 0
2019-04-09T16:09:39.866Z,1554826179.866 [BR_Ping1D](INFO): 135
2019-04-09T16:09:39.866Z,1554826179.866 [BR_Ping1D](INFO): 1
2019-04-09T16:09:39.866Z,1554826179.866 [BR_Ping1D](INFO): 21
2019-04-09T16:09:40.245Z,1554826180.245 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:40.261Z,1554826180.261 [CBIT](FAULT): Main Battery Failure. Count: 81
2019-04-09T16:09:40.630Z,1554826180.630 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T16:09:40.630Z,1554826180.630 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-09T16:09:40.630Z,1554826180.630 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T16:09:40.649Z,1554826180.649 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:40.649Z,1554826180.649 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:40.650Z,1554826180.650 [BR_Ping1D](INFO): startup: 1
2019-04-09T16:09:40.650Z,1554826180.650 [BR_Ping1D](INFO): UART requesting status
2019-04-09T16:09:40.650Z,1554826180.650 [BR_Ping1D](INFO): 66
2019-04-09T16:09:40.650Z,1554826180.650 [BR_Ping1D](INFO): 82
2019-04-09T16:09:40.650Z,1554826180.650 [BR_Ping1D](INFO): 0
2019-04-09T16:09:40.650Z,1554826180.650 [BR_Ping1D](INFO): 0
2019-04-09T16:09:40.650Z,1554826180.650 [BR_Ping1D](INFO): 186
2019-04-09T16:09:40.651Z,1554826180.651 [BR_Ping1D](INFO): 4
2019-04-09T16:09:40.651Z,1554826180.651 [BR_Ping1D](INFO): 0
2019-04-09T16:09:40.651Z,1554826180.651 [BR_Ping1D](INFO): 0
2019-04-09T16:09:40.651Z,1554826180.651 [BR_Ping1D](INFO): 82
2019-04-09T16:09:40.651Z,1554826180.651 [BR_Ping1D](INFO): 1
2019-04-09T16:09:40.658Z,1554826180.658 [BR_Ping1D](INFO): 66
2019-04-09T16:09:40.658Z,1554826180.658 [BR_Ping1D](INFO): 82
2019-04-09T16:09:40.658Z,1554826180.658 [BR_Ping1D](INFO): 0
2019-04-09T16:09:40.658Z,1554826180.658 [BR_Ping1D](INFO): 0
2019-04-09T16:09:40.658Z,1554826180.658 [BR_Ping1D](INFO): 191
2019-04-09T16:09:40.658Z,1554826180.658 [BR_Ping1D](INFO): 4
2019-04-09T16:09:40.658Z,1554826180.658 [BR_Ping1D](INFO): 0
2019-04-09T16:09:40.659Z,1554826180.659 [BR_Ping1D](INFO): 0
2019-04-09T16:09:40.659Z,1554826180.659 [BR_Ping1D](INFO): 87
2019-04-09T16:09:40.659Z,1554826180.659 [BR_Ping1D](INFO): 1
2019-04-09T16:09:40.666Z,1554826180.666 [BR_Ping1D](INFO): 66
2019-04-09T16:09:40.666Z,1554826180.666 [BR_Ping1D](INFO): 82
2019-04-09T16:09:40.666Z,1554826180.666 [BR_Ping1D](INFO): 0
2019-04-09T16:09:40.666Z,1554826180.666 [BR_Ping1D](INFO): 0
2019-04-09T16:09:40.666Z,1554826180.666 [BR_Ping1D](INFO): 179
2019-04-09T16:09:40.666Z,1554826180.666 [BR_Ping1D](INFO): 4
2019-04-09T16:09:40.666Z,1554826180.666 [BR_Ping1D](INFO): 0
2019-04-09T16:09:40.667Z,1554826180.667 [BR_Ping1D](INFO): 0
2019-04-09T16:09:40.667Z,1554826180.667 [BR_Ping1D](INFO): 75
2019-04-09T16:09:40.667Z,1554826180.667 [BR_Ping1D](INFO): 1
2019-04-09T16:09:40.719Z,1554826180.719 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T16:09:40.720Z,1554826180.720 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-09T16:09:41.154Z,1554826181.154 [Aanderaa_O2](INFO): Powering down
2019-04-09T16:09:41.174Z,1554826181.174 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:41.175Z,1554826181.175 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:41.175Z,1554826181.175 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:41.175Z,1554826181.175 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:41.175Z,1554826181.175 [BR_Ping1D](INFO): elapsed 1.3317
2019-04-09T16:09:41.175Z,1554826181.175 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:41.176Z,1554826181.176 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:41.176Z,1554826181.176 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:41.176Z,1554826181.176 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:41.458Z,1554826181.458 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:41.458Z,1554826181.458 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:41.458Z,1554826181.458 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:41.458Z,1554826181.458 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:41.458Z,1554826181.458 [BR_Ping1D](INFO): elapsed 1.6150
2019-04-09T16:09:41.459Z,1554826181.459 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:41.459Z,1554826181.459 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:41.459Z,1554826181.459 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:41.459Z,1554826181.459 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:41.861Z,1554826181.861 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:41.861Z,1554826181.861 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:41.861Z,1554826181.861 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:41.862Z,1554826181.862 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:41.862Z,1554826181.862 [BR_Ping1D](INFO): elapsed 2.0184
2019-04-09T16:09:41.862Z,1554826181.862 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:41.862Z,1554826181.862 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:41.863Z,1554826181.863 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:41.863Z,1554826181.863 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:41.895Z,1554826181.895 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.006052
CHAN A1 (24V): -0.127200
CHAN A2 (12V): -0.005266
CHAN A3 (5V): -0.002742
CHAN B0 (3.3V): -0.001098
CHAN B1 (3.15aV): -0.001464
CHAN B2 (3.15bV): -0.001729
CHAN B3 (GND): 0.000243
OPEN: -0.000762
Full Scale Calc: 4.765 mA, -1.589 mA
2019-04-09T16:09:42.265Z,1554826182.265 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:42.266Z,1554826182.266 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:42.266Z,1554826182.266 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:42.266Z,1554826182.266 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:42.266Z,1554826182.266 [BR_Ping1D](INFO): elapsed 2.4228
2019-04-09T16:09:42.267Z,1554826182.267 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:42.267Z,1554826182.267 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:42.267Z,1554826182.267 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:42.267Z,1554826182.267 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:42.672Z,1554826182.672 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:42.672Z,1554826182.672 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:42.672Z,1554826182.672 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:42.672Z,1554826182.672 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:42.672Z,1554826182.672 [BR_Ping1D](INFO): elapsed 2.8289
2019-04-09T16:09:42.673Z,1554826182.673 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:42.673Z,1554826182.673 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:42.673Z,1554826182.673 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:42.673Z,1554826182.673 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:43.073Z,1554826183.073 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:43.074Z,1554826183.074 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:43.082Z,1554826183.082 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:43.082Z,1554826183.082 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:43.082Z,1554826183.082 [BR_Ping1D](INFO): elapsed 3.2385
2019-04-09T16:09:43.082Z,1554826183.082 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:43.082Z,1554826183.082 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:43.083Z,1554826183.083 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:43.083Z,1554826183.083 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:43.477Z,1554826183.477 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:43.477Z,1554826183.477 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:43.477Z,1554826183.477 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:43.477Z,1554826183.477 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:43.478Z,1554826183.478 [BR_Ping1D](INFO): elapsed 3.6342
2019-04-09T16:09:43.478Z,1554826183.478 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:43.478Z,1554826183.478 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:43.478Z,1554826183.478 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:43.478Z,1554826183.478 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:43.881Z,1554826183.881 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:43.882Z,1554826183.882 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:43.882Z,1554826183.882 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:43.882Z,1554826183.882 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:43.882Z,1554826183.882 [BR_Ping1D](INFO): elapsed 4.0388
2019-04-09T16:09:43.883Z,1554826183.883 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:43.883Z,1554826183.883 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:43.883Z,1554826183.883 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:43.883Z,1554826183.883 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:44.285Z,1554826184.285 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:44.285Z,1554826184.285 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:44.285Z,1554826184.285 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:44.286Z,1554826184.286 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:44.286Z,1554826184.286 [BR_Ping1D](INFO): elapsed 4.4424
2019-04-09T16:09:44.286Z,1554826184.286 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:44.286Z,1554826184.286 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:44.286Z,1554826184.286 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:44.287Z,1554826184.287 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:44.300Z,1554826184.300 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T16:09:44.302Z,1554826184.302 [CBIT](FAULT): Main Battery Failure. Count: 91
2019-04-09T16:09:44.690Z,1554826184.690 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:44.690Z,1554826184.690 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:44.690Z,1554826184.690 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:44.690Z,1554826184.690 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:44.691Z,1554826184.691 [BR_Ping1D](INFO): elapsed 4.8472
2019-04-09T16:09:44.691Z,1554826184.691 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:44.691Z,1554826184.691 [BR_Ping1D](INFO): No UART data
2019-04-09T16:09:44.691Z,1554826184.691 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:09:44.691Z,1554826184.691 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:09:45.105Z,1554826185.105 [BR_Ping1D](INFO): Starting
2019-04-09T16:09:45.105Z,1554826185.105 [BR_Ping1D](INFO): readConfig
2019-04-09T16:09:45.105Z,1554826185.105 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:09:45.105Z,1554826185.105 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:09:45.105Z,1554826185.105 [BR_Ping1D](INFO): elapsed 5.2620
2019-04-09T16:09:45.106Z,1554826185.106 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:09:45.106Z,1554826185.106 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T16:09:45.106Z,1554826185.106 [BR_Ping1D] Data Fault, FailCount= 5
2019-04-09T16:09:45.106Z,1554826185.106 [BR_Ping1D](ERROR): Data Fault
2019-04-09T16:09:45.132Z,1554826185.132 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T16:09:45.132Z,1554826185.132 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-04-09T16:09:45.483Z,1554826185.483 [NAL9602](INFO): NAL9602 initialized
2019-04-09T16:09:45.498Z,1554826185.498 [BR_Ping1D](INFO): Stop
2019-04-09T16:09:45.498Z,1554826185.498 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:09:45.499Z,1554826185.499 [BR_Ping1D](INFO): Powering down
2019-04-09T16:09:45.624Z,1554826185.624 [CBIT](INFO): Critical error at 20190409T160945
2019-04-09T16:09:45.922Z,1554826185.922 [BR_Ping1D](INFO): Stopping
2019-04-09T16:09:46.304Z,1554826186.304 [NAL9602](DEBUG): Fix Requested
2019-04-09T16:09:46.319Z,1554826186.319 [BR_Ping1D](INFO): Stopping
2019-04-09T16:09:46.727Z,1554826186.727 [BR_Ping1D](INFO): Stopped
2019-04-09T16:09:48.392Z,1554826188.392 [CBIT](FAULT): Main Battery Failure. Count: 101
2019-04-09T16:09:49.030Z,1554826189.030 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T16:09:49.030Z,1554826189.030 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-09T16:09:49.030Z,1554826189.030 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T16:09:49.212Z,1554826189.212 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T16:09:49.212Z,1554826189.212 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-09T16:09:49.434Z,1554826189.434 [CTD_Seabird](INFO): Powering down
2019-04-09T16:09:51.538Z,1554826191.538 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:09:51.538Z,1554826191.538 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2019-04-09T16:09:51.538Z,1554826191.538 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:09:51.703Z,1554826191.703 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:09:51.943Z,1554826191.943 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:09:52.410Z,1554826192.410 [CBIT](FAULT): Main Battery Failure. Count: 111
2019-04-09T16:09:52.816Z,1554826192.816 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:09:52.816Z,1554826192.816 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2019-04-09T16:09:55.178Z,1554826195.178 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:09:55.178Z,1554826195.178 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:09:56.425Z,1554826196.425 [CBIT](FAULT): Main Battery Failure. Count: 121
2019-04-09T16:10:00.481Z,1554826200.481 [CBIT](FAULT): Main Battery Failure. Count: 131
2019-04-09T16:10:04.521Z,1554826204.521 [CBIT](FAULT): Main Battery Failure. Count: 141
2019-04-09T16:10:08.545Z,1554826208.545 [CBIT](FAULT): Main Battery Failure. Count: 151
2019-04-09T16:10:10.984Z,1554826210.984 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T16:10:10.984Z,1554826210.984 [SBIT](FAULT): Control surface position failure.
2019-04-09T16:10:12.585Z,1554826212.585 [CBIT](FAULT): Main Battery Failure. Count: 161
2019-04-09T16:10:15.378Z,1554826215.378 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:10:15.379Z,1554826215.379 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2019-04-09T16:10:15.379Z,1554826215.379 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:10:15.480Z,1554826215.480 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:10:15.782Z,1554826215.782 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:10:16.626Z,1554826216.626 [CBIT](FAULT): Main Battery Failure. Count: 171
2019-04-09T16:10:16.629Z,1554826216.629 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:10:16.629Z,1554826216.629 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2019-04-09T16:10:19.014Z,1554826219.014 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:10:19.015Z,1554826219.015 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:10:20.678Z,1554826220.678 [CBIT](FAULT): Main Battery Failure. Count: 181
2019-04-09T16:10:24.704Z,1554826224.704 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T16:10:24.705Z,1554826224.705 [SBIT](FAULT): Control surface position failure.
2019-04-09T16:10:24.706Z,1554826224.706 [CBIT](FAULT): Main Battery Failure. Count: 191
2019-04-09T16:10:25.107Z,1554826225.107 [SBIT](CRITICAL): SBIT FAILED
2019-04-09T16:10:25.123Z,1554826225.123 [CommandLine](IMPORTANT): got command configSet list
2019-04-09T16:10:25.123Z,1554826225.123 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-04-09T16:10:25.124Z,1554826225.124 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-04-09T16:10:25.124Z,1554826225.124 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2019-04-09T16:10:25.147Z,1554826225.147 [CBIT](INFO): Critical error at 20190409T161025
2019-04-09T16:10:25.523Z,1554826225.523 [MissionManager](IMPORTANT): Started mission Startup
2019-04-09T16:10:25.523Z,1554826225.523 [Startup] Running Loop=1
2019-04-09T16:10:25.524Z,1554826225.524 [Startup](DEBUG): Aggregate::initialize Startup
2019-04-09T16:10:25.524Z,1554826225.524 [Startup:A.GoToSurface] Running Loop=1
2019-04-09T16:10:25.524Z,1554826225.524 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-09T16:10:25.524Z,1554826225.524 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-09T16:10:25.525Z,1554826225.525 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-09T16:10:25.525Z,1554826225.525 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-09T16:10:25.526Z,1554826225.526 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-09T16:10:25.526Z,1554826225.526 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-09T16:10:25.528Z,1554826225.528 [Startup:StartupSatComms] Running Loop=1
2019-04-09T16:10:25.528Z,1554826225.528 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-04-09T16:10:25.528Z,1554826225.528 [Startup:StartupSatComms:A] Running Loop=1
2019-04-09T16:10:25.924Z,1554826225.924 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-04-09T16:10:28.745Z,1554826228.745 [CBIT](FAULT): Main Battery Failure. Count: 201
2019-04-09T16:10:32.785Z,1554826232.785 [CBIT](FAULT): Main Battery Failure. Count: 211
2019-04-09T16:10:36.841Z,1554826236.841 [CBIT](FAULT): Main Battery Failure. Count: 221
2019-04-09T16:10:39.214Z,1554826239.214 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:10:39.215Z,1554826239.215 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2019-04-09T16:10:39.215Z,1554826239.215 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:10:39.246Z,1554826239.246 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:10:39.618Z,1554826239.618 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:10:40.464Z,1554826240.464 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:10:40.464Z,1554826240.464 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2019-04-09T16:10:40.861Z,1554826240.861 [CBIT](FAULT): Main Battery Failure. Count: 231
2019-04-09T16:10:42.850Z,1554826242.850 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:10:42.851Z,1554826242.851 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:10:44.912Z,1554826244.912 [CBIT](FAULT): Main Battery Failure. Count: 241
2019-04-09T16:10:48.945Z,1554826248.945 [CBIT](FAULT): Main Battery Failure. Count: 251
2019-04-09T16:10:52.992Z,1554826252.992 [CBIT](FAULT): Main Battery Failure. Count: 261
2019-04-09T16:10:57.041Z,1554826257.041 [CBIT](FAULT): Main Battery Failure. Count: 271
2019-04-09T16:11:01.065Z,1554826261.065 [CBIT](FAULT): Main Battery Failure. Count: 281
2019-04-09T16:11:03.050Z,1554826263.050 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:11:03.051Z,1554826263.051 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2019-04-09T16:11:03.051Z,1554826263.051 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:11:03.124Z,1554826263.124 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:11:03.454Z,1554826263.454 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:11:04.299Z,1554826264.299 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:11:04.300Z,1554826264.300 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2019-04-09T16:11:05.101Z,1554826265.101 [CBIT](FAULT): Main Battery Failure. Count: 291
2019-04-09T16:11:06.686Z,1554826266.686 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:11:06.687Z,1554826266.687 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:11:06.725Z,1554826266.725 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T16:11:06.725Z,1554826266.725 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-04-09T16:11:06.725Z,1554826266.725 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T16:11:06.727Z,1554826266.727 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T16:11:06.727Z,1554826266.727 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-04-09T16:11:06.727Z,1554826266.727 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T16:11:06.728Z,1554826266.728 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T16:11:06.728Z,1554826266.728 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-04-09T16:11:06.728Z,1554826266.728 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T16:11:06.739Z,1554826266.739 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T16:11:06.739Z,1554826266.739 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T16:11:06.739Z,1554826266.739 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T16:11:07.123Z,1554826267.123 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T16:11:07.123Z,1554826267.123 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-04-09T16:11:07.123Z,1554826267.123 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T16:11:07.123Z,1554826267.123 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-04-09T16:11:07.124Z,1554826267.124 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T16:11:07.124Z,1554826267.124 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-04-09T16:11:07.533Z,1554826267.533 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T16:11:09.155Z,1554826269.155 [CBIT](FAULT): Main Battery Failure. Count: 301
2019-04-09T16:11:13.191Z,1554826273.191 [CBIT](FAULT): Main Battery Failure. Count: 311
2019-04-09T16:11:17.219Z,1554826277.219 [CBIT](FAULT): Main Battery Failure. Count: 321
2019-04-09T16:11:21.265Z,1554826281.265 [CBIT](FAULT): Main Battery Failure. Count: 331
2019-04-09T16:11:25.299Z,1554826285.299 [CBIT](FAULT): Main Battery Failure. Count: 341
2019-04-09T16:11:25.707Z,1554826285.707 [Startup:StartupSatComms:A](INFO): Timed out from 2019-04-09T16:10:25.5Z
2019-04-09T16:11:25.707Z,1554826285.707 [Startup:StartupSatComms:A] Stopped
2019-04-09T16:11:25.707Z,1554826285.707 [Startup:StartupSatComms:B] Running Loop=1
2019-04-09T16:11:26.110Z,1554826286.110 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-09T16:11:26.886Z,1554826286.886 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:11:26.887Z,1554826286.887 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2019-04-09T16:11:26.887Z,1554826286.887 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:11:26.918Z,1554826286.918 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:11:27.290Z,1554826287.290 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:11:28.140Z,1554826288.140 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:11:28.140Z,1554826288.140 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2019-04-09T16:11:29.355Z,1554826289.355 [CBIT](FAULT): Main Battery Failure. Count: 351
2019-04-09T16:11:30.522Z,1554826290.522 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:11:30.523Z,1554826290.523 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:11:33.395Z,1554826293.395 [CBIT](FAULT): Main Battery Failure. Count: 361
2019-04-09T16:11:37.419Z,1554826297.419 [CBIT](FAULT): Main Battery Failure. Count: 371
2019-04-09T16:11:41.465Z,1554826301.465 [CBIT](FAULT): Main Battery Failure. Count: 381
2019-04-09T16:11:45.517Z,1554826305.517 [CBIT](FAULT): Main Battery Failure. Count: 391
2019-04-09T16:11:46.340Z,1554826306.340 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004837
2019-04-09T16:11:48.871Z,1554826308.871 [CommandLine](IMPORTANT): got command restart application
2019-04-09T16:11:49.562Z,1554826309.562 [CBIT](FAULT): Main Battery Failure. Count: 401
2019-04-09T16:11:49.874Z,1554826309.874 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:11:49.874Z,1554826309.874 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:11:49.947Z,1554826309.947 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-04-09T16:11:49.947Z,1554826309.947 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:11:49.947Z,1554826309.947 [CommandLine](INFO): Join timeout helper Thread ID is 4182
2019-04-09T16:11:49.948Z,1554826309.948 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-04-09T16:11:49.949Z,1554826309.949 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:11:49.954Z,1554826309.954 [NavChartDb](INFO): Join timeout helper Thread ID is 4183
2019-04-09T16:11:50.166Z,1554826310.166 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:11:50.166Z,1554826310.166 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:11:50.170Z,1554826310.170 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-04-09T16:11:50.170Z,1554826310.170 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:11:50.171Z,1554826310.171 [Radio_Surface](INFO): Join timeout helper Thread ID is 4184
2019-04-09T16:11:50.470Z,1554826310.470 [Radio_Surface](INFO): Powering down
2019-04-09T16:11:50.471Z,1554826310.471 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:11:50.471Z,1554826310.471 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:11:50.490Z,1554826310.490 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-04-09T16:11:50.490Z,1554826310.490 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:11:50.490Z,1554826310.490 [DataOverHttps](INFO): Join timeout helper Thread ID is 4185
2019-04-09T16:11:50.722Z,1554826310.722 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:11:50.722Z,1554826310.722 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7
2019-04-09T16:11:50.722Z,1554826310.722 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:11:50.787Z,1554826310.787 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:11:51.126Z,1554826311.126 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:11:51.957Z,1554826311.957 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:11:51.960Z,1554826311.960 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:11:51.974Z,1554826311.974 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-04-09T16:11:51.974Z,1554826311.974 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:11:51.975Z,1554826311.975 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4186
2019-04-09T16:11:51.976Z,1554826311.976 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:11:51.976Z,1554826311.976 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7
2019-04-09T16:11:52.262Z,1554826312.262 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:11:52.263Z,1554826312.263 [WetLabsBB2FL](INFO): Powering down
2019-04-09T16:11:52.263Z,1554826312.263 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:11:52.267Z,1554826312.267 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-04-09T16:11:52.267Z,1554826312.267 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:11:52.267Z,1554826312.267 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4187
2019-04-09T16:11:52.406Z,1554826312.406 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:11:52.406Z,1554826312.406 [CTD_Seabird](INFO): Powering down
2019-04-09T16:11:52.418Z,1554826312.418 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:11:52.443Z,1554826312.443 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-04-09T16:11:52.443Z,1554826312.443 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:11:52.443Z,1554826312.443 [logger](INFO): Join timeout helper Thread ID is 4188
2019-04-09T16:11:52.451Z,1554826312.451 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:11:52.452Z,1554826312.452 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:11:52.453Z,1554826312.453 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-04-09T16:11:52.453Z,1554826312.453 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:11:52.453Z,1554826312.453 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-04-09T16:11:52.453Z,1554826312.453 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:11:52.454Z,1554826312.454 [controlThread](INFO): Join timeout helper Thread ID is 4189
2019-04-09T16:11:52.738Z,1554826312.738 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:11:52.738Z,1554826312.738 [controlThread](DEBUG): Uninitializing ControlThread
2019-04-09T16:11:52.739Z,1554826312.739 [Aanderaa_O2](INFO): Powering down
2019-04-09T16:11:52.741Z,1554826312.741 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:11:52.742Z,1554826312.742 [NAL9602](INFO): Powering down
2019-04-09T16:11:52.744Z,1554826312.744 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:11:52.744Z,1554826312.744 [BR_Ping1D](INFO): Powering down
2019-04-09T16:11:52.745Z,1554826312.745 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-04-09T16:11:52.745Z,1554826312.745 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-04-09T16:11:52.746Z,1554826312.746 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-04-09T16:11:52.747Z,1554826312.747 [MissionManager](INFO): Uninitializing Mission Default
2019-04-09T16:11:52.749Z,1554826312.749 [MissionManager](INFO): Uninitializing Mission Startup
2019-04-09T16:11:52.749Z,1554826312.749 [Startup] Stopped
2019-04-09T16:11:52.749Z,1554826312.749 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-04-09T16:11:52.749Z,1554826312.749 [Startup:A.GoToSurface] Stopped
2019-04-09T16:11:52.749Z,1554826312.749 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-09T16:11:52.749Z,1554826312.749 [Startup:StartupSatComms] Stopped
2019-04-09T16:11:52.749Z,1554826312.749 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-04-09T16:11:52.750Z,1554826312.750 [Startup:StartupSatComms:B] Stopped
2019-04-09T16:11:52.751Z,1554826312.751 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-04-09T16:11:52.751Z,1554826312.751 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-04-09T16:11:52.751Z,1554826312.751 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-04-09T16:11:52.751Z,1554826312.751 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-04-09T16:11:52.752Z,1554826312.752 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-04-09T16:11:52.752Z,1554826312.752 [BuoyancyServo](INFO): Powering down
2019-04-09T16:11:52.766Z,1554826312.766 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-09T16:11:52.766Z,1554826312.766 [ElevatorServo](INFO): Powering down
2019-04-09T16:11:52.767Z,1554826312.767 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:11:52.767Z,1554826312.767 [MassServo](INFO): Powering down
2019-04-09T16:11:52.768Z,1554826312.768 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-09T16:11:52.768Z,1554826312.768 [RudderServo](INFO): Powering down
2019-04-09T16:11:52.769Z,1554826312.769 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-04-09T16:11:52.769Z,1554826312.769 [ThrusterServo](INFO): Powering down
2019-04-09T16:11:52.770Z,1554826312.770 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-04-09T16:11:52.770Z,1554826312.770 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-04-09T16:11:52.770Z,1554826312.770 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-04-09T16:11:52.770Z,1554826312.770 [CBIT](DEBUG): Powering off loads.
2019-04-09T16:11:52.782Z,1554826312.782 [CBIT](DEBUG): Disabling WDT.
2019-04-09T16:11:52.794Z,1554826312.794 [CBIT](DEBUG): Opening all GF detection circuits.
2019-04-09T16:11:52.795Z,1554826312.795 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:11:52.941Z,1554826312.941 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:11:52.946Z,1554826312.946 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:11:53.013Z,1554826313.013 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:11:53.018Z,1554826313.018 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:11:53.069Z,1554826313.069 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:11:53.127Z,1554826313.127 [logger ThreadHandler](INFO): Thread cancelled.