2019-04-09T01:10:26.251Z,1554772226.251 [Supervisor](DEBUG): Initializing supervisor.
2019-04-09T01:10:26.254Z,1554772226.254 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-09T01:10:26.254Z,1554772226.254 [SyncHandler](INFO): Protected caller Thread ID is 5323
2019-04-09T01:10:26.255Z,1554772226.255 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-04-09T01:10:26.256Z,1554772226.256 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-04-09T01:10:26.256Z,1554772226.256 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5324
2019-04-09T01:10:26.259Z,1554772226.259 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-04-09T01:10:26.272Z,1554772226.272 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-04-09T01:10:26.273Z,1554772226.273 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-04-09T01:10:26.273Z,1554772226.273 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5325
2019-04-09T01:10:26.274Z,1554772226.274 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-04-09T01:10:26.275Z,1554772226.275 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-04-09T01:10:26.276Z,1554772226.276 [logger ThreadHandler](INFO): Protected caller Thread ID is 5326
2019-04-09T01:10:26.278Z,1554772226.278 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-04-09T01:10:26.278Z,1554772226.278 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-04-09T01:10:26.279Z,1554772226.279 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-04-09T01:10:26.373Z,1554772226.373 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-04-09T01:10:26.374Z,1554772226.374 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-04-09T01:10:26.575Z,1554772226.575 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-04-09T01:10:26.576Z,1554772226.576 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-04-09T01:10:26.712Z,1554772226.712 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-04-09T01:10:26.713Z,1554772226.713 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-04-09T01:10:27.172Z,1554772227.172 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-04-09T01:10:27.173Z,1554772227.173 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-04-09T01:10:27.616Z,1554772227.616 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-04-09T01:10:27.617Z,1554772227.617 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-04-09T01:10:28.086Z,1554772228.086 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-04-09T01:10:28.087Z,1554772228.087 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-04-09T01:10:28.393Z,1554772228.393 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-04-09T01:10:28.394Z,1554772228.394 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-04-09T01:10:28.727Z,1554772228.727 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-04-09T01:10:28.727Z,1554772228.727 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-04-09T01:10:29.122Z,1554772229.122 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-04-09T01:10:29.122Z,1554772229.122 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-04-09T01:10:29.272Z,1554772229.272 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-04-09T01:10:29.272Z,1554772229.272 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-04-09T01:10:29.401Z,1554772229.401 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-04-09T01:10:29.402Z,1554772229.402 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-04-09T01:10:29.648Z,1554772229.648 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-04-09T01:10:29.787Z,1554772229.787 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-04-09T01:10:29.787Z,1554772229.787 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-04-09T01:10:30.083Z,1554772230.083 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-04-09T01:10:30.084Z,1554772230.084 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-04-09T01:10:30.489Z,1554772230.489 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-04-09T01:10:30.502Z,1554772230.502 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-04-09T01:10:30.503Z,1554772230.503 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-04-09T01:10:30.586Z,1554772230.586 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-04-09T01:10:30.818Z,1554772230.818 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-04-09T01:10:30.819Z,1554772230.819 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-04-09T01:10:30.913Z,1554772230.913 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-04-09T01:10:31.087Z,1554772231.087 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-04-09T01:10:31.301Z,1554772231.301 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-04-09T01:10:31.385Z,1554772231.385 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-04-09T01:10:31.483Z,1554772231.483 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-04-09T01:10:31.576Z,1554772231.576 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-04-09T01:10:31.727Z,1554772231.727 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-04-09T01:10:31.831Z,1554772231.831 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-04-09T01:10:31.928Z,1554772231.928 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2019-04-09T01:10:31.929Z,1554772231.929 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-04-09T01:10:31.933Z,1554772231.933 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-04-09T01:10:32.032Z,1554772232.032 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-04-09T01:10:32.033Z,1554772232.033 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-04-09T01:10:32.161Z,1554772232.161 [BuoyancyServo] Loaded
2019-04-09T01:10:32.161Z,1554772232.161 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-04-09T01:10:32.176Z,1554772232.176 [ElevatorServo] Loaded
2019-04-09T01:10:32.177Z,1554772232.177 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-04-09T01:10:32.192Z,1554772232.192 [MassServo] Loaded
2019-04-09T01:10:32.192Z,1554772232.192 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-04-09T01:10:32.207Z,1554772232.207 [RudderServo] Loaded
2019-04-09T01:10:32.208Z,1554772232.208 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-04-09T01:10:32.222Z,1554772232.222 [ThrusterServo] Loaded
2019-04-09T01:10:32.222Z,1554772232.222 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-04-09T01:10:32.223Z,1554772232.223 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-04-09T01:10:32.223Z,1554772232.223 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-04-09T01:10:32.360Z,1554772232.360 [SBIT](DEBUG): Construct Startup Built In Test.
2019-04-09T01:10:32.388Z,1554772232.388 [SBIT] Loaded
2019-04-09T01:10:32.388Z,1554772232.388 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-04-09T01:10:32.389Z,1554772232.389 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-04-09T01:10:32.417Z,1554772232.417 [IBIT] Loaded
2019-04-09T01:10:32.418Z,1554772232.418 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-04-09T01:10:32.421Z,1554772232.421 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-04-09T01:10:32.811Z,1554772232.811 [CBIT] Loaded
2019-04-09T01:10:32.811Z,1554772232.811 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-04-09T01:10:32.811Z,1554772232.811 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-04-09T01:10:32.812Z,1554772232.812 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-04-09T01:10:32.997Z,1554772232.997 [Aanderaa_O2] Loaded
2019-04-09T01:10:32.997Z,1554772232.997 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-04-09T01:10:33.007Z,1554772233.007 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-04-09T01:10:33.012Z,1554772233.012 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-04-09T01:10:33.014Z,1554772233.014 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-04-09T01:10:33.019Z,1554772233.019 [CTD_Seabird](INFO): created writer for : depth
2019-04-09T01:10:33.019Z,1554772233.019 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-04-09T01:10:33.025Z,1554772233.025 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-04-09T01:10:33.025Z,1554772233.025 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-04-09T01:10:33.031Z,1554772233.031 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-04-09T01:10:33.031Z,1554772233.031 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-04-09T01:10:33.036Z,1554772233.036 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-04-09T01:10:33.037Z,1554772233.037 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-04-09T01:10:33.043Z,1554772233.043 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-04-09T01:10:33.043Z,1554772233.043 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-04-09T01:10:33.048Z,1554772233.048 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-04-09T01:10:33.075Z,1554772233.075 [CTD_Seabird] Loaded
2019-04-09T01:10:33.075Z,1554772233.075 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-04-09T01:10:33.077Z,1554772233.077 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406654E0
2019-04-09T01:10:33.077Z,1554772233.077 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5405
2019-04-09T01:10:33.103Z,1554772233.103 [ESPComponent] Loaded
2019-04-09T01:10:33.103Z,1554772233.103 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-04-09T01:10:33.117Z,1554772233.117 [PAR_Licor] Loaded
2019-04-09T01:10:33.118Z,1554772233.118 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-04-09T01:10:33.166Z,1554772233.166 [WetLabsBB2FL] Loaded
2019-04-09T01:10:33.166Z,1554772233.166 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-04-09T01:10:33.167Z,1554772233.167 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406954E0
2019-04-09T01:10:33.168Z,1554772233.168 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5406
2019-04-09T01:10:33.168Z,1554772233.168 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-04-09T01:10:33.169Z,1554772233.169 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-04-09T01:10:33.209Z,1554772233.209 [DepthRateCalculator] Loaded
2019-04-09T01:10:33.210Z,1554772233.210 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-04-09T01:10:33.215Z,1554772233.215 [PitchRateCalculator] Loaded
2019-04-09T01:10:33.216Z,1554772233.216 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-04-09T01:10:33.232Z,1554772233.232 [SpeedCalculator] Loaded
2019-04-09T01:10:33.232Z,1554772233.232 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-04-09T01:10:33.254Z,1554772233.254 [TempGradientCalculator] Loaded
2019-04-09T01:10:33.254Z,1554772233.254 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-04-09T01:10:33.260Z,1554772233.260 [YawRateCalculator] Loaded
2019-04-09T01:10:33.260Z,1554772233.260 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-04-09T01:10:33.293Z,1554772233.293 [ElevatorOffsetCalculator] Loaded
2019-04-09T01:10:33.293Z,1554772233.293 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-04-09T01:10:33.294Z,1554772233.294 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-04-09T01:10:33.295Z,1554772233.295 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-04-09T01:10:33.321Z,1554772233.321 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-04-09T01:10:33.322Z,1554772233.322 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-04-09T01:10:33.425Z,1554772233.425 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-04-09T01:10:33.426Z,1554772233.426 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-04-09T01:10:33.486Z,1554772233.486 [DeadReckonUsingSpeedCalculator] Loaded
2019-04-09T01:10:33.486Z,1554772233.486 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-04-09T01:10:33.547Z,1554772233.547 [DeadReckonWithRespectToSeafloor] Loaded
2019-04-09T01:10:33.548Z,1554772233.548 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-04-09T01:10:33.569Z,1554772233.569 [NavChart] Loaded
2019-04-09T01:10:33.569Z,1554772233.569 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-04-09T01:10:33.573Z,1554772233.573 [UniversalFixResidualReporter] Loaded
2019-04-09T01:10:33.573Z,1554772233.573 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-04-09T01:10:33.574Z,1554772233.574 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-04-09T01:10:33.575Z,1554772233.575 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-04-09T01:10:33.888Z,1554772233.888 [AcousticModem_Benthos_ATM900] Loaded
2019-04-09T01:10:33.888Z,1554772233.888 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-04-09T01:10:33.973Z,1554772233.973 [DataOverHttps] Loaded
2019-04-09T01:10:33.973Z,1554772233.973 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-04-09T01:10:33.975Z,1554772233.975 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409704E0
2019-04-09T01:10:33.975Z,1554772233.975 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5407
2019-04-09T01:10:33.988Z,1554772233.988 [Depth_Keller] Loaded
2019-04-09T01:10:33.989Z,1554772233.989 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-04-09T01:10:34.081Z,1554772234.081 [NAL9602] Loaded
2019-04-09T01:10:34.081Z,1554772234.081 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-04-09T01:10:34.087Z,1554772234.087 [Onboard] Loaded
2019-04-09T01:10:34.087Z,1554772234.087 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-04-09T01:10:34.090Z,1554772234.090 [Radio_Surface] Loaded
2019-04-09T01:10:34.091Z,1554772234.091 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-04-09T01:10:34.092Z,1554772234.092 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A04E0
2019-04-09T01:10:34.092Z,1554772234.092 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5408
2019-04-09T01:10:35.892Z,1554772235.892 [BPC1] Loaded
2019-04-09T01:10:35.892Z,1554772235.892 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-04-09T01:10:35.894Z,1554772235.894 [BR_Ping1D](INFO): constructor
2019-04-09T01:10:35.923Z,1554772235.923 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minrange
2019-04-09T01:10:35.925Z,1554772235.925 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.maxrange
2019-04-09T01:10:35.927Z,1554772235.927 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.soundspeed
2019-04-09T01:10:35.929Z,1554772235.929 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.automode
2019-04-09T01:10:35.932Z,1554772235.932 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.gainindex
2019-04-09T01:10:35.934Z,1554772235.934 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minconfidence
2019-04-09T01:10:35.936Z,1554772235.936 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.pingenable
2019-04-09T01:10:35.938Z,1554772235.938 [BR_Ping1D] Loaded
2019-04-09T01:10:35.938Z,1554772235.938 [ComponentRegistry](DEBUG): SyncComponent "BR_Ping1D" handled in the control thread.
2019-04-09T01:10:35.939Z,1554772235.939 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-04-09T01:10:35.939Z,1554772235.939 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-04-09T01:10:36.004Z,1554772236.004 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-04-09T01:10:36.004Z,1554772236.004 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-04-09T01:10:36.071Z,1554772236.071 [VerticalControl](DEBUG): Construct VerticalControl.
2019-04-09T01:10:36.156Z,1554772236.156 [VerticalControl] Loaded
2019-04-09T01:10:36.157Z,1554772236.157 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-04-09T01:10:36.157Z,1554772236.157 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-04-09T01:10:36.215Z,1554772236.215 [HorizontalControl] Loaded
2019-04-09T01:10:36.216Z,1554772236.216 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-04-09T01:10:36.216Z,1554772236.216 [SpeedControl](DEBUG): Construct SpeedControl.
2019-04-09T01:10:36.218Z,1554772236.218 [SpeedControl] Loaded
2019-04-09T01:10:36.219Z,1554772236.219 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-04-09T01:10:36.219Z,1554772236.219 [LoopControl](DEBUG): Construct LoopControl.
2019-04-09T01:10:36.220Z,1554772236.220 [LoopControl] Loaded
2019-04-09T01:10:36.220Z,1554772236.220 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-04-09T01:10:36.221Z,1554772236.221 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-04-09T01:10:36.221Z,1554772236.221 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-04-09T01:10:36.235Z,1554772236.235 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-04-09T01:10:36.235Z,1554772236.235 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-04-09T01:10:36.514Z,1554772236.514 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-04-09T01:10:36.518Z,1554772236.518 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-04-09T01:10:36.519Z,1554772236.519 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-04-09T01:10:36.526Z,1554772236.526 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-04-09T01:10:36.527Z,1554772236.527 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACD4E0
2019-04-09T01:10:36.527Z,1554772236.527 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5409
2019-04-09T01:10:36.532Z,1554772236.532 [Supervisor](INFO): Main Thread ID is 4347
2019-04-09T01:10:36.532Z,1554772236.532 [Supervisor](DEBUG): Running supervisor.
2019-04-09T01:10:36.533Z,1554772236.533 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5410
2019-04-09T01:10:36.535Z,1554772236.535 [controlThread ThreadHandler](INFO): Handler Thread ID is 5411
2019-04-09T01:10:36.536Z,1554772236.536 [controlThread](DEBUG): Initializing ControlThread
2019-04-09T01:10:36.537Z,1554772236.537 [SBIT](INFO): Initialize SBIT Component.
2019-04-09T01:10:36.538Z,1554772236.538 [SBIT](IMPORTANT): git: 2019-03-18-31-ge98d5dc
2019-04-09T01:10:36.538Z,1554772236.538 [SBIT](INFO): git hash: e98d5dc50cbc39e402f19b9946ff4dc0776c8725
2019-04-09T01:10:36.539Z,1554772236.539 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-04-09T01:10:36.540Z,1554772236.540 [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-09T01:10:36.541Z,1554772236.541 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-04-09T01:10:36.542Z,1554772236.542 [IBIT](INFO): Initialize IBIT Component.
2019-04-09T01:10:36.542Z,1554772236.542 [CBIT](DEBUG): Initialize CBIT Component.
2019-04-09T01:10:36.543Z,1554772236.543 [logger ThreadHandler](INFO): Handler Thread ID is 5412
2019-04-09T01:10:36.554Z,1554772236.554 [CBIT](DEBUG): Initialized mux pins.
2019-04-09T01:10:36.554Z,1554772236.554 [CBIT](DEBUG): Initializing the watchdog timer.
2019-04-09T01:10:36.562Z,1554772236.562 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5413
2019-04-09T01:10:36.563Z,1554772236.563 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T01:10:36.567Z,1554772236.567 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T01:10:36.568Z,1554772236.568 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5415
2019-04-09T01:10:36.569Z,1554772236.569 [WetLabsBB2FL](INFO): Powering down
2019-04-09T01:10:36.578Z,1554772236.578 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-04-09T01:10:36.578Z,1554772236.578 [CBIT](DEBUG): Initializing heartbeat.
2019-04-09T01:10:36.598Z,1554772236.598 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5416
2019-04-09T01:10:36.599Z,1554772236.599 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-04-09T01:10:36.608Z,1554772236.608 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5417
2019-04-09T01:10:36.626Z,1554772236.626 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5418
2019-04-09T01:10:36.629Z,1554772236.629 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-09T01:10:36.629Z,1554772236.629 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-04-09T01:10:36.630Z,1554772236.630 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-09T01:10:36.630Z,1554772236.630 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-04-09T01:10:36.630Z,1554772236.630 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-09T01:10:36.630Z,1554772236.630 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-04-09T01:10:36.630Z,1554772236.630 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-09T01:10:36.631Z,1554772236.631 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-04-09T01:10:36.631Z,1554772236.631 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-09T01:10:36.631Z,1554772236.631 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-04-09T01:10:36.631Z,1554772236.631 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-09T01:10:36.631Z,1554772236.631 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-04-09T01:10:36.632Z,1554772236.632 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-09T01:10:36.632Z,1554772236.632 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-04-09T01:10:36.632Z,1554772236.632 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-09T01:10:36.632Z,1554772236.632 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-04-09T01:10:36.650Z,1554772236.650 [CBIT](DEBUG): Deactivating GF circuits.
2019-04-09T01:10:36.650Z,1554772236.650 [CBIT](DEBUG): Deactivating emergency mode.
2019-04-09T01:10:36.686Z,1554772236.686 [CBIT](DEBUG): Backplane powered.
2019-04-09T01:10:36.690Z,1554772236.690 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-04-09T01:10:36.691Z,1554772236.691 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-04-09T01:10:36.691Z,1554772236.691 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-04-09T01:10:36.691Z,1554772236.691 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-04-09T01:10:36.692Z,1554772236.692 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-04-09T01:10:36.692Z,1554772236.692 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-04-09T01:10:36.693Z,1554772236.693 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T01:10:36.694Z,1554772236.694 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-04-09T01:10:36.695Z,1554772236.695 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-04-09T01:10:36.699Z,1554772236.699 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-04-09T01:10:36.701Z,1554772236.701 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-04-09T01:10:36.702Z,1554772236.702 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-04-09T01:10:36.703Z,1554772236.703 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-04-09T01:10:36.704Z,1554772236.704 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-04-09T01:10:36.734Z,1554772236.734 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T01:10:36.770Z,1554772236.770 [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-09T01:10:36.771Z,1554772236.771 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-04-09T01:10:36.826Z,1554772236.826 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-04-09T01:10:36.828Z,1554772236.828 [Default:A.Wait](DEBUG): Construct Wait.
2019-04-09T01:10:36.829Z,1554772236.829 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T01:10:36.889Z,1554772236.889 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-04-09T01:10:36.907Z,1554772236.907 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-04-09T01:10:36.913Z,1554772236.913 [Default:E.Execute](DEBUG): Construct Execute.
2019-04-09T01:10:36.936Z,1554772236.936 [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-09T01:10:36.941Z,1554772236.941 [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-09T01:10:36.979Z,1554772236.979 [ESPComponent](INFO): powering down ESP
2019-04-09T01:10:37.010Z,1554772237.010 [Radio_Surface](INFO): Powering up
2019-04-09T01:10:37.394Z,1554772237.394 [PAR_Licor](INFO): A/D timeout, 8 tries over 133 ms

2019-04-09T01:10:37.394Z,1554772237.394 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T01:10:37.395Z,1554772237.395 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:10:37.395Z,1554772237.395 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:10:37.395Z,1554772237.395 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:10:37.558Z,1554772237.558 [BR_Ping1D](INFO): Start
2019-04-09T01:10:37.594Z,1554772237.594 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:10:37.602Z,1554772237.602 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-04-09T01:10:37.604Z,1554772237.604 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:10:37.610Z,1554772237.610 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-09T01:10:37.611Z,1554772237.611 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:10:37.618Z,1554772237.618 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:10:37.619Z,1554772237.619 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:10:37.626Z,1554772237.626 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-09T01:10:37.627Z,1554772237.627 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:10:37.634Z,1554772237.634 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-04-09T01:10:37.637Z,1554772237.637 [CommandLine](FAULT): Scheduling is paused
2019-04-09T01:10:37.637Z,1554772237.637 [CBIT](INFO): Critical error at 20190409T011035
2019-04-09T01:10:37.637Z,1554772237.637 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-04-09T01:10:37.639Z,1554772237.639 [CBIT](FAULT): Main Battery Failure. Count: 1
2019-04-09T01:10:37.642Z,1554772237.642 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:10:37.642Z,1554772237.642 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:10:37.643Z,1554772237.643 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T01:10:37.740Z,1554772237.740 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:38.194Z,1554772238.194 [PAR_Licor](INFO): A/D timeout, 9 tries over 142 ms

2019-04-09T01:10:38.194Z,1554772238.194 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T01:10:38.194Z,1554772238.194 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:10:38.230Z,1554772238.230 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:38.230Z,1554772238.230 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:38.231Z,1554772238.231 [BR_Ping1D](INFO): XXXX automode1: 1
2019-04-09T01:10:38.231Z,1554772238.231 [BR_Ping1D](INFO): XXXX automode2: 1
2019-04-09T01:10:38.231Z,1554772238.231 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T01:10:38.242Z,1554772238.242 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T01:10:38.242Z,1554772238.242 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T01:10:38.243Z,1554772238.243 [BR_Ping1D](INFO): sending request for Soundspeed: 1075049184
2019-04-09T01:10:38.243Z,1554772238.243 [BR_Ping1D](INFO): sending request for Ping Enable: 224
2019-04-09T01:10:38.602Z,1554772238.602 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-04-09T01:10:38.602Z,1554772238.602 [RudderServo](FAULT): Rudder failed to initialize
2019-04-09T01:10:38.602Z,1554772238.602 [RudderServo] Communications Fault, FailCount= 1
2019-04-09T01:10:38.602Z,1554772238.602 [RudderServo](ERROR): Communications Fault
2019-04-09T01:10:38.713Z,1554772238.713 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-04-09T01:10:38.714Z,1554772238.714 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:10:38.714Z,1554772238.714 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:10:38.714Z,1554772238.714 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T01:10:38.749Z,1554772238.749 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:38.750Z,1554772238.750 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:38.750Z,1554772238.750 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:38.750Z,1554772238.750 [BR_Ping1D](INFO): XXXX automode2: 1
2019-04-09T01:10:38.750Z,1554772238.750 [BR_Ping1D](INFO): UART requesting status
2019-04-09T01:10:38.911Z,1554772238.911 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-09T01:10:38.911Z,1554772238.911 [RudderServo](INFO): Powering down
2019-04-09T01:10:39.145Z,1554772239.145 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:39.146Z,1554772239.146 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:39.146Z,1554772239.146 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:39.146Z,1554772239.146 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:39.146Z,1554772239.146 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:39.146Z,1554772239.146 [BR_Ping1D](INFO): elapsed0.9152
2019-04-09T01:10:39.147Z,1554772239.147 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:39.147Z,1554772239.147 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:39.666Z,1554772239.666 [PAR_Licor](INFO): A/D timeout, 9 tries over 138 ms

2019-04-09T01:10:39.666Z,1554772239.666 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T01:10:39.666Z,1554772239.666 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:10:39.687Z,1554772239.687 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:39.687Z,1554772239.687 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:39.687Z,1554772239.687 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:39.687Z,1554772239.687 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:39.687Z,1554772239.687 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:39.687Z,1554772239.687 [BR_Ping1D](INFO): elapsed1.4563
2019-04-09T01:10:39.688Z,1554772239.688 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:39.688Z,1554772239.688 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:39.715Z,1554772239.715 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:10:39.835Z,1554772239.835 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-09T01:10:39.840Z,1554772239.840 [CBIT](INFO): Clearing failed state for component RudderServo
2019-04-09T01:10:39.840Z,1554772239.840 [RudderServo] No Fault, FailCount= 1
2019-04-09T01:10:39.840Z,1554772239.840 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:10:39.840Z,1554772239.840 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:10:39.840Z,1554772239.840 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T01:10:39.954Z,1554772239.954 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:39.954Z,1554772239.954 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:39.955Z,1554772239.955 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:39.955Z,1554772239.955 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:39.955Z,1554772239.955 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:39.955Z,1554772239.955 [BR_Ping1D](INFO): elapsed1.7241
2019-04-09T01:10:39.955Z,1554772239.955 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:39.956Z,1554772239.956 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:40.318Z,1554772240.318 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:10:40.318Z,1554772240.318 [MassServo] Communications Fault, FailCount= 1
2019-04-09T01:10:40.318Z,1554772240.318 [MassServo](ERROR): Communications Fault
2019-04-09T01:10:40.346Z,1554772240.346 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:10:40.401Z,1554772240.401 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:40.401Z,1554772240.401 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:40.401Z,1554772240.401 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:40.401Z,1554772240.401 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:40.402Z,1554772240.402 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:40.402Z,1554772240.402 [BR_Ping1D](INFO): elapsed2.1706
2019-04-09T01:10:40.402Z,1554772240.402 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:40.402Z,1554772240.402 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:40.526Z,1554772240.526 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:10:40.526Z,1554772240.526 [MassServo](INFO): Powering down
2019-04-09T01:10:40.777Z,1554772240.777 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:40.777Z,1554772240.777 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:40.777Z,1554772240.777 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:40.778Z,1554772240.778 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:40.778Z,1554772240.778 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:40.778Z,1554772240.778 [BR_Ping1D](INFO): elapsed2.5468
2019-04-09T01:10:40.778Z,1554772240.778 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:40.778Z,1554772240.778 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:41.302Z,1554772241.302 [PAR_Licor](INFO): A/D timeout, 9 tries over 142 ms

2019-04-09T01:10:41.302Z,1554772241.302 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T01:10:41.302Z,1554772241.302 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:10:41.343Z,1554772241.343 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:41.343Z,1554772241.343 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:41.343Z,1554772241.343 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:41.343Z,1554772241.343 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:41.344Z,1554772241.344 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:41.344Z,1554772241.344 [BR_Ping1D](INFO): elapsed3.1126
2019-04-09T01:10:41.344Z,1554772241.344 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:41.344Z,1554772241.344 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:41.428Z,1554772241.428 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:10:41.547Z,1554772241.547 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:10:41.690Z,1554772241.690 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T01:10:41.690Z,1554772241.690 [MassServo] No Fault, FailCount= 1
2019-04-09T01:10:41.690Z,1554772241.690 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:10:41.691Z,1554772241.691 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:10:41.691Z,1554772241.691 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T01:10:41.723Z,1554772241.723 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:41.723Z,1554772241.723 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:41.723Z,1554772241.723 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:41.723Z,1554772241.723 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:41.723Z,1554772241.723 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:41.724Z,1554772241.724 [BR_Ping1D](INFO): elapsed3.4924
2019-04-09T01:10:41.724Z,1554772241.724 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:41.724Z,1554772241.724 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:41.760Z,1554772241.760 [CBIT](FAULT): Main Battery Failure. Count: 11
2019-04-09T01:10:42.226Z,1554772242.226 [PAR_Licor](INFO): A/D timeout, 8 tries over 126 ms

2019-04-09T01:10:42.226Z,1554772242.226 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T01:10:42.226Z,1554772242.226 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:10:42.266Z,1554772242.266 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:42.267Z,1554772242.267 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:42.267Z,1554772242.267 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:42.267Z,1554772242.267 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:42.267Z,1554772242.267 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:42.267Z,1554772242.267 [BR_Ping1D](INFO): elapsed4.0361
2019-04-09T01:10:42.267Z,1554772242.267 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:42.268Z,1554772242.268 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:42.338Z,1554772242.338 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:10:42.338Z,1554772242.338 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:10:42.338Z,1554772242.338 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T01:10:42.525Z,1554772242.525 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:42.526Z,1554772242.526 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:42.526Z,1554772242.526 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:42.526Z,1554772242.526 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:42.526Z,1554772242.526 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:42.526Z,1554772242.526 [BR_Ping1D](INFO): elapsed4.2952
2019-04-09T01:10:42.527Z,1554772242.527 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:42.527Z,1554772242.527 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:43.042Z,1554772243.042 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-09T01:10:43.042Z,1554772243.042 [PAR_Licor] Data Fault, FailCount= 3
2019-04-09T01:10:43.043Z,1554772243.043 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:10:43.094Z,1554772243.094 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:43.094Z,1554772243.094 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:43.095Z,1554772243.095 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:43.095Z,1554772243.095 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:43.095Z,1554772243.095 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:43.095Z,1554772243.095 [BR_Ping1D](INFO): elapsed4.8640
2019-04-09T01:10:43.095Z,1554772243.095 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:43.096Z,1554772243.096 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:43.182Z,1554772243.182 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:10:43.183Z,1554772243.183 [CBIT](CRITICAL): Data Fault in component: PAR_Licor
2019-04-09T01:10:43.329Z,1554772243.329 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:43.329Z,1554772243.329 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:43.330Z,1554772243.330 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:43.330Z,1554772243.330 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:43.330Z,1554772243.330 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:43.330Z,1554772243.330 [BR_Ping1D](INFO): elapsed5.0990
2019-04-09T01:10:43.330Z,1554772243.330 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:43.331Z,1554772243.331 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-09T01:10:43.331Z,1554772243.331 [BR_Ping1D](ERROR): Data Fault
2019-04-09T01:10:43.664Z,1554772243.664 [CBIT](INFO): Critical error at 20190409T011043
2019-04-09T01:10:43.667Z,1554772243.667 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T01:10:43.731Z,1554772243.731 [BR_Ping1D](INFO): Stop
2019-04-09T01:10:43.731Z,1554772243.731 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:10:44.150Z,1554772244.150 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:10:44.150Z,1554772244.150 [MassServo] Communications Fault, FailCount= 2
2019-04-09T01:10:44.150Z,1554772244.150 [MassServo](ERROR): Communications Fault
2019-04-09T01:10:44.154Z,1554772244.154 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:10:44.189Z,1554772244.189 [BR_Ping1D](INFO): Stopping
2019-04-09T01:10:44.215Z,1554772244.215 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:10:44.215Z,1554772244.215 [MassServo](INFO): Powering down
2019-04-09T01:10:44.582Z,1554772244.582 [BR_Ping1D](INFO): Stopping
2019-04-09T01:10:44.987Z,1554772244.987 [BR_Ping1D](INFO): Stopped
2019-04-09T01:10:45.015Z,1554772245.015 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:10:45.135Z,1554772245.135 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:10:45.140Z,1554772245.140 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T01:10:45.140Z,1554772245.140 [MassServo] No Fault, FailCount= 2
2019-04-09T01:10:45.141Z,1554772245.141 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T01:10:45.141Z,1554772245.141 [BR_Ping1D] No Fault, FailCount= 1
2019-04-09T01:10:45.390Z,1554772245.390 [BR_Ping1D](INFO): Start
2019-04-09T01:10:45.793Z,1554772245.793 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:45.805Z,1554772245.805 [CBIT](FAULT): Main Battery Failure. Count: 21
2019-04-09T01:10:46.198Z,1554772246.198 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:46.198Z,1554772246.198 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:46.198Z,1554772246.198 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:46.198Z,1554772246.198 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:46.198Z,1554772246.198 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T01:10:46.210Z,1554772246.210 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T01:10:46.210Z,1554772246.210 [BR_Ping1D](INFO): sending request for Auto Mode: 228
2019-04-09T01:10:46.210Z,1554772246.210 [BR_Ping1D](INFO): sending request for Soundspeed: 1077250380
2019-04-09T01:10:46.211Z,1554772246.211 [BR_Ping1D](INFO): sending request for Ping Enable: 224
2019-04-09T01:10:46.601Z,1554772246.601 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:46.635Z,1554772246.635 [CBIT](CRITICAL): Environmental Failure. Press:14.870923 PSI. Humidity:45%. Temp:26 C. ABORTING MISSION
2019-04-09T01:10:46.986Z,1554772246.986 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T01:10:46.986Z,1554772246.986 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T01:10:46.987Z,1554772246.987 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T01:10:47.007Z,1554772247.007 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:47.007Z,1554772247.007 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:47.007Z,1554772247.007 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:47.007Z,1554772247.007 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:47.008Z,1554772247.008 [BR_Ping1D](INFO): UART requesting status
2019-04-09T01:10:47.044Z,1554772247.044 [CBIT](INFO): Critical error at 20190409T011046
2019-04-09T01:10:47.047Z,1554772247.047 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T01:10:47.510Z,1554772247.510 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:10:47.584Z,1554772247.584 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:47.584Z,1554772247.584 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:47.584Z,1554772247.584 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:47.584Z,1554772247.584 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:47.585Z,1554772247.585 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:47.585Z,1554772247.585 [BR_Ping1D](INFO): elapsed1.3864
2019-04-09T01:10:47.585Z,1554772247.585 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:47.585Z,1554772247.585 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:47.938Z,1554772247.938 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:10:47.938Z,1554772247.938 [MassServo] Communications Fault, FailCount= 3
2019-04-09T01:10:47.938Z,1554772247.938 [MassServo](ERROR): Communications Fault
2019-04-09T01:10:47.943Z,1554772247.943 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:10:47.971Z,1554772247.971 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:47.971Z,1554772247.971 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:47.971Z,1554772247.971 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:47.971Z,1554772247.971 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:47.971Z,1554772247.971 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:47.971Z,1554772247.971 [BR_Ping1D](INFO): elapsed1.7730
2019-04-09T01:10:47.972Z,1554772247.972 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:47.972Z,1554772247.972 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:47.999Z,1554772247.999 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:10:47.999Z,1554772247.999 [MassServo](INFO): Powering down
2019-04-09T01:10:48.369Z,1554772248.369 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:48.369Z,1554772248.369 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:48.370Z,1554772248.370 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:48.370Z,1554772248.370 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:48.370Z,1554772248.370 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:48.370Z,1554772248.370 [BR_Ping1D](INFO): elapsed2.1717
2019-04-09T01:10:48.370Z,1554772248.370 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:48.371Z,1554772248.371 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:48.387Z,1554772248.387 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T01:10:48.387Z,1554772248.387 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-09T01:10:48.774Z,1554772248.774 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:48.774Z,1554772248.774 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:48.775Z,1554772248.775 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:48.775Z,1554772248.775 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:48.775Z,1554772248.775 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:48.775Z,1554772248.775 [BR_Ping1D](INFO): elapsed2.5766
2019-04-09T01:10:48.775Z,1554772248.775 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:48.775Z,1554772248.775 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:48.787Z,1554772248.787 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:10:48.907Z,1554772248.907 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:10:48.911Z,1554772248.911 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T01:10:48.911Z,1554772248.911 [MassServo] No Fault, FailCount= 3
2019-04-09T01:10:49.189Z,1554772249.189 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:49.189Z,1554772249.189 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:49.190Z,1554772249.190 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:49.190Z,1554772249.190 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:49.190Z,1554772249.190 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:49.190Z,1554772249.190 [BR_Ping1D](INFO): elapsed2.9917
2019-04-09T01:10:49.190Z,1554772249.190 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:49.191Z,1554772249.191 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:49.581Z,1554772249.581 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:49.582Z,1554772249.582 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:49.582Z,1554772249.582 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:49.582Z,1554772249.582 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:49.582Z,1554772249.582 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:49.582Z,1554772249.582 [BR_Ping1D](INFO): elapsed3.3840
2019-04-09T01:10:49.583Z,1554772249.583 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:49.583Z,1554772249.583 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:49.614Z,1554772249.614 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T01:10:49.614Z,1554772249.614 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-09T01:10:49.614Z,1554772249.614 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T01:10:49.986Z,1554772249.986 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:49.986Z,1554772249.986 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:49.986Z,1554772249.986 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:49.986Z,1554772249.986 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:49.986Z,1554772249.986 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:49.987Z,1554772249.987 [BR_Ping1D](INFO): elapsed3.7881
2019-04-09T01:10:49.987Z,1554772249.987 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:49.987Z,1554772249.987 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:50.019Z,1554772250.019 [CTD_Seabird](INFO): Powering down
2019-04-09T01:10:50.040Z,1554772250.040 [CBIT](FAULT): Main Battery Failure. Count: 31
2019-04-09T01:10:50.058Z,1554772250.058 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T01:10:50.390Z,1554772250.390 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:50.390Z,1554772250.390 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:50.390Z,1554772250.390 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:50.390Z,1554772250.390 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:50.390Z,1554772250.390 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:50.391Z,1554772250.391 [BR_Ping1D](INFO): elapsed4.1921
2019-04-09T01:10:50.391Z,1554772250.391 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:50.391Z,1554772250.391 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:50.793Z,1554772250.793 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:50.793Z,1554772250.793 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:50.794Z,1554772250.794 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:50.794Z,1554772250.794 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:50.794Z,1554772250.794 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:50.794Z,1554772250.794 [BR_Ping1D](INFO): elapsed4.5957
2019-04-09T01:10:50.794Z,1554772250.794 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:50.795Z,1554772250.795 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:51.199Z,1554772251.199 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:51.199Z,1554772251.199 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:51.199Z,1554772251.199 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:51.199Z,1554772251.199 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:51.200Z,1554772251.200 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:51.200Z,1554772251.200 [BR_Ping1D](INFO): elapsed5.0013
2019-04-09T01:10:51.200Z,1554772251.200 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:51.200Z,1554772251.200 [BR_Ping1D] Data Fault, FailCount= 2
2019-04-09T01:10:51.200Z,1554772251.200 [BR_Ping1D](ERROR): Data Fault
2019-04-09T01:10:51.522Z,1554772251.522 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:10:51.522Z,1554772251.522 [MassServo] Communications Fault, FailCount= 4
2019-04-09T01:10:51.522Z,1554772251.522 [MassServo](ERROR): Communications Fault
2019-04-09T01:10:51.526Z,1554772251.526 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:10:51.527Z,1554772251.527 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T01:10:51.527Z,1554772251.527 [CTD_Seabird] No Fault, FailCount= 1
2019-04-09T01:10:51.527Z,1554772251.527 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T01:10:51.601Z,1554772251.601 [BR_Ping1D](INFO): Stop
2019-04-09T01:10:51.601Z,1554772251.601 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:10:51.687Z,1554772251.687 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:10:51.687Z,1554772251.687 [MassServo](INFO): Powering down
2019-04-09T01:10:51.826Z,1554772251.826 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T01:10:51.828Z,1554772251.828 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T01:10:52.006Z,1554772252.006 [BR_Ping1D](INFO): Stopping
2019-04-09T01:10:52.410Z,1554772252.410 [BR_Ping1D](INFO): Stopping
2019-04-09T01:10:52.441Z,1554772252.441 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:10:52.559Z,1554772252.559 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:10:52.563Z,1554772252.563 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T01:10:52.563Z,1554772252.563 [MassServo] No Fault, FailCount= 4
2019-04-09T01:10:52.814Z,1554772252.814 [BR_Ping1D](INFO): Stopped
2019-04-09T01:10:52.843Z,1554772252.843 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T01:10:52.843Z,1554772252.843 [BR_Ping1D] No Fault, FailCount= 2
2019-04-09T01:10:53.219Z,1554772253.219 [BR_Ping1D](INFO): Start
2019-04-09T01:10:53.621Z,1554772253.621 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:54.034Z,1554772254.034 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:54.034Z,1554772254.034 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:54.034Z,1554772254.034 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:54.034Z,1554772254.034 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:54.035Z,1554772254.035 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T01:10:54.046Z,1554772254.046 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T01:10:54.046Z,1554772254.046 [BR_Ping1D](INFO): sending request for Auto Mode: 228
2019-04-09T01:10:54.047Z,1554772254.047 [BR_Ping1D](INFO): sending request for Soundspeed: 1077250380
2019-04-09T01:10:54.047Z,1554772254.047 [BR_Ping1D](INFO): sending request for Ping Enable: 205
2019-04-09T01:10:54.117Z,1554772254.117 [CBIT](FAULT): Main Battery Failure. Count: 41
2019-04-09T01:10:54.430Z,1554772254.430 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:54.834Z,1554772254.834 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:54.834Z,1554772254.834 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:54.834Z,1554772254.834 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:54.835Z,1554772254.835 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:54.835Z,1554772254.835 [BR_Ping1D](INFO): UART requesting status
2019-04-09T01:10:55.170Z,1554772255.170 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:10:55.170Z,1554772255.170 [MassServo] Communications Fault, FailCount= 5
2019-04-09T01:10:55.170Z,1554772255.170 [MassServo](ERROR): Communications Fault
2019-04-09T01:10:55.174Z,1554772255.174 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:10:55.175Z,1554772255.175 [CBIT](CRITICAL): Communications Fault in component: MassServo
2019-04-09T01:10:55.256Z,1554772255.256 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:55.256Z,1554772255.256 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:55.256Z,1554772255.256 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:55.256Z,1554772255.256 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:55.257Z,1554772255.257 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:55.257Z,1554772255.257 [BR_Ping1D](INFO): elapsed1.2219
2019-04-09T01:10:55.257Z,1554772255.257 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:55.257Z,1554772255.257 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:55.295Z,1554772255.295 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:10:55.295Z,1554772255.295 [MassServo](INFO): Powering down
2019-04-09T01:10:55.298Z,1554772255.298 [CBIT](INFO): Critical error at 20190409T011055
2019-04-09T01:10:55.643Z,1554772255.643 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:55.643Z,1554772255.643 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:55.643Z,1554772255.643 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:55.643Z,1554772255.643 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:55.643Z,1554772255.643 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:55.643Z,1554772255.643 [BR_Ping1D](INFO): elapsed1.6087
2019-04-09T01:10:55.644Z,1554772255.644 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:55.644Z,1554772255.644 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:56.046Z,1554772256.046 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:56.046Z,1554772256.046 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:56.047Z,1554772256.047 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:56.047Z,1554772256.047 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:56.047Z,1554772256.047 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:56.047Z,1554772256.047 [BR_Ping1D](INFO): elapsed2.0122
2019-04-09T01:10:56.047Z,1554772256.047 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:56.047Z,1554772256.047 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:56.068Z,1554772256.068 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:10:56.187Z,1554772256.187 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:10:56.450Z,1554772256.450 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:56.450Z,1554772256.450 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:56.450Z,1554772256.450 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:56.451Z,1554772256.451 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:56.451Z,1554772256.451 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:56.451Z,1554772256.451 [BR_Ping1D](INFO): elapsed2.4162
2019-04-09T01:10:56.451Z,1554772256.451 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:56.451Z,1554772256.451 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:56.854Z,1554772256.854 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:56.854Z,1554772256.854 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:56.854Z,1554772256.854 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:56.854Z,1554772256.854 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:56.854Z,1554772256.854 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:56.855Z,1554772256.855 [BR_Ping1D](INFO): elapsed2.8198
2019-04-09T01:10:56.855Z,1554772256.855 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:56.855Z,1554772256.855 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:57.258Z,1554772257.258 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:57.258Z,1554772257.258 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:57.258Z,1554772257.258 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:57.258Z,1554772257.258 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:57.258Z,1554772257.258 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:57.259Z,1554772257.259 [BR_Ping1D](INFO): elapsed3.2238
2019-04-09T01:10:57.259Z,1554772257.259 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:57.259Z,1554772257.259 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:57.643Z,1554772257.643 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:10:57.643Z,1554772257.643 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-04-09T01:10:57.643Z,1554772257.643 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:10:57.663Z,1554772257.663 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:57.663Z,1554772257.663 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:57.663Z,1554772257.663 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:57.663Z,1554772257.663 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:57.663Z,1554772257.663 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:57.663Z,1554772257.663 [BR_Ping1D](INFO): elapsed3.6286
2019-04-09T01:10:57.664Z,1554772257.664 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:57.664Z,1554772257.664 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:57.679Z,1554772257.679 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:10:58.046Z,1554772258.046 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:10:58.222Z,1554772258.222 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:58.222Z,1554772258.222 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:58.222Z,1554772258.222 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:58.222Z,1554772258.222 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:58.222Z,1554772258.222 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:58.223Z,1554772258.223 [BR_Ping1D](INFO): elapsed4.1879
2019-04-09T01:10:58.223Z,1554772258.223 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:58.223Z,1554772258.223 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:58.297Z,1554772258.297 [CBIT](FAULT): Main Battery Failure. Count: 51
2019-04-09T01:10:58.475Z,1554772258.475 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:58.475Z,1554772258.475 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:58.475Z,1554772258.475 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:58.475Z,1554772258.475 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:58.475Z,1554772258.475 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:58.476Z,1554772258.476 [BR_Ping1D](INFO): elapsed4.4408
2019-04-09T01:10:58.476Z,1554772258.476 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:58.476Z,1554772258.476 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:58.855Z,1554772258.855 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T01:10:58.855Z,1554772258.855 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-09T01:10:58.855Z,1554772258.855 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T01:10:58.874Z,1554772258.874 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:58.874Z,1554772258.874 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:58.874Z,1554772258.874 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:58.874Z,1554772258.874 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:58.874Z,1554772258.874 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:58.875Z,1554772258.875 [BR_Ping1D](INFO): elapsed4.8398
2019-04-09T01:10:58.875Z,1554772258.875 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:58.875Z,1554772258.875 [BR_Ping1D](INFO): No UART data
2019-04-09T01:10:58.891Z,1554772258.891 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T01:10:58.891Z,1554772258.891 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:10:58.891Z,1554772258.891 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-04-09T01:10:59.378Z,1554772259.378 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:10:59.399Z,1554772259.399 [BR_Ping1D](INFO): Starting
2019-04-09T01:10:59.399Z,1554772259.399 [BR_Ping1D](INFO): readConfig
2019-04-09T01:10:59.399Z,1554772259.399 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:10:59.399Z,1554772259.399 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:10:59.400Z,1554772259.400 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:10:59.400Z,1554772259.400 [BR_Ping1D](INFO): elapsed5.3649
2019-04-09T01:10:59.400Z,1554772259.400 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:10:59.400Z,1554772259.400 [BR_Ping1D] Data Fault, FailCount= 3
2019-04-09T01:10:59.400Z,1554772259.400 [BR_Ping1D](ERROR): Data Fault
2019-04-09T01:10:59.427Z,1554772259.427 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T01:10:59.706Z,1554772259.706 [BR_Ping1D](INFO): Stop
2019-04-09T01:10:59.706Z,1554772259.706 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:11:00.086Z,1554772260.086 [BR_Ping1D](INFO): Stopping
2019-04-09T01:11:00.115Z,1554772260.115 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T01:11:00.115Z,1554772260.115 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-09T01:11:00.503Z,1554772260.503 [BR_Ping1D](INFO): Stopping
2019-04-09T01:11:00.924Z,1554772260.924 [BR_Ping1D](INFO): Stopped
2019-04-09T01:11:00.976Z,1554772260.976 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T01:11:00.976Z,1554772260.976 [BR_Ping1D] No Fault, FailCount= 3
2019-04-09T01:11:01.279Z,1554772261.279 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:11:01.279Z,1554772261.279 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:11:01.313Z,1554772261.313 [BR_Ping1D](INFO): Start
2019-04-09T01:11:01.343Z,1554772261.343 [SBIT](IMPORTANT): Beginning Startup BIT
2019-04-09T01:11:01.348Z,1554772261.348 [CBIT](IMPORTANT): Beginning ground fault scan
2019-04-09T01:11:01.702Z,1554772261.702 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:02.106Z,1554772262.106 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:02.106Z,1554772262.106 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:02.107Z,1554772262.107 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:02.107Z,1554772262.107 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:02.107Z,1554772262.107 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T01:11:02.118Z,1554772262.118 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T01:11:02.118Z,1554772262.118 [BR_Ping1D](INFO): sending request for Auto Mode: 228
2019-04-09T01:11:02.119Z,1554772262.119 [BR_Ping1D](INFO): sending request for Soundspeed: 1077250380
2019-04-09T01:11:02.119Z,1554772262.119 [BR_Ping1D](INFO): sending request for Ping Enable: 225
2019-04-09T01:11:02.184Z,1554772262.184 [CBIT](FAULT): Main Battery Failure. Count: 61
2019-04-09T01:11:02.510Z,1554772262.510 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:02.914Z,1554772262.914 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:02.914Z,1554772262.914 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:02.914Z,1554772262.914 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:02.915Z,1554772262.915 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:02.915Z,1554772262.915 [BR_Ping1D](INFO): UART requesting status
2019-04-09T01:11:03.318Z,1554772263.318 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:03.318Z,1554772263.318 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:03.318Z,1554772263.318 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:03.318Z,1554772263.318 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:03.318Z,1554772263.318 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:03.319Z,1554772263.319 [BR_Ping1D](INFO): elapsed1.2115
2019-04-09T01:11:03.319Z,1554772263.319 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:03.319Z,1554772263.319 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:03.721Z,1554772263.721 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:03.722Z,1554772263.722 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:03.722Z,1554772263.722 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:03.722Z,1554772263.722 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:03.722Z,1554772263.722 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:03.722Z,1554772263.722 [BR_Ping1D](INFO): elapsed1.6153
2019-04-09T01:11:03.723Z,1554772263.723 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:03.723Z,1554772263.723 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:04.111Z,1554772264.111 [NAL9602](INFO): Powering up NAL9602
2019-04-09T01:11:04.137Z,1554772264.137 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:04.137Z,1554772264.137 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:04.137Z,1554772264.137 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:04.137Z,1554772264.137 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:04.138Z,1554772264.138 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:04.138Z,1554772264.138 [BR_Ping1D](INFO): elapsed2.0306
2019-04-09T01:11:04.138Z,1554772264.138 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:04.138Z,1554772264.138 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:04.529Z,1554772264.529 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:04.529Z,1554772264.529 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:04.530Z,1554772264.530 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:04.530Z,1554772264.530 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:04.530Z,1554772264.530 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:04.530Z,1554772264.530 [BR_Ping1D](INFO): elapsed2.4232
2019-04-09T01:11:04.531Z,1554772264.531 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:04.531Z,1554772264.531 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:04.878Z,1554772264.878 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T01:11:04.878Z,1554772264.878 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-09T01:11:04.878Z,1554772264.878 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T01:11:04.935Z,1554772264.935 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:04.935Z,1554772264.935 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:04.935Z,1554772264.935 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:04.935Z,1554772264.935 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:04.935Z,1554772264.935 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:04.936Z,1554772264.936 [BR_Ping1D](INFO): elapsed2.8285
2019-04-09T01:11:04.936Z,1554772264.936 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:04.936Z,1554772264.936 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:05.095Z,1554772265.095 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T01:11:05.283Z,1554772265.283 [CTD_Seabird](INFO): Powering down
2019-04-09T01:11:05.341Z,1554772265.341 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:05.341Z,1554772265.341 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:05.341Z,1554772265.341 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:05.342Z,1554772265.342 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:05.342Z,1554772265.342 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:05.342Z,1554772265.342 [BR_Ping1D](INFO): elapsed3.2351
2019-04-09T01:11:05.342Z,1554772265.342 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:05.343Z,1554772265.343 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:05.742Z,1554772265.742 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:05.742Z,1554772265.742 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:05.742Z,1554772265.742 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:05.742Z,1554772265.742 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:05.742Z,1554772265.742 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:05.743Z,1554772265.743 [BR_Ping1D](INFO): elapsed3.6356
2019-04-09T01:11:05.743Z,1554772265.743 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:05.743Z,1554772265.743 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:06.145Z,1554772266.145 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:06.145Z,1554772266.145 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:06.145Z,1554772266.145 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:06.146Z,1554772266.146 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:06.146Z,1554772266.146 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:06.146Z,1554772266.146 [BR_Ping1D](INFO): elapsed4.0389
2019-04-09T01:11:06.146Z,1554772266.146 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:06.146Z,1554772266.146 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:06.292Z,1554772266.292 [CBIT](FAULT): Main Battery Failure. Count: 71
2019-04-09T01:11:06.303Z,1554772266.303 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T01:11:06.303Z,1554772266.303 [CTD_Seabird] No Fault, FailCount= 2
2019-04-09T01:11:06.549Z,1554772266.549 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:06.550Z,1554772266.550 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:06.550Z,1554772266.550 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:06.550Z,1554772266.550 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:06.550Z,1554772266.550 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:06.550Z,1554772266.550 [BR_Ping1D](INFO): elapsed4.4434
2019-04-09T01:11:06.551Z,1554772266.551 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:06.551Z,1554772266.551 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:06.626Z,1554772266.626 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T01:11:06.628Z,1554772266.628 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T01:11:06.953Z,1554772266.953 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:06.953Z,1554772266.953 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:06.954Z,1554772266.954 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:06.954Z,1554772266.954 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:06.954Z,1554772266.954 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:06.954Z,1554772266.954 [BR_Ping1D](INFO): elapsed4.8472
2019-04-09T01:11:06.954Z,1554772266.954 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:06.955Z,1554772266.955 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:07.361Z,1554772267.361 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:07.361Z,1554772267.361 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:07.361Z,1554772267.361 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:07.361Z,1554772267.361 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:07.362Z,1554772267.362 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:07.362Z,1554772267.362 [BR_Ping1D](INFO): elapsed5.2548
2019-04-09T01:11:07.362Z,1554772267.362 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:07.362Z,1554772267.362 [BR_Ping1D] Data Fault, FailCount= 4
2019-04-09T01:11:07.362Z,1554772267.362 [BR_Ping1D](ERROR): Data Fault
2019-04-09T01:11:07.392Z,1554772267.392 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T01:11:07.761Z,1554772267.761 [BR_Ping1D](INFO): Stop
2019-04-09T01:11:07.761Z,1554772267.761 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:11:08.166Z,1554772268.166 [BR_Ping1D](INFO): Stopping
2019-04-09T01:11:08.569Z,1554772268.569 [BR_Ping1D](INFO): Stopping
2019-04-09T01:11:08.974Z,1554772268.974 [BR_Ping1D](INFO): Stopped
2019-04-09T01:11:08.992Z,1554772268.992 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T01:11:08.992Z,1554772268.992 [BR_Ping1D] No Fault, FailCount= 4
2019-04-09T01:11:09.377Z,1554772269.377 [BR_Ping1D](INFO): Start
2019-04-09T01:11:09.781Z,1554772269.781 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:10.185Z,1554772270.185 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:10.185Z,1554772270.185 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:10.186Z,1554772270.186 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:10.186Z,1554772270.186 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:10.186Z,1554772270.186 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T01:11:10.198Z,1554772270.198 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T01:11:10.199Z,1554772270.199 [BR_Ping1D](INFO): sending request for Auto Mode: 228
2019-04-09T01:11:10.201Z,1554772270.201 [BR_Ping1D](INFO): sending request for Soundspeed: 1077250380
2019-04-09T01:11:10.202Z,1554772270.202 [BR_Ping1D](INFO): sending request for Ping Enable: 24
2019-04-09T01:11:10.255Z,1554772270.255 [CBIT](FAULT): Main Battery Failure. Count: 81
2019-04-09T01:11:10.571Z,1554772270.571 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T01:11:10.571Z,1554772270.571 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-09T01:11:10.571Z,1554772270.571 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T01:11:10.590Z,1554772270.590 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:10.656Z,1554772270.656 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T01:11:10.656Z,1554772270.656 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-09T01:11:11.094Z,1554772271.094 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:11:11.120Z,1554772271.120 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:11.120Z,1554772271.120 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:11.121Z,1554772271.121 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:11.121Z,1554772271.121 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:11.121Z,1554772271.121 [BR_Ping1D](INFO): UART requesting status
2019-04-09T01:11:11.398Z,1554772271.398 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:11.399Z,1554772271.399 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:11.399Z,1554772271.399 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:11.399Z,1554772271.399 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:11.399Z,1554772271.399 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:11.399Z,1554772271.399 [BR_Ping1D](INFO): elapsed1.2132
2019-04-09T01:11:11.400Z,1554772271.400 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:11.400Z,1554772271.400 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:11.801Z,1554772271.801 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:11.801Z,1554772271.801 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:11.802Z,1554772271.802 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:11.802Z,1554772271.802 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:11.802Z,1554772271.802 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:11.802Z,1554772271.802 [BR_Ping1D](INFO): elapsed1.6161
2019-04-09T01:11:11.803Z,1554772271.803 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:11.803Z,1554772271.803 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:12.205Z,1554772272.205 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:12.206Z,1554772272.206 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:12.206Z,1554772272.206 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:12.206Z,1554772272.206 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:12.206Z,1554772272.206 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:12.206Z,1554772272.206 [BR_Ping1D](INFO): elapsed2.0203
2019-04-09T01:11:12.207Z,1554772272.207 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:12.207Z,1554772272.207 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:12.227Z,1554772272.227 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.007016
CHAN A1 (24V): -0.127655
CHAN A2 (12V): -0.005797
CHAN A3 (5V): -0.002736
CHAN B0 (3.3V): -0.000929
CHAN B1 (3.15aV): -0.001172
CHAN B2 (3.15bV): -0.001556
CHAN B3 (GND): -0.000107
OPEN: -0.001221
Full Scale Calc: 4.765 mA, -1.589 mA
2019-04-09T01:11:12.609Z,1554772272.609 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:12.609Z,1554772272.609 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:12.609Z,1554772272.609 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:12.610Z,1554772272.610 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:12.610Z,1554772272.610 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:12.610Z,1554772272.610 [BR_Ping1D](INFO): elapsed2.4238
2019-04-09T01:11:12.610Z,1554772272.610 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:12.610Z,1554772272.610 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:13.014Z,1554772273.014 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:13.014Z,1554772273.014 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:13.014Z,1554772273.014 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:13.014Z,1554772273.014 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:13.014Z,1554772273.014 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:13.015Z,1554772273.015 [BR_Ping1D](INFO): elapsed2.8285
2019-04-09T01:11:13.015Z,1554772273.015 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:13.015Z,1554772273.015 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:13.414Z,1554772273.414 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:13.414Z,1554772273.414 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:13.414Z,1554772273.414 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:13.415Z,1554772273.415 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:13.415Z,1554772273.415 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:13.415Z,1554772273.415 [BR_Ping1D](INFO): elapsed3.2287
2019-04-09T01:11:13.415Z,1554772273.415 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:13.415Z,1554772273.415 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:13.822Z,1554772273.822 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:13.822Z,1554772273.822 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:13.822Z,1554772273.822 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:13.822Z,1554772273.822 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:13.822Z,1554772273.822 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:13.823Z,1554772273.823 [BR_Ping1D](INFO): elapsed3.6363
2019-04-09T01:11:13.823Z,1554772273.823 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:13.823Z,1554772273.823 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:14.228Z,1554772274.228 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:14.228Z,1554772274.228 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:14.229Z,1554772274.229 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:14.229Z,1554772274.229 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:14.229Z,1554772274.229 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:14.229Z,1554772274.229 [BR_Ping1D](INFO): elapsed4.0429
2019-04-09T01:11:14.229Z,1554772274.229 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:14.229Z,1554772274.229 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:14.245Z,1554772274.245 [CBIT](FAULT): Main Battery Failure. Count: 91
2019-04-09T01:11:14.644Z,1554772274.644 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:14.644Z,1554772274.644 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:14.645Z,1554772274.645 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:14.645Z,1554772274.645 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:14.645Z,1554772274.645 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:14.645Z,1554772274.645 [BR_Ping1D](INFO): elapsed4.4589
2019-04-09T01:11:14.645Z,1554772274.645 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:14.646Z,1554772274.646 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:14.665Z,1554772274.665 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T01:11:15.019Z,1554772275.019 [NAL9602](INFO): NAL9602 initialized
2019-04-09T01:11:15.034Z,1554772275.034 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:15.034Z,1554772275.034 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:15.034Z,1554772275.034 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:15.034Z,1554772275.034 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:15.035Z,1554772275.035 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:15.035Z,1554772275.035 [BR_Ping1D](INFO): elapsed4.8485
2019-04-09T01:11:15.035Z,1554772275.035 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:15.035Z,1554772275.035 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:15.448Z,1554772275.448 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:15.448Z,1554772275.448 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:15.448Z,1554772275.448 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:15.448Z,1554772275.448 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:15.449Z,1554772275.449 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:15.449Z,1554772275.449 [BR_Ping1D](INFO): elapsed5.2626
2019-04-09T01:11:15.449Z,1554772275.449 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:15.449Z,1554772275.449 [BR_Ping1D] Data Fault, FailCount= 5
2019-04-09T01:11:15.449Z,1554772275.449 [BR_Ping1D](ERROR): Data Fault
2019-04-09T01:11:15.489Z,1554772275.489 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T01:11:15.502Z,1554772275.502 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-04-09T01:11:15.838Z,1554772275.838 [NAL9602](DEBUG): Fix Requested
2019-04-09T01:11:15.853Z,1554772275.853 [BR_Ping1D](INFO): Stop
2019-04-09T01:11:15.853Z,1554772275.853 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:11:15.956Z,1554772275.956 [CBIT](INFO): Critical error at 20190409T011115
2019-04-09T01:11:16.261Z,1554772276.261 [BR_Ping1D](INFO): Stopping
2019-04-09T01:11:16.673Z,1554772276.673 [BR_Ping1D](INFO): Stopping
2019-04-09T01:11:17.054Z,1554772277.054 [BR_Ping1D](INFO): Stopped
2019-04-09T01:11:18.333Z,1554772278.333 [CBIT](FAULT): Main Battery Failure. Count: 101
2019-04-09T01:11:19.678Z,1554772279.678 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T01:11:19.678Z,1554772279.678 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-09T01:11:19.678Z,1554772279.678 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T01:11:19.959Z,1554772279.959 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T01:11:19.959Z,1554772279.959 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-09T01:11:20.082Z,1554772280.082 [CTD_Seabird](INFO): Powering down
2019-04-09T01:11:21.479Z,1554772281.479 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:11:21.479Z,1554772281.479 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2019-04-09T01:11:21.479Z,1554772281.479 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:11:21.587Z,1554772281.587 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:11:21.882Z,1554772281.882 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:11:22.580Z,1554772282.580 [CBIT](FAULT): Main Battery Failure. Count: 111
2019-04-09T01:11:22.736Z,1554772282.736 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:11:22.736Z,1554772282.736 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2019-04-09T01:11:25.115Z,1554772285.115 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:11:25.115Z,1554772285.115 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:11:26.361Z,1554772286.361 [CBIT](FAULT): Main Battery Failure. Count: 121
2019-04-09T01:11:29.943Z,1554772289.943 [CommandLine](IMPORTANT): got command failComponent none BR_Ping1D
2019-04-09T01:11:29.943Z,1554772289.943 [BR_Ping1D] No Fault, FailCount= 5
2019-04-09T01:11:29.943Z,1554772289.943 [CommandLine](IMPORTANT): BR_Ping1D failureMode is No Fault
2019-04-09T01:11:29.993Z,1554772289.993 [BR_Ping1D](INFO): Start
2019-04-09T01:11:30.385Z,1554772290.385 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:30.398Z,1554772290.398 [CBIT](FAULT): Main Battery Failure. Count: 131
2019-04-09T01:11:30.790Z,1554772290.790 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:30.790Z,1554772290.790 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:30.790Z,1554772290.790 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:30.790Z,1554772290.790 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:30.790Z,1554772290.790 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T01:11:30.802Z,1554772290.802 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T01:11:30.803Z,1554772290.803 [BR_Ping1D](INFO): sending request for Auto Mode: 228
2019-04-09T01:11:30.804Z,1554772290.804 [BR_Ping1D](INFO): sending request for Soundspeed: 1077250380
2019-04-09T01:11:30.806Z,1554772290.806 [BR_Ping1D](INFO): sending request for Ping Enable: 62
2019-04-09T01:11:31.193Z,1554772291.193 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:31.598Z,1554772291.598 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:31.599Z,1554772291.599 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:31.599Z,1554772291.599 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:31.599Z,1554772291.599 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:31.599Z,1554772291.599 [BR_Ping1D](INFO): UART requesting status
2019-04-09T01:11:32.004Z,1554772292.004 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:32.004Z,1554772292.004 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:32.005Z,1554772292.005 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:32.005Z,1554772292.005 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:32.005Z,1554772292.005 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:32.005Z,1554772292.005 [BR_Ping1D](INFO): elapsed1.2146
2019-04-09T01:11:32.005Z,1554772292.005 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:32.005Z,1554772292.005 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:32.410Z,1554772292.410 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:32.410Z,1554772292.410 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:32.411Z,1554772292.411 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:32.411Z,1554772292.411 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:32.411Z,1554772292.411 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:32.411Z,1554772292.411 [BR_Ping1D](INFO): elapsed1.6205
2019-04-09T01:11:32.411Z,1554772292.411 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:32.411Z,1554772292.411 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:32.810Z,1554772292.810 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:32.810Z,1554772292.810 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:32.810Z,1554772292.810 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:32.810Z,1554772292.810 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:32.810Z,1554772292.810 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:32.811Z,1554772292.811 [BR_Ping1D](INFO): elapsed2.0201
2019-04-09T01:11:32.811Z,1554772292.811 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:32.811Z,1554772292.811 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:33.214Z,1554772293.214 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:33.214Z,1554772293.214 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:33.214Z,1554772293.214 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:33.214Z,1554772293.214 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:33.214Z,1554772293.214 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:33.215Z,1554772293.215 [BR_Ping1D](INFO): elapsed2.4242
2019-04-09T01:11:33.215Z,1554772293.215 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:33.215Z,1554772293.215 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:33.622Z,1554772293.622 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:33.622Z,1554772293.622 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:33.623Z,1554772293.623 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:33.623Z,1554772293.623 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:33.623Z,1554772293.623 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:33.623Z,1554772293.623 [BR_Ping1D](INFO): elapsed2.8325
2019-04-09T01:11:33.623Z,1554772293.623 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:33.623Z,1554772293.623 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:34.032Z,1554772294.032 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:34.032Z,1554772294.032 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:34.033Z,1554772294.033 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:34.033Z,1554772294.033 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:34.033Z,1554772294.033 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:34.033Z,1554772294.033 [BR_Ping1D](INFO): elapsed3.2427
2019-04-09T01:11:34.033Z,1554772294.033 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:34.034Z,1554772294.034 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:34.436Z,1554772294.436 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:34.436Z,1554772294.436 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:34.436Z,1554772294.436 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:34.437Z,1554772294.437 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:34.437Z,1554772294.437 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:34.437Z,1554772294.437 [BR_Ping1D](INFO): elapsed3.6465
2019-04-09T01:11:34.437Z,1554772294.437 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:34.437Z,1554772294.437 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:34.457Z,1554772294.457 [CBIT](FAULT): Main Battery Failure. Count: 141
2019-04-09T01:11:34.849Z,1554772294.849 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:34.849Z,1554772294.849 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:34.849Z,1554772294.849 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:34.849Z,1554772294.849 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:34.849Z,1554772294.849 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:34.849Z,1554772294.849 [BR_Ping1D](INFO): elapsed4.0589
2019-04-09T01:11:34.850Z,1554772294.850 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:34.850Z,1554772294.850 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:35.247Z,1554772295.247 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:35.247Z,1554772295.247 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:35.247Z,1554772295.247 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:35.247Z,1554772295.247 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:35.247Z,1554772295.247 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:35.248Z,1554772295.248 [BR_Ping1D](INFO): elapsed4.4571
2019-04-09T01:11:35.248Z,1554772295.248 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:35.248Z,1554772295.248 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:35.638Z,1554772295.638 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:35.638Z,1554772295.638 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:35.638Z,1554772295.638 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:35.638Z,1554772295.638 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:35.638Z,1554772295.638 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:35.639Z,1554772295.639 [BR_Ping1D](INFO): elapsed4.8480
2019-04-09T01:11:35.639Z,1554772295.639 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:35.639Z,1554772295.639 [BR_Ping1D](INFO): No UART data
2019-04-09T01:11:36.042Z,1554772296.042 [BR_Ping1D](INFO): Starting
2019-04-09T01:11:36.042Z,1554772296.042 [BR_Ping1D](INFO): readConfig
2019-04-09T01:11:36.042Z,1554772296.042 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:11:36.043Z,1554772296.043 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:11:36.043Z,1554772296.043 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:11:36.043Z,1554772296.043 [BR_Ping1D](INFO): elapsed5.2524
2019-04-09T01:11:36.043Z,1554772296.043 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:11:36.043Z,1554772296.043 [BR_Ping1D] Data Fault, FailCount= 6
2019-04-09T01:11:36.043Z,1554772296.043 [BR_Ping1D](ERROR): Data Fault
2019-04-09T01:11:36.064Z,1554772296.064 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T01:11:36.065Z,1554772296.065 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-04-09T01:11:36.446Z,1554772296.446 [BR_Ping1D](INFO): Stop
2019-04-09T01:11:36.446Z,1554772296.446 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:11:36.537Z,1554772296.537 [CBIT](INFO): Critical error at 20190409T011136
2019-04-09T01:11:36.853Z,1554772296.853 [BR_Ping1D](INFO): Stopping
2019-04-09T01:11:37.258Z,1554772297.258 [BR_Ping1D](INFO): Stopping
2019-04-09T01:11:37.658Z,1554772297.658 [BR_Ping1D](INFO): Stopped
2019-04-09T01:11:38.485Z,1554772298.485 [CBIT](FAULT): Main Battery Failure. Count: 151
2019-04-09T01:11:41.316Z,1554772301.316 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T01:11:41.316Z,1554772301.316 [SBIT](FAULT): Control surface position failure.
2019-04-09T01:11:42.534Z,1554772302.534 [CBIT](FAULT): Main Battery Failure. Count: 161
2019-04-09T01:11:45.315Z,1554772305.315 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:11:45.315Z,1554772305.315 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2019-04-09T01:11:45.315Z,1554772305.315 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:11:45.452Z,1554772305.452 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:11:45.718Z,1554772305.718 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:11:46.800Z,1554772306.800 [CBIT](FAULT): Main Battery Failure. Count: 171
2019-04-09T01:11:46.803Z,1554772306.803 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:11:46.803Z,1554772306.803 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2019-04-09T01:11:48.950Z,1554772308.950 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:11:48.951Z,1554772308.951 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:11:50.621Z,1554772310.621 [CBIT](FAULT): Main Battery Failure. Count: 181
2019-04-09T01:11:54.642Z,1554772314.642 [CBIT](FAULT): Main Battery Failure. Count: 191
2019-04-09T01:11:55.060Z,1554772315.060 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T01:11:55.060Z,1554772315.060 [SBIT](FAULT): Control surface position failure.
2019-04-09T01:11:55.460Z,1554772315.460 [SBIT](CRITICAL): SBIT FAILED
2019-04-09T01:11:55.476Z,1554772315.476 [CBIT](INFO): Critical error at 20190409T011155
2019-04-09T01:11:55.562Z,1554772315.562 [CommandLine](IMPORTANT): got command configSet list
2019-04-09T01:11:55.563Z,1554772315.563 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-04-09T01:11:55.563Z,1554772315.563 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-04-09T01:11:55.564Z,1554772315.564 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2019-04-09T01:11:55.864Z,1554772315.864 [MissionManager](IMPORTANT): Started mission Startup
2019-04-09T01:11:55.864Z,1554772315.864 [Startup] Running Loop=1
2019-04-09T01:11:55.865Z,1554772315.865 [Startup](DEBUG): Aggregate::initialize Startup
2019-04-09T01:11:55.865Z,1554772315.865 [Startup:A.GoToSurface] Running Loop=1
2019-04-09T01:11:55.865Z,1554772315.865 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-09T01:11:55.866Z,1554772315.866 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-09T01:11:55.866Z,1554772315.866 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-09T01:11:55.867Z,1554772315.867 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-09T01:11:55.867Z,1554772315.867 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-09T01:11:55.867Z,1554772315.867 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-09T01:11:55.869Z,1554772315.869 [Startup:StartupSatComms] Running Loop=1
2019-04-09T01:11:55.869Z,1554772315.869 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-04-09T01:11:55.869Z,1554772315.869 [Startup:StartupSatComms:A] Running Loop=1
2019-04-09T01:11:56.269Z,1554772316.269 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-04-09T01:11:58.679Z,1554772318.679 [CBIT](FAULT): Main Battery Failure. Count: 201
2019-04-09T01:11:59.360Z,1554772319.360 [CommandLine](IMPORTANT): got command load
2019-04-09T01:11:59.360Z,1554772319.360 [CommandLine](FAULT): Incomplete syntax. Try: help load
2019-04-09T01:12:02.726Z,1554772322.726 [CBIT](FAULT): Main Battery Failure. Count: 211
2019-04-09T01:12:02.907Z,1554772322.907 [CommandLine](IMPORTANT): got command help failComponent
2019-04-09T01:12:06.784Z,1554772326.784 [CBIT](FAULT): Main Battery Failure. Count: 221
2019-04-09T01:12:09.150Z,1554772329.150 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:12:09.151Z,1554772329.151 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2019-04-09T01:12:09.151Z,1554772329.151 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:12:09.209Z,1554772329.209 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:12:09.555Z,1554772329.555 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:12:10.400Z,1554772330.400 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:12:10.400Z,1554772330.400 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2019-04-09T01:12:10.812Z,1554772330.812 [CBIT](FAULT): Main Battery Failure. Count: 231
2019-04-09T01:12:12.786Z,1554772332.786 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:12:12.787Z,1554772332.787 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:12:14.842Z,1554772334.842 [CBIT](FAULT): Main Battery Failure. Count: 241
2019-04-09T01:12:18.881Z,1554772338.881 [CBIT](FAULT): Main Battery Failure. Count: 251
2019-04-09T01:12:22.924Z,1554772342.924 [CBIT](FAULT): Main Battery Failure. Count: 261
2019-04-09T01:12:26.712Z,1554772346.712 [CommandLine](IMPORTANT): got command failComponent none BR_Ping1D
2019-04-09T01:12:26.712Z,1554772346.712 [BR_Ping1D] No Fault, FailCount= 6
2019-04-09T01:12:26.712Z,1554772346.712 [CommandLine](IMPORTANT): BR_Ping1D failureMode is No Fault
2019-04-09T01:12:26.963Z,1554772346.963 [BR_Ping1D](INFO): Start
2019-04-09T01:12:26.979Z,1554772346.979 [CBIT](FAULT): Main Battery Failure. Count: 271
2019-04-09T01:12:27.361Z,1554772347.361 [BR_Ping1D](INFO): Starting
2019-04-09T01:12:27.768Z,1554772347.768 [BR_Ping1D](INFO): Starting
2019-04-09T01:12:27.768Z,1554772347.768 [BR_Ping1D](INFO): readConfig
2019-04-09T01:12:27.769Z,1554772347.769 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:12:27.769Z,1554772347.769 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:12:27.769Z,1554772347.769 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T01:12:27.782Z,1554772347.782 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T01:12:27.783Z,1554772347.783 [BR_Ping1D](INFO): sending request for Auto Mode: 228
2019-04-09T01:12:27.805Z,1554772347.805 [BR_Ping1D](INFO): sending request for Soundspeed: 1077250380
2019-04-09T01:12:27.807Z,1554772347.807 [BR_Ping1D](INFO): sending request for Ping Enable: 23
2019-04-09T01:12:28.158Z,1554772348.158 [BR_Ping1D](INFO): Starting
2019-04-09T01:12:28.561Z,1554772348.561 [BR_Ping1D](INFO): Starting
2019-04-09T01:12:28.562Z,1554772348.562 [BR_Ping1D](INFO): readConfig
2019-04-09T01:12:28.562Z,1554772348.562 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:12:28.562Z,1554772348.562 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:12:28.562Z,1554772348.562 [BR_Ping1D](INFO): UART requesting status
2019-04-09T01:12:28.963Z,1554772348.963 [BR_Ping1D](INFO): Starting
2019-04-09T01:12:28.963Z,1554772348.963 [BR_Ping1D](INFO): readConfig
2019-04-09T01:12:28.964Z,1554772348.964 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:12:28.964Z,1554772348.964 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:12:28.964Z,1554772348.964 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:12:28.964Z,1554772348.964 [BR_Ping1D](INFO): elapsed1.1950
2019-04-09T01:12:28.964Z,1554772348.964 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:12:28.964Z,1554772348.964 [BR_Ping1D](INFO): No UART data
2019-04-09T01:12:29.370Z,1554772349.370 [BR_Ping1D](INFO): Starting
2019-04-09T01:12:29.370Z,1554772349.370 [BR_Ping1D](INFO): readConfig
2019-04-09T01:12:29.370Z,1554772349.370 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:12:29.370Z,1554772349.370 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:12:29.370Z,1554772349.370 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:12:29.371Z,1554772349.371 [BR_Ping1D](INFO): elapsed1.6015
2019-04-09T01:12:29.371Z,1554772349.371 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:12:29.371Z,1554772349.371 [BR_Ping1D](INFO): No UART data
2019-04-09T01:12:29.783Z,1554772349.783 [BR_Ping1D](INFO): Starting
2019-04-09T01:12:29.783Z,1554772349.783 [BR_Ping1D](INFO): readConfig
2019-04-09T01:12:29.784Z,1554772349.784 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:12:29.784Z,1554772349.784 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:12:29.784Z,1554772349.784 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:12:29.784Z,1554772349.784 [BR_Ping1D](INFO): elapsed2.0150
2019-04-09T01:12:29.784Z,1554772349.784 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:12:29.784Z,1554772349.784 [BR_Ping1D](INFO): No UART data
2019-04-09T01:12:30.178Z,1554772350.178 [BR_Ping1D](INFO): Starting
2019-04-09T01:12:30.178Z,1554772350.178 [BR_Ping1D](INFO): readConfig
2019-04-09T01:12:30.178Z,1554772350.178 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:12:30.178Z,1554772350.178 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:12:30.178Z,1554772350.178 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:12:30.179Z,1554772350.179 [BR_Ping1D](INFO): elapsed2.4097
2019-04-09T01:12:30.179Z,1554772350.179 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:12:30.179Z,1554772350.179 [BR_Ping1D](INFO): No UART data
2019-04-09T01:12:30.586Z,1554772350.586 [BR_Ping1D](INFO): Starting
2019-04-09T01:12:30.587Z,1554772350.587 [BR_Ping1D](INFO): readConfig
2019-04-09T01:12:30.587Z,1554772350.587 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:12:30.587Z,1554772350.587 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:12:30.587Z,1554772350.587 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:12:30.587Z,1554772350.587 [BR_Ping1D](INFO): elapsed2.8183
2019-04-09T01:12:30.588Z,1554772350.588 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:12:30.588Z,1554772350.588 [BR_Ping1D](INFO): No UART data
2019-04-09T01:12:30.986Z,1554772350.986 [BR_Ping1D](INFO): Starting
2019-04-09T01:12:30.986Z,1554772350.986 [BR_Ping1D](INFO): readConfig
2019-04-09T01:12:30.986Z,1554772350.986 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:12:30.986Z,1554772350.986 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:12:30.986Z,1554772350.986 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:12:30.987Z,1554772350.987 [BR_Ping1D](INFO): elapsed3.2176
2019-04-09T01:12:30.987Z,1554772350.987 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:12:30.987Z,1554772350.987 [BR_Ping1D](INFO): No UART data
2019-04-09T01:12:31.005Z,1554772351.005 [CBIT](FAULT): Main Battery Failure. Count: 281
2019-04-09T01:12:31.390Z,1554772351.390 [BR_Ping1D](INFO): Starting
2019-04-09T01:12:31.390Z,1554772351.390 [BR_Ping1D](INFO): readConfig
2019-04-09T01:12:31.391Z,1554772351.391 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:12:31.391Z,1554772351.391 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:12:31.391Z,1554772351.391 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:12:31.391Z,1554772351.391 [BR_Ping1D](INFO): elapsed3.6221
2019-04-09T01:12:31.391Z,1554772351.391 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:12:31.392Z,1554772351.392 [BR_Ping1D](INFO): No UART data
2019-04-09T01:12:31.804Z,1554772351.804 [BR_Ping1D](INFO): Starting
2019-04-09T01:12:31.805Z,1554772351.805 [BR_Ping1D](INFO): readConfig
2019-04-09T01:12:31.805Z,1554772351.805 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:12:31.805Z,1554772351.805 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:12:31.805Z,1554772351.805 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:12:31.805Z,1554772351.805 [BR_Ping1D](INFO): elapsed4.0363
2019-04-09T01:12:31.806Z,1554772351.806 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:12:31.806Z,1554772351.806 [BR_Ping1D](INFO): No UART data
2019-04-09T01:12:32.209Z,1554772352.209 [BR_Ping1D](INFO): Starting
2019-04-09T01:12:32.209Z,1554772352.209 [BR_Ping1D](INFO): readConfig
2019-04-09T01:12:32.209Z,1554772352.209 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:12:32.209Z,1554772352.209 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:12:32.209Z,1554772352.209 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:12:32.210Z,1554772352.210 [BR_Ping1D](INFO): elapsed4.4405
2019-04-09T01:12:32.210Z,1554772352.210 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:12:32.210Z,1554772352.210 [BR_Ping1D](INFO): No UART data
2019-04-09T01:12:32.612Z,1554772352.612 [BR_Ping1D](INFO): Starting
2019-04-09T01:12:32.612Z,1554772352.612 [BR_Ping1D](INFO): readConfig
2019-04-09T01:12:32.613Z,1554772352.613 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:12:32.613Z,1554772352.613 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:12:32.613Z,1554772352.613 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:12:32.613Z,1554772352.613 [BR_Ping1D](INFO): elapsed4.8440
2019-04-09T01:12:32.613Z,1554772352.613 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:12:32.613Z,1554772352.613 [BR_Ping1D](INFO): No UART data
2019-04-09T01:12:32.986Z,1554772352.986 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:12:32.987Z,1554772352.987 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2019-04-09T01:12:32.987Z,1554772352.987 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:12:33.026Z,1554772353.026 [BR_Ping1D](INFO): Starting
2019-04-09T01:12:33.026Z,1554772353.026 [BR_Ping1D](INFO): readConfig
2019-04-09T01:12:33.026Z,1554772353.026 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:12:33.027Z,1554772353.027 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:12:33.027Z,1554772353.027 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:12:33.027Z,1554772353.027 [BR_Ping1D](INFO): elapsed5.2580
2019-04-09T01:12:33.027Z,1554772353.027 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:12:33.027Z,1554772353.027 [BR_Ping1D] Data Fault, FailCount= 7
2019-04-09T01:12:33.027Z,1554772353.027 [BR_Ping1D](ERROR): Data Fault
2019-04-09T01:12:33.048Z,1554772353.048 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:12:33.049Z,1554772353.049 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T01:12:33.049Z,1554772353.049 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-04-09T01:12:33.390Z,1554772353.390 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:12:33.531Z,1554772353.531 [BR_Ping1D](INFO): Stop
2019-04-09T01:12:33.531Z,1554772353.531 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:12:33.639Z,1554772353.639 [CBIT](INFO): Critical error at 20190409T011233
2019-04-09T01:12:33.812Z,1554772353.812 [BR_Ping1D](INFO): Stopping
2019-04-09T01:12:34.218Z,1554772354.218 [BR_Ping1D](INFO): Stopping
2019-04-09T01:12:34.259Z,1554772354.259 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:12:34.259Z,1554772354.259 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2019-04-09T01:12:34.626Z,1554772354.626 [BR_Ping1D](INFO): Stopped
2019-04-09T01:12:35.035Z,1554772355.035 [CBIT](FAULT): Main Battery Failure. Count: 291
2019-04-09T01:12:36.623Z,1554772356.623 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:12:36.623Z,1554772356.623 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:12:37.069Z,1554772357.069 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T01:12:37.069Z,1554772357.069 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-04-09T01:12:37.069Z,1554772357.069 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T01:12:37.071Z,1554772357.071 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T01:12:37.071Z,1554772357.071 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-04-09T01:12:37.071Z,1554772357.071 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T01:12:37.072Z,1554772357.072 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T01:12:37.073Z,1554772357.073 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-04-09T01:12:37.073Z,1554772357.073 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T01:12:37.083Z,1554772357.083 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T01:12:37.084Z,1554772357.084 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T01:12:37.084Z,1554772357.084 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T01:12:37.483Z,1554772357.483 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T01:12:37.483Z,1554772357.483 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-04-09T01:12:37.484Z,1554772357.484 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T01:12:37.484Z,1554772357.484 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-04-09T01:12:37.484Z,1554772357.484 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T01:12:37.484Z,1554772357.484 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-04-09T01:12:37.877Z,1554772357.877 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T01:12:39.082Z,1554772359.082 [CBIT](FAULT): Main Battery Failure. Count: 301
2019-04-09T01:12:43.132Z,1554772363.132 [CBIT](FAULT): Main Battery Failure. Count: 311
2019-04-09T01:12:47.161Z,1554772367.161 [CBIT](FAULT): Main Battery Failure. Count: 321
2019-04-09T01:12:51.213Z,1554772371.213 [CBIT](FAULT): Main Battery Failure. Count: 331
2019-04-09T01:12:55.241Z,1554772375.241 [CBIT](FAULT): Main Battery Failure. Count: 341
2019-04-09T01:12:56.044Z,1554772376.044 [Startup:StartupSatComms:A](INFO): Timed out from 2019-04-09T01:11:55.9Z
2019-04-09T01:12:56.044Z,1554772376.044 [Startup:StartupSatComms:A] Stopped
2019-04-09T01:12:56.044Z,1554772376.044 [Startup:StartupSatComms:B] Running Loop=1
2019-04-09T01:12:56.451Z,1554772376.451 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-09T01:12:56.824Z,1554772376.824 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:12:56.825Z,1554772376.825 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2019-04-09T01:12:56.825Z,1554772376.825 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:12:56.856Z,1554772376.856 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:12:57.227Z,1554772377.227 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:12:58.089Z,1554772378.089 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:12:58.089Z,1554772378.089 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2019-04-09T01:12:59.288Z,1554772379.288 [CBIT](FAULT): Main Battery Failure. Count: 351
2019-04-09T01:13:00.458Z,1554772380.458 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:13:00.459Z,1554772380.459 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:13:03.337Z,1554772383.337 [CBIT](FAULT): Main Battery Failure. Count: 361
2019-04-09T01:13:07.384Z,1554772387.384 [CBIT](FAULT): Main Battery Failure. Count: 371
2019-04-09T01:13:11.417Z,1554772391.417 [CBIT](FAULT): Main Battery Failure. Count: 381
2019-04-09T01:13:15.472Z,1554772395.472 [CBIT](FAULT): Main Battery Failure. Count: 391
2019-04-09T01:13:19.498Z,1554772399.498 [CBIT](FAULT): Main Battery Failure. Count: 401
2019-04-09T01:13:19.968Z,1554772399.968 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005067
2019-04-09T01:13:20.658Z,1554772400.658 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:13:20.659Z,1554772400.659 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7
2019-04-09T01:13:20.659Z,1554772400.659 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:13:20.693Z,1554772400.693 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:13:21.062Z,1554772401.062 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:13:21.916Z,1554772401.916 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:13:21.916Z,1554772401.916 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7
2019-04-09T01:13:23.545Z,1554772403.545 [CBIT](FAULT): Main Battery Failure. Count: 411
2019-04-09T01:13:24.294Z,1554772404.294 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:13:24.294Z,1554772404.294 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:13:25.022Z,1554772405.022 [DataOverHttps](INFO): Sending 79 bytes from file Logs/20190409T005229/Courier0007.lzma
2019-04-09T01:13:25.828Z,1554772405.828 [DataOverHttps](INFO): Moved sent file to Logs/20190409T005229/Courier0007.lzma.bak
2019-04-09T01:13:25.828Z,1554772405.828 [DataOverHttps](INFO): SBD MOMSN=10492881
2019-04-09T01:13:27.570Z,1554772407.570 [CBIT](FAULT): Main Battery Failure. Count: 421
2019-04-09T01:13:31.601Z,1554772411.601 [CBIT](FAULT): Main Battery Failure. Count: 431
2019-04-09T01:13:35.652Z,1554772415.652 [CBIT](FAULT): Main Battery Failure. Count: 441
2019-04-09T01:13:37.590Z,1554772417.590 [DataOverHttps](INFO): Sending 349 bytes from file Logs/20190409T010736/Courier0000.lzma
2019-04-09T01:13:38.395Z,1554772418.395 [DataOverHttps](INFO): Moved sent file to Logs/20190409T010736/Courier0000.lzma.bak
2019-04-09T01:13:38.396Z,1554772418.395 [DataOverHttps](INFO): SBD MOMSN=10492884
2019-04-09T01:13:39.689Z,1554772419.689 [CBIT](FAULT): Main Battery Failure. Count: 451
2019-04-09T01:13:40.917Z,1554772420.917 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T01:13:40.917Z,1554772420.917 [Aanderaa_O2] No Fault, FailCount= 3
2019-04-09T01:13:43.721Z,1554772423.721 [CBIT](FAULT): Main Battery Failure. Count: 461
2019-04-09T01:13:44.495Z,1554772424.495 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:13:44.495Z,1554772424.495 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 8
2019-04-09T01:13:44.495Z,1554772424.495 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:13:44.533Z,1554772424.533 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:13:44.533Z,1554772424.533 [CBIT](FAULT): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:13:44.898Z,1554772424.898 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:13:47.770Z,1554772427.770 [CBIT](FAULT): Main Battery Failure. Count: 471
2019-04-09T01:13:49.784Z,1554772429.784 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T01:13:49.784Z,1554772429.784 [CTD_Seabird] No Fault, FailCount= 3
2019-04-09T01:13:50.037Z,1554772430.037 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190409T010736/Courier0004.lzma
2019-04-09T01:13:50.102Z,1554772430.102 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T01:13:50.104Z,1554772430.104 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T01:13:50.843Z,1554772430.843 [DataOverHttps](INFO): Moved sent file to Logs/20190409T010736/Courier0004.lzma.bak
2019-04-09T01:13:50.844Z,1554772430.844 [DataOverHttps](INFO): SBD MOMSN=10492898
2019-04-09T01:13:51.362Z,1554772431.362 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T01:13:51.362Z,1554772431.362 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T01:13:51.362Z,1554772431.362 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T01:13:51.424Z,1554772431.424 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T01:13:51.886Z,1554772431.886 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:13:51.923Z,1554772431.923 [CBIT](FAULT): Main Battery Failure. Count: 481
2019-04-09T01:13:52.611Z,1554772432.611 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T01:13:52.611Z,1554772432.611 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-09T01:13:55.854Z,1554772435.854 [CBIT](FAULT): Main Battery Failure. Count: 491
2019-04-09T01:13:56.250Z,1554772436.250 [Startup:StartupSatComms:B](INFO): Timed out from 2019-04-09T01:12:56.0Z
2019-04-09T01:13:56.250Z,1554772436.250 [Startup:StartupSatComms:B] Stopped
2019-04-09T01:13:56.251Z,1554772436.251 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-04-09T01:13:56.251Z,1554772436.251 [Startup:StartupSatComms] Stopped
2019-04-09T01:13:56.251Z,1554772436.251 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-04-09T01:13:56.251Z,1554772436.251 [Startup](INFO): Completed Startup
2019-04-09T01:13:56.252Z,1554772436.252 [MissionManager](INFO): Startup is completed.
2019-04-09T01:13:56.252Z,1554772436.252 [MissionManager](INFO): Uninitializing Mission Startup
2019-04-09T01:13:56.252Z,1554772436.252 [Startup] Stopped
2019-04-09T01:13:56.252Z,1554772436.252 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-04-09T01:13:56.252Z,1554772436.252 [Startup:A.GoToSurface] Stopped
2019-04-09T01:13:56.252Z,1554772436.252 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-09T01:13:56.659Z,1554772436.659 [MissionManager](IMPORTANT): Started mission Default
2019-04-09T01:13:56.659Z,1554772436.659 [Default] Running Loop=1
2019-04-09T01:13:56.659Z,1554772436.659 [Default](DEBUG): Aggregate::initialize Default
2019-04-09T01:13:56.659Z,1554772436.659 [Default:B.GoToSurface] Running Loop=1
2019-04-09T01:13:56.659Z,1554772436.659 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-09T01:13:56.660Z,1554772436.660 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-09T01:13:56.660Z,1554772436.660 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-09T01:13:56.660Z,1554772436.660 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-09T01:13:56.661Z,1554772436.661 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-09T01:13:56.661Z,1554772436.661 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-09T01:13:56.661Z,1554772436.661 [Default:A.Wait] Running Loop=1
2019-04-09T01:13:56.661Z,1554772436.661 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-04-09T01:13:59.881Z,1554772439.881 [CBIT](FAULT): Main Battery Failure. Count: 501
2019-04-09T01:14:02.170Z,1554772442.170 [DataOverHttps](INFO): Sending 374 bytes from file Logs/20190409T011026/Courier0000.lzma
2019-04-09T01:14:02.975Z,1554772442.975 [DataOverHttps](INFO): Moved sent file to Logs/20190409T011026/Courier0000.lzma.bak
2019-04-09T01:14:02.975Z,1554772442.975 [DataOverHttps](INFO): SBD MOMSN=10492900
2019-04-09T01:14:03.078Z,1554772443.078 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T01:14:03.078Z,1554772443.078 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-09T01:14:03.078Z,1554772443.078 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T01:14:03.130Z,1554772443.130 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T01:14:03.166Z,1554772443.166 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T01:14:03.166Z,1554772443.166 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-09T01:14:03.166Z,1554772443.166 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T01:14:03.570Z,1554772443.570 [CTD_Seabird](INFO): Powering down
2019-04-09T01:14:03.602Z,1554772443.602 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:14:03.653Z,1554772443.653 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T01:14:03.933Z,1554772443.933 [CBIT](FAULT): Main Battery Failure. Count: 511
2019-04-09T01:14:04.328Z,1554772444.328 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T01:14:04.328Z,1554772444.328 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-09T01:14:04.728Z,1554772444.728 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T01:14:04.728Z,1554772444.728 [CTD_Seabird] No Fault, FailCount= 1
2019-04-09T01:14:04.914Z,1554772444.914 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T01:14:04.916Z,1554772444.916 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T01:14:07.961Z,1554772447.961 [CBIT](FAULT): Main Battery Failure. Count: 521
2019-04-09T01:14:09.980Z,1554772449.980 [Default:A.Wait](INFO): Done Waiting.
2019-04-09T01:14:09.980Z,1554772449.980 [Default:A.Wait] Stopped
2019-04-09T01:14:09.980Z,1554772449.980 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-04-09T01:14:10.382Z,1554772450.382 [Default:CheckIn] Running Loop=1
2019-04-09T01:14:10.382Z,1554772450.382 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-09T01:14:10.382Z,1554772450.382 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-09T01:14:10.784Z,1554772450.784 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-04-09T01:14:12.012Z,1554772452.012 [CBIT](FAULT): Main Battery Failure. Count: 531
2019-04-09T01:14:14.794Z,1554772454.794 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T01:14:14.794Z,1554772454.794 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-09T01:14:14.795Z,1554772454.795 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T01:14:14.833Z,1554772454.833 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T01:14:14.833Z,1554772454.833 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-09T01:14:15.318Z,1554772455.318 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:14:16.042Z,1554772456.042 [CBIT](FAULT): Main Battery Failure. Count: 541
2019-04-09T01:14:17.966Z,1554772457.966 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T01:14:17.966Z,1554772457.966 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-09T01:14:17.966Z,1554772457.966 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T01:14:18.058Z,1554772458.058 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T01:14:18.370Z,1554772458.370 [CTD_Seabird](INFO): Powering down
2019-04-09T01:14:19.688Z,1554772459.688 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T01:14:19.688Z,1554772459.688 [CTD_Seabird] No Fault, FailCount= 2
2019-04-09T01:14:19.718Z,1554772459.718 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T01:14:19.720Z,1554772459.720 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T01:14:20.085Z,1554772460.085 [CBIT](FAULT): Main Battery Failure. Count: 551
2019-04-09T01:14:24.115Z,1554772464.115 [CBIT](FAULT): Main Battery Failure. Count: 561
2019-04-09T01:14:28.187Z,1554772468.187 [CBIT](FAULT): Main Battery Failure. Count: 571
2019-04-09T01:14:32.212Z,1554772472.212 [CBIT](FAULT): Main Battery Failure. Count: 581
2019-04-09T01:14:32.770Z,1554772472.770 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T01:14:32.770Z,1554772472.770 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-09T01:14:32.770Z,1554772472.770 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T01:14:33.024Z,1554772473.024 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T01:14:33.024Z,1554772473.024 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-09T01:14:33.174Z,1554772473.174 [CTD_Seabird](INFO): Powering down
2019-04-09T01:14:36.235Z,1554772476.235 [CBIT](FAULT): Main Battery Failure. Count: 591
2019-04-09T01:14:38.264Z,1554772478.264 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T01:14:38.264Z,1554772478.264 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-04-09T01:14:38.264Z,1554772478.264 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T01:14:38.265Z,1554772478.265 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T01:14:38.265Z,1554772478.265 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-04-09T01:14:38.266Z,1554772478.266 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T01:14:38.267Z,1554772478.267 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T01:14:38.267Z,1554772478.267 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-04-09T01:14:38.267Z,1554772478.267 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T01:14:38.315Z,1554772478.315 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T01:14:38.316Z,1554772478.316 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T01:14:38.316Z,1554772478.316 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T01:14:38.679Z,1554772478.679 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T01:14:38.679Z,1554772478.679 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-04-09T01:14:38.680Z,1554772478.680 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T01:14:38.680Z,1554772478.680 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-04-09T01:14:38.680Z,1554772478.680 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T01:14:38.680Z,1554772478.680 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-04-09T01:14:39.065Z,1554772479.065 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T01:14:40.285Z,1554772480.285 [CBIT](FAULT): Main Battery Failure. Count: 601
2019-04-09T01:14:44.325Z,1554772484.325 [CBIT](FAULT): Main Battery Failure. Count: 611
2019-04-09T01:14:48.380Z,1554772488.380 [CBIT](FAULT): Main Battery Failure. Count: 621
2019-04-09T01:14:52.402Z,1554772492.402 [CBIT](FAULT): Main Battery Failure. Count: 631
2019-04-09T01:14:56.437Z,1554772496.437 [CBIT](FAULT): Main Battery Failure. Count: 641
2019-04-09T01:15:00.490Z,1554772500.490 [CBIT](FAULT): Main Battery Failure. Count: 651
2019-04-09T01:15:04.542Z,1554772504.542 [CBIT](FAULT): Main Battery Failure. Count: 661
2019-04-09T01:15:08.562Z,1554772508.562 [CBIT](FAULT): Main Battery Failure. Count: 671
2019-04-09T01:15:12.602Z,1554772512.602 [CBIT](FAULT): Main Battery Failure. Count: 681
2019-04-09T01:15:16.642Z,1554772516.642 [CBIT](FAULT): Main Battery Failure. Count: 691
2019-04-09T01:15:20.692Z,1554772520.692 [CBIT](FAULT): Main Battery Failure. Count: 701
2019-04-09T01:15:24.741Z,1554772524.741 [CBIT](FAULT): Main Battery Failure. Count: 711
2019-04-09T01:15:28.770Z,1554772528.770 [CBIT](FAULT): Main Battery Failure. Count: 721
2019-04-09T01:15:32.801Z,1554772532.801 [CBIT](FAULT): Main Battery Failure. Count: 731
2019-04-09T01:15:36.842Z,1554772536.842 [CBIT](FAULT): Main Battery Failure. Count: 741
2019-04-09T01:15:37.639Z,1554772537.639 [BPC1](FAULT): Failed to initialize
2019-04-09T01:15:37.639Z,1554772537.639 [BPC1] Communications Fault, FailCount= 1
2019-04-09T01:15:37.639Z,1554772537.639 [BPC1](ERROR): Communications Fault
2019-04-09T01:15:37.654Z,1554772537.654 [CBIT](ERROR): Communications Fault in component: BPC1
2019-04-09T01:15:40.897Z,1554772540.897 [CBIT](FAULT): Main Battery Failure. Count: 751
2019-04-09T01:15:44.925Z,1554772544.925 [CBIT](FAULT): Main Battery Failure. Count: 761
2019-04-09T01:15:48.962Z,1554772548.962 [CBIT](FAULT): Main Battery Failure. Count: 771
2019-04-09T01:15:53.001Z,1554772553.001 [CBIT](FAULT): Main Battery Failure. Count: 781
2019-04-09T01:15:55.438Z,1554772555.438 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T01:15:55.438Z,1554772555.438 [MassServo] No Fault, FailCount= 5
2019-04-09T01:15:57.362Z,1554772557.362 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:15:57.362Z,1554772557.362 [MassServo] Communications Fault, FailCount= 1
2019-04-09T01:15:57.362Z,1554772557.362 [MassServo](ERROR): Communications Fault
2019-04-09T01:15:57.364Z,1554772557.364 [CBIT](FAULT): Main Battery Failure. Count: 791
2019-04-09T01:15:57.366Z,1554772557.366 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:15:57.464Z,1554772557.464 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:15:57.464Z,1554772557.464 [MassServo](INFO): Powering down
2019-04-09T01:15:58.256Z,1554772558.256 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:15:58.375Z,1554772558.375 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:15:58.379Z,1554772558.379 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T01:15:58.379Z,1554772558.379 [MassServo] No Fault, FailCount= 1
2019-04-09T01:16:00.994Z,1554772560.994 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:16:00.994Z,1554772560.994 [MassServo] Communications Fault, FailCount= 2
2019-04-09T01:16:00.994Z,1554772560.994 [MassServo](ERROR): Communications Fault
2019-04-09T01:16:00.998Z,1554772560.998 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:16:01.092Z,1554772561.092 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:16:01.092Z,1554772561.092 [MassServo](INFO): Powering down
2019-04-09T01:16:01.099Z,1554772561.099 [CBIT](FAULT): Main Battery Failure. Count: 801
2019-04-09T01:16:01.898Z,1554772561.898 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:16:02.015Z,1554772562.015 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:16:02.019Z,1554772562.019 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T01:16:02.019Z,1554772562.019 [MassServo] No Fault, FailCount= 2
2019-04-09T01:16:04.614Z,1554772564.614 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:16:04.614Z,1554772564.614 [MassServo] Communications Fault, FailCount= 3
2019-04-09T01:16:04.614Z,1554772564.614 [MassServo](ERROR): Communications Fault
2019-04-09T01:16:04.618Z,1554772564.618 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:16:04.732Z,1554772564.732 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:16:04.732Z,1554772564.732 [MassServo](INFO): Powering down
2019-04-09T01:16:05.130Z,1554772565.130 [CBIT](FAULT): Main Battery Failure. Count: 811
2019-04-09T01:16:05.532Z,1554772565.532 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:16:05.651Z,1554772565.651 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:16:05.655Z,1554772565.655 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T01:16:05.655Z,1554772565.655 [MassServo] No Fault, FailCount= 3
2019-04-09T01:16:08.270Z,1554772568.270 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:16:08.270Z,1554772568.270 [MassServo] Communications Fault, FailCount= 4
2019-04-09T01:16:08.270Z,1554772568.270 [MassServo](ERROR): Communications Fault
2019-04-09T01:16:08.274Z,1554772568.274 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:16:08.368Z,1554772568.368 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:16:08.368Z,1554772568.368 [MassServo](INFO): Powering down
2019-04-09T01:16:09.160Z,1554772569.160 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:16:09.279Z,1554772569.279 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:16:09.281Z,1554772569.281 [CBIT](FAULT): Main Battery Failure. Count: 821
2019-04-09T01:16:09.283Z,1554772569.283 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T01:16:09.283Z,1554772569.283 [MassServo] No Fault, FailCount= 4
2019-04-09T01:16:11.890Z,1554772571.890 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:16:11.890Z,1554772571.890 [MassServo] Communications Fault, FailCount= 5
2019-04-09T01:16:11.890Z,1554772571.890 [MassServo](ERROR): Communications Fault
2019-04-09T01:16:11.894Z,1554772571.894 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:16:11.895Z,1554772571.895 [CBIT](CRITICAL): Communications Fault in component: MassServo
2019-04-09T01:16:12.023Z,1554772572.023 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:16:12.023Z,1554772572.023 [MassServo](INFO): Powering down
2019-04-09T01:16:12.026Z,1554772572.026 [CBIT](INFO): Critical error at 20190409T011611
2019-04-09T01:16:12.819Z,1554772572.819 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:16:12.939Z,1554772572.939 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:16:13.213Z,1554772573.213 [CBIT](FAULT): Main Battery Failure. Count: 831
2019-04-09T01:16:17.253Z,1554772577.253 [CBIT](FAULT): Main Battery Failure. Count: 841
2019-04-09T01:16:18.832Z,1554772578.832 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-04-09T01:16:19.654Z,1554772579.654 [NAL9602](DEBUG): Fix Requested
2019-04-09T01:16:21.281Z,1554772581.281 [CBIT](FAULT): Main Battery Failure. Count: 851
2019-04-09T01:16:24.502Z,1554772584.502 [NAL9602](DEBUG): Fix Requested
2019-04-09T01:16:25.334Z,1554772585.334 [CBIT](FAULT): Main Battery Failure. Count: 861
2019-04-09T01:16:27.330Z,1554772587.330 [NAL9602](DEBUG): Fix Requested
2019-04-09T01:16:29.362Z,1554772589.362 [CBIT](FAULT): Main Battery Failure. Count: 871
2019-04-09T01:16:30.554Z,1554772590.554 [NAL9602](DEBUG): Fix Requested
2019-04-09T01:16:33.386Z,1554772593.386 [NAL9602](DEBUG): Fix Requested
2019-04-09T01:16:33.412Z,1554772593.412 [CBIT](FAULT): Main Battery Failure. Count: 881
2019-04-09T01:16:36.618Z,1554772596.618 [NAL9602](DEBUG): Fix Requested
2019-04-09T01:16:37.453Z,1554772597.453 [CBIT](FAULT): Main Battery Failure. Count: 891
2019-04-09T01:16:39.446Z,1554772599.446 [NAL9602](DEBUG): Fix Requested
2019-04-09T01:16:39.469Z,1554772599.469 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T01:16:39.469Z,1554772599.469 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-04-09T01:16:39.469Z,1554772599.469 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T01:16:39.471Z,1554772599.471 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T01:16:39.471Z,1554772599.471 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-04-09T01:16:39.471Z,1554772599.471 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T01:16:39.473Z,1554772599.473 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T01:16:39.473Z,1554772599.473 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2019-04-09T01:16:39.473Z,1554772599.473 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T01:16:39.483Z,1554772599.483 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T01:16:39.484Z,1554772599.484 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T01:16:39.484Z,1554772599.484 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T01:16:39.889Z,1554772599.889 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T01:16:39.889Z,1554772599.889 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-04-09T01:16:39.890Z,1554772599.890 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T01:16:39.890Z,1554772599.890 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-04-09T01:16:39.891Z,1554772599.891 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T01:16:39.891Z,1554772599.891 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2019-04-09T01:16:40.273Z,1554772600.273 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T01:16:41.490Z,1554772601.490 [CBIT](FAULT): Main Battery Failure. Count: 901
2019-04-09T01:16:42.274Z,1554772602.274 [NAL9602](DEBUG): Fix Requested
2019-04-09T01:16:44.302Z,1554772604.302 [NAL9602](DEBUG): Fix Requested
2019-04-09T01:16:45.152Z,1554772605.152 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T01:16:45.152Z,1554772605.152 [Aanderaa_O2] No Fault, FailCount= 3
2019-04-09T01:16:45.572Z,1554772605.572 [CBIT](FAULT): Main Battery Failure. Count: 911
2019-04-09T01:16:47.538Z,1554772607.538 [NAL9602](DEBUG): Fix Requested
2019-04-09T01:16:49.585Z,1554772609.585 [CBIT](FAULT): Main Battery Failure. Count: 921
2019-04-09T01:16:50.366Z,1554772610.366 [NAL9602](DEBUG): Fix Requested
2019-04-09T01:16:53.120Z,1554772613.120 [CommandLine](IMPORTANT): got command restart application
2019-04-09T01:16:53.598Z,1554772613.598 [NAL9602](DEBUG): Fix Requested
2019-04-09T01:16:53.640Z,1554772613.640 [CBIT](FAULT): Main Battery Failure. Count: 931
2019-04-09T01:16:54.126Z,1554772614.126 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:16:54.126Z,1554772614.126 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:16:54.306Z,1554772614.306 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-04-09T01:16:54.306Z,1554772614.306 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:16:54.307Z,1554772614.307 [CommandLine](INFO): Join timeout helper Thread ID is 5483
2019-04-09T01:16:54.307Z,1554772614.307 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-04-09T01:16:54.307Z,1554772614.307 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:16:54.308Z,1554772614.308 [NavChartDb](INFO): Join timeout helper Thread ID is 5484
2019-04-09T01:16:54.626Z,1554772614.626 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:16:54.626Z,1554772614.626 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:16:54.630Z,1554772614.630 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-04-09T01:16:54.630Z,1554772614.630 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:16:54.631Z,1554772614.631 [Radio_Surface](INFO): Join timeout helper Thread ID is 5485
2019-04-09T01:16:54.754Z,1554772614.754 [Radio_Surface](INFO): Powering down
2019-04-09T01:16:54.755Z,1554772614.755 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:16:54.755Z,1554772614.755 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:16:54.770Z,1554772614.770 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-04-09T01:16:54.770Z,1554772614.770 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:16:54.770Z,1554772614.770 [DataOverHttps](INFO): Join timeout helper Thread ID is 5486
2019-04-09T01:16:55.026Z,1554772615.026 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:16:55.026Z,1554772615.026 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:16:55.046Z,1554772615.046 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-04-09T01:16:55.046Z,1554772615.046 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:16:55.046Z,1554772615.046 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5487
2019-04-09T01:16:55.134Z,1554772615.134 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:16:55.135Z,1554772615.135 [WetLabsBB2FL](INFO): Powering down
2019-04-09T01:16:55.135Z,1554772615.135 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:16:55.147Z,1554772615.147 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-04-09T01:16:55.147Z,1554772615.147 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:16:55.147Z,1554772615.147 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5488
2019-04-09T01:16:55.522Z,1554772615.522 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:16:55.522Z,1554772615.522 [CTD_Seabird](INFO): Powering down
2019-04-09T01:16:55.534Z,1554772615.534 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:16:55.547Z,1554772615.547 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-04-09T01:16:55.547Z,1554772615.547 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:16:55.547Z,1554772615.547 [logger](INFO): Join timeout helper Thread ID is 5489
2019-04-09T01:16:55.598Z,1554772615.598 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T01:16:55.598Z,1554772615.598 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T01:16:55.598Z,1554772615.598 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T01:16:55.602Z,1554772615.602 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:16:55.602Z,1554772615.602 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:16:55.608Z,1554772615.608 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-04-09T01:16:55.608Z,1554772615.608 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:16:55.608Z,1554772615.608 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-04-09T01:16:55.608Z,1554772615.608 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:16:55.608Z,1554772615.608 [controlThread](INFO): Join timeout helper Thread ID is 5490
2019-04-09T01:16:55.636Z,1554772615.636 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T01:16:55.637Z,1554772615.637 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:16:55.638Z,1554772615.638 [controlThread](DEBUG): Uninitializing ControlThread
2019-04-09T01:16:55.758Z,1554772615.758 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:16:55.760Z,1554772615.760 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:16:55.761Z,1554772615.761 [NAL9602](INFO): Powering down
2019-04-09T01:16:55.763Z,1554772615.763 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:16:55.764Z,1554772615.764 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-04-09T01:16:55.765Z,1554772615.765 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-04-09T01:16:55.766Z,1554772615.766 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-04-09T01:16:55.766Z,1554772615.766 [MissionManager](INFO): Uninitializing Mission Default
2019-04-09T01:16:55.766Z,1554772615.766 [Default] Stopped
2019-04-09T01:16:55.767Z,1554772615.767 [Default](DEBUG): Aggregate::uninitialize Default
2019-04-09T01:16:55.767Z,1554772615.767 [Default:B.GoToSurface] Stopped
2019-04-09T01:16:55.767Z,1554772615.767 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-09T01:16:55.767Z,1554772615.767 [Default:CheckIn] Stopped
2019-04-09T01:16:55.767Z,1554772615.767 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-09T01:16:55.767Z,1554772615.767 [Default:CheckIn:Read_GPS] Stopped
2019-04-09T01:16:55.769Z,1554772615.769 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-04-09T01:16:55.770Z,1554772615.770 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-04-09T01:16:55.770Z,1554772615.770 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-04-09T01:16:55.770Z,1554772615.770 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-04-09T01:16:55.771Z,1554772615.771 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-04-09T01:16:55.771Z,1554772615.771 [BuoyancyServo](INFO): Powering down
2019-04-09T01:16:55.782Z,1554772615.782 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-09T01:16:55.782Z,1554772615.782 [ElevatorServo](INFO): Powering down
2019-04-09T01:16:55.783Z,1554772615.783 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:16:55.783Z,1554772615.783 [MassServo](INFO): Powering down
2019-04-09T01:16:55.784Z,1554772615.784 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-09T01:16:55.784Z,1554772615.784 [RudderServo](INFO): Powering down
2019-04-09T01:16:55.785Z,1554772615.785 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-04-09T01:16:55.785Z,1554772615.785 [ThrusterServo](INFO): Powering down
2019-04-09T01:16:55.786Z,1554772615.786 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-04-09T01:16:55.786Z,1554772615.786 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-04-09T01:16:55.786Z,1554772615.786 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-04-09T01:16:55.787Z,1554772615.787 [CBIT](DEBUG): Powering off loads.
2019-04-09T01:16:55.798Z,1554772615.798 [CBIT](DEBUG): Disabling WDT.
2019-04-09T01:16:55.810Z,1554772615.810 [CBIT](DEBUG): Opening all GF detection circuits.
2019-04-09T01:16:55.811Z,1554772615.811 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:16:55.969Z,1554772615.969 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:16:55.974Z,1554772615.974 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:16:56.041Z,1554772616.041 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:16:56.046Z,1554772616.046 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:16:56.097Z,1554772616.097 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:16:56.156Z,1554772616.156 [logger ThreadHandler](INFO): Thread cancelled.