2019-04-09T16:14:55.090Z,1554826495.090 [Supervisor](DEBUG): Initializing supervisor.
2019-04-09T16:14:55.093Z,1554826495.093 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-09T16:14:55.094Z,1554826495.094 [SyncHandler](INFO): Protected caller Thread ID is 4340
2019-04-09T16:14:55.095Z,1554826495.095 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-04-09T16:14:55.095Z,1554826495.095 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-04-09T16:14:55.096Z,1554826495.096 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4341
2019-04-09T16:14:55.099Z,1554826495.099 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-04-09T16:14:55.111Z,1554826495.111 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-04-09T16:14:55.112Z,1554826495.112 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-04-09T16:14:55.113Z,1554826495.113 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4342
2019-04-09T16:14:55.114Z,1554826495.114 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-04-09T16:14:55.114Z,1554826495.114 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-04-09T16:14:55.115Z,1554826495.115 [logger ThreadHandler](INFO): Protected caller Thread ID is 4343
2019-04-09T16:14:55.117Z,1554826495.117 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-04-09T16:14:55.117Z,1554826495.117 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-04-09T16:14:55.119Z,1554826495.119 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-04-09T16:14:55.215Z,1554826495.215 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-04-09T16:14:55.216Z,1554826495.216 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-04-09T16:14:55.420Z,1554826495.420 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-04-09T16:14:55.421Z,1554826495.421 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-04-09T16:14:55.560Z,1554826495.560 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-04-09T16:14:55.560Z,1554826495.560 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-04-09T16:14:56.024Z,1554826496.024 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-04-09T16:14:56.025Z,1554826496.025 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-04-09T16:14:56.473Z,1554826496.473 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-04-09T16:14:56.474Z,1554826496.474 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-04-09T16:14:56.951Z,1554826496.951 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-04-09T16:14:56.952Z,1554826496.952 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-04-09T16:14:57.262Z,1554826497.262 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-04-09T16:14:57.263Z,1554826497.263 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-04-09T16:14:57.613Z,1554826497.613 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-04-09T16:14:57.614Z,1554826497.614 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-04-09T16:14:58.028Z,1554826498.028 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-04-09T16:14:58.028Z,1554826498.028 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-04-09T16:14:58.409Z,1554826498.409 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-04-09T16:14:58.409Z,1554826498.409 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-04-09T16:14:58.519Z,1554826498.519 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-04-09T16:14:58.519Z,1554826498.519 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-04-09T16:14:58.602Z,1554826498.602 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-04-09T16:14:58.705Z,1554826498.705 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-04-09T16:14:58.705Z,1554826498.705 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-04-09T16:14:58.902Z,1554826498.902 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-04-09T16:14:58.902Z,1554826498.902 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-04-09T16:14:59.112Z,1554826499.112 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-04-09T16:14:59.115Z,1554826499.115 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-04-09T16:14:59.115Z,1554826499.115 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-04-09T16:14:59.199Z,1554826499.199 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-04-09T16:14:59.438Z,1554826499.438 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-04-09T16:14:59.438Z,1554826499.438 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-04-09T16:14:59.535Z,1554826499.535 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-04-09T16:14:59.710Z,1554826499.710 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-04-09T16:14:59.925Z,1554826499.925 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-04-09T16:15:00.012Z,1554826500.012 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-04-09T16:15:00.114Z,1554826500.114 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-04-09T16:15:00.208Z,1554826500.208 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-04-09T16:15:00.360Z,1554826500.360 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-04-09T16:15:00.466Z,1554826500.466 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-04-09T16:15:00.566Z,1554826500.566 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2019-04-09T16:15:00.566Z,1554826500.566 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-04-09T16:15:00.570Z,1554826500.570 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-04-09T16:15:00.678Z,1554826500.678 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-04-09T16:15:00.678Z,1554826500.678 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-04-09T16:15:00.813Z,1554826500.813 [BuoyancyServo] Loaded
2019-04-09T16:15:00.813Z,1554826500.813 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-04-09T16:15:00.829Z,1554826500.829 [ElevatorServo] Loaded
2019-04-09T16:15:00.829Z,1554826500.829 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-04-09T16:15:00.845Z,1554826500.845 [MassServo] Loaded
2019-04-09T16:15:00.845Z,1554826500.845 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-04-09T16:15:00.860Z,1554826500.860 [RudderServo] Loaded
2019-04-09T16:15:00.861Z,1554826500.861 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-04-09T16:15:00.875Z,1554826500.875 [ThrusterServo] Loaded
2019-04-09T16:15:00.875Z,1554826500.875 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-04-09T16:15:00.876Z,1554826500.876 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-04-09T16:15:00.876Z,1554826500.876 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-04-09T16:15:01.025Z,1554826501.025 [SBIT](DEBUG): Construct Startup Built In Test.
2019-04-09T16:15:01.054Z,1554826501.054 [SBIT] Loaded
2019-04-09T16:15:01.054Z,1554826501.054 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-04-09T16:15:01.055Z,1554826501.055 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-04-09T16:15:01.084Z,1554826501.084 [IBIT] Loaded
2019-04-09T16:15:01.084Z,1554826501.084 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-04-09T16:15:01.087Z,1554826501.087 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-04-09T16:15:01.494Z,1554826501.494 [CBIT] Loaded
2019-04-09T16:15:01.494Z,1554826501.494 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-04-09T16:15:01.495Z,1554826501.495 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-04-09T16:15:01.495Z,1554826501.495 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-04-09T16:15:01.693Z,1554826501.693 [Aanderaa_O2] Loaded
2019-04-09T16:15:01.693Z,1554826501.693 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-04-09T16:15:01.703Z,1554826501.703 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-04-09T16:15:01.708Z,1554826501.708 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-04-09T16:15:01.710Z,1554826501.710 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-04-09T16:15:01.715Z,1554826501.715 [CTD_Seabird](INFO): created writer for : depth
2019-04-09T16:15:01.716Z,1554826501.716 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-04-09T16:15:01.721Z,1554826501.721 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-04-09T16:15:01.722Z,1554826501.722 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-04-09T16:15:01.728Z,1554826501.728 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-04-09T16:15:01.728Z,1554826501.728 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-04-09T16:15:01.734Z,1554826501.734 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-04-09T16:15:01.735Z,1554826501.735 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-04-09T16:15:01.740Z,1554826501.740 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-04-09T16:15:01.741Z,1554826501.741 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-04-09T16:15:01.746Z,1554826501.746 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-04-09T16:15:01.774Z,1554826501.774 [CTD_Seabird] Loaded
2019-04-09T16:15:01.774Z,1554826501.774 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-04-09T16:15:01.775Z,1554826501.775 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406654E0
2019-04-09T16:15:01.776Z,1554826501.776 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4424
2019-04-09T16:15:01.802Z,1554826501.802 [ESPComponent] Loaded
2019-04-09T16:15:01.802Z,1554826501.802 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-04-09T16:15:01.816Z,1554826501.816 [PAR_Licor] Loaded
2019-04-09T16:15:01.817Z,1554826501.817 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-04-09T16:15:01.867Z,1554826501.867 [WetLabsBB2FL] Loaded
2019-04-09T16:15:01.867Z,1554826501.867 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-04-09T16:15:01.868Z,1554826501.868 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406954E0
2019-04-09T16:15:01.868Z,1554826501.868 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4425
2019-04-09T16:15:01.869Z,1554826501.869 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-04-09T16:15:01.870Z,1554826501.870 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-04-09T16:15:01.913Z,1554826501.913 [DepthRateCalculator] Loaded
2019-04-09T16:15:01.913Z,1554826501.913 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-04-09T16:15:01.919Z,1554826501.919 [PitchRateCalculator] Loaded
2019-04-09T16:15:01.919Z,1554826501.919 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-04-09T16:15:01.936Z,1554826501.936 [SpeedCalculator] Loaded
2019-04-09T16:15:01.936Z,1554826501.936 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-04-09T16:15:01.958Z,1554826501.958 [TempGradientCalculator] Loaded
2019-04-09T16:15:01.958Z,1554826501.958 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-04-09T16:15:01.965Z,1554826501.965 [YawRateCalculator] Loaded
2019-04-09T16:15:01.965Z,1554826501.965 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-04-09T16:15:01.999Z,1554826501.999 [ElevatorOffsetCalculator] Loaded
2019-04-09T16:15:01.000Z,1554826502.000 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-04-09T16:15:02.000Z,1554826502.000 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-04-09T16:15:02.001Z,1554826502.001 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-04-09T16:15:02.029Z,1554826502.029 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-04-09T16:15:02.029Z,1554826502.029 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-04-09T16:15:02.137Z,1554826502.137 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-04-09T16:15:02.137Z,1554826502.137 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-04-09T16:15:02.200Z,1554826502.200 [DeadReckonUsingSpeedCalculator] Loaded
2019-04-09T16:15:02.200Z,1554826502.200 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-04-09T16:15:02.262Z,1554826502.262 [DeadReckonWithRespectToSeafloor] Loaded
2019-04-09T16:15:02.263Z,1554826502.263 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-04-09T16:15:02.284Z,1554826502.284 [NavChart] Loaded
2019-04-09T16:15:02.284Z,1554826502.284 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-04-09T16:15:02.288Z,1554826502.288 [UniversalFixResidualReporter] Loaded
2019-04-09T16:15:02.289Z,1554826502.289 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-04-09T16:15:02.289Z,1554826502.289 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-04-09T16:15:02.289Z,1554826502.289 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-04-09T16:15:02.622Z,1554826502.622 [AcousticModem_Benthos_ATM900] Loaded
2019-04-09T16:15:02.623Z,1554826502.623 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-04-09T16:15:02.695Z,1554826502.695 [DataOverHttps] Loaded
2019-04-09T16:15:02.695Z,1554826502.695 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-04-09T16:15:02.696Z,1554826502.696 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409714E0
2019-04-09T16:15:02.697Z,1554826502.697 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4426
2019-04-09T16:15:02.710Z,1554826502.710 [Depth_Keller] Loaded
2019-04-09T16:15:02.711Z,1554826502.711 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-04-09T16:15:02.807Z,1554826502.807 [NAL9602] Loaded
2019-04-09T16:15:02.808Z,1554826502.808 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-04-09T16:15:02.813Z,1554826502.813 [Onboard] Loaded
2019-04-09T16:15:02.813Z,1554826502.813 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-04-09T16:15:02.817Z,1554826502.817 [Radio_Surface] Loaded
2019-04-09T16:15:02.817Z,1554826502.817 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-04-09T16:15:02.818Z,1554826502.818 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A14E0
2019-04-09T16:15:02.818Z,1554826502.818 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4427
2019-04-09T16:15:04.393Z,1554826504.393 [BPC1] Loaded
2019-04-09T16:15:04.394Z,1554826504.394 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-04-09T16:15:04.425Z,1554826504.425 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minrange
2019-04-09T16:15:04.427Z,1554826504.427 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.maxrange
2019-04-09T16:15:04.429Z,1554826504.429 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.soundspeed
2019-04-09T16:15:04.431Z,1554826504.431 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.automode
2019-04-09T16:15:04.434Z,1554826504.434 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.gainindex
2019-04-09T16:15:04.436Z,1554826504.436 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minconfidence
2019-04-09T16:15:04.438Z,1554826504.438 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.pingenable
2019-04-09T16:15:04.440Z,1554826504.440 [BR_Ping1D] Loaded
2019-04-09T16:15:04.441Z,1554826504.441 [ComponentRegistry](DEBUG): SyncComponent "BR_Ping1D" handled in the control thread.
2019-04-09T16:15:04.441Z,1554826504.441 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-04-09T16:15:04.441Z,1554826504.441 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-04-09T16:15:04.511Z,1554826504.511 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-04-09T16:15:04.511Z,1554826504.511 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-04-09T16:15:04.584Z,1554826504.584 [VerticalControl](DEBUG): Construct VerticalControl.
2019-04-09T16:15:04.670Z,1554826504.670 [VerticalControl] Loaded
2019-04-09T16:15:04.671Z,1554826504.671 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-04-09T16:15:04.671Z,1554826504.671 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-04-09T16:15:04.731Z,1554826504.731 [HorizontalControl] Loaded
2019-04-09T16:15:04.731Z,1554826504.731 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-04-09T16:15:04.732Z,1554826504.732 [SpeedControl](DEBUG): Construct SpeedControl.
2019-04-09T16:15:04.733Z,1554826504.733 [SpeedControl] Loaded
2019-04-09T16:15:04.733Z,1554826504.733 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-04-09T16:15:04.734Z,1554826504.734 [LoopControl](DEBUG): Construct LoopControl.
2019-04-09T16:15:04.735Z,1554826504.735 [LoopControl] Loaded
2019-04-09T16:15:04.735Z,1554826504.735 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-04-09T16:15:04.736Z,1554826504.736 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-04-09T16:15:04.736Z,1554826504.736 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-04-09T16:15:04.750Z,1554826504.750 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-04-09T16:15:04.751Z,1554826504.751 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-04-09T16:15:05.067Z,1554826505.067 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-04-09T16:15:05.125Z,1554826505.125 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-04-09T16:15:05.126Z,1554826505.126 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-04-09T16:15:05.145Z,1554826505.145 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-04-09T16:15:05.174Z,1554826505.174 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACE4E0
2019-04-09T16:15:05.174Z,1554826505.174 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4428
2019-04-09T16:15:05.976Z,1554826505.976 [Supervisor](INFO): Main Thread ID is 4347
2019-04-09T16:15:05.977Z,1554826505.977 [Supervisor](DEBUG): Running supervisor.
2019-04-09T16:15:05.977Z,1554826505.977 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4429
2019-04-09T16:15:05.980Z,1554826505.980 [controlThread ThreadHandler](INFO): Handler Thread ID is 4430
2019-04-09T16:15:05.980Z,1554826505.980 [controlThread](DEBUG): Initializing ControlThread
2019-04-09T16:15:05.982Z,1554826505.982 [SBIT](INFO): Initialize SBIT Component.
2019-04-09T16:15:05.982Z,1554826505.982 [SBIT](IMPORTANT): git: 2019-03-18-31-ge98d5dc
2019-04-09T16:15:05.982Z,1554826505.982 [SBIT](INFO): git hash: e98d5dc50cbc39e402f19b9946ff4dc0776c8725
2019-04-09T16:15:05.983Z,1554826505.983 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-04-09T16:15:05.984Z,1554826505.984 [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:15:05.985Z,1554826505.985 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-04-09T16:15:05.986Z,1554826505.986 [IBIT](INFO): Initialize IBIT Component.
2019-04-09T16:15:05.987Z,1554826505.987 [CBIT](DEBUG): Initialize CBIT Component.
2019-04-09T16:15:05.994Z,1554826505.994 [logger ThreadHandler](INFO): Handler Thread ID is 4431
2019-04-09T16:15:05.998Z,1554826505.998 [CBIT](DEBUG): Initialized mux pins.
2019-04-09T16:15:05.998Z,1554826505.998 [CBIT](DEBUG): Initializing the watchdog timer.
2019-04-09T16:15:06.018Z,1554826506.018 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4432
2019-04-09T16:15:06.019Z,1554826506.019 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4433
2019-04-09T16:15:06.020Z,1554826506.020 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4434
2019-04-09T16:15:06.022Z,1554826506.022 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T16:15:06.022Z,1554826506.022 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-04-09T16:15:06.022Z,1554826506.022 [CBIT](DEBUG): Initializing heartbeat.
2019-04-09T16:15:06.024Z,1554826506.024 [WetLabsBB2FL](INFO): Powering down
2019-04-09T16:15:06.039Z,1554826506.039 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-04-09T16:15:06.041Z,1554826506.041 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4436
2019-04-09T16:15:06.055Z,1554826506.055 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T16:15:06.062Z,1554826506.062 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4437
2019-04-09T16:15:06.094Z,1554826506.094 [CBIT](DEBUG): Deactivating GF circuits.
2019-04-09T16:15:06.106Z,1554826506.106 [CBIT](DEBUG): Deactivating emergency mode.
2019-04-09T16:15:06.109Z,1554826506.109 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-09T16:15:06.109Z,1554826506.109 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-04-09T16:15:06.109Z,1554826506.109 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-09T16:15:06.110Z,1554826506.110 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-04-09T16:15:06.110Z,1554826506.110 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-09T16:15:06.110Z,1554826506.110 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-04-09T16:15:06.110Z,1554826506.110 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-09T16:15:06.111Z,1554826506.111 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-04-09T16:15:06.111Z,1554826506.111 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-09T16:15:06.111Z,1554826506.111 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-04-09T16:15:06.111Z,1554826506.111 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-09T16:15:06.111Z,1554826506.111 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-04-09T16:15:06.112Z,1554826506.112 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-09T16:15:06.112Z,1554826506.112 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-04-09T16:15:06.112Z,1554826506.112 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-09T16:15:06.112Z,1554826506.112 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-04-09T16:15:06.142Z,1554826506.142 [CBIT](DEBUG): Backplane powered.
2019-04-09T16:15:06.146Z,1554826506.146 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-04-09T16:15:06.147Z,1554826506.147 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-04-09T16:15:06.147Z,1554826506.147 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-04-09T16:15:06.147Z,1554826506.147 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-04-09T16:15:06.148Z,1554826506.148 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-04-09T16:15:06.148Z,1554826506.148 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-04-09T16:15:06.149Z,1554826506.149 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T16:15:06.150Z,1554826506.150 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-04-09T16:15:06.151Z,1554826506.151 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-04-09T16:15:06.155Z,1554826506.155 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-04-09T16:15:06.157Z,1554826506.157 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-04-09T16:15:06.158Z,1554826506.158 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-04-09T16:15:06.159Z,1554826506.159 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-04-09T16:15:06.159Z,1554826506.159 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-04-09T16:15:06.198Z,1554826506.198 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T16:15:06.225Z,1554826506.225 [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:15:06.234Z,1554826506.234 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-04-09T16:15:06.304Z,1554826506.304 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-04-09T16:15:06.305Z,1554826506.305 [Default:A.Wait](DEBUG): Construct Wait.
2019-04-09T16:15:06.307Z,1554826506.307 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T16:15:06.345Z,1554826506.345 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-04-09T16:15:06.364Z,1554826506.364 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-04-09T16:15:06.370Z,1554826506.370 [Default:E.Execute](DEBUG): Construct Execute.
2019-04-09T16:15:06.389Z,1554826506.389 [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:15:06.394Z,1554826506.394 [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:15:06.415Z,1554826506.415 [ESPComponent](INFO): powering down ESP
2019-04-09T16:15:06.446Z,1554826506.446 [Radio_Surface](INFO): Powering up
2019-04-09T16:15:06.878Z,1554826506.878 [PAR_Licor](INFO): A/D timeout, 8 tries over 151 ms

2019-04-09T16:15:06.879Z,1554826506.879 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T16:15:06.879Z,1554826506.879 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:15:06.879Z,1554826506.879 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:15:06.880Z,1554826506.880 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:15:06.984Z,1554826506.984 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:15:06.990Z,1554826506.990 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-04-09T16:15:06.992Z,1554826506.992 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:15:06.998Z,1554826506.998 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-09T16:15:06.999Z,1554826506.999 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:15:07.006Z,1554826507.006 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:15:07.007Z,1554826507.007 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:15:07.014Z,1554826507.014 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-09T16:15:07.015Z,1554826507.015 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:15:07.023Z,1554826507.023 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-04-09T16:15:07.025Z,1554826507.025 [CommandLine](FAULT): Scheduling is paused
2019-04-09T16:15:07.027Z,1554826507.027 [CBIT](INFO): Critical error at 20190409T161504
2019-04-09T16:15:07.027Z,1554826507.027 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-04-09T16:15:07.028Z,1554826507.028 [CBIT](FAULT): Main Battery Failure. Count: 1
2019-04-09T16:15:07.032Z,1554826507.032 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:15:07.032Z,1554826507.032 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T16:15:07.032Z,1554826507.032 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T16:15:07.578Z,1554826507.578 [PAR_Licor](INFO): A/D timeout, 8 tries over 138 ms

2019-04-09T16:15:07.578Z,1554826507.578 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T16:15:07.578Z,1554826507.578 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:15:07.614Z,1554826507.614 [BR_Ping1D](INFO): startup: 0
2019-04-09T16:15:07.614Z,1554826507.614 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T16:15:07.626Z,1554826507.626 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T16:15:07.626Z,1554826507.626 [BR_Ping1D](INFO): 66
2019-04-09T16:15:07.626Z,1554826507.626 [BR_Ping1D](INFO): 82
2019-04-09T16:15:07.626Z,1554826507.626 [BR_Ping1D](INFO): 2
2019-04-09T16:15:07.627Z,1554826507.627 [BR_Ping1D](INFO): 0
2019-04-09T16:15:07.627Z,1554826507.627 [BR_Ping1D](INFO): 236
2019-04-09T16:15:07.627Z,1554826507.627 [BR_Ping1D](INFO): 3
2019-04-09T16:15:07.627Z,1554826507.627 [BR_Ping1D](INFO): 0
2019-04-09T16:15:07.627Z,1554826507.627 [BR_Ping1D](INFO): 0
2019-04-09T16:15:07.627Z,1554826507.627 [BR_Ping1D](INFO): 1
2019-04-09T16:15:07.627Z,1554826507.627 [BR_Ping1D](INFO): 30
2019-04-09T16:15:07.627Z,1554826507.627 [BR_Ping1D](INFO): 164
2019-04-09T16:15:07.627Z,1554826507.627 [BR_Ping1D](INFO): 1
2019-04-09T16:15:07.628Z,1554826507.628 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T16:15:07.628Z,1554826507.628 [BR_Ping1D](INFO): 66
2019-04-09T16:15:07.628Z,1554826507.628 [BR_Ping1D](INFO): 82
2019-04-09T16:15:07.628Z,1554826507.628 [BR_Ping1D](INFO): 1
2019-04-09T16:15:07.628Z,1554826507.628 [BR_Ping1D](INFO): 0
2019-04-09T16:15:07.628Z,1554826507.628 [BR_Ping1D](INFO): 235
2019-04-09T16:15:07.628Z,1554826507.628 [BR_Ping1D](INFO): 3
2019-04-09T16:15:07.628Z,1554826507.628 [BR_Ping1D](INFO): 0
2019-04-09T16:15:07.629Z,1554826507.629 [BR_Ping1D](INFO): 0
2019-04-09T16:15:07.629Z,1554826507.629 [BR_Ping1D](INFO): 132
2019-04-09T16:15:07.629Z,1554826507.629 [BR_Ping1D](INFO): 1
2019-04-09T16:15:07.629Z,1554826507.629 [BR_Ping1D](INFO): 21
2019-04-09T16:15:07.629Z,1554826507.629 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T16:15:07.629Z,1554826507.629 [BR_Ping1D](INFO): 66
2019-04-09T16:15:07.629Z,1554826507.629 [BR_Ping1D](INFO): 82
2019-04-09T16:15:07.629Z,1554826507.629 [BR_Ping1D](INFO): 4
2019-04-09T16:15:07.630Z,1554826507.630 [BR_Ping1D](INFO): 0
2019-04-09T16:15:07.630Z,1554826507.630 [BR_Ping1D](INFO): 234
2019-04-09T16:15:07.631Z,1554826507.631 [BR_Ping1D](INFO): 3
2019-04-09T16:15:07.631Z,1554826507.631 [BR_Ping1D](INFO): 0
2019-04-09T16:15:07.632Z,1554826507.632 [BR_Ping1D](INFO): 0
2019-04-09T16:15:07.632Z,1554826507.632 [BR_Ping1D](INFO): 96
2019-04-09T16:15:07.633Z,1554826507.633 [BR_Ping1D](INFO): 227
2019-04-09T16:15:07.633Z,1554826507.633 [BR_Ping1D](INFO): 22
2019-04-09T16:15:07.642Z,1554826507.642 [BR_Ping1D](INFO): 0
2019-04-09T16:15:07.642Z,1554826507.642 [BR_Ping1D](INFO): 222
2019-04-09T16:15:07.642Z,1554826507.642 [BR_Ping1D](INFO): 2
2019-04-09T16:15:07.643Z,1554826507.643 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T16:15:07.644Z,1554826507.644 [BR_Ping1D](INFO): 66
2019-04-09T16:15:07.644Z,1554826507.644 [BR_Ping1D](INFO): 82
2019-04-09T16:15:07.645Z,1554826507.645 [BR_Ping1D](INFO): 1
2019-04-09T16:15:07.645Z,1554826507.645 [BR_Ping1D](INFO): 0
2019-04-09T16:15:07.646Z,1554826507.646 [BR_Ping1D](INFO): 238
2019-04-09T16:15:07.646Z,1554826507.646 [BR_Ping1D](INFO): 3
2019-04-09T16:15:07.647Z,1554826507.647 [BR_Ping1D](INFO): 0
2019-04-09T16:15:07.647Z,1554826507.647 [BR_Ping1D](INFO): 0
2019-04-09T16:15:07.647Z,1554826507.647 [BR_Ping1D](INFO): 135
2019-04-09T16:15:07.647Z,1554826507.647 [BR_Ping1D](INFO): 1
2019-04-09T16:15:07.647Z,1554826507.647 [BR_Ping1D](INFO): 21
2019-04-09T16:15:07.994Z,1554826507.994 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-04-09T16:15:07.994Z,1554826507.994 [RudderServo](FAULT): Rudder failed to initialize
2019-04-09T16:15:07.994Z,1554826507.994 [RudderServo] Communications Fault, FailCount= 1
2019-04-09T16:15:07.994Z,1554826507.994 [RudderServo](ERROR): Communications Fault
2019-04-09T16:15:08.105Z,1554826508.105 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-04-09T16:15:08.106Z,1554826508.106 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:15:08.106Z,1554826508.106 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T16:15:08.106Z,1554826508.106 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T16:15:08.145Z,1554826508.145 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:08.145Z,1554826508.145 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:08.146Z,1554826508.146 [BR_Ping1D](INFO): startup: 1
2019-04-09T16:15:08.146Z,1554826508.146 [BR_Ping1D](INFO): UART requesting status
2019-04-09T16:15:08.146Z,1554826508.146 [BR_Ping1D](INFO): 66
2019-04-09T16:15:08.146Z,1554826508.146 [BR_Ping1D](INFO): 82
2019-04-09T16:15:08.146Z,1554826508.146 [BR_Ping1D](INFO): 0
2019-04-09T16:15:08.146Z,1554826508.146 [BR_Ping1D](INFO): 0
2019-04-09T16:15:08.146Z,1554826508.146 [BR_Ping1D](INFO): 186
2019-04-09T16:15:08.146Z,1554826508.146 [BR_Ping1D](INFO): 4
2019-04-09T16:15:08.147Z,1554826508.147 [BR_Ping1D](INFO): 0
2019-04-09T16:15:08.147Z,1554826508.147 [BR_Ping1D](INFO): 0
2019-04-09T16:15:08.147Z,1554826508.147 [BR_Ping1D](INFO): 82
2019-04-09T16:15:08.147Z,1554826508.147 [BR_Ping1D](INFO): 1
2019-04-09T16:15:08.154Z,1554826508.154 [BR_Ping1D](INFO): 66
2019-04-09T16:15:08.154Z,1554826508.154 [BR_Ping1D](INFO): 82
2019-04-09T16:15:08.154Z,1554826508.154 [BR_Ping1D](INFO): 0
2019-04-09T16:15:08.154Z,1554826508.154 [BR_Ping1D](INFO): 0
2019-04-09T16:15:08.154Z,1554826508.154 [BR_Ping1D](INFO): 191
2019-04-09T16:15:08.155Z,1554826508.155 [BR_Ping1D](INFO): 4
2019-04-09T16:15:08.155Z,1554826508.155 [BR_Ping1D](INFO): 0
2019-04-09T16:15:08.155Z,1554826508.155 [BR_Ping1D](INFO): 0
2019-04-09T16:15:08.155Z,1554826508.155 [BR_Ping1D](INFO): 87
2019-04-09T16:15:08.155Z,1554826508.155 [BR_Ping1D](INFO): 1
2019-04-09T16:15:08.162Z,1554826508.162 [BR_Ping1D](INFO): 66
2019-04-09T16:15:08.162Z,1554826508.162 [BR_Ping1D](INFO): 82
2019-04-09T16:15:08.162Z,1554826508.162 [BR_Ping1D](INFO): 0
2019-04-09T16:15:08.162Z,1554826508.162 [BR_Ping1D](INFO): 0
2019-04-09T16:15:08.162Z,1554826508.162 [BR_Ping1D](INFO): 179
2019-04-09T16:15:08.162Z,1554826508.162 [BR_Ping1D](INFO): 4
2019-04-09T16:15:08.163Z,1554826508.163 [BR_Ping1D](INFO): 0
2019-04-09T16:15:08.163Z,1554826508.163 [BR_Ping1D](INFO): 0
2019-04-09T16:15:08.163Z,1554826508.163 [BR_Ping1D](INFO): 75
2019-04-09T16:15:08.163Z,1554826508.163 [BR_Ping1D](INFO): 1
2019-04-09T16:15:08.327Z,1554826508.327 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-09T16:15:08.327Z,1554826508.327 [RudderServo](INFO): Powering down
2019-04-09T16:15:08.537Z,1554826508.537 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:08.538Z,1554826508.538 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:08.539Z,1554826508.539 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:08.539Z,1554826508.539 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:08.539Z,1554826508.539 [BR_Ping1D](INFO): elapsed 0.9252
2019-04-09T16:15:08.539Z,1554826508.539 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:08.540Z,1554826508.540 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:08.540Z,1554826508.540 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:08.540Z,1554826508.540 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:09.058Z,1554826509.058 [PAR_Licor](INFO): A/D timeout, 8 tries over 138 ms

2019-04-09T16:15:09.058Z,1554826509.058 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T16:15:09.058Z,1554826509.058 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:15:09.096Z,1554826509.096 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:09.096Z,1554826509.096 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:09.096Z,1554826509.096 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:09.096Z,1554826509.096 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:09.097Z,1554826509.097 [BR_Ping1D](INFO): elapsed 1.4828
2019-04-09T16:15:09.097Z,1554826509.097 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:09.097Z,1554826509.097 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:09.097Z,1554826509.097 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:09.097Z,1554826509.097 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:09.119Z,1554826509.119 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:15:09.239Z,1554826509.239 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-09T16:15:09.243Z,1554826509.243 [CBIT](INFO): Clearing failed state for component RudderServo
2019-04-09T16:15:09.243Z,1554826509.243 [RudderServo] No Fault, FailCount= 1
2019-04-09T16:15:09.244Z,1554826509.244 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:15:09.244Z,1554826509.244 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T16:15:09.244Z,1554826509.244 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T16:15:09.345Z,1554826509.345 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:09.345Z,1554826509.345 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:09.346Z,1554826509.346 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:09.346Z,1554826509.346 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:09.346Z,1554826509.346 [BR_Ping1D](INFO): elapsed 1.7323
2019-04-09T16:15:09.346Z,1554826509.346 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:09.347Z,1554826509.347 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:09.347Z,1554826509.347 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:09.347Z,1554826509.347 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:09.690Z,1554826509.690 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T16:15:09.690Z,1554826509.690 [MassServo] Communications Fault, FailCount= 1
2019-04-09T16:15:09.690Z,1554826509.690 [MassServo](ERROR): Communications Fault
2019-04-09T16:15:09.717Z,1554826509.717 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T16:15:09.740Z,1554826509.740 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:09.740Z,1554826509.740 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:09.740Z,1554826509.740 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:09.741Z,1554826509.741 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:09.741Z,1554826509.741 [BR_Ping1D](INFO): elapsed 2.1270
2019-04-09T16:15:09.741Z,1554826509.741 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:09.741Z,1554826509.741 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:09.741Z,1554826509.741 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:09.741Z,1554826509.741 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:09.802Z,1554826509.802 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:15:09.802Z,1554826509.802 [MassServo](INFO): Powering down
2019-04-09T16:15:10.149Z,1554826510.149 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:10.149Z,1554826510.149 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:10.150Z,1554826510.150 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:10.150Z,1554826510.150 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:10.150Z,1554826510.150 [BR_Ping1D](INFO): elapsed 2.5361
2019-04-09T16:15:10.150Z,1554826510.150 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:10.150Z,1554826510.150 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:10.151Z,1554826510.151 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:10.151Z,1554826510.151 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:10.674Z,1554826510.674 [PAR_Licor](INFO): A/D timeout, 9 tries over 142 ms

2019-04-09T16:15:10.674Z,1554826510.674 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T16:15:10.674Z,1554826510.674 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:15:10.711Z,1554826510.711 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:10.711Z,1554826510.711 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:10.711Z,1554826510.711 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:10.711Z,1554826510.711 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:10.711Z,1554826510.711 [BR_Ping1D](INFO): elapsed 3.0975
2019-04-09T16:15:10.712Z,1554826510.712 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:10.712Z,1554826510.712 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:10.712Z,1554826510.712 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:10.712Z,1554826510.712 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:10.792Z,1554826510.792 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:15:10.911Z,1554826510.911 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:15:11.074Z,1554826511.074 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T16:15:11.074Z,1554826511.074 [MassServo] No Fault, FailCount= 1
2019-04-09T16:15:11.074Z,1554826511.074 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:15:11.075Z,1554826511.075 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T16:15:11.075Z,1554826511.075 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T16:15:11.137Z,1554826511.137 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:11.137Z,1554826511.137 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:11.137Z,1554826511.137 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:11.137Z,1554826511.137 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:11.138Z,1554826511.138 [BR_Ping1D](INFO): elapsed 3.5239
2019-04-09T16:15:11.138Z,1554826511.138 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:11.138Z,1554826511.138 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:11.138Z,1554826511.138 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:11.138Z,1554826511.138 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:11.209Z,1554826511.209 [CBIT](FAULT): Main Battery Failure. Count: 11
2019-04-09T16:15:11.506Z,1554826511.506 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:11.506Z,1554826511.506 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:11.506Z,1554826511.506 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:11.506Z,1554826511.506 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:11.507Z,1554826511.507 [BR_Ping1D](INFO): elapsed 3.8929
2019-04-09T16:15:11.507Z,1554826511.507 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:11.507Z,1554826511.507 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:11.507Z,1554826511.507 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:11.507Z,1554826511.507 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:12.022Z,1554826512.022 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-09T16:15:12.022Z,1554826512.022 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T16:15:12.022Z,1554826512.022 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:15:12.070Z,1554826512.070 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:12.070Z,1554826512.070 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:12.070Z,1554826512.070 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:12.070Z,1554826512.070 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:12.070Z,1554826512.070 [BR_Ping1D](INFO): elapsed 4.4565
2019-04-09T16:15:12.071Z,1554826512.071 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:12.071Z,1554826512.071 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:12.071Z,1554826512.071 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:12.071Z,1554826512.071 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:12.128Z,1554826512.128 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:15:12.128Z,1554826512.128 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T16:15:12.128Z,1554826512.128 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T16:15:12.314Z,1554826512.314 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:12.314Z,1554826512.314 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:12.314Z,1554826512.314 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:12.315Z,1554826512.315 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:12.315Z,1554826512.315 [BR_Ping1D](INFO): elapsed 4.7009
2019-04-09T16:15:12.315Z,1554826512.315 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:12.315Z,1554826512.315 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:12.315Z,1554826512.315 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:12.315Z,1554826512.315 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:12.717Z,1554826512.717 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:12.717Z,1554826512.717 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:12.718Z,1554826512.718 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:12.718Z,1554826512.718 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:12.718Z,1554826512.718 [BR_Ping1D](INFO): elapsed 5.1041
2019-04-09T16:15:12.718Z,1554826512.718 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:12.718Z,1554826512.718 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T16:15:12.718Z,1554826512.718 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-09T16:15:12.718Z,1554826512.718 [BR_Ping1D](ERROR): Data Fault
2019-04-09T16:15:13.078Z,1554826513.078 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T16:15:13.230Z,1554826513.230 [PAR_Licor](INFO): A/D timeout, 7 tries over 130 ms

2019-04-09T16:15:13.230Z,1554826513.230 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T16:15:13.230Z,1554826513.230 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:15:13.270Z,1554826513.270 [BR_Ping1D](INFO): Stop
2019-04-09T16:15:13.271Z,1554826513.271 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:15:13.271Z,1554826513.271 [BR_Ping1D](INFO): Powering down
2019-04-09T16:15:13.714Z,1554826513.714 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T16:15:13.714Z,1554826513.714 [MassServo] Communications Fault, FailCount= 2
2019-04-09T16:15:13.714Z,1554826513.714 [MassServo](ERROR): Communications Fault
2019-04-09T16:15:13.719Z,1554826513.719 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T16:15:13.719Z,1554826513.719 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:15:13.719Z,1554826513.719 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T16:15:13.719Z,1554826513.719 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T16:15:13.764Z,1554826513.764 [BR_Ping1D](INFO): Stopping
2019-04-09T16:15:13.807Z,1554826513.807 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:15:13.807Z,1554826513.807 [MassServo](INFO): Powering down
2019-04-09T16:15:14.272Z,1554826514.272 [PAR_Licor](INFO): A/D timeout, 9 tries over 144 ms

2019-04-09T16:15:14.272Z,1554826514.272 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T16:15:14.272Z,1554826514.272 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:15:14.305Z,1554826514.305 [BR_Ping1D](INFO): Stopping
2019-04-09T16:15:14.356Z,1554826514.356 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:15:14.356Z,1554826514.356 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T16:15:14.356Z,1554826514.356 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T16:15:14.561Z,1554826514.561 [BR_Ping1D](INFO): Stopped
2019-04-09T16:15:14.592Z,1554826514.592 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:15:14.711Z,1554826514.711 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:15:14.716Z,1554826514.716 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T16:15:14.716Z,1554826514.716 [MassServo] No Fault, FailCount= 2
2019-04-09T16:15:14.716Z,1554826514.716 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T16:15:14.716Z,1554826514.716 [BR_Ping1D] No Fault, FailCount= 1
2019-04-09T16:15:15.074Z,1554826515.074 [PAR_Licor](INFO): A/D timeout, 9 tries over 138 ms

2019-04-09T16:15:15.074Z,1554826515.074 [PAR_Licor] Data Fault, FailCount= 3
2019-04-09T16:15:15.074Z,1554826515.074 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:15:15.095Z,1554826515.095 [BR_Ping1D](INFO): Start
2019-04-09T16:15:15.143Z,1554826515.143 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:15:15.143Z,1554826515.143 [CBIT](CRITICAL): Data Fault in component: PAR_Licor
2019-04-09T16:15:15.358Z,1554826515.358 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:15.389Z,1554826515.389 [CBIT](INFO): Critical error at 20190409T161515
2019-04-09T16:15:15.389Z,1554826515.389 [CBIT](FAULT): Main Battery Failure. Count: 21
2019-04-09T16:15:15.762Z,1554826515.762 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:15.763Z,1554826515.763 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:15.763Z,1554826515.763 [BR_Ping1D](INFO): startup: 0
2019-04-09T16:15:15.763Z,1554826515.763 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T16:15:15.774Z,1554826515.774 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T16:15:15.774Z,1554826515.774 [BR_Ping1D](INFO): 66
2019-04-09T16:15:15.774Z,1554826515.774 [BR_Ping1D](INFO): 82
2019-04-09T16:15:15.774Z,1554826515.774 [BR_Ping1D](INFO): 2
2019-04-09T16:15:15.774Z,1554826515.774 [BR_Ping1D](INFO): 0
2019-04-09T16:15:15.775Z,1554826515.775 [BR_Ping1D](INFO): 236
2019-04-09T16:15:15.775Z,1554826515.775 [BR_Ping1D](INFO): 3
2019-04-09T16:15:15.775Z,1554826515.775 [BR_Ping1D](INFO): 0
2019-04-09T16:15:15.775Z,1554826515.775 [BR_Ping1D](INFO): 0
2019-04-09T16:15:15.775Z,1554826515.775 [BR_Ping1D](INFO): 1
2019-04-09T16:15:15.775Z,1554826515.775 [BR_Ping1D](INFO): 30
2019-04-09T16:15:15.775Z,1554826515.775 [BR_Ping1D](INFO): 164
2019-04-09T16:15:15.775Z,1554826515.775 [BR_Ping1D](INFO): 1
2019-04-09T16:15:15.775Z,1554826515.775 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T16:15:15.776Z,1554826515.776 [BR_Ping1D](INFO): 66
2019-04-09T16:15:15.776Z,1554826515.776 [BR_Ping1D](INFO): 82
2019-04-09T16:15:15.776Z,1554826515.776 [BR_Ping1D](INFO): 1
2019-04-09T16:15:15.776Z,1554826515.776 [BR_Ping1D](INFO): 0
2019-04-09T16:15:15.776Z,1554826515.776 [BR_Ping1D](INFO): 235
2019-04-09T16:15:15.776Z,1554826515.776 [BR_Ping1D](INFO): 3
2019-04-09T16:15:15.776Z,1554826515.776 [BR_Ping1D](INFO): 0
2019-04-09T16:15:15.776Z,1554826515.776 [BR_Ping1D](INFO): 0
2019-04-09T16:15:15.776Z,1554826515.776 [BR_Ping1D](INFO): 132
2019-04-09T16:15:15.777Z,1554826515.777 [BR_Ping1D](INFO): 1
2019-04-09T16:15:15.777Z,1554826515.777 [BR_Ping1D](INFO): 21
2019-04-09T16:15:15.777Z,1554826515.777 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T16:15:15.777Z,1554826515.777 [BR_Ping1D](INFO): 66
2019-04-09T16:15:15.777Z,1554826515.777 [BR_Ping1D](INFO): 82
2019-04-09T16:15:15.777Z,1554826515.777 [BR_Ping1D](INFO): 4
2019-04-09T16:15:15.777Z,1554826515.777 [BR_Ping1D](INFO): 0
2019-04-09T16:15:15.777Z,1554826515.777 [BR_Ping1D](INFO): 234
2019-04-09T16:15:15.777Z,1554826515.777 [BR_Ping1D](INFO): 3
2019-04-09T16:15:15.778Z,1554826515.778 [BR_Ping1D](INFO): 0
2019-04-09T16:15:15.778Z,1554826515.778 [BR_Ping1D](INFO): 0
2019-04-09T16:15:15.779Z,1554826515.779 [BR_Ping1D](INFO): 96
2019-04-09T16:15:15.779Z,1554826515.779 [BR_Ping1D](INFO): 227
2019-04-09T16:15:15.780Z,1554826515.780 [BR_Ping1D](INFO): 22
2019-04-09T16:15:15.780Z,1554826515.780 [BR_Ping1D](INFO): 0
2019-04-09T16:15:15.781Z,1554826515.781 [BR_Ping1D](INFO): 222
2019-04-09T16:15:15.781Z,1554826515.781 [BR_Ping1D](INFO): 2
2019-04-09T16:15:15.787Z,1554826515.787 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T16:15:15.787Z,1554826515.787 [BR_Ping1D](INFO): 66
2019-04-09T16:15:15.787Z,1554826515.787 [BR_Ping1D](INFO): 82
2019-04-09T16:15:15.787Z,1554826515.787 [BR_Ping1D](INFO): 1
2019-04-09T16:15:15.787Z,1554826515.787 [BR_Ping1D](INFO): 0
2019-04-09T16:15:15.787Z,1554826515.787 [BR_Ping1D](INFO): 238
2019-04-09T16:15:15.787Z,1554826515.787 [BR_Ping1D](INFO): 3
2019-04-09T16:15:15.787Z,1554826515.787 [BR_Ping1D](INFO): 0
2019-04-09T16:15:15.788Z,1554826515.788 [BR_Ping1D](INFO): 0
2019-04-09T16:15:15.788Z,1554826515.788 [BR_Ping1D](INFO): 135
2019-04-09T16:15:15.788Z,1554826515.788 [BR_Ping1D](INFO): 1
2019-04-09T16:15:15.788Z,1554826515.788 [BR_Ping1D](INFO): 21
2019-04-09T16:15:16.166Z,1554826516.166 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:16.199Z,1554826516.199 [CBIT](CRITICAL): Environmental Failure. Press:14.866898 PSI. Humidity:35%. Temp:26 C. ABORTING MISSION
2019-04-09T16:15:16.550Z,1554826516.550 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T16:15:16.550Z,1554826516.550 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T16:15:16.551Z,1554826516.551 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T16:15:16.573Z,1554826516.573 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:16.574Z,1554826516.574 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:16.574Z,1554826516.574 [BR_Ping1D](INFO): startup: 1
2019-04-09T16:15:16.574Z,1554826516.574 [BR_Ping1D](INFO): UART requesting status
2019-04-09T16:15:16.574Z,1554826516.574 [BR_Ping1D](INFO): 66
2019-04-09T16:15:16.574Z,1554826516.574 [BR_Ping1D](INFO): 82
2019-04-09T16:15:16.574Z,1554826516.574 [BR_Ping1D](INFO): 0
2019-04-09T16:15:16.575Z,1554826516.575 [BR_Ping1D](INFO): 0
2019-04-09T16:15:16.575Z,1554826516.575 [BR_Ping1D](INFO): 186
2019-04-09T16:15:16.575Z,1554826516.575 [BR_Ping1D](INFO): 4
2019-04-09T16:15:16.575Z,1554826516.575 [BR_Ping1D](INFO): 0
2019-04-09T16:15:16.575Z,1554826516.575 [BR_Ping1D](INFO): 0
2019-04-09T16:15:16.575Z,1554826516.575 [BR_Ping1D](INFO): 82
2019-04-09T16:15:16.575Z,1554826516.575 [BR_Ping1D](INFO): 1
2019-04-09T16:15:16.582Z,1554826516.582 [BR_Ping1D](INFO): 66
2019-04-09T16:15:16.582Z,1554826516.582 [BR_Ping1D](INFO): 82
2019-04-09T16:15:16.582Z,1554826516.582 [BR_Ping1D](INFO): 0
2019-04-09T16:15:16.582Z,1554826516.582 [BR_Ping1D](INFO): 0
2019-04-09T16:15:16.582Z,1554826516.582 [BR_Ping1D](INFO): 191
2019-04-09T16:15:16.582Z,1554826516.582 [BR_Ping1D](INFO): 4
2019-04-09T16:15:16.582Z,1554826516.582 [BR_Ping1D](INFO): 0
2019-04-09T16:15:16.583Z,1554826516.583 [BR_Ping1D](INFO): 0
2019-04-09T16:15:16.583Z,1554826516.583 [BR_Ping1D](INFO): 87
2019-04-09T16:15:16.583Z,1554826516.583 [BR_Ping1D](INFO): 1
2019-04-09T16:15:16.590Z,1554826516.590 [BR_Ping1D](INFO): 66
2019-04-09T16:15:16.590Z,1554826516.590 [BR_Ping1D](INFO): 82
2019-04-09T16:15:16.590Z,1554826516.590 [BR_Ping1D](INFO): 0
2019-04-09T16:15:16.590Z,1554826516.590 [BR_Ping1D](INFO): 0
2019-04-09T16:15:16.590Z,1554826516.590 [BR_Ping1D](INFO): 179
2019-04-09T16:15:16.590Z,1554826516.590 [BR_Ping1D](INFO): 4
2019-04-09T16:15:16.590Z,1554826516.590 [BR_Ping1D](INFO): 0
2019-04-09T16:15:16.591Z,1554826516.591 [BR_Ping1D](INFO): 0
2019-04-09T16:15:16.591Z,1554826516.591 [BR_Ping1D](INFO): 75
2019-04-09T16:15:16.591Z,1554826516.591 [BR_Ping1D](INFO): 1
2019-04-09T16:15:16.608Z,1554826516.608 [CBIT](INFO): Critical error at 20190409T161516
2019-04-09T16:15:16.610Z,1554826516.610 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T16:15:17.074Z,1554826517.074 [Aanderaa_O2](INFO): Powering down
2019-04-09T16:15:17.153Z,1554826517.153 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:17.153Z,1554826517.153 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:17.154Z,1554826517.154 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:17.154Z,1554826517.154 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:17.154Z,1554826517.154 [BR_Ping1D](INFO): elapsed 1.3909
2019-04-09T16:15:17.154Z,1554826517.154 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:17.154Z,1554826517.154 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:17.155Z,1554826517.155 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:17.155Z,1554826517.155 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:17.506Z,1554826517.506 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T16:15:17.506Z,1554826517.506 [MassServo] Communications Fault, FailCount= 3
2019-04-09T16:15:17.506Z,1554826517.506 [MassServo](ERROR): Communications Fault
2019-04-09T16:15:17.510Z,1554826517.510 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T16:15:17.539Z,1554826517.539 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:17.539Z,1554826517.539 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:17.539Z,1554826517.539 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:17.539Z,1554826517.539 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:17.539Z,1554826517.539 [BR_Ping1D](INFO): elapsed 1.7763
2019-04-09T16:15:17.540Z,1554826517.540 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:17.540Z,1554826517.540 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:17.540Z,1554826517.540 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:17.540Z,1554826517.540 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:17.551Z,1554826517.551 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:15:17.551Z,1554826517.551 [MassServo](INFO): Powering down
2019-04-09T16:15:17.937Z,1554826517.937 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:17.937Z,1554826517.937 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:17.938Z,1554826517.938 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:17.938Z,1554826517.938 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:17.938Z,1554826517.938 [BR_Ping1D](INFO): elapsed 2.1750
2019-04-09T16:15:17.938Z,1554826517.938 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:17.938Z,1554826517.938 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:17.939Z,1554826517.939 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:17.939Z,1554826517.939 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:17.955Z,1554826517.955 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T16:15:17.955Z,1554826517.955 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-09T16:15:18.342Z,1554826518.342 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:18.342Z,1554826518.342 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:18.343Z,1554826518.343 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:18.343Z,1554826518.343 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:18.343Z,1554826518.343 [BR_Ping1D](INFO): elapsed 2.5799
2019-04-09T16:15:18.343Z,1554826518.343 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:18.343Z,1554826518.343 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:18.344Z,1554826518.344 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:18.344Z,1554826518.344 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:18.355Z,1554826518.355 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:15:18.475Z,1554826518.475 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:15:18.479Z,1554826518.479 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T16:15:18.479Z,1554826518.479 [MassServo] No Fault, FailCount= 3
2019-04-09T16:15:18.757Z,1554826518.757 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:18.757Z,1554826518.757 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:18.757Z,1554826518.757 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:18.758Z,1554826518.758 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:18.758Z,1554826518.758 [BR_Ping1D](INFO): elapsed 2.9948
2019-04-09T16:15:18.758Z,1554826518.758 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:18.758Z,1554826518.758 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:18.758Z,1554826518.758 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:18.759Z,1554826518.759 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:19.150Z,1554826519.150 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:19.150Z,1554826519.150 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:19.150Z,1554826519.150 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:19.150Z,1554826519.150 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:19.150Z,1554826519.150 [BR_Ping1D](INFO): elapsed 3.3874
2019-04-09T16:15:19.151Z,1554826519.151 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:19.151Z,1554826519.151 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:19.151Z,1554826519.151 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:19.151Z,1554826519.151 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:19.554Z,1554826519.554 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:19.554Z,1554826519.554 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:19.554Z,1554826519.554 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:19.554Z,1554826519.554 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:19.554Z,1554826519.554 [BR_Ping1D](INFO): elapsed 3.7913
2019-04-09T16:15:19.555Z,1554826519.555 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:19.555Z,1554826519.555 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:19.555Z,1554826519.555 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:19.555Z,1554826519.555 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:19.592Z,1554826519.592 [CBIT](FAULT): Main Battery Failure. Count: 31
2019-04-09T16:15:19.958Z,1554826519.958 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:19.958Z,1554826519.958 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:19.958Z,1554826519.958 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:19.958Z,1554826519.958 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:19.958Z,1554826519.958 [BR_Ping1D](INFO): elapsed 4.1953
2019-04-09T16:15:19.959Z,1554826519.959 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:19.959Z,1554826519.959 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:19.959Z,1554826519.959 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:19.959Z,1554826519.959 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:20.074Z,1554826520.074 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T16:15:20.074Z,1554826520.074 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-09T16:15:20.074Z,1554826520.074 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T16:15:20.361Z,1554826520.361 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:20.361Z,1554826520.361 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:20.362Z,1554826520.362 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:20.362Z,1554826520.362 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:20.362Z,1554826520.362 [BR_Ping1D](INFO): elapsed 4.5989
2019-04-09T16:15:20.362Z,1554826520.362 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:20.362Z,1554826520.362 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:20.363Z,1554826520.363 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:20.363Z,1554826520.363 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:20.395Z,1554826520.395 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T16:15:20.478Z,1554826520.478 [CTD_Seabird](INFO): Powering down
2019-04-09T16:15:20.766Z,1554826520.766 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:20.766Z,1554826520.766 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:20.767Z,1554826520.767 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:20.767Z,1554826520.767 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:20.767Z,1554826520.767 [BR_Ping1D](INFO): elapsed 5.0039
2019-04-09T16:15:20.767Z,1554826520.767 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:20.767Z,1554826520.767 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T16:15:20.767Z,1554826520.767 [BR_Ping1D] Data Fault, FailCount= 2
2019-04-09T16:15:20.767Z,1554826520.767 [BR_Ping1D](ERROR): Data Fault
2019-04-09T16:15:21.090Z,1554826521.090 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T16:15:21.090Z,1554826521.090 [MassServo] Communications Fault, FailCount= 4
2019-04-09T16:15:21.090Z,1554826521.090 [MassServo](ERROR): Communications Fault
2019-04-09T16:15:21.095Z,1554826521.095 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T16:15:21.095Z,1554826521.095 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T16:15:21.169Z,1554826521.169 [BR_Ping1D](INFO): Stop
2019-04-09T16:15:21.169Z,1554826521.169 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:15:21.169Z,1554826521.169 [BR_Ping1D](INFO): Powering down
2019-04-09T16:15:21.255Z,1554826521.255 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:15:21.255Z,1554826521.255 [MassServo](INFO): Powering down
2019-04-09T16:15:21.573Z,1554826521.573 [BR_Ping1D](INFO): Stopping
2019-04-09T16:15:21.587Z,1554826521.587 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T16:15:21.587Z,1554826521.587 [CTD_Seabird] No Fault, FailCount= 1
2019-04-09T16:15:21.842Z,1554826521.842 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T16:15:21.844Z,1554826521.844 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T16:15:21.982Z,1554826521.982 [BR_Ping1D](INFO): Stopping
2019-04-09T16:15:21.996Z,1554826521.996 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:15:22.115Z,1554826522.115 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:15:22.119Z,1554826522.119 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T16:15:22.119Z,1554826522.119 [MassServo] No Fault, FailCount= 4
2019-04-09T16:15:22.382Z,1554826522.382 [BR_Ping1D](INFO): Stopped
2019-04-09T16:15:22.415Z,1554826522.415 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T16:15:22.415Z,1554826522.415 [BR_Ping1D] No Fault, FailCount= 2
2019-04-09T16:15:22.785Z,1554826522.785 [BR_Ping1D](INFO): Start
2019-04-09T16:15:23.190Z,1554826523.190 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:23.593Z,1554826523.593 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:23.593Z,1554826523.593 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:23.594Z,1554826523.594 [BR_Ping1D](INFO): startup: 0
2019-04-09T16:15:23.594Z,1554826523.594 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T16:15:23.606Z,1554826523.606 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T16:15:23.606Z,1554826523.606 [BR_Ping1D](INFO): 66
2019-04-09T16:15:23.606Z,1554826523.606 [BR_Ping1D](INFO): 82
2019-04-09T16:15:23.606Z,1554826523.606 [BR_Ping1D](INFO): 2
2019-04-09T16:15:23.606Z,1554826523.606 [BR_Ping1D](INFO): 0
2019-04-09T16:15:23.606Z,1554826523.606 [BR_Ping1D](INFO): 236
2019-04-09T16:15:23.607Z,1554826523.607 [BR_Ping1D](INFO): 3
2019-04-09T16:15:23.607Z,1554826523.607 [BR_Ping1D](INFO): 0
2019-04-09T16:15:23.607Z,1554826523.607 [BR_Ping1D](INFO): 0
2019-04-09T16:15:23.607Z,1554826523.607 [BR_Ping1D](INFO): 1
2019-04-09T16:15:23.607Z,1554826523.607 [BR_Ping1D](INFO): 30
2019-04-09T16:15:23.607Z,1554826523.607 [BR_Ping1D](INFO): 164
2019-04-09T16:15:23.607Z,1554826523.607 [BR_Ping1D](INFO): 1
2019-04-09T16:15:23.607Z,1554826523.607 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T16:15:23.607Z,1554826523.607 [BR_Ping1D](INFO): 66
2019-04-09T16:15:23.608Z,1554826523.608 [BR_Ping1D](INFO): 82
2019-04-09T16:15:23.608Z,1554826523.608 [BR_Ping1D](INFO): 1
2019-04-09T16:15:23.608Z,1554826523.608 [BR_Ping1D](INFO): 0
2019-04-09T16:15:23.608Z,1554826523.608 [BR_Ping1D](INFO): 235
2019-04-09T16:15:23.608Z,1554826523.608 [BR_Ping1D](INFO): 3
2019-04-09T16:15:23.608Z,1554826523.608 [BR_Ping1D](INFO): 0
2019-04-09T16:15:23.608Z,1554826523.608 [BR_Ping1D](INFO): 0
2019-04-09T16:15:23.608Z,1554826523.608 [BR_Ping1D](INFO): 132
2019-04-09T16:15:23.608Z,1554826523.608 [BR_Ping1D](INFO): 1
2019-04-09T16:15:23.608Z,1554826523.608 [BR_Ping1D](INFO): 21
2019-04-09T16:15:23.609Z,1554826523.609 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T16:15:23.609Z,1554826523.609 [BR_Ping1D](INFO): 66
2019-04-09T16:15:23.609Z,1554826523.609 [BR_Ping1D](INFO): 82
2019-04-09T16:15:23.609Z,1554826523.609 [BR_Ping1D](INFO): 4
2019-04-09T16:15:23.609Z,1554826523.609 [BR_Ping1D](INFO): 0
2019-04-09T16:15:23.609Z,1554826523.609 [BR_Ping1D](INFO): 234
2019-04-09T16:15:23.609Z,1554826523.609 [BR_Ping1D](INFO): 3
2019-04-09T16:15:23.609Z,1554826523.609 [BR_Ping1D](INFO): 0
2019-04-09T16:15:23.609Z,1554826523.609 [BR_Ping1D](INFO): 0
2019-04-09T16:15:23.610Z,1554826523.610 [BR_Ping1D](INFO): 96
2019-04-09T16:15:23.610Z,1554826523.610 [BR_Ping1D](INFO): 227
2019-04-09T16:15:23.611Z,1554826523.611 [BR_Ping1D](INFO): 22
2019-04-09T16:15:23.611Z,1554826523.611 [BR_Ping1D](INFO): 0
2019-04-09T16:15:23.612Z,1554826523.612 [BR_Ping1D](INFO): 222
2019-04-09T16:15:23.612Z,1554826523.612 [BR_Ping1D](INFO): 2
2019-04-09T16:15:23.613Z,1554826523.613 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T16:15:23.615Z,1554826523.615 [BR_Ping1D](INFO): 66
2019-04-09T16:15:23.615Z,1554826523.615 [BR_Ping1D](INFO): 82
2019-04-09T16:15:23.616Z,1554826523.616 [BR_Ping1D](INFO): 1
2019-04-09T16:15:23.616Z,1554826523.616 [BR_Ping1D](INFO): 0
2019-04-09T16:15:23.617Z,1554826523.617 [BR_Ping1D](INFO): 238
2019-04-09T16:15:23.617Z,1554826523.617 [BR_Ping1D](INFO): 3
2019-04-09T16:15:23.618Z,1554826523.618 [BR_Ping1D](INFO): 0
2019-04-09T16:15:23.618Z,1554826523.618 [BR_Ping1D](INFO): 0
2019-04-09T16:15:23.619Z,1554826523.619 [BR_Ping1D](INFO): 135
2019-04-09T16:15:23.619Z,1554826523.619 [BR_Ping1D](INFO): 1
2019-04-09T16:15:23.619Z,1554826523.619 [BR_Ping1D](INFO): 21
2019-04-09T16:15:23.646Z,1554826523.646 [CBIT](FAULT): Main Battery Failure. Count: 41
2019-04-09T16:15:23.998Z,1554826523.998 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:24.402Z,1554826524.402 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:24.402Z,1554826524.402 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:24.403Z,1554826524.403 [BR_Ping1D](INFO): startup: 1
2019-04-09T16:15:24.403Z,1554826524.403 [BR_Ping1D](INFO): UART requesting status
2019-04-09T16:15:24.403Z,1554826524.403 [BR_Ping1D](INFO): 66
2019-04-09T16:15:24.403Z,1554826524.403 [BR_Ping1D](INFO): 82
2019-04-09T16:15:24.403Z,1554826524.403 [BR_Ping1D](INFO): 0
2019-04-09T16:15:24.403Z,1554826524.403 [BR_Ping1D](INFO): 0
2019-04-09T16:15:24.403Z,1554826524.403 [BR_Ping1D](INFO): 186
2019-04-09T16:15:24.403Z,1554826524.403 [BR_Ping1D](INFO): 4
2019-04-09T16:15:24.403Z,1554826524.403 [BR_Ping1D](INFO): 0
2019-04-09T16:15:24.403Z,1554826524.403 [BR_Ping1D](INFO): 0
2019-04-09T16:15:24.404Z,1554826524.404 [BR_Ping1D](INFO): 82
2019-04-09T16:15:24.404Z,1554826524.404 [BR_Ping1D](INFO): 1
2019-04-09T16:15:24.410Z,1554826524.410 [BR_Ping1D](INFO): 66
2019-04-09T16:15:24.410Z,1554826524.410 [BR_Ping1D](INFO): 82
2019-04-09T16:15:24.410Z,1554826524.410 [BR_Ping1D](INFO): 0
2019-04-09T16:15:24.410Z,1554826524.410 [BR_Ping1D](INFO): 0
2019-04-09T16:15:24.410Z,1554826524.410 [BR_Ping1D](INFO): 191
2019-04-09T16:15:24.410Z,1554826524.410 [BR_Ping1D](INFO): 4
2019-04-09T16:15:24.411Z,1554826524.411 [BR_Ping1D](INFO): 0
2019-04-09T16:15:24.411Z,1554826524.411 [BR_Ping1D](INFO): 0
2019-04-09T16:15:24.411Z,1554826524.411 [BR_Ping1D](INFO): 87
2019-04-09T16:15:24.411Z,1554826524.411 [BR_Ping1D](INFO): 1
2019-04-09T16:15:24.418Z,1554826524.418 [BR_Ping1D](INFO): 66
2019-04-09T16:15:24.418Z,1554826524.418 [BR_Ping1D](INFO): 82
2019-04-09T16:15:24.418Z,1554826524.418 [BR_Ping1D](INFO): 0
2019-04-09T16:15:24.418Z,1554826524.418 [BR_Ping1D](INFO): 0
2019-04-09T16:15:24.418Z,1554826524.418 [BR_Ping1D](INFO): 179
2019-04-09T16:15:24.418Z,1554826524.418 [BR_Ping1D](INFO): 4
2019-04-09T16:15:24.418Z,1554826524.418 [BR_Ping1D](INFO): 0
2019-04-09T16:15:24.419Z,1554826524.419 [BR_Ping1D](INFO): 0
2019-04-09T16:15:24.419Z,1554826524.419 [BR_Ping1D](INFO): 75
2019-04-09T16:15:24.419Z,1554826524.419 [BR_Ping1D](INFO): 1
2019-04-09T16:15:24.746Z,1554826524.746 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T16:15:24.746Z,1554826524.746 [MassServo] Communications Fault, FailCount= 5
2019-04-09T16:15:24.746Z,1554826524.746 [MassServo](ERROR): Communications Fault
2019-04-09T16:15:24.750Z,1554826524.750 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T16:15:24.751Z,1554826524.751 [CBIT](CRITICAL): Communications Fault in component: MassServo
2019-04-09T16:15:24.806Z,1554826524.806 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:24.806Z,1554826524.806 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:24.806Z,1554826524.806 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:24.806Z,1554826524.806 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:24.806Z,1554826524.806 [BR_Ping1D](INFO): elapsed 1.2124
2019-04-09T16:15:24.807Z,1554826524.807 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:24.807Z,1554826524.807 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:24.807Z,1554826524.807 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:24.807Z,1554826524.807 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:24.835Z,1554826524.835 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:15:24.835Z,1554826524.835 [MassServo](INFO): Powering down
2019-04-09T16:15:24.838Z,1554826524.838 [CBIT](INFO): Critical error at 20190409T161524
2019-04-09T16:15:25.209Z,1554826525.209 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:25.209Z,1554826525.209 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:25.210Z,1554826525.210 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:25.210Z,1554826525.210 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:25.210Z,1554826525.210 [BR_Ping1D](INFO): elapsed 1.6160
2019-04-09T16:15:25.210Z,1554826525.210 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:25.210Z,1554826525.210 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:25.211Z,1554826525.211 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:25.211Z,1554826525.211 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:25.614Z,1554826525.614 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:25.614Z,1554826525.614 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:25.615Z,1554826525.615 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:25.615Z,1554826525.615 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:25.615Z,1554826525.615 [BR_Ping1D](INFO): elapsed 2.0209
2019-04-09T16:15:25.615Z,1554826525.615 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:25.615Z,1554826525.615 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:25.616Z,1554826525.616 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:25.616Z,1554826525.616 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:25.643Z,1554826525.643 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:15:25.763Z,1554826525.763 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:15:26.017Z,1554826526.017 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:26.017Z,1554826526.017 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:26.018Z,1554826526.018 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:26.018Z,1554826526.018 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:26.018Z,1554826526.018 [BR_Ping1D](INFO): elapsed 2.4238
2019-04-09T16:15:26.018Z,1554826526.018 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:26.018Z,1554826526.018 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:26.018Z,1554826526.018 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:26.019Z,1554826526.019 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:26.434Z,1554826526.434 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:26.434Z,1554826526.434 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:26.435Z,1554826526.435 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:26.435Z,1554826526.435 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:26.435Z,1554826526.435 [BR_Ping1D](INFO): elapsed 2.8410
2019-04-09T16:15:26.435Z,1554826526.435 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:26.435Z,1554826526.435 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:26.436Z,1554826526.436 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:26.436Z,1554826526.436 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:26.826Z,1554826526.826 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:26.826Z,1554826526.826 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:26.826Z,1554826526.826 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:26.826Z,1554826526.826 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:26.827Z,1554826526.827 [BR_Ping1D](INFO): elapsed 3.2324
2019-04-09T16:15:26.827Z,1554826526.827 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:26.827Z,1554826526.827 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:26.827Z,1554826526.827 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:26.827Z,1554826526.827 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:27.211Z,1554826527.211 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:15:27.211Z,1554826527.211 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-04-09T16:15:27.211Z,1554826527.211 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:15:27.230Z,1554826527.230 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:27.230Z,1554826527.230 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:27.231Z,1554826527.231 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:27.231Z,1554826527.231 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:27.231Z,1554826527.231 [BR_Ping1D](INFO): elapsed 3.6368
2019-04-09T16:15:27.231Z,1554826527.231 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:27.231Z,1554826527.231 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:27.231Z,1554826527.231 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:27.232Z,1554826527.232 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:27.247Z,1554826527.247 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:15:27.615Z,1554826527.615 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:15:27.803Z,1554826527.803 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:27.803Z,1554826527.803 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:27.803Z,1554826527.803 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:27.803Z,1554826527.803 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:27.803Z,1554826527.803 [BR_Ping1D](INFO): elapsed 4.2092
2019-04-09T16:15:27.804Z,1554826527.804 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:27.804Z,1554826527.804 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:27.804Z,1554826527.804 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:27.804Z,1554826527.804 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:27.816Z,1554826527.816 [CBIT](FAULT): Main Battery Failure. Count: 51
2019-04-09T16:15:28.056Z,1554826528.056 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:28.056Z,1554826528.056 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:28.056Z,1554826528.056 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:28.056Z,1554826528.056 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:28.056Z,1554826528.056 [BR_Ping1D](INFO): elapsed 4.4622
2019-04-09T16:15:28.057Z,1554826528.057 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:28.057Z,1554826528.057 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:28.057Z,1554826528.057 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:28.057Z,1554826528.057 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:28.422Z,1554826528.422 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T16:15:28.422Z,1554826528.422 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-09T16:15:28.422Z,1554826528.422 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T16:15:28.442Z,1554826528.442 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:28.442Z,1554826528.442 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:28.442Z,1554826528.442 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:28.442Z,1554826528.442 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:28.443Z,1554826528.443 [BR_Ping1D](INFO): elapsed 4.8485
2019-04-09T16:15:28.443Z,1554826528.443 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:28.443Z,1554826528.443 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:28.443Z,1554826528.443 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:28.443Z,1554826528.443 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:28.483Z,1554826528.483 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T16:15:28.483Z,1554826528.483 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:15:28.483Z,1554826528.483 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-04-09T16:15:28.946Z,1554826528.946 [Aanderaa_O2](INFO): Powering down
2019-04-09T16:15:28.966Z,1554826528.966 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:28.966Z,1554826528.966 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:28.967Z,1554826528.967 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:28.967Z,1554826528.967 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:28.967Z,1554826528.967 [BR_Ping1D](INFO): elapsed 5.3729
2019-04-09T16:15:28.967Z,1554826528.967 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:28.967Z,1554826528.967 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T16:15:28.967Z,1554826528.967 [BR_Ping1D] Data Fault, FailCount= 3
2019-04-09T16:15:28.968Z,1554826528.968 [BR_Ping1D](ERROR): Data Fault
2019-04-09T16:15:29.007Z,1554826529.007 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T16:15:29.250Z,1554826529.250 [BR_Ping1D](INFO): Stop
2019-04-09T16:15:29.250Z,1554826529.250 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:15:29.250Z,1554826529.250 [BR_Ping1D](INFO): Powering down
2019-04-09T16:15:29.654Z,1554826529.654 [BR_Ping1D](INFO): Stopping
2019-04-09T16:15:29.687Z,1554826529.687 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T16:15:29.687Z,1554826529.687 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-09T16:15:30.058Z,1554826530.058 [BR_Ping1D](INFO): Stopping
2019-04-09T16:15:30.474Z,1554826530.474 [BR_Ping1D](INFO): Stopped
2019-04-09T16:15:30.500Z,1554826530.500 [SBIT](IMPORTANT): Beginning Startup BIT
2019-04-09T16:15:30.504Z,1554826530.504 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T16:15:30.504Z,1554826530.504 [BR_Ping1D] No Fault, FailCount= 3
2019-04-09T16:15:30.505Z,1554826530.505 [CBIT](IMPORTANT): Beginning ground fault scan
2019-04-09T16:15:30.846Z,1554826530.846 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:15:30.847Z,1554826530.847 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:15:30.886Z,1554826530.886 [BR_Ping1D](INFO): Start
2019-04-09T16:15:31.267Z,1554826531.267 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:31.673Z,1554826531.673 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:31.673Z,1554826531.673 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:31.674Z,1554826531.674 [BR_Ping1D](INFO): startup: 0
2019-04-09T16:15:31.674Z,1554826531.674 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T16:15:31.686Z,1554826531.686 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T16:15:31.686Z,1554826531.686 [BR_Ping1D](INFO): 66
2019-04-09T16:15:31.686Z,1554826531.686 [BR_Ping1D](INFO): 82
2019-04-09T16:15:31.686Z,1554826531.686 [BR_Ping1D](INFO): 2
2019-04-09T16:15:31.686Z,1554826531.686 [BR_Ping1D](INFO): 0
2019-04-09T16:15:31.686Z,1554826531.686 [BR_Ping1D](INFO): 236
2019-04-09T16:15:31.687Z,1554826531.687 [BR_Ping1D](INFO): 3
2019-04-09T16:15:31.687Z,1554826531.687 [BR_Ping1D](INFO): 0
2019-04-09T16:15:31.687Z,1554826531.687 [BR_Ping1D](INFO): 0
2019-04-09T16:15:31.687Z,1554826531.687 [BR_Ping1D](INFO): 1
2019-04-09T16:15:31.687Z,1554826531.687 [BR_Ping1D](INFO): 30
2019-04-09T16:15:31.687Z,1554826531.687 [BR_Ping1D](INFO): 164
2019-04-09T16:15:31.687Z,1554826531.687 [BR_Ping1D](INFO): 1
2019-04-09T16:15:31.687Z,1554826531.687 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T16:15:31.687Z,1554826531.687 [BR_Ping1D](INFO): 66
2019-04-09T16:15:31.688Z,1554826531.688 [BR_Ping1D](INFO): 82
2019-04-09T16:15:31.688Z,1554826531.688 [BR_Ping1D](INFO): 1
2019-04-09T16:15:31.688Z,1554826531.688 [BR_Ping1D](INFO): 0
2019-04-09T16:15:31.688Z,1554826531.688 [BR_Ping1D](INFO): 235
2019-04-09T16:15:31.688Z,1554826531.688 [BR_Ping1D](INFO): 3
2019-04-09T16:15:31.688Z,1554826531.688 [BR_Ping1D](INFO): 0
2019-04-09T16:15:31.688Z,1554826531.688 [BR_Ping1D](INFO): 0
2019-04-09T16:15:31.688Z,1554826531.688 [BR_Ping1D](INFO): 132
2019-04-09T16:15:31.688Z,1554826531.688 [BR_Ping1D](INFO): 1
2019-04-09T16:15:31.688Z,1554826531.688 [BR_Ping1D](INFO): 21
2019-04-09T16:15:31.689Z,1554826531.689 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T16:15:31.689Z,1554826531.689 [BR_Ping1D](INFO): 66
2019-04-09T16:15:31.689Z,1554826531.689 [BR_Ping1D](INFO): 82
2019-04-09T16:15:31.689Z,1554826531.689 [BR_Ping1D](INFO): 4
2019-04-09T16:15:31.689Z,1554826531.689 [BR_Ping1D](INFO): 0
2019-04-09T16:15:31.689Z,1554826531.689 [BR_Ping1D](INFO): 234
2019-04-09T16:15:31.689Z,1554826531.689 [BR_Ping1D](INFO): 3
2019-04-09T16:15:31.689Z,1554826531.689 [BR_Ping1D](INFO): 0
2019-04-09T16:15:31.689Z,1554826531.689 [BR_Ping1D](INFO): 0
2019-04-09T16:15:31.689Z,1554826531.689 [BR_Ping1D](INFO): 96
2019-04-09T16:15:31.690Z,1554826531.690 [BR_Ping1D](INFO): 227
2019-04-09T16:15:31.690Z,1554826531.690 [BR_Ping1D](INFO): 22
2019-04-09T16:15:31.691Z,1554826531.691 [BR_Ping1D](INFO): 0
2019-04-09T16:15:31.691Z,1554826531.691 [BR_Ping1D](INFO): 222
2019-04-09T16:15:31.692Z,1554826531.692 [BR_Ping1D](INFO): 2
2019-04-09T16:15:31.693Z,1554826531.693 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T16:15:31.694Z,1554826531.694 [BR_Ping1D](INFO): 66
2019-04-09T16:15:31.694Z,1554826531.694 [BR_Ping1D](INFO): 82
2019-04-09T16:15:31.695Z,1554826531.695 [BR_Ping1D](INFO): 1
2019-04-09T16:15:31.695Z,1554826531.695 [BR_Ping1D](INFO): 0
2019-04-09T16:15:31.696Z,1554826531.696 [BR_Ping1D](INFO): 238
2019-04-09T16:15:31.696Z,1554826531.696 [BR_Ping1D](INFO): 3
2019-04-09T16:15:31.697Z,1554826531.697 [BR_Ping1D](INFO): 0
2019-04-09T16:15:31.697Z,1554826531.697 [BR_Ping1D](INFO): 0
2019-04-09T16:15:31.698Z,1554826531.698 [BR_Ping1D](INFO): 135
2019-04-09T16:15:31.698Z,1554826531.698 [BR_Ping1D](INFO): 1
2019-04-09T16:15:31.699Z,1554826531.699 [BR_Ping1D](INFO): 21
2019-04-09T16:15:31.786Z,1554826531.786 [CBIT](FAULT): Main Battery Failure. Count: 61
2019-04-09T16:15:32.077Z,1554826532.077 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:32.478Z,1554826532.478 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:32.478Z,1554826532.478 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:32.479Z,1554826532.479 [BR_Ping1D](INFO): startup: 1
2019-04-09T16:15:32.479Z,1554826532.479 [BR_Ping1D](INFO): UART requesting status
2019-04-09T16:15:32.479Z,1554826532.479 [BR_Ping1D](INFO): 66
2019-04-09T16:15:32.479Z,1554826532.479 [BR_Ping1D](INFO): 82
2019-04-09T16:15:32.479Z,1554826532.479 [BR_Ping1D](INFO): 0
2019-04-09T16:15:32.479Z,1554826532.479 [BR_Ping1D](INFO): 0
2019-04-09T16:15:32.479Z,1554826532.479 [BR_Ping1D](INFO): 186
2019-04-09T16:15:32.479Z,1554826532.479 [BR_Ping1D](INFO): 4
2019-04-09T16:15:32.479Z,1554826532.479 [BR_Ping1D](INFO): 0
2019-04-09T16:15:32.480Z,1554826532.480 [BR_Ping1D](INFO): 0
2019-04-09T16:15:32.480Z,1554826532.480 [BR_Ping1D](INFO): 82
2019-04-09T16:15:32.480Z,1554826532.480 [BR_Ping1D](INFO): 1
2019-04-09T16:15:32.486Z,1554826532.486 [BR_Ping1D](INFO): 66
2019-04-09T16:15:32.486Z,1554826532.486 [BR_Ping1D](INFO): 82
2019-04-09T16:15:32.486Z,1554826532.486 [BR_Ping1D](INFO): 0
2019-04-09T16:15:32.486Z,1554826532.486 [BR_Ping1D](INFO): 0
2019-04-09T16:15:32.486Z,1554826532.486 [BR_Ping1D](INFO): 191
2019-04-09T16:15:32.486Z,1554826532.486 [BR_Ping1D](INFO): 4
2019-04-09T16:15:32.486Z,1554826532.486 [BR_Ping1D](INFO): 0
2019-04-09T16:15:32.486Z,1554826532.486 [BR_Ping1D](INFO): 0
2019-04-09T16:15:32.487Z,1554826532.487 [BR_Ping1D](INFO): 87
2019-04-09T16:15:32.487Z,1554826532.487 [BR_Ping1D](INFO): 1
2019-04-09T16:15:32.494Z,1554826532.494 [BR_Ping1D](INFO): 66
2019-04-09T16:15:32.494Z,1554826532.494 [BR_Ping1D](INFO): 82
2019-04-09T16:15:32.494Z,1554826532.494 [BR_Ping1D](INFO): 0
2019-04-09T16:15:32.494Z,1554826532.494 [BR_Ping1D](INFO): 0
2019-04-09T16:15:32.494Z,1554826532.494 [BR_Ping1D](INFO): 179
2019-04-09T16:15:32.494Z,1554826532.494 [BR_Ping1D](INFO): 4
2019-04-09T16:15:32.494Z,1554826532.494 [BR_Ping1D](INFO): 0
2019-04-09T16:15:32.495Z,1554826532.495 [BR_Ping1D](INFO): 0
2019-04-09T16:15:32.495Z,1554826532.495 [BR_Ping1D](INFO): 75
2019-04-09T16:15:32.495Z,1554826532.495 [BR_Ping1D](INFO): 1
2019-04-09T16:15:32.879Z,1554826532.879 [NAL9602](INFO): Powering up NAL9602
2019-04-09T16:15:32.896Z,1554826532.896 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:32.896Z,1554826532.896 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:32.896Z,1554826532.896 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:32.896Z,1554826532.896 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:32.896Z,1554826532.896 [BR_Ping1D](INFO): elapsed 1.2222
2019-04-09T16:15:32.896Z,1554826532.896 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:32.897Z,1554826532.897 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:32.897Z,1554826532.897 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:32.897Z,1554826532.897 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:33.289Z,1554826533.289 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:33.289Z,1554826533.289 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:33.290Z,1554826533.290 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:33.290Z,1554826533.290 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:33.290Z,1554826533.290 [BR_Ping1D](INFO): elapsed 1.6160
2019-04-09T16:15:33.290Z,1554826533.290 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:33.290Z,1554826533.290 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:33.291Z,1554826533.291 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:33.291Z,1554826533.291 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:33.694Z,1554826533.694 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:33.694Z,1554826533.694 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:33.694Z,1554826533.694 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:33.694Z,1554826533.694 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:33.694Z,1554826533.694 [BR_Ping1D](INFO): elapsed 2.0204
2019-04-09T16:15:33.695Z,1554826533.695 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:33.695Z,1554826533.695 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:33.695Z,1554826533.695 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:33.695Z,1554826533.695 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:34.097Z,1554826534.097 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:34.098Z,1554826534.098 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:34.098Z,1554826534.098 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:34.098Z,1554826534.098 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:34.098Z,1554826534.098 [BR_Ping1D](INFO): elapsed 2.4242
2019-04-09T16:15:34.098Z,1554826534.098 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:34.099Z,1554826534.099 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:34.099Z,1554826534.099 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:34.099Z,1554826534.099 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:34.502Z,1554826534.502 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:34.503Z,1554826534.503 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:34.503Z,1554826534.503 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:34.503Z,1554826534.503 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:34.503Z,1554826534.503 [BR_Ping1D](INFO): elapsed 2.8292
2019-04-09T16:15:34.503Z,1554826534.503 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:34.504Z,1554826534.504 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:34.504Z,1554826534.504 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:34.504Z,1554826534.504 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:34.894Z,1554826534.894 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T16:15:34.894Z,1554826534.894 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-09T16:15:34.895Z,1554826534.895 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T16:15:34.917Z,1554826534.917 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:34.917Z,1554826534.917 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:34.918Z,1554826534.918 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:34.918Z,1554826534.918 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:34.918Z,1554826534.918 [BR_Ping1D](INFO): elapsed 3.2440
2019-04-09T16:15:34.918Z,1554826534.918 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:34.918Z,1554826534.918 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:34.919Z,1554826534.919 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:34.919Z,1554826534.919 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:35.055Z,1554826535.055 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T16:15:35.299Z,1554826535.299 [CTD_Seabird](INFO): Powering down
2019-04-09T16:15:35.311Z,1554826535.311 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:35.311Z,1554826535.311 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:35.311Z,1554826535.311 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:35.311Z,1554826535.311 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:35.312Z,1554826535.312 [BR_Ping1D](INFO): elapsed 3.6376
2019-04-09T16:15:35.312Z,1554826535.312 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:35.312Z,1554826535.312 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:35.312Z,1554826535.312 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:35.312Z,1554826535.312 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:35.713Z,1554826535.713 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:35.713Z,1554826535.713 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:35.713Z,1554826535.713 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:35.713Z,1554826535.713 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:35.714Z,1554826535.714 [BR_Ping1D](INFO): elapsed 4.0396
2019-04-09T16:15:35.714Z,1554826535.714 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:35.714Z,1554826535.714 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:35.714Z,1554826535.714 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:35.714Z,1554826535.714 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:35.762Z,1554826535.762 [CBIT](FAULT): Main Battery Failure. Count: 71
2019-04-09T16:15:36.117Z,1554826536.117 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:36.118Z,1554826536.118 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:36.118Z,1554826536.118 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:36.118Z,1554826536.118 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:36.118Z,1554826536.118 [BR_Ping1D](INFO): elapsed 4.4442
2019-04-09T16:15:36.118Z,1554826536.118 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:36.119Z,1554826536.119 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:36.119Z,1554826536.119 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:36.119Z,1554826536.119 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:36.521Z,1554826536.521 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:36.521Z,1554826536.521 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:36.522Z,1554826536.522 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:36.522Z,1554826536.522 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:36.522Z,1554826536.522 [BR_Ping1D](INFO): elapsed 4.8481
2019-04-09T16:15:36.522Z,1554826536.522 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:36.523Z,1554826536.523 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:36.523Z,1554826536.523 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:36.523Z,1554826536.523 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:36.551Z,1554826536.551 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T16:15:36.552Z,1554826536.552 [CTD_Seabird] No Fault, FailCount= 2
2019-04-09T16:15:36.642Z,1554826536.642 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T16:15:36.644Z,1554826536.644 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T16:15:36.925Z,1554826536.925 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:36.926Z,1554826536.926 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:36.926Z,1554826536.926 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:36.926Z,1554826536.926 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:36.926Z,1554826536.926 [BR_Ping1D](INFO): elapsed 5.2524
2019-04-09T16:15:36.927Z,1554826536.927 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:36.927Z,1554826536.927 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T16:15:36.927Z,1554826536.927 [BR_Ping1D] Data Fault, FailCount= 4
2019-04-09T16:15:36.927Z,1554826536.927 [BR_Ping1D](ERROR): Data Fault
2019-04-09T16:15:36.955Z,1554826536.955 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T16:15:37.329Z,1554826537.329 [BR_Ping1D](INFO): Stop
2019-04-09T16:15:37.329Z,1554826537.329 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:15:37.329Z,1554826537.329 [BR_Ping1D](INFO): Powering down
2019-04-09T16:15:37.734Z,1554826537.734 [BR_Ping1D](INFO): Stopping
2019-04-09T16:15:38.140Z,1554826538.140 [BR_Ping1D](INFO): Stopping
2019-04-09T16:15:38.542Z,1554826538.542 [BR_Ping1D](INFO): Stopped
2019-04-09T16:15:38.579Z,1554826538.579 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T16:15:38.579Z,1554826538.579 [BR_Ping1D] No Fault, FailCount= 4
2019-04-09T16:15:38.945Z,1554826538.945 [BR_Ping1D](INFO): Start
2019-04-09T16:15:39.349Z,1554826539.349 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:39.753Z,1554826539.753 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:39.753Z,1554826539.753 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:39.753Z,1554826539.753 [BR_Ping1D](INFO): startup: 0
2019-04-09T16:15:39.753Z,1554826539.753 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T16:15:39.766Z,1554826539.766 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T16:15:39.766Z,1554826539.766 [BR_Ping1D](INFO): 66
2019-04-09T16:15:39.766Z,1554826539.766 [BR_Ping1D](INFO): 82
2019-04-09T16:15:39.766Z,1554826539.766 [BR_Ping1D](INFO): 2
2019-04-09T16:15:39.766Z,1554826539.766 [BR_Ping1D](INFO): 0
2019-04-09T16:15:39.766Z,1554826539.766 [BR_Ping1D](INFO): 236
2019-04-09T16:15:39.767Z,1554826539.767 [BR_Ping1D](INFO): 3
2019-04-09T16:15:39.767Z,1554826539.767 [BR_Ping1D](INFO): 0
2019-04-09T16:15:39.767Z,1554826539.767 [BR_Ping1D](INFO): 0
2019-04-09T16:15:39.767Z,1554826539.767 [BR_Ping1D](INFO): 1
2019-04-09T16:15:39.767Z,1554826539.767 [BR_Ping1D](INFO): 30
2019-04-09T16:15:39.767Z,1554826539.767 [BR_Ping1D](INFO): 164
2019-04-09T16:15:39.767Z,1554826539.767 [BR_Ping1D](INFO): 1
2019-04-09T16:15:39.767Z,1554826539.767 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T16:15:39.768Z,1554826539.768 [BR_Ping1D](INFO): 66
2019-04-09T16:15:39.768Z,1554826539.768 [BR_Ping1D](INFO): 82
2019-04-09T16:15:39.768Z,1554826539.768 [BR_Ping1D](INFO): 1
2019-04-09T16:15:39.768Z,1554826539.768 [BR_Ping1D](INFO): 0
2019-04-09T16:15:39.768Z,1554826539.768 [BR_Ping1D](INFO): 235
2019-04-09T16:15:39.768Z,1554826539.768 [BR_Ping1D](INFO): 3
2019-04-09T16:15:39.768Z,1554826539.768 [BR_Ping1D](INFO): 0
2019-04-09T16:15:39.768Z,1554826539.768 [BR_Ping1D](INFO): 0
2019-04-09T16:15:39.768Z,1554826539.768 [BR_Ping1D](INFO): 132
2019-04-09T16:15:39.768Z,1554826539.768 [BR_Ping1D](INFO): 1
2019-04-09T16:15:39.768Z,1554826539.768 [BR_Ping1D](INFO): 21
2019-04-09T16:15:39.769Z,1554826539.769 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T16:15:39.769Z,1554826539.769 [BR_Ping1D](INFO): 66
2019-04-09T16:15:39.769Z,1554826539.769 [BR_Ping1D](INFO): 82
2019-04-09T16:15:39.769Z,1554826539.769 [BR_Ping1D](INFO): 4
2019-04-09T16:15:39.769Z,1554826539.769 [BR_Ping1D](INFO): 0
2019-04-09T16:15:39.769Z,1554826539.769 [BR_Ping1D](INFO): 234
2019-04-09T16:15:39.769Z,1554826539.769 [BR_Ping1D](INFO): 3
2019-04-09T16:15:39.769Z,1554826539.769 [BR_Ping1D](INFO): 0
2019-04-09T16:15:39.770Z,1554826539.770 [BR_Ping1D](INFO): 0
2019-04-09T16:15:39.770Z,1554826539.770 [BR_Ping1D](INFO): 96
2019-04-09T16:15:39.771Z,1554826539.771 [BR_Ping1D](INFO): 227
2019-04-09T16:15:39.771Z,1554826539.771 [BR_Ping1D](INFO): 22
2019-04-09T16:15:39.772Z,1554826539.772 [BR_Ping1D](INFO): 0
2019-04-09T16:15:39.772Z,1554826539.772 [BR_Ping1D](INFO): 222
2019-04-09T16:15:39.773Z,1554826539.773 [BR_Ping1D](INFO): 2
2019-04-09T16:15:39.774Z,1554826539.774 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T16:15:39.775Z,1554826539.775 [BR_Ping1D](INFO): 66
2019-04-09T16:15:39.775Z,1554826539.775 [BR_Ping1D](INFO): 82
2019-04-09T16:15:39.776Z,1554826539.776 [BR_Ping1D](INFO): 1
2019-04-09T16:15:39.776Z,1554826539.776 [BR_Ping1D](INFO): 0
2019-04-09T16:15:39.777Z,1554826539.777 [BR_Ping1D](INFO): 238
2019-04-09T16:15:39.777Z,1554826539.777 [BR_Ping1D](INFO): 3
2019-04-09T16:15:39.778Z,1554826539.778 [BR_Ping1D](INFO): 0
2019-04-09T16:15:39.778Z,1554826539.778 [BR_Ping1D](INFO): 0
2019-04-09T16:15:39.778Z,1554826539.778 [BR_Ping1D](INFO): 135
2019-04-09T16:15:39.778Z,1554826539.778 [BR_Ping1D](INFO): 1
2019-04-09T16:15:39.778Z,1554826539.778 [BR_Ping1D](INFO): 21
2019-04-09T16:15:39.870Z,1554826539.870 [CBIT](FAULT): Main Battery Failure. Count: 81
2019-04-09T16:15:40.138Z,1554826540.138 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T16:15:40.139Z,1554826540.139 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-09T16:15:40.139Z,1554826540.139 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T16:15:40.158Z,1554826540.158 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:40.183Z,1554826540.183 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T16:15:40.183Z,1554826540.183 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-09T16:15:40.662Z,1554826540.662 [Aanderaa_O2](INFO): Powering down
2019-04-09T16:15:40.679Z,1554826540.679 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:40.679Z,1554826540.679 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:40.680Z,1554826540.680 [BR_Ping1D](INFO): startup: 1
2019-04-09T16:15:40.680Z,1554826540.680 [BR_Ping1D](INFO): UART requesting status
2019-04-09T16:15:40.680Z,1554826540.680 [BR_Ping1D](INFO): 66
2019-04-09T16:15:40.680Z,1554826540.680 [BR_Ping1D](INFO): 82
2019-04-09T16:15:40.680Z,1554826540.680 [BR_Ping1D](INFO): 0
2019-04-09T16:15:40.680Z,1554826540.680 [BR_Ping1D](INFO): 0
2019-04-09T16:15:40.680Z,1554826540.680 [BR_Ping1D](INFO): 186
2019-04-09T16:15:40.680Z,1554826540.680 [BR_Ping1D](INFO): 4
2019-04-09T16:15:40.681Z,1554826540.681 [BR_Ping1D](INFO): 0
2019-04-09T16:15:40.681Z,1554826540.681 [BR_Ping1D](INFO): 0
2019-04-09T16:15:40.681Z,1554826540.681 [BR_Ping1D](INFO): 82
2019-04-09T16:15:40.681Z,1554826540.681 [BR_Ping1D](INFO): 1
2019-04-09T16:15:40.690Z,1554826540.690 [BR_Ping1D](INFO): 66
2019-04-09T16:15:40.690Z,1554826540.690 [BR_Ping1D](INFO): 82
2019-04-09T16:15:40.690Z,1554826540.690 [BR_Ping1D](INFO): 0
2019-04-09T16:15:40.690Z,1554826540.690 [BR_Ping1D](INFO): 0
2019-04-09T16:15:40.690Z,1554826540.690 [BR_Ping1D](INFO): 191
2019-04-09T16:15:40.690Z,1554826540.690 [BR_Ping1D](INFO): 4
2019-04-09T16:15:40.690Z,1554826540.690 [BR_Ping1D](INFO): 0
2019-04-09T16:15:40.691Z,1554826540.691 [BR_Ping1D](INFO): 0
2019-04-09T16:15:40.691Z,1554826540.691 [BR_Ping1D](INFO): 87
2019-04-09T16:15:40.691Z,1554826540.691 [BR_Ping1D](INFO): 1
2019-04-09T16:15:40.698Z,1554826540.698 [BR_Ping1D](INFO): 66
2019-04-09T16:15:40.698Z,1554826540.698 [BR_Ping1D](INFO): 82
2019-04-09T16:15:40.698Z,1554826540.698 [BR_Ping1D](INFO): 0
2019-04-09T16:15:40.698Z,1554826540.698 [BR_Ping1D](INFO): 0
2019-04-09T16:15:40.698Z,1554826540.698 [BR_Ping1D](INFO): 179
2019-04-09T16:15:40.698Z,1554826540.698 [BR_Ping1D](INFO): 4
2019-04-09T16:15:40.698Z,1554826540.698 [BR_Ping1D](INFO): 0
2019-04-09T16:15:40.698Z,1554826540.698 [BR_Ping1D](INFO): 0
2019-04-09T16:15:40.699Z,1554826540.699 [BR_Ping1D](INFO): 75
2019-04-09T16:15:40.699Z,1554826540.699 [BR_Ping1D](INFO): 1
2019-04-09T16:15:40.963Z,1554826540.963 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:40.963Z,1554826540.963 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:40.964Z,1554826540.964 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:40.964Z,1554826540.964 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:40.964Z,1554826540.964 [BR_Ping1D](INFO): elapsed 1.2103
2019-04-09T16:15:40.964Z,1554826540.964 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:40.964Z,1554826540.964 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:40.964Z,1554826540.964 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:40.965Z,1554826540.965 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:41.369Z,1554826541.369 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:41.370Z,1554826541.370 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:41.370Z,1554826541.370 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:41.370Z,1554826541.370 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:41.371Z,1554826541.371 [BR_Ping1D](INFO): elapsed 1.6170
2019-04-09T16:15:41.371Z,1554826541.371 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:41.371Z,1554826541.371 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:41.371Z,1554826541.371 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:41.371Z,1554826541.371 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:41.391Z,1554826541.391 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.006613
CHAN A1 (24V): -0.127220
CHAN A2 (12V): -0.004814
CHAN A3 (5V): -0.002890
CHAN B0 (3.3V): -0.001126
CHAN B1 (3.15aV): -0.000938
CHAN B2 (3.15bV): -0.000991
CHAN B3 (GND): -0.000447
OPEN: -0.001021
Full Scale Calc: 4.765 mA, -1.589 mA
2019-04-09T16:15:41.774Z,1554826541.774 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:41.774Z,1554826541.774 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:41.774Z,1554826541.774 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:41.774Z,1554826541.774 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:41.775Z,1554826541.775 [BR_Ping1D](INFO): elapsed 2.0210
2019-04-09T16:15:41.775Z,1554826541.775 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:41.775Z,1554826541.775 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:41.775Z,1554826541.775 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:41.775Z,1554826541.775 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:42.177Z,1554826542.177 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:42.177Z,1554826542.177 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:42.178Z,1554826542.178 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:42.178Z,1554826542.178 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:42.178Z,1554826542.178 [BR_Ping1D](INFO): elapsed 2.4245
2019-04-09T16:15:42.178Z,1554826542.178 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:42.178Z,1554826542.178 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:42.179Z,1554826542.179 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:42.179Z,1554826542.179 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:42.590Z,1554826542.590 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:42.590Z,1554826542.590 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:42.591Z,1554826542.591 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:42.591Z,1554826542.591 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:42.591Z,1554826542.591 [BR_Ping1D](INFO): elapsed 2.8373
2019-04-09T16:15:42.591Z,1554826542.591 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:42.591Z,1554826542.591 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:42.591Z,1554826542.591 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:42.592Z,1554826542.592 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:42.988Z,1554826542.988 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:42.988Z,1554826542.988 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:42.988Z,1554826542.988 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:42.988Z,1554826542.988 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:42.989Z,1554826542.989 [BR_Ping1D](INFO): elapsed 3.2351
2019-04-09T16:15:42.989Z,1554826542.989 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:42.989Z,1554826542.989 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:42.989Z,1554826542.989 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:42.989Z,1554826542.989 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:43.404Z,1554826543.404 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:43.405Z,1554826543.405 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:43.405Z,1554826543.405 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:43.405Z,1554826543.405 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:43.405Z,1554826543.405 [BR_Ping1D](INFO): elapsed 3.6516
2019-04-09T16:15:43.405Z,1554826543.405 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:43.406Z,1554826543.406 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:43.406Z,1554826543.406 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:43.406Z,1554826543.406 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:43.779Z,1554826543.779 [NAL9602](INFO): NAL9602 initialized
2019-04-09T16:15:43.794Z,1554826543.794 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:43.794Z,1554826543.794 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:43.794Z,1554826543.794 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:43.794Z,1554826543.794 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:43.795Z,1554826543.795 [BR_Ping1D](INFO): elapsed 4.0411
2019-04-09T16:15:43.795Z,1554826543.795 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:43.795Z,1554826543.795 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:43.795Z,1554826543.795 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:43.795Z,1554826543.795 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:43.808Z,1554826543.808 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T16:15:43.840Z,1554826543.840 [CBIT](FAULT): Main Battery Failure. Count: 91
2019-04-09T16:15:44.207Z,1554826544.207 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:44.207Z,1554826544.207 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:44.207Z,1554826544.207 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:44.207Z,1554826544.207 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:44.207Z,1554826544.207 [BR_Ping1D](INFO): elapsed 4.4538
2019-04-09T16:15:44.208Z,1554826544.208 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:44.208Z,1554826544.208 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:44.208Z,1554826544.208 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:44.208Z,1554826544.208 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:44.598Z,1554826544.598 [NAL9602](DEBUG): Fix Requested
2019-04-09T16:15:44.613Z,1554826544.613 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:44.613Z,1554826544.613 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:44.613Z,1554826544.613 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:44.613Z,1554826544.613 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:44.613Z,1554826544.613 [BR_Ping1D](INFO): elapsed 4.8599
2019-04-09T16:15:44.614Z,1554826544.614 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:44.614Z,1554826544.614 [BR_Ping1D](INFO): No UART data
2019-04-09T16:15:44.614Z,1554826544.614 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:15:44.614Z,1554826544.614 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:15:45.021Z,1554826545.021 [BR_Ping1D](INFO): Starting
2019-04-09T16:15:45.021Z,1554826545.021 [BR_Ping1D](INFO): readConfig
2019-04-09T16:15:45.021Z,1554826545.021 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:15:45.021Z,1554826545.021 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:15:45.021Z,1554826545.021 [BR_Ping1D](INFO): elapsed 5.2679
2019-04-09T16:15:45.022Z,1554826545.022 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:15:45.022Z,1554826545.022 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T16:15:45.022Z,1554826545.022 [BR_Ping1D] Data Fault, FailCount= 5
2019-04-09T16:15:45.022Z,1554826545.022 [BR_Ping1D](ERROR): Data Fault
2019-04-09T16:15:45.104Z,1554826545.104 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T16:15:45.104Z,1554826545.104 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-04-09T16:15:45.432Z,1554826545.432 [BR_Ping1D](INFO): Stop
2019-04-09T16:15:45.432Z,1554826545.432 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:15:45.432Z,1554826545.432 [BR_Ping1D](INFO): Powering down
2019-04-09T16:15:45.520Z,1554826545.520 [CBIT](INFO): Critical error at 20190409T161545
2019-04-09T16:15:45.814Z,1554826545.814 [BR_Ping1D](INFO): Stopping
2019-04-09T16:15:46.220Z,1554826546.220 [BR_Ping1D](INFO): Stopping
2019-04-09T16:15:46.626Z,1554826546.626 [BR_Ping1D](INFO): Stopped
2019-04-09T16:15:47.912Z,1554826547.912 [CBIT](FAULT): Main Battery Failure. Count: 101
2019-04-09T16:15:49.694Z,1554826549.694 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T16:15:49.694Z,1554826549.694 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-09T16:15:49.694Z,1554826549.694 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T16:15:49.935Z,1554826549.935 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T16:15:49.935Z,1554826549.935 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-09T16:15:50.098Z,1554826550.098 [CTD_Seabird](INFO): Powering down
2019-04-09T16:15:51.047Z,1554826551.047 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:15:51.047Z,1554826551.047 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2019-04-09T16:15:51.047Z,1554826551.047 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:15:51.219Z,1554826551.219 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:15:51.451Z,1554826551.451 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:15:51.917Z,1554826551.917 [CBIT](FAULT): Main Battery Failure. Count: 111
2019-04-09T16:15:52.332Z,1554826552.332 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:15:52.332Z,1554826552.332 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2019-04-09T16:15:54.703Z,1554826554.703 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:15:54.703Z,1554826554.703 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:15:55.961Z,1554826555.961 [CBIT](FAULT): Main Battery Failure. Count: 121
2019-04-09T16:15:59.985Z,1554826559.985 [CBIT](FAULT): Main Battery Failure. Count: 131
2019-04-09T16:16:04.041Z,1554826564.041 [CBIT](FAULT): Main Battery Failure. Count: 141
2019-04-09T16:16:08.085Z,1554826568.085 [CBIT](FAULT): Main Battery Failure. Count: 151
2019-04-09T16:16:10.492Z,1554826570.492 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T16:16:10.492Z,1554826570.492 [SBIT](FAULT): Control surface position failure.
2019-04-09T16:16:12.102Z,1554826572.102 [CBIT](FAULT): Main Battery Failure. Count: 161
2019-04-09T16:16:14.902Z,1554826574.902 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:16:14.903Z,1554826574.903 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2019-04-09T16:16:14.903Z,1554826574.903 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:16:15.015Z,1554826575.015 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:16:15.307Z,1554826575.307 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:16:16.149Z,1554826576.149 [CBIT](FAULT): Main Battery Failure. Count: 171
2019-04-09T16:16:16.151Z,1554826576.151 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:16:16.152Z,1554826576.152 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2019-04-09T16:16:18.538Z,1554826578.538 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:16:18.539Z,1554826578.539 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:16:20.197Z,1554826580.197 [CBIT](FAULT): Main Battery Failure. Count: 181
2019-04-09T16:16:24.240Z,1554826584.240 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T16:16:24.240Z,1554826584.240 [SBIT](FAULT): Control surface position failure.
2019-04-09T16:16:24.242Z,1554826584.242 [CBIT](FAULT): Main Battery Failure. Count: 191
2019-04-09T16:16:24.643Z,1554826584.643 [SBIT](CRITICAL): SBIT FAILED
2019-04-09T16:16:24.659Z,1554826584.659 [CBIT](INFO): Critical error at 20190409T161624
2019-04-09T16:16:24.703Z,1554826584.703 [CommandLine](IMPORTANT): got command configSet list
2019-04-09T16:16:24.703Z,1554826584.703 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-04-09T16:16:24.704Z,1554826584.704 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-04-09T16:16:24.704Z,1554826584.704 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2019-04-09T16:16:25.047Z,1554826585.047 [MissionManager](IMPORTANT): Started mission Startup
2019-04-09T16:16:25.047Z,1554826585.047 [Startup] Running Loop=1
2019-04-09T16:16:25.048Z,1554826585.048 [Startup](DEBUG): Aggregate::initialize Startup
2019-04-09T16:16:25.048Z,1554826585.048 [Startup:A.GoToSurface] Running Loop=1
2019-04-09T16:16:25.048Z,1554826585.048 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-09T16:16:25.048Z,1554826585.048 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-09T16:16:25.049Z,1554826585.049 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-09T16:16:25.049Z,1554826585.049 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-09T16:16:25.050Z,1554826585.050 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-09T16:16:25.050Z,1554826585.050 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-09T16:16:25.051Z,1554826585.051 [Startup:StartupSatComms] Running Loop=1
2019-04-09T16:16:25.052Z,1554826585.052 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-04-09T16:16:25.052Z,1554826585.052 [Startup:StartupSatComms:A] Running Loop=1
2019-04-09T16:16:25.439Z,1554826585.439 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-04-09T16:16:28.271Z,1554826588.271 [CBIT](FAULT): Main Battery Failure. Count: 201
2019-04-09T16:16:32.309Z,1554826592.309 [CBIT](FAULT): Main Battery Failure. Count: 211
2019-04-09T16:16:36.351Z,1554826596.351 [CBIT](FAULT): Main Battery Failure. Count: 221
2019-04-09T16:16:38.739Z,1554826598.739 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:16:38.739Z,1554826598.739 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2019-04-09T16:16:38.739Z,1554826598.739 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:16:38.791Z,1554826598.791 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:16:39.142Z,1554826599.142 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:16:39.999Z,1554826599.999 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:16:39.999Z,1554826599.999 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2019-04-09T16:16:40.396Z,1554826600.396 [CBIT](FAULT): Main Battery Failure. Count: 231
2019-04-09T16:16:42.374Z,1554826602.374 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:16:42.375Z,1554826602.375 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:16:44.445Z,1554826604.445 [CBIT](FAULT): Main Battery Failure. Count: 241
2019-04-09T16:16:48.473Z,1554826608.473 [CBIT](FAULT): Main Battery Failure. Count: 251
2019-04-09T16:16:52.510Z,1554826612.510 [CBIT](FAULT): Main Battery Failure. Count: 261
2019-04-09T16:16:56.550Z,1554826616.550 [CBIT](FAULT): Main Battery Failure. Count: 271
2019-04-09T16:17:00.600Z,1554826620.600 [CBIT](FAULT): Main Battery Failure. Count: 281
2019-04-09T16:17:02.575Z,1554826622.575 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:17:02.575Z,1554826622.575 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2019-04-09T16:17:02.575Z,1554826622.575 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:17:02.627Z,1554826622.627 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:17:02.979Z,1554826622.979 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:17:03.824Z,1554826623.824 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:17:03.824Z,1554826623.824 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2019-04-09T16:17:04.630Z,1554826624.630 [CBIT](FAULT): Main Battery Failure. Count: 291
2019-04-09T16:17:06.211Z,1554826626.211 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:17:06.211Z,1554826626.211 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:17:06.271Z,1554826626.271 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T16:17:06.272Z,1554826626.272 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-04-09T16:17:06.272Z,1554826626.272 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T16:17:06.273Z,1554826626.273 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T16:17:06.273Z,1554826626.273 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-04-09T16:17:06.273Z,1554826626.273 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T16:17:06.275Z,1554826626.275 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T16:17:06.275Z,1554826626.275 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-04-09T16:17:06.275Z,1554826626.275 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T16:17:06.286Z,1554826626.286 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T16:17:06.286Z,1554826626.286 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T16:17:06.286Z,1554826626.286 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T16:17:06.666Z,1554826626.666 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T16:17:06.666Z,1554826626.666 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-04-09T16:17:06.667Z,1554826626.667 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T16:17:06.667Z,1554826626.667 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-04-09T16:17:06.667Z,1554826626.667 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T16:17:06.667Z,1554826626.667 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-04-09T16:17:07.041Z,1554826627.041 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T16:17:08.665Z,1554826628.665 [CBIT](FAULT): Main Battery Failure. Count: 301
2019-04-09T16:17:12.706Z,1554826632.706 [CBIT](FAULT): Main Battery Failure. Count: 311
2019-04-09T16:17:16.769Z,1554826636.769 [CBIT](FAULT): Main Battery Failure. Count: 321
2019-04-09T16:17:20.789Z,1554826640.789 [CBIT](FAULT): Main Battery Failure. Count: 331
2019-04-09T16:17:24.829Z,1554826644.829 [CBIT](FAULT): Main Battery Failure. Count: 341
2019-04-09T16:17:25.227Z,1554826645.227 [Startup:StartupSatComms:A](INFO): Timed out from 2019-04-09T16:16:25.1Z
2019-04-09T16:17:25.228Z,1554826645.228 [Startup:StartupSatComms:A] Stopped
2019-04-09T16:17:25.228Z,1554826645.228 [Startup:StartupSatComms:B] Running Loop=1
2019-04-09T16:17:25.632Z,1554826645.632 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-09T16:17:26.474Z,1554826646.474 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:17:26.475Z,1554826646.475 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2019-04-09T16:17:26.475Z,1554826646.475 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:17:26.568Z,1554826646.568 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:17:26.879Z,1554826646.879 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:17:27.736Z,1554826647.736 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:17:27.736Z,1554826647.736 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2019-04-09T16:17:28.946Z,1554826648.946 [CBIT](FAULT): Main Battery Failure. Count: 351
2019-04-09T16:17:30.110Z,1554826650.110 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:17:30.111Z,1554826650.111 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:17:32.984Z,1554826652.984 [CBIT](FAULT): Main Battery Failure. Count: 361
2019-04-09T16:17:36.396Z,1554826656.396 [CommandLine](IMPORTANT): got command restart application
2019-04-09T16:17:37.033Z,1554826657.033 [CBIT](FAULT): Main Battery Failure. Count: 371
2019-04-09T16:17:37.402Z,1554826657.402 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:17:37.403Z,1554826657.403 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:17:37.502Z,1554826657.502 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-04-09T16:17:37.502Z,1554826657.502 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:17:37.503Z,1554826657.503 [CommandLine](INFO): Join timeout helper Thread ID is 4482
2019-04-09T16:17:37.503Z,1554826657.503 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-04-09T16:17:37.503Z,1554826657.503 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:17:37.504Z,1554826657.504 [NavChartDb](INFO): Join timeout helper Thread ID is 4483
2019-04-09T16:17:37.898Z,1554826657.898 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:17:37.898Z,1554826657.898 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:17:37.902Z,1554826657.902 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-04-09T16:17:37.902Z,1554826657.902 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:17:37.902Z,1554826657.902 [Radio_Surface](INFO): Join timeout helper Thread ID is 4484
2019-04-09T16:17:37.954Z,1554826657.954 [Radio_Surface](INFO): Powering down
2019-04-09T16:17:37.955Z,1554826657.955 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:17:37.955Z,1554826657.955 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:17:37.962Z,1554826657.962 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-04-09T16:17:37.962Z,1554826657.962 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:17:37.962Z,1554826657.962 [DataOverHttps](INFO): Join timeout helper Thread ID is 4485
2019-04-09T16:17:38.334Z,1554826658.334 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:17:38.334Z,1554826658.334 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:17:38.350Z,1554826658.350 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-04-09T16:17:38.350Z,1554826658.350 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:17:38.350Z,1554826658.350 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4486
2019-04-09T16:17:38.762Z,1554826658.762 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:17:38.763Z,1554826658.763 [WetLabsBB2FL](INFO): Powering down
2019-04-09T16:17:38.763Z,1554826658.763 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:17:38.771Z,1554826658.771 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-04-09T16:17:38.771Z,1554826658.771 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:17:38.771Z,1554826658.771 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4487
2019-04-09T16:17:38.906Z,1554826658.906 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:17:38.906Z,1554826658.906 [CTD_Seabird](INFO): Powering down
2019-04-09T16:17:38.918Z,1554826658.918 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:17:38.931Z,1554826658.931 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-04-09T16:17:38.931Z,1554826658.931 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:17:38.931Z,1554826658.931 [logger](INFO): Join timeout helper Thread ID is 4488
2019-04-09T16:17:39.026Z,1554826659.026 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:17:39.026Z,1554826659.026 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:17:39.031Z,1554826659.031 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-04-09T16:17:39.031Z,1554826659.031 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:17:39.031Z,1554826659.031 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-04-09T16:17:39.031Z,1554826659.031 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:17:39.031Z,1554826659.031 [controlThread](INFO): Join timeout helper Thread ID is 4489
2019-04-09T16:17:39.037Z,1554826659.037 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:17:39.037Z,1554826659.037 [controlThread](DEBUG): Uninitializing ControlThread
2019-04-09T16:17:39.037Z,1554826659.037 [Aanderaa_O2](INFO): Powering down
2019-04-09T16:17:39.040Z,1554826659.040 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:17:39.158Z,1554826659.158 [NAL9602](INFO): Powering down
2019-04-09T16:17:39.160Z,1554826659.160 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:17:39.160Z,1554826659.160 [BR_Ping1D](INFO): Powering down
2019-04-09T16:17:39.162Z,1554826659.162 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-04-09T16:17:39.163Z,1554826659.163 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-04-09T16:17:39.163Z,1554826659.163 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-04-09T16:17:39.164Z,1554826659.164 [MissionManager](INFO): Uninitializing Mission Default
2019-04-09T16:17:39.166Z,1554826659.166 [MissionManager](INFO): Uninitializing Mission Startup
2019-04-09T16:17:39.166Z,1554826659.166 [Startup] Stopped
2019-04-09T16:17:39.167Z,1554826659.167 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-04-09T16:17:39.167Z,1554826659.167 [Startup:A.GoToSurface] Stopped
2019-04-09T16:17:39.167Z,1554826659.167 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-09T16:17:39.167Z,1554826659.167 [Startup:StartupSatComms] Stopped
2019-04-09T16:17:39.167Z,1554826659.167 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-04-09T16:17:39.167Z,1554826659.167 [Startup:StartupSatComms:B] Stopped
2019-04-09T16:17:39.168Z,1554826659.168 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-04-09T16:17:39.169Z,1554826659.169 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-04-09T16:17:39.169Z,1554826659.169 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-04-09T16:17:39.169Z,1554826659.169 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-04-09T16:17:39.169Z,1554826659.169 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-04-09T16:17:39.169Z,1554826659.169 [BuoyancyServo](INFO): Powering down
2019-04-09T16:17:39.182Z,1554826659.182 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-09T16:17:39.182Z,1554826659.182 [ElevatorServo](INFO): Powering down
2019-04-09T16:17:39.183Z,1554826659.183 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:17:39.183Z,1554826659.183 [MassServo](INFO): Powering down
2019-04-09T16:17:39.184Z,1554826659.184 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-09T16:17:39.184Z,1554826659.184 [RudderServo](INFO): Powering down
2019-04-09T16:17:39.185Z,1554826659.185 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-04-09T16:17:39.185Z,1554826659.185 [ThrusterServo](INFO): Powering down
2019-04-09T16:17:39.186Z,1554826659.186 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-04-09T16:17:39.186Z,1554826659.186 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-04-09T16:17:39.186Z,1554826659.186 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-04-09T16:17:39.186Z,1554826659.186 [CBIT](DEBUG): Powering off loads.
2019-04-09T16:17:39.198Z,1554826659.198 [CBIT](DEBUG): Disabling WDT.
2019-04-09T16:17:39.210Z,1554826659.210 [CBIT](DEBUG): Opening all GF detection circuits.
2019-04-09T16:17:39.211Z,1554826659.211 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:17:39.361Z,1554826659.361 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:17:39.366Z,1554826659.366 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:17:39.433Z,1554826659.433 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:17:39.438Z,1554826659.438 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:17:39.488Z,1554826659.488 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:17:39.548Z,1554826659.548 [logger ThreadHandler](INFO): Thread cancelled.