2019-04-09T14:52:46.678Z,1554821566.678 [Supervisor](DEBUG): Initializing supervisor.
2019-04-09T14:52:46.681Z,1554821566.681 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-09T14:52:46.681Z,1554821566.681 [SyncHandler](INFO): Protected caller Thread ID is 1788
2019-04-09T14:52:46.682Z,1554821566.682 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-04-09T14:52:46.683Z,1554821566.683 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-04-09T14:52:46.684Z,1554821566.684 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1789
2019-04-09T14:52:46.687Z,1554821566.687 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-04-09T14:52:46.699Z,1554821566.699 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-04-09T14:52:46.700Z,1554821566.700 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-04-09T14:52:46.700Z,1554821566.700 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1790
2019-04-09T14:52:46.701Z,1554821566.701 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-04-09T14:52:46.702Z,1554821566.702 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-04-09T14:52:46.703Z,1554821566.703 [logger ThreadHandler](INFO): Protected caller Thread ID is 1791
2019-04-09T14:52:46.705Z,1554821566.705 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-04-09T14:52:46.705Z,1554821566.705 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-04-09T14:52:46.707Z,1554821566.707 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-04-09T14:52:46.801Z,1554821566.801 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-04-09T14:52:46.802Z,1554821566.802 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-04-09T14:52:47.012Z,1554821567.012 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-04-09T14:52:47.013Z,1554821567.013 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-04-09T14:52:47.149Z,1554821567.149 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-04-09T14:52:47.149Z,1554821567.149 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-04-09T14:52:47.606Z,1554821567.606 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-04-09T14:52:47.607Z,1554821567.607 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-04-09T14:52:48.077Z,1554821568.077 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-04-09T14:52:48.077Z,1554821568.077 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-04-09T14:52:48.597Z,1554821568.597 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-04-09T14:52:48.598Z,1554821568.598 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-04-09T14:52:48.903Z,1554821568.903 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-04-09T14:52:48.904Z,1554821568.904 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-04-09T14:52:49.234Z,1554821569.234 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-04-09T14:52:49.234Z,1554821569.234 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-04-09T14:52:49.628Z,1554821569.628 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-04-09T14:52:49.629Z,1554821569.629 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-04-09T14:52:49.778Z,1554821569.778 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-04-09T14:52:49.778Z,1554821569.778 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-04-09T14:52:49.883Z,1554821569.883 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-04-09T14:52:49.883Z,1554821569.883 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-04-09T14:52:49.964Z,1554821569.964 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-04-09T14:52:50.065Z,1554821570.065 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-04-09T14:52:50.066Z,1554821570.066 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-04-09T14:52:50.259Z,1554821570.259 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-04-09T14:52:50.260Z,1554821570.260 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-04-09T14:52:50.467Z,1554821570.467 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-04-09T14:52:50.469Z,1554821570.469 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-04-09T14:52:50.469Z,1554821570.469 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-04-09T14:52:50.552Z,1554821570.552 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-04-09T14:52:50.784Z,1554821570.784 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-04-09T14:52:50.784Z,1554821570.784 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-04-09T14:52:50.879Z,1554821570.879 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-04-09T14:52:51.053Z,1554821571.053 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-04-09T14:52:51.265Z,1554821571.265 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-04-09T14:52:51.349Z,1554821571.349 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-04-09T14:52:51.447Z,1554821571.447 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-04-09T14:52:51.540Z,1554821571.540 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-04-09T14:52:51.691Z,1554821571.691 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-04-09T14:52:51.795Z,1554821571.795 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-04-09T14:52:51.892Z,1554821571.892 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2019-04-09T14:52:51.893Z,1554821571.893 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-04-09T14:52:51.897Z,1554821571.897 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-04-09T14:52:51.996Z,1554821571.996 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-04-09T14:52:51.996Z,1554821571.996 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-04-09T14:52:52.125Z,1554821572.125 [BuoyancyServo] Loaded
2019-04-09T14:52:52.125Z,1554821572.125 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-04-09T14:52:52.140Z,1554821572.140 [ElevatorServo] Loaded
2019-04-09T14:52:52.141Z,1554821572.141 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-04-09T14:52:52.156Z,1554821572.156 [MassServo] Loaded
2019-04-09T14:52:52.156Z,1554821572.156 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-04-09T14:52:52.171Z,1554821572.171 [RudderServo] Loaded
2019-04-09T14:52:52.172Z,1554821572.172 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-04-09T14:52:52.186Z,1554821572.186 [ThrusterServo] Loaded
2019-04-09T14:52:52.186Z,1554821572.186 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-04-09T14:52:52.187Z,1554821572.187 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-04-09T14:52:52.187Z,1554821572.187 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-04-09T14:52:52.323Z,1554821572.323 [SBIT](DEBUG): Construct Startup Built In Test.
2019-04-09T14:52:52.352Z,1554821572.352 [SBIT] Loaded
2019-04-09T14:52:52.352Z,1554821572.352 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-04-09T14:52:52.353Z,1554821572.353 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-04-09T14:52:52.381Z,1554821572.381 [IBIT] Loaded
2019-04-09T14:52:52.381Z,1554821572.381 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-04-09T14:52:52.385Z,1554821572.385 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-04-09T14:52:52.775Z,1554821572.775 [CBIT] Loaded
2019-04-09T14:52:52.775Z,1554821572.775 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-04-09T14:52:52.775Z,1554821572.775 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-04-09T14:52:52.776Z,1554821572.776 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-04-09T14:52:52.959Z,1554821572.959 [Aanderaa_O2] Loaded
2019-04-09T14:52:52.960Z,1554821572.960 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-04-09T14:52:52.969Z,1554821572.969 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-04-09T14:52:52.975Z,1554821572.975 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-04-09T14:52:52.976Z,1554821572.976 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-04-09T14:52:52.981Z,1554821572.981 [CTD_Seabird](INFO): created writer for : depth
2019-04-09T14:52:52.982Z,1554821572.982 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-04-09T14:52:52.987Z,1554821572.987 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-04-09T14:52:52.988Z,1554821572.988 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-04-09T14:52:52.993Z,1554821572.993 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-04-09T14:52:52.993Z,1554821572.993 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-04-09T14:52:52.999Z,1554821572.999 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-04-09T14:52:52.000Z,1554821573.000 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-04-09T14:52:53.005Z,1554821573.005 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-04-09T14:52:53.006Z,1554821573.006 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-04-09T14:52:53.011Z,1554821573.011 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-04-09T14:52:53.038Z,1554821573.038 [CTD_Seabird] Loaded
2019-04-09T14:52:53.038Z,1554821573.038 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-04-09T14:52:53.039Z,1554821573.039 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406654E0
2019-04-09T14:52:53.040Z,1554821573.040 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1870
2019-04-09T14:52:53.065Z,1554821573.065 [ESPComponent] Loaded
2019-04-09T14:52:53.066Z,1554821573.066 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-04-09T14:52:53.080Z,1554821573.080 [PAR_Licor] Loaded
2019-04-09T14:52:53.080Z,1554821573.080 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-04-09T14:52:53.128Z,1554821573.128 [WetLabsBB2FL] Loaded
2019-04-09T14:52:53.129Z,1554821573.129 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-04-09T14:52:53.130Z,1554821573.130 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406954E0
2019-04-09T14:52:53.130Z,1554821573.130 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1871
2019-04-09T14:52:53.131Z,1554821573.131 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-04-09T14:52:53.131Z,1554821573.131 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-04-09T14:52:53.265Z,1554821573.265 [DepthRateCalculator] Loaded
2019-04-09T14:52:53.265Z,1554821573.265 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-04-09T14:52:53.271Z,1554821573.271 [PitchRateCalculator] Loaded
2019-04-09T14:52:53.271Z,1554821573.271 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-04-09T14:52:53.331Z,1554821573.331 [SpeedCalculator] Loaded
2019-04-09T14:52:53.332Z,1554821573.332 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-04-09T14:52:53.353Z,1554821573.353 [TempGradientCalculator] Loaded
2019-04-09T14:52:53.354Z,1554821573.354 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-04-09T14:52:53.360Z,1554821573.360 [YawRateCalculator] Loaded
2019-04-09T14:52:53.360Z,1554821573.360 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-04-09T14:52:53.393Z,1554821573.393 [ElevatorOffsetCalculator] Loaded
2019-04-09T14:52:53.393Z,1554821573.393 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-04-09T14:52:53.394Z,1554821573.394 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-04-09T14:52:53.394Z,1554821573.394 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-04-09T14:52:53.476Z,1554821573.476 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-04-09T14:52:53.477Z,1554821573.477 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-04-09T14:52:53.876Z,1554821573.876 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-04-09T14:52:53.877Z,1554821573.877 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-04-09T14:52:53.941Z,1554821573.941 [DeadReckonUsingSpeedCalculator] Loaded
2019-04-09T14:52:53.942Z,1554821573.942 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-04-09T14:52:54.002Z,1554821574.002 [DeadReckonWithRespectToSeafloor] Loaded
2019-04-09T14:52:54.003Z,1554821574.003 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-04-09T14:52:54.023Z,1554821574.023 [NavChart] Loaded
2019-04-09T14:52:54.024Z,1554821574.024 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-04-09T14:52:54.028Z,1554821574.028 [UniversalFixResidualReporter] Loaded
2019-04-09T14:52:54.028Z,1554821574.028 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-04-09T14:52:54.029Z,1554821574.029 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-04-09T14:52:54.029Z,1554821574.029 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-04-09T14:52:54.502Z,1554821574.502 [AcousticModem_Benthos_ATM900] Loaded
2019-04-09T14:52:54.503Z,1554821574.503 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-04-09T14:52:54.575Z,1554821574.575 [DataOverHttps] Loaded
2019-04-09T14:52:54.575Z,1554821574.575 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-04-09T14:52:54.576Z,1554821574.576 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409714E0
2019-04-09T14:52:54.576Z,1554821574.576 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1872
2019-04-09T14:52:54.590Z,1554821574.590 [Depth_Keller] Loaded
2019-04-09T14:52:54.590Z,1554821574.590 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-04-09T14:52:54.683Z,1554821574.683 [NAL9602] Loaded
2019-04-09T14:52:54.683Z,1554821574.683 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-04-09T14:52:54.688Z,1554821574.688 [Onboard] Loaded
2019-04-09T14:52:54.689Z,1554821574.689 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-04-09T14:52:54.692Z,1554821574.692 [Radio_Surface] Loaded
2019-04-09T14:52:54.692Z,1554821574.692 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-04-09T14:52:54.693Z,1554821574.693 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A14E0
2019-04-09T14:52:54.694Z,1554821574.694 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1873
2019-04-09T14:52:55.997Z,1554821575.997 [BPC1] Loaded
2019-04-09T14:52:55.997Z,1554821575.997 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-04-09T14:52:55.999Z,1554821575.999 [BR_Ping1D](INFO): constructor
2019-04-09T14:52:56.028Z,1554821576.028 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minrange
2019-04-09T14:52:56.030Z,1554821576.030 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.maxrange
2019-04-09T14:52:56.032Z,1554821576.032 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.soundspeed
2019-04-09T14:52:56.034Z,1554821576.034 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.automode
2019-04-09T14:52:56.037Z,1554821576.037 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.gainindex
2019-04-09T14:52:56.039Z,1554821576.039 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minconfidence
2019-04-09T14:52:56.041Z,1554821576.041 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.pingenable
2019-04-09T14:52:56.043Z,1554821576.043 [BR_Ping1D] Loaded
2019-04-09T14:52:56.044Z,1554821576.044 [ComponentRegistry](DEBUG): SyncComponent "BR_Ping1D" handled in the control thread.
2019-04-09T14:52:56.044Z,1554821576.044 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-04-09T14:52:56.045Z,1554821576.045 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-04-09T14:52:56.109Z,1554821576.109 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-04-09T14:52:56.109Z,1554821576.109 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-04-09T14:52:56.176Z,1554821576.176 [VerticalControl](DEBUG): Construct VerticalControl.
2019-04-09T14:52:56.261Z,1554821576.261 [VerticalControl] Loaded
2019-04-09T14:52:56.261Z,1554821576.261 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-04-09T14:52:56.262Z,1554821576.262 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-04-09T14:52:56.321Z,1554821576.321 [HorizontalControl] Loaded
2019-04-09T14:52:56.321Z,1554821576.321 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-04-09T14:52:56.322Z,1554821576.322 [SpeedControl](DEBUG): Construct SpeedControl.
2019-04-09T14:52:56.323Z,1554821576.323 [SpeedControl] Loaded
2019-04-09T14:52:56.324Z,1554821576.324 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-04-09T14:52:56.324Z,1554821576.324 [LoopControl](DEBUG): Construct LoopControl.
2019-04-09T14:52:56.325Z,1554821576.325 [LoopControl] Loaded
2019-04-09T14:52:56.325Z,1554821576.325 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-04-09T14:52:56.326Z,1554821576.326 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-04-09T14:52:56.326Z,1554821576.326 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-04-09T14:52:56.340Z,1554821576.340 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-04-09T14:52:56.340Z,1554821576.340 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-04-09T14:52:56.617Z,1554821576.617 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-04-09T14:52:56.621Z,1554821576.621 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-04-09T14:52:56.622Z,1554821576.622 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-04-09T14:52:56.629Z,1554821576.629 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-04-09T14:52:56.630Z,1554821576.630 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACE4E0
2019-04-09T14:52:56.630Z,1554821576.630 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1874
2019-04-09T14:52:56.635Z,1554821576.635 [Supervisor](INFO): Main Thread ID is 4347
2019-04-09T14:52:56.635Z,1554821576.635 [Supervisor](DEBUG): Running supervisor.
2019-04-09T14:52:56.636Z,1554821576.636 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1875
2019-04-09T14:52:56.638Z,1554821576.638 [controlThread ThreadHandler](INFO): Handler Thread ID is 1876
2019-04-09T14:52:56.639Z,1554821576.639 [controlThread](DEBUG): Initializing ControlThread
2019-04-09T14:52:56.641Z,1554821576.641 [SBIT](INFO): Initialize SBIT Component.
2019-04-09T14:52:56.641Z,1554821576.641 [SBIT](IMPORTANT): git: 2019-03-18-31-ge98d5dc
2019-04-09T14:52:56.641Z,1554821576.641 [SBIT](INFO): git hash: e98d5dc50cbc39e402f19b9946ff4dc0776c8725
2019-04-09T14:52:56.642Z,1554821576.642 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-04-09T14:52:56.643Z,1554821576.643 [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-09T14:52:56.644Z,1554821576.644 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-04-09T14:52:56.645Z,1554821576.645 [IBIT](INFO): Initialize IBIT Component.
2019-04-09T14:52:56.646Z,1554821576.646 [CBIT](DEBUG): Initialize CBIT Component.
2019-04-09T14:52:56.647Z,1554821576.647 [logger ThreadHandler](INFO): Handler Thread ID is 1877
2019-04-09T14:52:56.658Z,1554821576.658 [CBIT](DEBUG): Initialized mux pins.
2019-04-09T14:52:56.658Z,1554821576.658 [CBIT](DEBUG): Initializing the watchdog timer.
2019-04-09T14:52:56.666Z,1554821576.666 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1878
2019-04-09T14:52:56.667Z,1554821576.667 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T14:52:56.671Z,1554821576.671 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T14:52:56.672Z,1554821576.672 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1880
2019-04-09T14:52:56.673Z,1554821576.673 [WetLabsBB2FL](INFO): Powering down
2019-04-09T14:52:56.682Z,1554821576.682 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-04-09T14:52:56.682Z,1554821576.682 [CBIT](DEBUG): Initializing heartbeat.
2019-04-09T14:52:56.702Z,1554821576.702 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1881
2019-04-09T14:52:56.703Z,1554821576.703 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-04-09T14:52:56.712Z,1554821576.712 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1882
2019-04-09T14:52:56.730Z,1554821576.730 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1883
2019-04-09T14:52:56.733Z,1554821576.733 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-09T14:52:56.734Z,1554821576.734 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-04-09T14:52:56.734Z,1554821576.734 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-09T14:52:56.734Z,1554821576.734 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-04-09T14:52:56.734Z,1554821576.734 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-09T14:52:56.735Z,1554821576.735 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-04-09T14:52:56.735Z,1554821576.735 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-09T14:52:56.735Z,1554821576.735 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-04-09T14:52:56.735Z,1554821576.735 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-09T14:52:56.735Z,1554821576.735 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-04-09T14:52:56.736Z,1554821576.736 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-09T14:52:56.736Z,1554821576.736 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-04-09T14:52:56.736Z,1554821576.736 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-09T14:52:56.736Z,1554821576.736 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-04-09T14:52:56.736Z,1554821576.736 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-09T14:52:56.736Z,1554821576.736 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-04-09T14:52:56.754Z,1554821576.754 [CBIT](DEBUG): Deactivating GF circuits.
2019-04-09T14:52:56.754Z,1554821576.754 [CBIT](DEBUG): Deactivating emergency mode.
2019-04-09T14:52:56.790Z,1554821576.790 [CBIT](DEBUG): Backplane powered.
2019-04-09T14:52:56.794Z,1554821576.794 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-04-09T14:52:56.795Z,1554821576.795 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-04-09T14:52:56.795Z,1554821576.795 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-04-09T14:52:56.795Z,1554821576.795 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-04-09T14:52:56.796Z,1554821576.796 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-04-09T14:52:56.796Z,1554821576.796 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-04-09T14:52:56.797Z,1554821576.797 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T14:52:56.798Z,1554821576.798 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-04-09T14:52:56.799Z,1554821576.799 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-04-09T14:52:56.807Z,1554821576.807 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-04-09T14:52:56.809Z,1554821576.809 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-04-09T14:52:56.810Z,1554821576.810 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-04-09T14:52:56.810Z,1554821576.810 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-04-09T14:52:56.811Z,1554821576.811 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-04-09T14:52:56.837Z,1554821576.837 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T14:52:56.873Z,1554821576.873 [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-09T14:52:56.873Z,1554821576.873 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-04-09T14:52:56.951Z,1554821576.951 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-04-09T14:52:56.952Z,1554821576.952 [Default:A.Wait](DEBUG): Construct Wait.
2019-04-09T14:52:56.976Z,1554821576.976 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T14:52:57.020Z,1554821577.020 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-04-09T14:52:57.022Z,1554821577.022 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-04-09T14:52:57.044Z,1554821577.044 [Default:E.Execute](DEBUG): Construct Execute.
2019-04-09T14:52:57.047Z,1554821577.047 [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-09T14:52:57.062Z,1554821577.062 [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-09T14:52:57.083Z,1554821577.083 [ESPComponent](INFO): powering down ESP
2019-04-09T14:52:57.114Z,1554821577.114 [Radio_Surface](INFO): Powering up
2019-04-09T14:52:57.511Z,1554821577.511 [PAR_Licor](INFO): A/D timeout, 8 tries over 133 ms

2019-04-09T14:52:57.511Z,1554821577.511 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T14:52:57.512Z,1554821577.512 [PAR_Licor](ERROR): Data Fault
2019-04-09T14:52:57.512Z,1554821577.512 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T14:52:57.512Z,1554821577.512 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T14:52:57.535Z,1554821577.535 [Depth_Keller](ERROR): Pressure reading out of range: 1895.684326 decibar
2019-04-09T14:52:57.586Z,1554821577.586 [BR_Ping1D](INFO): Start
2019-04-09T14:52:57.591Z,1554821577.591 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-04-09T14:52:57.636Z,1554821577.636 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-04-09T14:52:57.642Z,1554821577.642 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-04-09T14:52:57.644Z,1554821577.644 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-09T14:52:57.650Z,1554821577.650 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-09T14:52:57.651Z,1554821577.651 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T14:52:57.658Z,1554821577.658 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T14:52:57.659Z,1554821577.659 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-09T14:52:57.666Z,1554821577.666 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-09T14:52:57.667Z,1554821577.667 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-04-09T14:52:57.674Z,1554821577.674 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-04-09T14:52:57.677Z,1554821577.677 [CommandLine](FAULT): Scheduling is paused
2019-04-09T14:52:57.677Z,1554821577.677 [CBIT](INFO): Critical error at 20190409T145256
2019-04-09T14:52:57.677Z,1554821577.677 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-04-09T14:52:57.679Z,1554821577.679 [CBIT](FAULT): Main Battery Failure. Count: 1
2019-04-09T14:52:57.683Z,1554821577.683 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T14:52:57.683Z,1554821577.683 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T14:52:57.683Z,1554821577.683 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T14:52:57.796Z,1554821577.796 [BR_Ping1D](INFO): Starting
2019-04-09T14:52:58.226Z,1554821578.226 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-09T14:52:58.226Z,1554821578.226 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T14:52:58.226Z,1554821578.226 [PAR_Licor](ERROR): Data Fault
2019-04-09T14:52:58.269Z,1554821578.269 [BR_Ping1D](INFO): Starting
2019-04-09T14:52:58.269Z,1554821578.269 [BR_Ping1D](INFO): readConfig
2019-04-09T14:52:58.270Z,1554821578.270 [BR_Ping1D](INFO): startup: 0
2019-04-09T14:52:58.270Z,1554821578.270 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T14:52:58.282Z,1554821578.282 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T14:52:58.282Z,1554821578.282 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T14:52:58.283Z,1554821578.283 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T14:52:58.283Z,1554821578.283 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T14:52:58.662Z,1554821578.662 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-04-09T14:52:58.662Z,1554821578.662 [RudderServo](FAULT): Rudder failed to initialize
2019-04-09T14:52:58.662Z,1554821578.662 [RudderServo] Communications Fault, FailCount= 1
2019-04-09T14:52:58.662Z,1554821578.662 [RudderServo](ERROR): Communications Fault
2019-04-09T14:52:58.773Z,1554821578.773 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-04-09T14:52:58.774Z,1554821578.774 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T14:52:58.774Z,1554821578.774 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T14:52:58.774Z,1554821578.774 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T14:52:58.821Z,1554821578.821 [BR_Ping1D](INFO): Starting
2019-04-09T14:52:58.821Z,1554821578.821 [BR_Ping1D](INFO): readConfig
2019-04-09T14:52:58.821Z,1554821578.821 [BR_Ping1D](INFO): startup: 1
2019-04-09T14:52:58.821Z,1554821578.821 [BR_Ping1D](INFO): UART requesting status
2019-04-09T14:52:58.991Z,1554821578.991 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-09T14:52:58.991Z,1554821578.991 [RudderServo](INFO): Powering down
2019-04-09T14:52:59.206Z,1554821579.206 [BR_Ping1D](INFO): Starting
2019-04-09T14:52:59.206Z,1554821579.206 [BR_Ping1D](INFO): readConfig
2019-04-09T14:52:59.206Z,1554821579.206 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:52:59.206Z,1554821579.206 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:52:59.207Z,1554821579.207 [BR_Ping1D](INFO): elapsed 0.9368
2019-04-09T14:52:59.207Z,1554821579.207 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:52:59.209Z,1554821579.209 [BR_Ping1D](INFO): Parsing UART packet of type 1203
2019-04-09T14:52:59.209Z,1554821579.209 [BR_Ping1D](INFO): No UART data
2019-04-09T14:52:59.209Z,1554821579.209 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T14:52:59.209Z,1554821579.209 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:52:59.722Z,1554821579.722 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-09T14:52:59.722Z,1554821579.722 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T14:52:59.722Z,1554821579.722 [PAR_Licor](ERROR): Data Fault
2019-04-09T14:52:59.772Z,1554821579.772 [BR_Ping1D](INFO): Starting
2019-04-09T14:52:59.772Z,1554821579.772 [BR_Ping1D](INFO): readConfig
2019-04-09T14:52:59.772Z,1554821579.772 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:52:59.772Z,1554821579.772 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:52:59.772Z,1554821579.772 [BR_Ping1D](INFO): elapsed 1.5027
2019-04-09T14:52:59.773Z,1554821579.773 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:52:59.773Z,1554821579.773 [BR_Ping1D](INFO): No UART data
2019-04-09T14:52:59.773Z,1554821579.773 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T14:52:59.773Z,1554821579.773 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:52:59.799Z,1554821579.799 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-09T14:52:59.919Z,1554821579.919 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-09T14:52:59.923Z,1554821579.923 [CBIT](INFO): Clearing failed state for component RudderServo
2019-04-09T14:52:59.923Z,1554821579.923 [RudderServo] No Fault, FailCount= 1
2019-04-09T14:52:59.923Z,1554821579.923 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T14:52:59.924Z,1554821579.924 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T14:52:59.924Z,1554821579.924 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T14:53:00.013Z,1554821580.013 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:00.013Z,1554821580.013 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:00.014Z,1554821580.014 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:00.014Z,1554821580.014 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:00.014Z,1554821580.014 [BR_Ping1D](INFO): elapsed 1.7442
2019-04-09T14:53:00.014Z,1554821580.014 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:00.014Z,1554821580.014 [BR_Ping1D](INFO): No UART data
2019-04-09T14:53:00.015Z,1554821580.015 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T14:53:00.015Z,1554821580.015 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:53:00.354Z,1554821580.354 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T14:53:00.354Z,1554821580.354 [MassServo] Communications Fault, FailCount= 1
2019-04-09T14:53:00.354Z,1554821580.354 [MassServo](ERROR): Communications Fault
2019-04-09T14:53:00.382Z,1554821580.382 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T14:53:00.433Z,1554821580.433 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:00.434Z,1554821580.434 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:00.434Z,1554821580.434 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:00.434Z,1554821580.434 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:00.434Z,1554821580.434 [BR_Ping1D](INFO): elapsed 2.1647
2019-04-09T14:53:00.435Z,1554821580.435 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:00.435Z,1554821580.435 [BR_Ping1D](INFO): No UART data
2019-04-09T14:53:00.435Z,1554821580.435 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T14:53:00.435Z,1554821580.435 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:53:00.534Z,1554821580.534 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T14:53:00.535Z,1554821580.535 [MassServo](INFO): Powering down
2019-04-09T14:53:00.821Z,1554821580.821 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:00.821Z,1554821580.821 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:00.821Z,1554821580.821 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:00.822Z,1554821580.822 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:00.822Z,1554821580.822 [BR_Ping1D](INFO): elapsed 2.5521
2019-04-09T14:53:00.822Z,1554821580.822 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:00.822Z,1554821580.822 [BR_Ping1D](INFO): No UART data
2019-04-09T14:53:00.822Z,1554821580.822 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T14:53:00.823Z,1554821580.823 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:53:01.334Z,1554821581.334 [PAR_Licor](INFO): A/D timeout, 8 tries over 130 ms

2019-04-09T14:53:01.334Z,1554821581.334 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T14:53:01.334Z,1554821581.334 [PAR_Licor](ERROR): Data Fault
2019-04-09T14:53:01.374Z,1554821581.374 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:01.375Z,1554821581.375 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:01.375Z,1554821581.375 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:01.375Z,1554821581.375 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:01.375Z,1554821581.375 [BR_Ping1D](INFO): elapsed 3.1054
2019-04-09T14:53:01.375Z,1554821581.375 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:01.376Z,1554821581.376 [BR_Ping1D](INFO): No UART data
2019-04-09T14:53:01.376Z,1554821581.376 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T14:53:01.376Z,1554821581.376 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:53:01.464Z,1554821581.464 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T14:53:01.583Z,1554821581.583 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T14:53:01.750Z,1554821581.750 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T14:53:01.750Z,1554821581.750 [MassServo] No Fault, FailCount= 1
2019-04-09T14:53:01.750Z,1554821581.750 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T14:53:01.751Z,1554821581.751 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T14:53:01.751Z,1554821581.751 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T14:53:01.819Z,1554821581.819 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:01.819Z,1554821581.819 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:01.820Z,1554821581.820 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:01.820Z,1554821581.820 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:01.820Z,1554821581.820 [BR_Ping1D](INFO): elapsed 3.5503
2019-04-09T14:53:01.820Z,1554821581.820 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:01.820Z,1554821581.820 [BR_Ping1D](INFO): No UART data
2019-04-09T14:53:01.821Z,1554821581.821 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T14:53:01.821Z,1554821581.821 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:53:01.868Z,1554821581.868 [CBIT](FAULT): Main Battery Failure. Count: 11
2019-04-09T14:53:02.298Z,1554821582.298 [PAR_Licor](INFO): A/D timeout, 8 tries over 138 ms

2019-04-09T14:53:02.298Z,1554821582.298 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T14:53:02.298Z,1554821582.298 [PAR_Licor](ERROR): Data Fault
2019-04-09T14:53:02.333Z,1554821582.333 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:02.334Z,1554821582.334 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:02.334Z,1554821582.334 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:02.334Z,1554821582.334 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:02.334Z,1554821582.334 [BR_Ping1D](INFO): elapsed 4.0645
2019-04-09T14:53:02.335Z,1554821582.335 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:02.335Z,1554821582.335 [BR_Ping1D](INFO): No UART data
2019-04-09T14:53:02.335Z,1554821582.335 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T14:53:02.335Z,1554821582.335 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:53:02.398Z,1554821582.398 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T14:53:02.398Z,1554821582.398 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T14:53:02.398Z,1554821582.398 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T14:53:02.586Z,1554821582.586 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:02.586Z,1554821582.586 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:02.586Z,1554821582.586 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:02.586Z,1554821582.586 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:02.586Z,1554821582.586 [BR_Ping1D](INFO): elapsed 4.3166
2019-04-09T14:53:02.587Z,1554821582.587 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:02.587Z,1554821582.587 [BR_Ping1D](INFO): No UART data
2019-04-09T14:53:02.587Z,1554821582.587 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T14:53:02.587Z,1554821582.587 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:53:03.098Z,1554821583.098 [PAR_Licor](INFO): A/D timeout, 8 tries over 130 ms

2019-04-09T14:53:03.098Z,1554821583.098 [PAR_Licor] Data Fault, FailCount= 3
2019-04-09T14:53:03.098Z,1554821583.098 [PAR_Licor](ERROR): Data Fault
2019-04-09T14:53:03.132Z,1554821583.132 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:03.132Z,1554821583.132 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:03.132Z,1554821583.132 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:03.132Z,1554821583.132 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:03.133Z,1554821583.133 [BR_Ping1D](INFO): elapsed 4.8627
2019-04-09T14:53:03.133Z,1554821583.133 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:03.133Z,1554821583.133 [BR_Ping1D](INFO): No UART data
2019-04-09T14:53:03.133Z,1554821583.133 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T14:53:03.133Z,1554821583.133 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:53:03.243Z,1554821583.243 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T14:53:03.243Z,1554821583.243 [CBIT](CRITICAL): Data Fault in component: PAR_Licor
2019-04-09T14:53:03.389Z,1554821583.389 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:03.389Z,1554821583.389 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:03.390Z,1554821583.390 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:03.390Z,1554821583.390 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:03.390Z,1554821583.390 [BR_Ping1D](INFO): elapsed 5.1202
2019-04-09T14:53:03.390Z,1554821583.390 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:03.390Z,1554821583.390 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-09T14:53:03.390Z,1554821583.390 [BR_Ping1D](ERROR): Data Fault
2019-04-09T14:53:03.724Z,1554821583.724 [CBIT](INFO): Critical error at 20190409T145303
2019-04-09T14:53:03.728Z,1554821583.728 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T14:53:03.794Z,1554821583.794 [BR_Ping1D](INFO): Stop
2019-04-09T14:53:03.794Z,1554821583.794 [BR_Ping1D](INFO): uninitialize
2019-04-09T14:53:04.230Z,1554821584.230 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T14:53:04.230Z,1554821584.230 [MassServo] Communications Fault, FailCount= 2
2019-04-09T14:53:04.230Z,1554821584.230 [MassServo](ERROR): Communications Fault
2019-04-09T14:53:04.234Z,1554821584.234 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T14:53:04.257Z,1554821584.257 [BR_Ping1D](INFO): Stopping
2019-04-09T14:53:04.294Z,1554821584.294 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T14:53:04.295Z,1554821584.295 [MassServo](INFO): Powering down
2019-04-09T14:53:04.661Z,1554821584.661 [BR_Ping1D](INFO): Stopping
2019-04-09T14:53:05.065Z,1554821585.065 [BR_Ping1D](INFO): Stopped
2019-04-09T14:53:05.079Z,1554821585.079 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T14:53:05.199Z,1554821585.199 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T14:53:05.203Z,1554821585.203 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T14:53:05.203Z,1554821585.203 [MassServo] No Fault, FailCount= 2
2019-04-09T14:53:05.204Z,1554821585.204 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T14:53:05.204Z,1554821585.204 [BR_Ping1D] No Fault, FailCount= 1
2019-04-09T14:53:05.470Z,1554821585.470 [BR_Ping1D](INFO): Start
2019-04-09T14:53:05.873Z,1554821585.873 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:05.884Z,1554821585.884 [CBIT](FAULT): Main Battery Failure. Count: 21
2019-04-09T14:53:06.277Z,1554821586.277 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:06.278Z,1554821586.278 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:06.278Z,1554821586.278 [BR_Ping1D](INFO): startup: 0
2019-04-09T14:53:06.278Z,1554821586.278 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T14:53:06.290Z,1554821586.290 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T14:53:06.290Z,1554821586.290 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T14:53:06.290Z,1554821586.290 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T14:53:06.291Z,1554821586.291 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T14:53:06.682Z,1554821586.682 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:06.699Z,1554821586.699 [CBIT](CRITICAL): Environmental Failure. Press:14.902433 PSI. Humidity:36%. Temp:26 C. ABORTING MISSION
2019-04-09T14:53:07.086Z,1554821587.086 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:07.086Z,1554821587.086 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:07.086Z,1554821587.086 [BR_Ping1D](INFO): startup: 1
2019-04-09T14:53:07.086Z,1554821587.086 [BR_Ping1D](INFO): UART requesting status
2019-04-09T14:53:07.121Z,1554821587.121 [CBIT](INFO): Critical error at 20190409T145306
2019-04-09T14:53:07.470Z,1554821587.470 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T14:53:07.470Z,1554821587.470 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T14:53:07.470Z,1554821587.470 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T14:53:07.489Z,1554821587.489 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:07.489Z,1554821587.489 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:07.489Z,1554821587.489 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:07.490Z,1554821587.490 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:07.490Z,1554821587.490 [BR_Ping1D](INFO): elapsed 1.2116
2019-04-09T14:53:07.490Z,1554821587.490 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:07.491Z,1554821587.491 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T14:53:07.491Z,1554821587.491 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:53:07.830Z,1554821587.830 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T14:53:07.830Z,1554821587.830 [MassServo] Communications Fault, FailCount= 3
2019-04-09T14:53:07.830Z,1554821587.830 [MassServo](ERROR): Communications Fault
2019-04-09T14:53:07.834Z,1554821587.834 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T14:53:07.835Z,1554821587.835 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T14:53:07.994Z,1554821587.994 [Aanderaa_O2](INFO): Powering down
2019-04-09T14:53:08.065Z,1554821588.065 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:08.065Z,1554821588.065 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:08.065Z,1554821588.065 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:08.065Z,1554821588.065 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:08.066Z,1554821588.066 [BR_Ping1D](INFO): elapsed 1.7874
2019-04-09T14:53:08.066Z,1554821588.066 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:08.066Z,1554821588.066 [BR_Ping1D](INFO): Parsing UART packet of type 1203
2019-04-09T14:53:08.066Z,1554821588.066 [BR_Ping1D](INFO): No UART data
2019-04-09T14:53:08.067Z,1554821588.067 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T14:53:08.067Z,1554821588.067 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:53:08.095Z,1554821588.095 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T14:53:08.095Z,1554821588.095 [MassServo](INFO): Powering down
2019-04-09T14:53:08.297Z,1554821588.297 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:08.298Z,1554821588.298 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:08.298Z,1554821588.298 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:08.298Z,1554821588.298 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:08.298Z,1554821588.298 [BR_Ping1D](INFO): elapsed 2.0200
2019-04-09T14:53:08.298Z,1554821588.298 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:08.299Z,1554821588.299 [BR_Ping1D](INFO): No UART data
2019-04-09T14:53:08.299Z,1554821588.299 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T14:53:08.299Z,1554821588.299 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:53:08.703Z,1554821588.703 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:08.703Z,1554821588.703 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:08.704Z,1554821588.704 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:08.704Z,1554821588.704 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:08.704Z,1554821588.704 [BR_Ping1D](INFO): elapsed 2.4258
2019-04-09T14:53:08.704Z,1554821588.704 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:08.704Z,1554821588.704 [BR_Ping1D](INFO): No UART data
2019-04-09T14:53:08.705Z,1554821588.705 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T14:53:08.705Z,1554821588.705 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:53:08.726Z,1554821588.726 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T14:53:08.847Z,1554821588.847 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T14:53:08.852Z,1554821588.852 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T14:53:08.852Z,1554821588.852 [MassServo] No Fault, FailCount= 3
2019-04-09T14:53:08.852Z,1554821588.852 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T14:53:08.852Z,1554821588.852 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-09T14:53:09.105Z,1554821589.105 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:09.106Z,1554821589.106 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:09.106Z,1554821589.106 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:09.106Z,1554821589.106 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:09.106Z,1554821589.106 [BR_Ping1D](INFO): elapsed 2.8281
2019-04-09T14:53:09.106Z,1554821589.106 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:09.107Z,1554821589.107 [BR_Ping1D](INFO): No UART data
2019-04-09T14:53:09.107Z,1554821589.107 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T14:53:09.107Z,1554821589.107 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:53:09.522Z,1554821589.522 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:09.522Z,1554821589.522 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:09.522Z,1554821589.522 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:09.522Z,1554821589.522 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:09.522Z,1554821589.522 [BR_Ping1D](INFO): elapsed 3.2442
2019-04-09T14:53:09.523Z,1554821589.523 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:09.523Z,1554821589.523 [BR_Ping1D](INFO): No UART data
2019-04-09T14:53:09.523Z,1554821589.523 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T14:53:09.523Z,1554821589.523 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:53:09.718Z,1554821589.718 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T14:53:09.718Z,1554821589.718 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-09T14:53:09.718Z,1554821589.718 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T14:53:09.913Z,1554821589.913 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:09.913Z,1554821589.913 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:09.914Z,1554821589.914 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:09.914Z,1554821589.914 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:09.914Z,1554821589.914 [BR_Ping1D](INFO): elapsed 3.6358
2019-04-09T14:53:09.914Z,1554821589.914 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:09.914Z,1554821589.914 [BR_Ping1D](INFO): No UART data
2019-04-09T14:53:09.915Z,1554821589.915 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T14:53:09.915Z,1554821589.915 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:53:09.954Z,1554821589.954 [CBIT](FAULT): Main Battery Failure. Count: 31
2019-04-09T14:53:09.958Z,1554821589.958 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T14:53:10.122Z,1554821590.122 [CTD_Seabird](INFO): Powering down
2019-04-09T14:53:10.318Z,1554821590.318 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:10.318Z,1554821590.318 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:10.318Z,1554821590.318 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:10.318Z,1554821590.318 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:10.318Z,1554821590.318 [BR_Ping1D](INFO): elapsed 4.0402
2019-04-09T14:53:10.319Z,1554821590.319 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:10.319Z,1554821590.319 [BR_Ping1D](INFO): No UART data
2019-04-09T14:53:10.319Z,1554821590.319 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T14:53:10.319Z,1554821590.319 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:53:10.721Z,1554821590.721 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:10.721Z,1554821590.721 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:10.722Z,1554821590.722 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:10.722Z,1554821590.722 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:10.722Z,1554821590.722 [BR_Ping1D](INFO): elapsed 4.4437
2019-04-09T14:53:10.722Z,1554821590.722 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:10.722Z,1554821590.722 [BR_Ping1D](INFO): No UART data
2019-04-09T14:53:10.723Z,1554821590.723 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T14:53:10.723Z,1554821590.723 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:53:11.125Z,1554821591.125 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:11.126Z,1554821591.126 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:11.126Z,1554821591.126 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:11.126Z,1554821591.126 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:11.126Z,1554821591.126 [BR_Ping1D](INFO): elapsed 4.8481
2019-04-09T14:53:11.126Z,1554821591.126 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:11.127Z,1554821591.127 [BR_Ping1D](INFO): No UART data
2019-04-09T14:53:11.127Z,1554821591.127 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T14:53:11.127Z,1554821591.127 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:53:11.466Z,1554821591.466 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T14:53:11.466Z,1554821591.466 [MassServo] Communications Fault, FailCount= 4
2019-04-09T14:53:11.466Z,1554821591.466 [MassServo](ERROR): Communications Fault
2019-04-09T14:53:11.470Z,1554821591.470 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T14:53:11.471Z,1554821591.471 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T14:53:11.471Z,1554821591.471 [CTD_Seabird] No Fault, FailCount= 1
2019-04-09T14:53:11.498Z,1554821591.498 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T14:53:11.500Z,1554821591.500 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T14:53:11.533Z,1554821591.533 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:11.533Z,1554821591.533 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:11.534Z,1554821591.534 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:11.534Z,1554821591.534 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:11.534Z,1554821591.534 [BR_Ping1D](INFO): elapsed 5.2558
2019-04-09T14:53:11.534Z,1554821591.534 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:11.534Z,1554821591.534 [BR_Ping1D] Data Fault, FailCount= 2
2019-04-09T14:53:11.534Z,1554821591.534 [BR_Ping1D](ERROR): Data Fault
2019-04-09T14:53:11.563Z,1554821591.563 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T14:53:11.563Z,1554821591.563 [MassServo](INFO): Powering down
2019-04-09T14:53:11.572Z,1554821591.572 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T14:53:11.942Z,1554821591.942 [BR_Ping1D](INFO): Stop
2019-04-09T14:53:11.942Z,1554821591.942 [BR_Ping1D](INFO): uninitialize
2019-04-09T14:53:12.337Z,1554821592.337 [BR_Ping1D](INFO): Stopping
2019-04-09T14:53:12.351Z,1554821592.351 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T14:53:12.471Z,1554821592.471 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T14:53:12.475Z,1554821592.475 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T14:53:12.475Z,1554821592.475 [MassServo] No Fault, FailCount= 4
2019-04-09T14:53:12.741Z,1554821592.741 [BR_Ping1D](INFO): Stopping
2019-04-09T14:53:13.145Z,1554821593.145 [BR_Ping1D](INFO): Stopped
2019-04-09T14:53:13.183Z,1554821593.183 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T14:53:13.183Z,1554821593.183 [BR_Ping1D] No Fault, FailCount= 2
2019-04-09T14:53:13.550Z,1554821593.550 [BR_Ping1D](INFO): Start
2019-04-09T14:53:13.953Z,1554821593.953 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:13.969Z,1554821593.969 [CBIT](FAULT): Main Battery Failure. Count: 41
2019-04-09T14:53:14.358Z,1554821594.358 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:14.358Z,1554821594.358 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:14.358Z,1554821594.358 [BR_Ping1D](INFO): startup: 0
2019-04-09T14:53:14.358Z,1554821594.358 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T14:53:14.370Z,1554821594.370 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T14:53:14.370Z,1554821594.370 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T14:53:14.370Z,1554821594.370 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T14:53:14.371Z,1554821594.371 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T14:53:14.761Z,1554821594.761 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:15.086Z,1554821595.086 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T14:53:15.086Z,1554821595.086 [MassServo] Communications Fault, FailCount= 5
2019-04-09T14:53:15.086Z,1554821595.086 [MassServo](ERROR): Communications Fault
2019-04-09T14:53:15.090Z,1554821595.090 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T14:53:15.091Z,1554821595.091 [CBIT](CRITICAL): Communications Fault in component: MassServo
2019-04-09T14:53:15.166Z,1554821595.166 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:15.166Z,1554821595.166 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:15.167Z,1554821595.167 [BR_Ping1D](INFO): startup: 1
2019-04-09T14:53:15.167Z,1554821595.167 [BR_Ping1D](INFO): UART requesting status
2019-04-09T14:53:15.182Z,1554821595.182 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T14:53:15.182Z,1554821595.182 [MassServo](INFO): Powering down
2019-04-09T14:53:15.192Z,1554821595.192 [CBIT](INFO): Critical error at 20190409T145315
2019-04-09T14:53:15.570Z,1554821595.570 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:15.570Z,1554821595.570 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:15.570Z,1554821595.570 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:15.570Z,1554821595.570 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:15.571Z,1554821595.571 [BR_Ping1D](INFO): elapsed 1.2122
2019-04-09T14:53:15.571Z,1554821595.571 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:15.572Z,1554821595.572 [BR_Ping1D](INFO): Parsing UART packet of type 1203
2019-04-09T14:53:15.572Z,1554821595.572 [BR_Ping1D](INFO): No UART data
2019-04-09T14:53:15.573Z,1554821595.573 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T14:53:15.573Z,1554821595.573 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:53:15.974Z,1554821595.974 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:15.974Z,1554821595.974 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:15.975Z,1554821595.975 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:15.975Z,1554821595.975 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:15.975Z,1554821595.975 [BR_Ping1D](INFO): elapsed 1.6165
2019-04-09T14:53:15.975Z,1554821595.975 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:15.975Z,1554821595.975 [BR_Ping1D](INFO): No UART data
2019-04-09T14:53:15.976Z,1554821595.976 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T14:53:15.976Z,1554821595.976 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:53:16.002Z,1554821596.002 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T14:53:16.123Z,1554821596.123 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T14:53:16.382Z,1554821596.382 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:16.382Z,1554821596.382 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:16.382Z,1554821596.382 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:16.382Z,1554821596.382 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:16.383Z,1554821596.383 [BR_Ping1D](INFO): elapsed 2.0242
2019-04-09T14:53:16.383Z,1554821596.383 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:16.383Z,1554821596.383 [BR_Ping1D](INFO): No UART data
2019-04-09T14:53:16.383Z,1554821596.383 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T14:53:16.383Z,1554821596.383 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:53:16.782Z,1554821596.782 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:16.782Z,1554821596.782 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:16.782Z,1554821596.782 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:16.782Z,1554821596.782 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:16.783Z,1554821596.783 [BR_Ping1D](INFO): elapsed 2.4242
2019-04-09T14:53:16.783Z,1554821596.783 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:16.783Z,1554821596.783 [BR_Ping1D](INFO): No UART data
2019-04-09T14:53:16.783Z,1554821596.783 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T14:53:16.783Z,1554821596.783 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:53:17.186Z,1554821597.186 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:17.186Z,1554821597.186 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:17.186Z,1554821597.186 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:17.187Z,1554821597.187 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:17.187Z,1554821597.187 [BR_Ping1D](INFO): elapsed 2.8284
2019-04-09T14:53:17.187Z,1554821597.187 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:17.187Z,1554821597.187 [BR_Ping1D](INFO): No UART data
2019-04-09T14:53:17.187Z,1554821597.187 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T14:53:17.187Z,1554821597.187 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:53:17.571Z,1554821597.571 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T14:53:17.571Z,1554821597.571 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-04-09T14:53:17.571Z,1554821597.571 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T14:53:17.590Z,1554821597.590 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:17.590Z,1554821597.590 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:17.590Z,1554821597.590 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:17.590Z,1554821597.590 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:17.591Z,1554821597.591 [BR_Ping1D](INFO): elapsed 3.2322
2019-04-09T14:53:17.591Z,1554821597.591 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:17.591Z,1554821597.591 [BR_Ping1D](INFO): No UART data
2019-04-09T14:53:17.591Z,1554821597.591 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T14:53:17.591Z,1554821597.591 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:53:17.660Z,1554821597.660 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T14:53:17.975Z,1554821597.975 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T14:53:18.131Z,1554821598.131 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:18.131Z,1554821598.131 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:18.131Z,1554821598.131 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:18.131Z,1554821598.131 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:18.131Z,1554821598.131 [BR_Ping1D](INFO): elapsed 3.7731
2019-04-09T14:53:18.132Z,1554821598.132 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:18.132Z,1554821598.132 [BR_Ping1D](INFO): No UART data
2019-04-09T14:53:18.132Z,1554821598.132 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T14:53:18.132Z,1554821598.132 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:53:18.144Z,1554821598.144 [CBIT](FAULT): Main Battery Failure. Count: 51
2019-04-09T14:53:18.398Z,1554821598.398 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:18.398Z,1554821598.398 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:18.399Z,1554821598.399 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:18.399Z,1554821598.399 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:18.399Z,1554821598.399 [BR_Ping1D](INFO): elapsed 4.0405
2019-04-09T14:53:18.399Z,1554821598.399 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:18.399Z,1554821598.399 [BR_Ping1D](INFO): No UART data
2019-04-09T14:53:18.399Z,1554821598.399 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T14:53:18.400Z,1554821598.400 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:53:18.802Z,1554821598.802 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:18.802Z,1554821598.802 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:18.803Z,1554821598.803 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:18.803Z,1554821598.803 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:18.803Z,1554821598.803 [BR_Ping1D](INFO): elapsed 4.4447
2019-04-09T14:53:18.803Z,1554821598.803 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:18.803Z,1554821598.803 [BR_Ping1D](INFO): No UART data
2019-04-09T14:53:18.804Z,1554821598.804 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T14:53:18.804Z,1554821598.804 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:53:18.831Z,1554821598.831 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T14:53:18.831Z,1554821598.831 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-04-09T14:53:19.186Z,1554821599.186 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T14:53:19.186Z,1554821599.186 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-09T14:53:19.186Z,1554821599.186 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T14:53:19.206Z,1554821599.206 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:19.206Z,1554821599.206 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:19.207Z,1554821599.207 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:19.207Z,1554821599.207 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:19.207Z,1554821599.207 [BR_Ping1D](INFO): elapsed 4.8485
2019-04-09T14:53:19.207Z,1554821599.207 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:19.207Z,1554821599.207 [BR_Ping1D](INFO): No UART data
2019-04-09T14:53:19.207Z,1554821599.207 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T14:53:19.208Z,1554821599.208 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T14:53:19.235Z,1554821599.235 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T14:53:19.710Z,1554821599.710 [Aanderaa_O2](INFO): Powering down
2019-04-09T14:53:19.730Z,1554821599.730 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:19.730Z,1554821599.730 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:19.731Z,1554821599.731 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:19.731Z,1554821599.731 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:19.731Z,1554821599.731 [BR_Ping1D](INFO): elapsed 5.3727
2019-04-09T14:53:19.731Z,1554821599.731 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:19.731Z,1554821599.731 [BR_Ping1D] Data Fault, FailCount= 3
2019-04-09T14:53:19.731Z,1554821599.731 [BR_Ping1D](ERROR): Data Fault
2019-04-09T14:53:19.760Z,1554821599.760 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T14:53:20.013Z,1554821600.013 [BR_Ping1D](INFO): Stop
2019-04-09T14:53:20.014Z,1554821600.014 [BR_Ping1D](INFO): uninitialize
2019-04-09T14:53:20.417Z,1554821600.417 [BR_Ping1D](INFO): Stopping
2019-04-09T14:53:20.431Z,1554821600.431 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T14:53:20.431Z,1554821600.431 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-09T14:53:20.830Z,1554821600.830 [BR_Ping1D](INFO): Stopping
2019-04-09T14:53:21.218Z,1554821601.218 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T14:53:21.218Z,1554821601.218 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T14:53:21.265Z,1554821601.265 [BR_Ping1D](INFO): Stopped
2019-04-09T14:53:21.276Z,1554821601.276 [SBIT](IMPORTANT): Beginning Startup BIT
2019-04-09T14:53:21.280Z,1554821601.280 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T14:53:21.280Z,1554821601.280 [BR_Ping1D] No Fault, FailCount= 3
2019-04-09T14:53:21.280Z,1554821601.280 [CBIT](IMPORTANT): Beginning ground fault scan
2019-04-09T14:53:21.630Z,1554821601.630 [BR_Ping1D](INFO): Start
2019-04-09T14:53:22.033Z,1554821602.033 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:22.104Z,1554821602.104 [CBIT](FAULT): Main Battery Failure. Count: 61
2019-04-09T14:53:22.438Z,1554821602.438 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:22.438Z,1554821602.438 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:22.438Z,1554821602.438 [BR_Ping1D](INFO): startup: 0
2019-04-09T14:53:22.438Z,1554821602.438 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T14:53:22.450Z,1554821602.450 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T14:53:22.450Z,1554821602.450 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T14:53:22.450Z,1554821602.450 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T14:53:22.451Z,1554821602.451 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T14:53:22.838Z,1554821602.838 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:23.245Z,1554821603.245 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:23.246Z,1554821603.246 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:23.246Z,1554821603.246 [BR_Ping1D](INFO): startup: 1
2019-04-09T14:53:23.246Z,1554821603.246 [BR_Ping1D](INFO): UART requesting status
2019-04-09T14:53:23.649Z,1554821603.649 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:23.649Z,1554821603.649 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:23.650Z,1554821603.650 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:23.650Z,1554821603.650 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:23.650Z,1554821603.650 [BR_Ping1D](INFO): elapsed 1.2118
2019-04-09T14:53:23.650Z,1554821603.650 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:23.651Z,1554821603.651 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-09T14:53:23.651Z,1554821603.651 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T14:53:24.055Z,1554821604.055 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:24.055Z,1554821604.055 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:24.056Z,1554821604.056 [BR_Ping1D](INFO): startup: 0
2019-04-09T14:53:24.056Z,1554821604.056 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T14:53:24.070Z,1554821604.070 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T14:53:24.070Z,1554821604.070 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T14:53:24.070Z,1554821604.070 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T14:53:24.071Z,1554821604.071 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T14:53:24.457Z,1554821604.457 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:24.550Z,1554821604.550 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T14:53:24.550Z,1554821604.550 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-09T14:53:24.550Z,1554821604.550 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T14:53:24.571Z,1554821604.571 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T14:53:24.847Z,1554821604.847 [NAL9602](INFO): Powering up NAL9602
2019-04-09T14:53:24.863Z,1554821604.863 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:24.863Z,1554821604.863 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:24.863Z,1554821604.863 [BR_Ping1D](INFO): startup: 1
2019-04-09T14:53:24.864Z,1554821604.864 [BR_Ping1D](INFO): UART requesting status
2019-04-09T14:53:24.954Z,1554821604.954 [CTD_Seabird](INFO): Powering down
2019-04-09T14:53:25.265Z,1554821605.265 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:25.265Z,1554821605.265 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:25.265Z,1554821605.265 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:25.266Z,1554821605.266 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:25.266Z,1554821605.266 [BR_Ping1D](INFO): elapsed 1.2099
2019-04-09T14:53:25.266Z,1554821605.266 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:25.267Z,1554821605.267 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-09T14:53:25.267Z,1554821605.267 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T14:53:25.669Z,1554821605.669 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:25.670Z,1554821605.670 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:25.670Z,1554821605.670 [BR_Ping1D](INFO): startup: 0
2019-04-09T14:53:25.670Z,1554821605.670 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T14:53:25.682Z,1554821605.682 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T14:53:25.682Z,1554821605.682 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T14:53:25.682Z,1554821605.682 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T14:53:25.683Z,1554821605.683 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T14:53:26.073Z,1554821606.073 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:26.204Z,1554821606.204 [CBIT](FAULT): Main Battery Failure. Count: 71
2019-04-09T14:53:26.207Z,1554821606.207 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T14:53:26.207Z,1554821606.207 [CTD_Seabird] No Fault, FailCount= 2
2019-04-09T14:53:26.298Z,1554821606.298 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T14:53:26.306Z,1554821606.306 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T14:53:26.477Z,1554821606.477 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:26.478Z,1554821606.478 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:26.478Z,1554821606.478 [BR_Ping1D](INFO): startup: 1
2019-04-09T14:53:26.478Z,1554821606.478 [BR_Ping1D](INFO): UART requesting status
2019-04-09T14:53:26.881Z,1554821606.881 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:26.881Z,1554821606.881 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:26.881Z,1554821606.881 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:26.882Z,1554821606.882 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:26.882Z,1554821606.882 [BR_Ping1D](INFO): elapsed 1.2117
2019-04-09T14:53:26.882Z,1554821606.882 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:26.884Z,1554821606.884 [BR_Ping1D](INFO): Parsing UART packet of type 1203
2019-04-09T14:53:26.884Z,1554821606.884 [BR_Ping1D](INFO): No UART data
2019-04-09T14:53:26.884Z,1554821606.884 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T14:53:27.289Z,1554821607.289 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:27.289Z,1554821607.289 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:27.289Z,1554821607.289 [BR_Ping1D](INFO): startup: 0
2019-04-09T14:53:27.289Z,1554821607.289 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T14:53:27.302Z,1554821607.302 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T14:53:27.303Z,1554821607.303 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T14:53:27.306Z,1554821607.306 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T14:53:27.307Z,1554821607.307 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T14:53:27.689Z,1554821607.689 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:28.093Z,1554821608.093 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:28.094Z,1554821608.094 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:28.094Z,1554821608.094 [BR_Ping1D](INFO): startup: 1
2019-04-09T14:53:28.094Z,1554821608.094 [BR_Ping1D](INFO): UART requesting status
2019-04-09T14:53:28.497Z,1554821608.497 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:28.497Z,1554821608.497 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:28.497Z,1554821608.497 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:28.498Z,1554821608.498 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:28.498Z,1554821608.498 [BR_Ping1D](INFO): elapsed 1.2086
2019-04-09T14:53:28.498Z,1554821608.498 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:28.499Z,1554821608.499 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-09T14:53:28.499Z,1554821608.499 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T14:53:28.500Z,1554821608.500 [BR_Ping1D] Data Fault, FailCount= 4
2019-04-09T14:53:28.500Z,1554821608.500 [BR_Ping1D](ERROR): Data Fault
2019-04-09T14:53:28.558Z,1554821608.558 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T14:53:28.901Z,1554821608.901 [BR_Ping1D](INFO): Stop
2019-04-09T14:53:28.902Z,1554821608.902 [BR_Ping1D](INFO): uninitialize
2019-04-09T14:53:29.305Z,1554821609.305 [BR_Ping1D](INFO): Stopping
2019-04-09T14:53:29.709Z,1554821609.709 [BR_Ping1D](INFO): Stopping
2019-04-09T14:53:30.113Z,1554821610.113 [BR_Ping1D](INFO): Stopped
2019-04-09T14:53:30.125Z,1554821610.125 [CBIT](FAULT): Main Battery Failure. Count: 81
2019-04-09T14:53:30.139Z,1554821610.139 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T14:53:30.139Z,1554821610.139 [BR_Ping1D] No Fault, FailCount= 4
2019-04-09T14:53:30.516Z,1554821610.516 [BR_Ping1D](INFO): Start
2019-04-09T14:53:30.902Z,1554821610.902 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T14:53:30.902Z,1554821610.902 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-09T14:53:30.902Z,1554821610.902 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T14:53:30.934Z,1554821610.934 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:30.956Z,1554821610.956 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T14:53:30.956Z,1554821610.956 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-09T14:53:31.426Z,1554821611.426 [Aanderaa_O2](INFO): Powering down
2019-04-09T14:53:31.452Z,1554821611.452 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:31.453Z,1554821611.453 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:31.453Z,1554821611.453 [BR_Ping1D](INFO): startup: 0
2019-04-09T14:53:31.453Z,1554821611.453 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T14:53:31.466Z,1554821611.466 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T14:53:31.466Z,1554821611.466 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T14:53:31.466Z,1554821611.466 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T14:53:31.467Z,1554821611.467 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T14:53:31.729Z,1554821611.729 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:32.134Z,1554821612.134 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:32.134Z,1554821612.134 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:32.134Z,1554821612.134 [BR_Ping1D](INFO): startup: 1
2019-04-09T14:53:32.134Z,1554821612.134 [BR_Ping1D](INFO): UART requesting status
2019-04-09T14:53:32.203Z,1554821612.203 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.006080
CHAN A1 (24V): -0.127884
CHAN A2 (12V): -0.004848
CHAN A3 (5V): -0.002657
CHAN B0 (3.3V): -0.001808
CHAN B1 (3.15aV): -0.001432
CHAN B2 (3.15bV): -0.001337
CHAN B3 (GND): -0.000386
OPEN: -0.000841
Full Scale Calc: 4.765 mA, -1.589 mA
2019-04-09T14:53:32.537Z,1554821612.537 [BR_Ping1D](INFO): Starting
2019-04-09T14:53:32.537Z,1554821612.537 [BR_Ping1D](INFO): readConfig
2019-04-09T14:53:32.537Z,1554821612.537 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:53:32.538Z,1554821612.538 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:53:32.538Z,1554821612.538 [BR_Ping1D](INFO): elapsed 1.0846
2019-04-09T14:53:32.538Z,1554821612.538 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:53:32.539Z,1554821612.539 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-09T14:53:32.539Z,1554821612.539 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T14:53:32.540Z,1554821612.540 [BR_Ping1D] Data Fault, FailCount= 5
2019-04-09T14:53:32.540Z,1554821612.540 [BR_Ping1D](ERROR): Data Fault
2019-04-09T14:53:32.567Z,1554821612.567 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T14:53:32.568Z,1554821612.568 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-04-09T14:53:32.942Z,1554821612.942 [BR_Ping1D](INFO): Stop
2019-04-09T14:53:32.942Z,1554821612.942 [BR_Ping1D](INFO): uninitialize
2019-04-09T14:53:33.041Z,1554821613.041 [CBIT](INFO): Critical error at 20190409T145332
2019-04-09T14:53:33.346Z,1554821613.346 [BR_Ping1D](INFO): Stopping
2019-04-09T14:53:33.749Z,1554821613.749 [BR_Ping1D](INFO): Stopping
2019-04-09T14:53:34.155Z,1554821614.155 [BR_Ping1D](INFO): Stopped
2019-04-09T14:53:34.169Z,1554821614.169 [CBIT](FAULT): Main Battery Failure. Count: 91
2019-04-09T14:53:34.568Z,1554821614.568 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T14:53:35.755Z,1554821615.755 [NAL9602](INFO): NAL9602 initialized
2019-04-09T14:53:36.574Z,1554821616.574 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:53:38.257Z,1554821618.257 [CBIT](FAULT): Main Battery Failure. Count: 101
2019-04-09T14:53:39.355Z,1554821619.355 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T14:53:39.355Z,1554821619.355 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-09T14:53:39.355Z,1554821619.355 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T14:53:39.486Z,1554821619.486 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T14:53:39.486Z,1554821619.486 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-09T14:53:39.758Z,1554821619.758 [CTD_Seabird](INFO): Powering down
2019-04-09T14:53:41.406Z,1554821621.406 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T14:53:41.406Z,1554821621.406 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2019-04-09T14:53:41.406Z,1554821621.406 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T14:53:41.515Z,1554821621.515 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T14:53:41.810Z,1554821621.810 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T14:53:42.492Z,1554821622.492 [CBIT](FAULT): Main Battery Failure. Count: 111
2019-04-09T14:53:42.668Z,1554821622.668 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T14:53:42.668Z,1554821622.668 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2019-04-09T14:53:45.042Z,1554821625.042 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T14:53:45.043Z,1554821625.043 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T14:53:46.285Z,1554821626.285 [CBIT](FAULT): Main Battery Failure. Count: 121
2019-04-09T14:53:50.339Z,1554821630.339 [CBIT](FAULT): Main Battery Failure. Count: 131
2019-04-09T14:53:54.371Z,1554821634.371 [CBIT](FAULT): Main Battery Failure. Count: 141
2019-04-09T14:53:58.412Z,1554821638.412 [CBIT](FAULT): Main Battery Failure. Count: 151
2019-04-09T14:54:01.251Z,1554821641.251 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T14:54:01.251Z,1554821641.251 [SBIT](FAULT): Control surface position failure.
2019-04-09T14:54:02.451Z,1554821642.451 [CBIT](FAULT): Main Battery Failure. Count: 161
2019-04-09T14:54:05.243Z,1554821645.243 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T14:54:05.243Z,1554821645.243 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2019-04-09T14:54:05.243Z,1554821645.243 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T14:54:05.375Z,1554821645.375 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T14:54:05.646Z,1554821645.646 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T14:54:06.741Z,1554821646.741 [CBIT](FAULT): Main Battery Failure. Count: 171
2019-04-09T14:54:06.744Z,1554821646.744 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T14:54:06.744Z,1554821646.744 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2019-04-09T14:54:08.878Z,1554821648.878 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T14:54:08.879Z,1554821648.879 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T14:54:10.535Z,1554821650.535 [CBIT](FAULT): Main Battery Failure. Count: 181
2019-04-09T14:54:14.570Z,1554821654.570 [CBIT](FAULT): Main Battery Failure. Count: 191
2019-04-09T14:54:14.976Z,1554821654.976 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T14:54:14.976Z,1554821654.976 [SBIT](FAULT): Control surface position failure.
2019-04-09T14:54:15.372Z,1554821655.372 [SBIT](CRITICAL): SBIT FAILED
2019-04-09T14:54:15.387Z,1554821655.387 [CBIT](INFO): Critical error at 20190409T145415
2019-04-09T14:54:15.451Z,1554821655.451 [CommandLine](IMPORTANT): got command configSet list
2019-04-09T14:54:15.451Z,1554821655.451 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-04-09T14:54:15.452Z,1554821655.452 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-04-09T14:54:15.452Z,1554821655.452 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2019-04-09T14:54:15.795Z,1554821655.795 [MissionManager](IMPORTANT): Started mission Startup
2019-04-09T14:54:15.796Z,1554821655.796 [Startup] Running Loop=1
2019-04-09T14:54:15.796Z,1554821655.796 [Startup](DEBUG): Aggregate::initialize Startup
2019-04-09T14:54:15.796Z,1554821655.796 [Startup:A.GoToSurface] Running Loop=1
2019-04-09T14:54:15.796Z,1554821655.796 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-09T14:54:15.796Z,1554821655.796 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-09T14:54:15.797Z,1554821655.797 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-09T14:54:15.797Z,1554821655.797 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-09T14:54:15.798Z,1554821655.798 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-09T14:54:15.798Z,1554821655.798 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-09T14:54:15.800Z,1554821655.800 [Startup:StartupSatComms] Running Loop=1
2019-04-09T14:54:15.800Z,1554821655.800 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-04-09T14:54:15.800Z,1554821655.800 [Startup:StartupSatComms:A] Running Loop=1
2019-04-09T14:54:16.198Z,1554821656.198 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-04-09T14:54:18.610Z,1554821658.610 [CBIT](FAULT): Main Battery Failure. Count: 201
2019-04-09T14:54:22.650Z,1554821662.650 [CBIT](FAULT): Main Battery Failure. Count: 211
2019-04-09T14:54:26.711Z,1554821666.711 [CBIT](FAULT): Main Battery Failure. Count: 221
2019-04-09T14:54:29.078Z,1554821669.078 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T14:54:29.079Z,1554821669.079 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2019-04-09T14:54:29.079Z,1554821669.079 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T14:54:29.129Z,1554821669.129 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T14:54:29.482Z,1554821669.482 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T14:54:30.328Z,1554821670.328 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T14:54:30.328Z,1554821670.328 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2019-04-09T14:54:30.722Z,1554821670.722 [CBIT](FAULT): Main Battery Failure. Count: 231
2019-04-09T14:54:32.714Z,1554821672.714 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T14:54:32.715Z,1554821672.715 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T14:54:34.763Z,1554821674.763 [CBIT](FAULT): Main Battery Failure. Count: 241
2019-04-09T14:54:38.809Z,1554821678.809 [CBIT](FAULT): Main Battery Failure. Count: 251
2019-04-09T14:54:42.863Z,1554821682.863 [CBIT](FAULT): Main Battery Failure. Count: 261
2019-04-09T14:54:46.893Z,1554821686.893 [CBIT](FAULT): Main Battery Failure. Count: 271
2019-04-09T14:54:50.929Z,1554821690.929 [CBIT](FAULT): Main Battery Failure. Count: 281
2019-04-09T14:54:52.916Z,1554821692.916 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T14:54:52.916Z,1554821692.916 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2019-04-09T14:54:52.916Z,1554821692.916 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T14:54:52.968Z,1554821692.968 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T14:54:53.318Z,1554821693.318 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T14:54:54.175Z,1554821694.175 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T14:54:54.175Z,1554821694.175 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2019-04-09T14:54:54.970Z,1554821694.970 [CBIT](FAULT): Main Battery Failure. Count: 291
2019-04-09T14:54:56.550Z,1554821696.550 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T14:54:56.551Z,1554821696.551 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T14:54:56.986Z,1554821696.986 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T14:54:56.987Z,1554821696.987 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-04-09T14:54:56.987Z,1554821696.987 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T14:54:56.988Z,1554821696.988 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T14:54:56.988Z,1554821696.988 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-04-09T14:54:56.988Z,1554821696.988 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T14:54:56.990Z,1554821696.990 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T14:54:56.990Z,1554821696.990 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-04-09T14:54:56.990Z,1554821696.990 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T14:54:57.000Z,1554821697.000 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T14:54:57.000Z,1554821697.000 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T14:54:57.001Z,1554821697.001 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T14:54:57.399Z,1554821697.399 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T14:54:57.399Z,1554821697.399 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-04-09T14:54:57.400Z,1554821697.400 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T14:54:57.400Z,1554821697.400 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-04-09T14:54:57.400Z,1554821697.400 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T14:54:57.401Z,1554821697.401 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-04-09T14:54:57.801Z,1554821697.801 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T14:54:59.021Z,1554821699.021 [CBIT](FAULT): Main Battery Failure. Count: 301
2019-04-09T14:55:03.059Z,1554821703.059 [CBIT](FAULT): Main Battery Failure. Count: 311
2019-04-09T14:55:07.085Z,1554821707.085 [CBIT](FAULT): Main Battery Failure. Count: 321
2019-04-09T14:55:11.131Z,1554821711.131 [CBIT](FAULT): Main Battery Failure. Count: 331
2019-04-09T14:55:15.169Z,1554821715.169 [CBIT](FAULT): Main Battery Failure. Count: 341
2019-04-09T14:55:15.971Z,1554821715.971 [Startup:StartupSatComms:A](INFO): Timed out from 2019-04-09T14:54:15.8Z
2019-04-09T14:55:15.971Z,1554821715.971 [Startup:StartupSatComms:A] Stopped
2019-04-09T14:55:15.971Z,1554821715.971 [Startup:StartupSatComms:B] Running Loop=1
2019-04-09T14:55:16.376Z,1554821716.376 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-09T14:55:16.750Z,1554821716.750 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T14:55:16.751Z,1554821716.751 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2019-04-09T14:55:16.751Z,1554821716.751 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T14:55:16.782Z,1554821716.782 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T14:55:17.154Z,1554821717.154 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T14:55:18.003Z,1554821718.003 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T14:55:18.003Z,1554821718.003 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2019-04-09T14:55:19.220Z,1554821719.220 [CBIT](FAULT): Main Battery Failure. Count: 351
2019-04-09T14:55:20.386Z,1554821720.386 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T14:55:20.387Z,1554821720.387 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T14:55:23.242Z,1554821723.242 [CBIT](FAULT): Main Battery Failure. Count: 361
2019-04-09T14:55:27.290Z,1554821727.290 [CBIT](FAULT): Main Battery Failure. Count: 371
2019-04-09T14:55:31.330Z,1554821731.330 [CBIT](FAULT): Main Battery Failure. Count: 381
2019-04-09T14:55:35.381Z,1554821735.381 [CBIT](FAULT): Main Battery Failure. Count: 391
2019-04-09T14:55:37.696Z,1554821737.696 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005035
2019-04-09T14:55:39.410Z,1554821739.410 [CBIT](FAULT): Main Battery Failure. Count: 401
2019-04-09T14:55:40.586Z,1554821740.586 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T14:55:40.586Z,1554821740.586 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7
2019-04-09T14:55:40.586Z,1554821740.586 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T14:55:40.640Z,1554821740.640 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T14:55:40.990Z,1554821740.990 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T14:55:41.848Z,1554821741.848 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T14:55:41.848Z,1554821741.848 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7
2019-04-09T14:55:43.461Z,1554821743.461 [CBIT](FAULT): Main Battery Failure. Count: 411
2019-04-09T14:55:44.050Z,1554821744.050 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190409T144919/Courier0004.lzma
2019-04-09T14:55:44.222Z,1554821744.222 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T14:55:44.223Z,1554821744.223 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T14:55:44.855Z,1554821744.855 [DataOverHttps](INFO): Moved sent file to Logs/20190409T144919/Courier0004.lzma.bak
2019-04-09T14:55:44.856Z,1554821744.856 [DataOverHttps](INFO): SBD MOMSN=10512321
2019-04-09T14:55:47.506Z,1554821747.506 [CBIT](FAULT): Main Battery Failure. Count: 421
2019-04-09T14:55:51.540Z,1554821751.540 [CBIT](FAULT): Main Battery Failure. Count: 431
2019-04-09T14:55:55.593Z,1554821755.593 [CBIT](FAULT): Main Battery Failure. Count: 441
2019-04-09T14:55:59.310Z,1554821759.310 [DataOverHttps](INFO): Sending 360 bytes from file Logs/20190409T145246/Courier0000.lzma
2019-04-09T14:55:59.604Z,1554821759.604 [CBIT](FAULT): Main Battery Failure. Count: 451
2019-04-09T14:56:00.115Z,1554821760.115 [DataOverHttps](INFO): Moved sent file to Logs/20190409T145246/Courier0000.lzma.bak
2019-04-09T14:56:00.116Z,1554821760.116 [DataOverHttps](INFO): SBD MOMSN=10512323
2019-04-09T14:56:01.234Z,1554821761.234 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T14:56:01.234Z,1554821761.234 [Aanderaa_O2] No Fault, FailCount= 3
2019-04-09T14:56:03.651Z,1554821763.651 [CBIT](FAULT): Main Battery Failure. Count: 461
2019-04-09T14:56:04.423Z,1554821764.423 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T14:56:04.423Z,1554821764.423 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 8
2019-04-09T14:56:04.423Z,1554821764.423 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T14:56:04.460Z,1554821764.460 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T14:56:04.460Z,1554821764.460 [CBIT](FAULT): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T14:56:04.827Z,1554821764.827 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T14:56:07.690Z,1554821767.690 [CBIT](FAULT): Main Battery Failure. Count: 471
2019-04-09T14:56:09.711Z,1554821769.711 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T14:56:09.711Z,1554821769.711 [CTD_Seabird] No Fault, FailCount= 3
2019-04-09T14:56:09.822Z,1554821769.822 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T14:56:09.824Z,1554821769.824 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T14:56:11.694Z,1554821771.694 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T14:56:11.694Z,1554821771.694 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T14:56:11.694Z,1554821771.694 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T14:56:11.736Z,1554821771.736 [CBIT](FAULT): Main Battery Failure. Count: 481
2019-04-09T14:56:11.738Z,1554821771.738 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T14:56:12.218Z,1554821772.218 [Aanderaa_O2](INFO): Powering down
2019-04-09T14:56:12.955Z,1554821772.955 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T14:56:12.955Z,1554821772.955 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-09T14:56:15.769Z,1554821775.769 [CBIT](FAULT): Main Battery Failure. Count: 491
2019-04-09T14:56:16.168Z,1554821776.168 [Startup:StartupSatComms:B](INFO): Timed out from 2019-04-09T14:55:15.0Z
2019-04-09T14:56:16.168Z,1554821776.168 [Startup:StartupSatComms:B] Stopped
2019-04-09T14:56:16.168Z,1554821776.168 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-04-09T14:56:16.168Z,1554821776.168 [Startup:StartupSatComms] Stopped
2019-04-09T14:56:16.168Z,1554821776.168 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-04-09T14:56:16.169Z,1554821776.169 [Startup](INFO): Completed Startup
2019-04-09T14:56:16.169Z,1554821776.169 [MissionManager](INFO): Startup is completed.
2019-04-09T14:56:16.169Z,1554821776.169 [MissionManager](INFO): Uninitializing Mission Startup
2019-04-09T14:56:16.170Z,1554821776.170 [Startup] Stopped
2019-04-09T14:56:16.170Z,1554821776.170 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-04-09T14:56:16.170Z,1554821776.170 [Startup:A.GoToSurface] Stopped
2019-04-09T14:56:16.170Z,1554821776.170 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-09T14:56:16.583Z,1554821776.583 [MissionManager](IMPORTANT): Started mission Default
2019-04-09T14:56:16.583Z,1554821776.583 [Default] Running Loop=1
2019-04-09T14:56:16.583Z,1554821776.583 [Default](DEBUG): Aggregate::initialize Default
2019-04-09T14:56:16.583Z,1554821776.583 [Default:B.GoToSurface] Running Loop=1
2019-04-09T14:56:16.583Z,1554821776.583 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-09T14:56:16.583Z,1554821776.583 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-09T14:56:16.584Z,1554821776.584 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-09T14:56:16.584Z,1554821776.584 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-09T14:56:16.584Z,1554821776.584 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-09T14:56:16.585Z,1554821776.585 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-09T14:56:16.585Z,1554821776.585 [Default:A.Wait] Running Loop=1
2019-04-09T14:56:16.585Z,1554821776.585 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-04-09T14:56:19.826Z,1554821779.826 [CBIT](FAULT): Main Battery Failure. Count: 501
2019-04-09T14:56:22.890Z,1554821782.890 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T14:56:22.890Z,1554821782.890 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-09T14:56:22.890Z,1554821782.890 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T14:56:23.055Z,1554821783.055 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T14:56:23.084Z,1554821783.084 [DataOverHttps](INFO): Sending 590 bytes from file Logs/20190409T143553/Express0005.lzma
2019-04-09T14:56:23.294Z,1554821783.294 [CTD_Seabird](INFO): Powering down
2019-04-09T14:56:23.412Z,1554821783.412 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T14:56:23.412Z,1554821783.412 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-09T14:56:23.412Z,1554821783.412 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T14:56:23.449Z,1554821783.449 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T14:56:23.887Z,1554821783.887 [DataOverHttps](INFO): Moved sent file to Logs/20190409T143553/Express0005.lzma.bak
2019-04-09T14:56:23.888Z,1554821783.888 [DataOverHttps](INFO): SBD MOMSN=10512338
2019-04-09T14:56:23.938Z,1554821783.938 [Aanderaa_O2](INFO): Powering down
2019-04-09T14:56:23.977Z,1554821783.977 [CBIT](FAULT): Main Battery Failure. Count: 511
2019-04-09T14:56:24.259Z,1554821784.259 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T14:56:24.259Z,1554821784.259 [CTD_Seabird] No Fault, FailCount= 1
2019-04-09T14:56:24.642Z,1554821784.642 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T14:56:24.644Z,1554821784.644 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T14:56:24.658Z,1554821784.658 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T14:56:24.678Z,1554821784.678 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-09T14:56:27.909Z,1554821787.909 [CBIT](FAULT): Main Battery Failure. Count: 521
2019-04-09T14:56:29.916Z,1554821789.916 [Default:A.Wait](INFO): Done Waiting.
2019-04-09T14:56:29.916Z,1554821789.916 [Default:A.Wait] Stopped
2019-04-09T14:56:29.916Z,1554821789.916 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-04-09T14:56:30.308Z,1554821790.308 [Default:CheckIn] Running Loop=1
2019-04-09T14:56:30.308Z,1554821790.308 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-09T14:56:30.308Z,1554821790.308 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-09T14:56:30.715Z,1554821790.715 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-04-09T14:56:31.926Z,1554821791.926 [CBIT](FAULT): Main Battery Failure. Count: 531
2019-04-09T14:56:35.126Z,1554821795.126 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T14:56:35.126Z,1554821795.126 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-09T14:56:35.127Z,1554821795.127 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T14:56:35.164Z,1554821795.164 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T14:56:35.164Z,1554821795.164 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-09T14:56:35.650Z,1554821795.650 [Aanderaa_O2](INFO): Powering down
2019-04-09T14:56:35.971Z,1554821795.971 [CBIT](FAULT): Main Battery Failure. Count: 541
2019-04-09T14:56:37.694Z,1554821797.694 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T14:56:37.694Z,1554821797.694 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-09T14:56:37.694Z,1554821797.694 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T14:56:38.003Z,1554821798.003 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T14:56:38.098Z,1554821798.098 [CTD_Seabird](INFO): Powering down
2019-04-09T14:56:39.196Z,1554821799.196 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T14:56:39.196Z,1554821799.196 [CTD_Seabird] No Fault, FailCount= 2
2019-04-09T14:56:39.442Z,1554821799.442 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T14:56:39.444Z,1554821799.444 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T14:56:40.005Z,1554821800.005 [CBIT](FAULT): Main Battery Failure. Count: 551
2019-04-09T14:56:44.060Z,1554821804.060 [CBIT](FAULT): Main Battery Failure. Count: 561
2019-04-09T14:56:48.109Z,1554821808.109 [CBIT](FAULT): Main Battery Failure. Count: 571
2019-04-09T14:56:52.129Z,1554821812.129 [CBIT](FAULT): Main Battery Failure. Count: 581
2019-04-09T14:56:52.494Z,1554821812.494 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T14:56:52.494Z,1554821812.494 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-09T14:56:52.494Z,1554821812.494 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T14:56:52.530Z,1554821812.530 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T14:56:52.530Z,1554821812.530 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-09T14:56:52.906Z,1554821812.906 [CTD_Seabird](INFO): Powering down
2019-04-09T14:56:56.181Z,1554821816.181 [CBIT](FAULT): Main Battery Failure. Count: 591
2019-04-09T14:56:58.181Z,1554821818.181 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T14:56:58.181Z,1554821818.181 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-04-09T14:56:58.181Z,1554821818.181 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T14:56:58.194Z,1554821818.194 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T14:56:58.194Z,1554821818.194 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-04-09T14:56:58.195Z,1554821818.195 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T14:56:58.196Z,1554821818.196 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T14:56:58.196Z,1554821818.196 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-04-09T14:56:58.196Z,1554821818.196 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T14:56:58.207Z,1554821818.207 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T14:56:58.207Z,1554821818.207 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T14:56:58.207Z,1554821818.207 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T14:56:58.618Z,1554821818.618 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T14:56:58.618Z,1554821818.618 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-04-09T14:56:58.619Z,1554821818.619 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T14:56:58.619Z,1554821818.619 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-04-09T14:56:58.619Z,1554821818.619 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T14:56:58.620Z,1554821818.620 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-04-09T14:56:58.997Z,1554821818.997 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T14:57:00.209Z,1554821820.209 [CBIT](FAULT): Main Battery Failure. Count: 601
2019-04-09T14:57:04.260Z,1554821824.260 [CBIT](FAULT): Main Battery Failure. Count: 611
2019-04-09T14:57:08.285Z,1554821828.285 [CBIT](FAULT): Main Battery Failure. Count: 621
2019-04-09T14:57:12.328Z,1554821832.328 [CBIT](FAULT): Main Battery Failure. Count: 631
2019-04-09T14:57:16.369Z,1554821836.369 [CBIT](FAULT): Main Battery Failure. Count: 641
2019-04-09T14:57:20.416Z,1554821840.416 [CBIT](FAULT): Main Battery Failure. Count: 651
2019-04-09T14:57:24.450Z,1554821844.450 [CBIT](FAULT): Main Battery Failure. Count: 661
2019-04-09T14:57:28.497Z,1554821848.497 [CBIT](FAULT): Main Battery Failure. Count: 671
2019-04-09T14:57:32.525Z,1554821852.525 [CBIT](FAULT): Main Battery Failure. Count: 681
2019-04-09T14:57:36.585Z,1554821856.585 [CBIT](FAULT): Main Battery Failure. Count: 691
2019-04-09T14:57:40.627Z,1554821860.627 [CBIT](FAULT): Main Battery Failure. Count: 701
2019-04-09T14:57:44.642Z,1554821864.642 [CBIT](FAULT): Main Battery Failure. Count: 711
2019-04-09T14:57:48.683Z,1554821868.683 [CBIT](FAULT): Main Battery Failure. Count: 721
2019-04-09T14:57:52.729Z,1554821872.729 [CBIT](FAULT): Main Battery Failure. Count: 731
2019-04-09T14:57:56.776Z,1554821876.776 [CBIT](FAULT): Main Battery Failure. Count: 741
2019-04-09T14:57:57.970Z,1554821877.970 [BPC1](FAULT): Failed to initialize
2019-04-09T14:57:57.970Z,1554821877.970 [BPC1] Communications Fault, FailCount= 1
2019-04-09T14:57:57.970Z,1554821877.970 [BPC1](ERROR): Communications Fault
2019-04-09T14:57:57.985Z,1554821877.985 [CBIT](ERROR): Communications Fault in component: BPC1
2019-04-09T14:58:00.816Z,1554821880.816 [CBIT](FAULT): Main Battery Failure. Count: 751
2019-04-09T14:58:04.849Z,1554821884.849 [CBIT](FAULT): Main Battery Failure. Count: 761
2019-04-09T14:58:08.889Z,1554821888.889 [CBIT](FAULT): Main Battery Failure. Count: 771
2019-04-09T14:58:12.945Z,1554821892.945 [CBIT](FAULT): Main Battery Failure. Count: 781
2019-04-09T14:58:15.356Z,1554821895.356 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T14:58:15.356Z,1554821895.356 [MassServo] No Fault, FailCount= 5
2019-04-09T14:58:17.278Z,1554821897.278 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T14:58:17.278Z,1554821897.278 [MassServo] Communications Fault, FailCount= 1
2019-04-09T14:58:17.278Z,1554821897.278 [MassServo](ERROR): Communications Fault
2019-04-09T14:58:17.280Z,1554821897.280 [CBIT](FAULT): Main Battery Failure. Count: 791
2019-04-09T14:58:17.282Z,1554821897.282 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T14:58:17.397Z,1554821897.397 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T14:58:17.397Z,1554821897.397 [MassServo](INFO): Powering down
2019-04-09T14:58:18.195Z,1554821898.195 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T14:58:18.319Z,1554821898.319 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T14:58:18.323Z,1554821898.323 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T14:58:18.323Z,1554821898.323 [MassServo] No Fault, FailCount= 1
2019-04-09T14:58:20.910Z,1554821900.910 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T14:58:20.910Z,1554821900.910 [MassServo] Communications Fault, FailCount= 2
2019-04-09T14:58:20.910Z,1554821900.910 [MassServo](ERROR): Communications Fault
2019-04-09T14:58:20.914Z,1554821900.914 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T14:58:21.020Z,1554821901.020 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T14:58:21.021Z,1554821901.021 [MassServo](INFO): Powering down
2019-04-09T14:58:21.023Z,1554821901.023 [CBIT](FAULT): Main Battery Failure. Count: 801
2019-04-09T14:58:21.820Z,1554821901.820 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T14:58:21.939Z,1554821901.939 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T14:58:21.943Z,1554821901.943 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T14:58:21.943Z,1554821901.943 [MassServo] No Fault, FailCount= 2
2019-04-09T14:58:24.546Z,1554821904.546 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T14:58:24.546Z,1554821904.546 [MassServo] Communications Fault, FailCount= 3
2019-04-09T14:58:24.546Z,1554821904.546 [MassServo](ERROR): Communications Fault
2019-04-09T14:58:24.550Z,1554821904.550 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T14:58:24.657Z,1554821904.657 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T14:58:24.657Z,1554821904.657 [MassServo](INFO): Powering down
2019-04-09T14:58:25.064Z,1554821905.064 [CBIT](FAULT): Main Battery Failure. Count: 811
2019-04-09T14:58:25.452Z,1554821905.452 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T14:58:25.567Z,1554821905.567 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T14:58:25.573Z,1554821905.573 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T14:58:25.573Z,1554821905.573 [MassServo] No Fault, FailCount= 3
2019-04-09T14:58:28.198Z,1554821908.198 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T14:58:28.198Z,1554821908.198 [MassServo] Communications Fault, FailCount= 4
2019-04-09T14:58:28.198Z,1554821908.198 [MassServo](ERROR): Communications Fault
2019-04-09T14:58:28.202Z,1554821908.202 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T14:58:28.309Z,1554821908.309 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T14:58:28.309Z,1554821908.309 [MassServo](INFO): Powering down
2019-04-09T14:58:29.096Z,1554821909.096 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T14:58:29.211Z,1554821909.211 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T14:58:29.213Z,1554821909.213 [CBIT](FAULT): Main Battery Failure. Count: 821
2019-04-09T14:58:29.215Z,1554821909.215 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T14:58:29.215Z,1554821909.215 [MassServo] No Fault, FailCount= 4
2019-04-09T14:58:31.818Z,1554821911.818 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T14:58:31.818Z,1554821911.818 [MassServo] Communications Fault, FailCount= 5
2019-04-09T14:58:31.818Z,1554821911.818 [MassServo](ERROR): Communications Fault
2019-04-09T14:58:31.822Z,1554821911.822 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T14:58:31.823Z,1554821911.823 [CBIT](CRITICAL): Communications Fault in component: MassServo
2019-04-09T14:58:31.912Z,1554821911.912 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T14:58:31.912Z,1554821911.912 [MassServo](INFO): Powering down
2019-04-09T14:58:31.915Z,1554821911.915 [CBIT](INFO): Critical error at 20190409T145831
2019-04-09T14:58:32.728Z,1554821912.728 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T14:58:32.843Z,1554821912.843 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T14:58:32.847Z,1554821912.847 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T14:58:32.848Z,1554821912.848 [BR_Ping1D] No Fault, FailCount= 5
2019-04-09T14:58:33.124Z,1554821913.124 [BR_Ping1D](INFO): Start
2019-04-09T14:58:33.137Z,1554821913.137 [CBIT](FAULT): Main Battery Failure. Count: 831
2019-04-09T14:58:33.529Z,1554821913.529 [BR_Ping1D](INFO): Starting
2019-04-09T14:58:33.936Z,1554821913.936 [BR_Ping1D](INFO): Starting
2019-04-09T14:58:33.936Z,1554821913.936 [BR_Ping1D](INFO): readConfig
2019-04-09T14:58:33.936Z,1554821913.936 [BR_Ping1D](INFO): startup: 0
2019-04-09T14:58:33.937Z,1554821913.937 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T14:58:33.950Z,1554821913.950 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T14:58:33.951Z,1554821913.951 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T14:58:33.952Z,1554821913.952 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T14:58:33.953Z,1554821913.953 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T14:58:34.340Z,1554821914.340 [BR_Ping1D](INFO): Starting
2019-04-09T14:58:34.732Z,1554821914.732 [BR_Ping1D](INFO): Starting
2019-04-09T14:58:34.732Z,1554821914.732 [BR_Ping1D](INFO): readConfig
2019-04-09T14:58:34.733Z,1554821914.733 [BR_Ping1D](INFO): startup: 1
2019-04-09T14:58:34.733Z,1554821914.733 [BR_Ping1D](INFO): UART requesting status
2019-04-09T14:58:35.134Z,1554821915.134 [BR_Ping1D](INFO): Starting
2019-04-09T14:58:35.134Z,1554821915.134 [BR_Ping1D](INFO): readConfig
2019-04-09T14:58:35.134Z,1554821915.134 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:58:35.134Z,1554821915.134 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:58:35.134Z,1554821915.134 [BR_Ping1D](INFO): elapsed 1.1977
2019-04-09T14:58:35.135Z,1554821915.135 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:58:35.135Z,1554821915.135 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-09T14:58:35.136Z,1554821915.136 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T14:58:35.137Z,1554821915.137 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-09T14:58:35.137Z,1554821915.137 [BR_Ping1D](ERROR): Data Fault
2019-04-09T14:58:35.206Z,1554821915.206 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T14:58:35.537Z,1554821915.537 [BR_Ping1D](INFO): Stop
2019-04-09T14:58:35.538Z,1554821915.538 [BR_Ping1D](INFO): uninitialize
2019-04-09T14:58:35.946Z,1554821915.946 [BR_Ping1D](INFO): Stopping
2019-04-09T14:58:36.345Z,1554821916.345 [BR_Ping1D](INFO): Stopping
2019-04-09T14:58:36.747Z,1554821916.747 [BR_Ping1D](INFO): Stopped
2019-04-09T14:58:36.761Z,1554821916.761 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T14:58:36.761Z,1554821916.761 [BR_Ping1D] No Fault, FailCount= 1
2019-04-09T14:58:37.154Z,1554821917.154 [BR_Ping1D](INFO): Start
2019-04-09T14:58:37.169Z,1554821917.169 [CBIT](FAULT): Main Battery Failure. Count: 841
2019-04-09T14:58:37.558Z,1554821917.558 [BR_Ping1D](INFO): Starting
2019-04-09T14:58:37.967Z,1554821917.967 [BR_Ping1D](INFO): Starting
2019-04-09T14:58:37.967Z,1554821917.967 [BR_Ping1D](INFO): readConfig
2019-04-09T14:58:37.967Z,1554821917.967 [BR_Ping1D](INFO): startup: 0
2019-04-09T14:58:37.967Z,1554821917.967 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T14:58:37.978Z,1554821917.978 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T14:58:37.979Z,1554821917.979 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T14:58:37.980Z,1554821917.980 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T14:58:37.981Z,1554821917.981 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T14:58:38.380Z,1554821918.380 [BR_Ping1D](INFO): Starting
2019-04-09T14:58:38.787Z,1554821918.787 [BR_Ping1D](INFO): Starting
2019-04-09T14:58:38.787Z,1554821918.787 [BR_Ping1D](INFO): readConfig
2019-04-09T14:58:38.787Z,1554821918.787 [BR_Ping1D](INFO): startup: 1
2019-04-09T14:58:38.787Z,1554821918.787 [BR_Ping1D](INFO): UART requesting status
2019-04-09T14:58:39.184Z,1554821919.184 [BR_Ping1D](INFO): Starting
2019-04-09T14:58:39.184Z,1554821919.184 [BR_Ping1D](INFO): readConfig
2019-04-09T14:58:39.185Z,1554821919.185 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:58:39.185Z,1554821919.185 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:58:39.185Z,1554821919.185 [BR_Ping1D](INFO): elapsed 1.2176
2019-04-09T14:58:39.185Z,1554821919.185 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:58:39.186Z,1554821919.186 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-09T14:58:39.186Z,1554821919.186 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T14:58:39.187Z,1554821919.187 [BR_Ping1D] Data Fault, FailCount= 2
2019-04-09T14:58:39.187Z,1554821919.187 [BR_Ping1D](ERROR): Data Fault
2019-04-09T14:58:39.217Z,1554821919.217 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T14:58:39.563Z,1554821919.563 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-04-09T14:58:39.577Z,1554821919.577 [BR_Ping1D](INFO): Stop
2019-04-09T14:58:39.578Z,1554821919.578 [BR_Ping1D](INFO): uninitialize
2019-04-09T14:58:39.986Z,1554821919.986 [BR_Ping1D](INFO): Stopping
2019-04-09T14:58:40.382Z,1554821920.382 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:58:40.396Z,1554821920.396 [BR_Ping1D](INFO): Stopping
2019-04-09T14:58:40.801Z,1554821920.801 [BR_Ping1D](INFO): Stopped
2019-04-09T14:58:40.820Z,1554821920.820 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T14:58:40.820Z,1554821920.820 [BR_Ping1D] No Fault, FailCount= 2
2019-04-09T14:58:41.208Z,1554821921.208 [BR_Ping1D](INFO): Start
2019-04-09T14:58:41.221Z,1554821921.221 [CBIT](FAULT): Main Battery Failure. Count: 851
2019-04-09T14:58:41.599Z,1554821921.599 [BR_Ping1D](INFO): Starting
2019-04-09T14:58:42.002Z,1554821922.002 [BR_Ping1D](INFO): Starting
2019-04-09T14:58:42.002Z,1554821922.002 [BR_Ping1D](INFO): readConfig
2019-04-09T14:58:42.002Z,1554821922.002 [BR_Ping1D](INFO): startup: 0
2019-04-09T14:58:42.002Z,1554821922.002 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T14:58:42.018Z,1554821922.018 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T14:58:42.018Z,1554821922.018 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T14:58:42.019Z,1554821922.019 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T14:58:42.019Z,1554821922.019 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T14:58:42.406Z,1554821922.406 [BR_Ping1D](INFO): Starting
2019-04-09T14:58:42.806Z,1554821922.806 [BR_Ping1D](INFO): Starting
2019-04-09T14:58:42.806Z,1554821922.806 [BR_Ping1D](INFO): readConfig
2019-04-09T14:58:42.807Z,1554821922.807 [BR_Ping1D](INFO): startup: 1
2019-04-09T14:58:42.807Z,1554821922.807 [BR_Ping1D](INFO): UART requesting status
2019-04-09T14:58:43.210Z,1554821923.210 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:58:43.225Z,1554821923.225 [BR_Ping1D](INFO): Starting
2019-04-09T14:58:43.225Z,1554821923.225 [BR_Ping1D](INFO): readConfig
2019-04-09T14:58:43.225Z,1554821923.225 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:58:43.225Z,1554821923.225 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:58:43.225Z,1554821923.225 [BR_Ping1D](INFO): elapsed 1.2228
2019-04-09T14:58:43.226Z,1554821923.226 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:58:43.226Z,1554821923.226 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-09T14:58:43.227Z,1554821923.227 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T14:58:43.228Z,1554821923.228 [BR_Ping1D] Data Fault, FailCount= 3
2019-04-09T14:58:43.228Z,1554821923.228 [BR_Ping1D](ERROR): Data Fault
2019-04-09T14:58:43.248Z,1554821923.248 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T14:58:43.636Z,1554821923.636 [BR_Ping1D](INFO): Stop
2019-04-09T14:58:43.636Z,1554821923.636 [BR_Ping1D](INFO): uninitialize
2019-04-09T14:58:44.036Z,1554821924.036 [BR_Ping1D](INFO): Stopping
2019-04-09T14:58:44.423Z,1554821924.423 [BR_Ping1D](INFO): Stopping
2019-04-09T14:58:44.833Z,1554821924.833 [BR_Ping1D](INFO): Stopped
2019-04-09T14:58:44.848Z,1554821924.848 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T14:58:44.848Z,1554821924.848 [BR_Ping1D] No Fault, FailCount= 3
2019-04-09T14:58:45.234Z,1554821925.234 [BR_Ping1D](INFO): Start
2019-04-09T14:58:45.257Z,1554821925.257 [CBIT](FAULT): Main Battery Failure. Count: 861
2019-04-09T14:58:45.642Z,1554821925.642 [BR_Ping1D](INFO): Starting
2019-04-09T14:58:46.042Z,1554821926.042 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:58:46.056Z,1554821926.056 [BR_Ping1D](INFO): Starting
2019-04-09T14:58:46.056Z,1554821926.056 [BR_Ping1D](INFO): readConfig
2019-04-09T14:58:46.057Z,1554821926.057 [BR_Ping1D](INFO): startup: 0
2019-04-09T14:58:46.057Z,1554821926.057 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T14:58:46.070Z,1554821926.070 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T14:58:46.071Z,1554821926.071 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T14:58:46.073Z,1554821926.073 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T14:58:46.092Z,1554821926.092 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T14:58:46.456Z,1554821926.456 [BR_Ping1D](INFO): Starting
2019-04-09T14:58:46.860Z,1554821926.860 [BR_Ping1D](INFO): Starting
2019-04-09T14:58:46.860Z,1554821926.860 [BR_Ping1D](INFO): readConfig
2019-04-09T14:58:46.861Z,1554821926.861 [BR_Ping1D](INFO): startup: 1
2019-04-09T14:58:46.861Z,1554821926.861 [BR_Ping1D](INFO): UART requesting status
2019-04-09T14:58:47.254Z,1554821927.254 [BR_Ping1D](INFO): Starting
2019-04-09T14:58:47.254Z,1554821927.254 [BR_Ping1D](INFO): readConfig
2019-04-09T14:58:47.254Z,1554821927.254 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:58:47.254Z,1554821927.254 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:58:47.254Z,1554821927.254 [BR_Ping1D](INFO): elapsed 1.1976
2019-04-09T14:58:47.255Z,1554821927.255 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:58:47.255Z,1554821927.255 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-09T14:58:47.256Z,1554821927.256 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T14:58:47.257Z,1554821927.257 [BR_Ping1D] Data Fault, FailCount= 4
2019-04-09T14:58:47.257Z,1554821927.257 [BR_Ping1D](ERROR): Data Fault
2019-04-09T14:58:47.276Z,1554821927.276 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T14:58:47.657Z,1554821927.657 [BR_Ping1D](INFO): Stop
2019-04-09T14:58:47.658Z,1554821927.658 [BR_Ping1D](INFO): uninitialize
2019-04-09T14:58:48.067Z,1554821928.067 [BR_Ping1D](INFO): Stopping
2019-04-09T14:58:48.469Z,1554821928.469 [BR_Ping1D](INFO): Stopping
2019-04-09T14:58:48.874Z,1554821928.874 [BR_Ping1D](INFO): Stopped
2019-04-09T14:58:48.902Z,1554821928.902 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T14:58:48.902Z,1554821928.902 [BR_Ping1D] No Fault, FailCount= 4
2019-04-09T14:58:49.270Z,1554821929.270 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:58:49.284Z,1554821929.284 [BR_Ping1D](INFO): Start
2019-04-09T14:58:49.314Z,1554821929.314 [CBIT](FAULT): Main Battery Failure. Count: 871
2019-04-09T14:58:49.692Z,1554821929.692 [BR_Ping1D](INFO): Starting
2019-04-09T14:58:50.096Z,1554821930.096 [BR_Ping1D](INFO): Starting
2019-04-09T14:58:50.096Z,1554821930.096 [BR_Ping1D](INFO): readConfig
2019-04-09T14:58:50.096Z,1554821930.096 [BR_Ping1D](INFO): startup: 0
2019-04-09T14:58:50.097Z,1554821930.097 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T14:58:50.110Z,1554821930.110 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T14:58:50.111Z,1554821930.111 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T14:58:50.112Z,1554821930.112 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T14:58:50.113Z,1554821930.113 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T14:58:50.486Z,1554821930.486 [BR_Ping1D](INFO): Starting
2019-04-09T14:58:50.889Z,1554821930.889 [BR_Ping1D](INFO): Starting
2019-04-09T14:58:50.889Z,1554821930.889 [BR_Ping1D](INFO): readConfig
2019-04-09T14:58:50.890Z,1554821930.890 [BR_Ping1D](INFO): startup: 1
2019-04-09T14:58:50.890Z,1554821930.890 [BR_Ping1D](INFO): UART requesting status
2019-04-09T14:58:51.296Z,1554821931.296 [BR_Ping1D](INFO): Starting
2019-04-09T14:58:51.296Z,1554821931.296 [BR_Ping1D](INFO): readConfig
2019-04-09T14:58:51.296Z,1554821931.296 [BR_Ping1D](INFO): startup: 2
2019-04-09T14:58:51.296Z,1554821931.296 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T14:58:51.297Z,1554821931.297 [BR_Ping1D](INFO): elapsed 1.1998
2019-04-09T14:58:51.297Z,1554821931.297 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T14:58:51.297Z,1554821931.297 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-09T14:58:51.298Z,1554821931.298 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T14:58:51.299Z,1554821931.299 [BR_Ping1D] Data Fault, FailCount= 5
2019-04-09T14:58:51.299Z,1554821931.299 [BR_Ping1D](ERROR): Data Fault
2019-04-09T14:58:51.314Z,1554821931.314 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T14:58:51.314Z,1554821931.314 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-04-09T14:58:51.700Z,1554821931.700 [BR_Ping1D](INFO): Stop
2019-04-09T14:58:51.700Z,1554821931.700 [BR_Ping1D](INFO): uninitialize
2019-04-09T14:58:51.789Z,1554821931.789 [CBIT](INFO): Critical error at 20190409T145851
2019-04-09T14:58:52.098Z,1554821932.098 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:58:52.117Z,1554821932.117 [BR_Ping1D](INFO): Stopping
2019-04-09T14:58:52.520Z,1554821932.520 [BR_Ping1D](INFO): Stopping
2019-04-09T14:58:52.921Z,1554821932.921 [BR_Ping1D](INFO): Stopped
2019-04-09T14:58:53.329Z,1554821933.329 [CBIT](FAULT): Main Battery Failure. Count: 881
2019-04-09T14:58:55.330Z,1554821935.330 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:58:57.369Z,1554821937.369 [CBIT](FAULT): Main Battery Failure. Count: 891
2019-04-09T14:58:58.162Z,1554821938.162 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:58:59.021Z,1554821939.021 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T14:58:59.021Z,1554821939.021 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-04-09T14:58:59.021Z,1554821939.021 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T14:58:59.023Z,1554821939.023 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T14:58:59.023Z,1554821939.023 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-04-09T14:58:59.023Z,1554821939.023 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T14:58:59.024Z,1554821939.024 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T14:58:59.024Z,1554821939.024 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2019-04-09T14:58:59.024Z,1554821939.024 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T14:58:59.043Z,1554821939.043 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T14:58:59.043Z,1554821939.043 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T14:58:59.044Z,1554821939.044 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T14:58:59.415Z,1554821939.415 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T14:58:59.415Z,1554821939.415 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-04-09T14:58:59.415Z,1554821939.415 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T14:58:59.415Z,1554821939.415 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-04-09T14:58:59.416Z,1554821939.416 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T14:58:59.416Z,1554821939.416 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2019-04-09T14:58:59.789Z,1554821939.789 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T14:59:00.990Z,1554821940.990 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:59:01.446Z,1554821941.446 [CBIT](FAULT): Main Battery Failure. Count: 901
2019-04-09T14:59:03.022Z,1554821943.022 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:59:05.046Z,1554821945.046 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:59:05.477Z,1554821945.477 [CBIT](FAULT): Main Battery Failure. Count: 911
2019-04-09T14:59:05.480Z,1554821945.480 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T14:59:05.480Z,1554821945.480 [Aanderaa_O2] No Fault, FailCount= 3
2019-04-09T14:59:08.274Z,1554821948.274 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:59:09.505Z,1554821949.505 [CBIT](FAULT): Main Battery Failure. Count: 921
2019-04-09T14:59:11.103Z,1554821951.103 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:59:13.549Z,1554821953.549 [CBIT](FAULT): Main Battery Failure. Count: 931
2019-04-09T14:59:14.334Z,1554821954.334 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:59:15.934Z,1554821955.934 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T14:59:15.934Z,1554821955.934 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T14:59:15.934Z,1554821955.934 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T14:59:15.992Z,1554821955.992 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T14:59:16.458Z,1554821956.458 [Aanderaa_O2](INFO): Powering down
2019-04-09T14:59:17.166Z,1554821957.166 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:59:17.195Z,1554821957.195 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T14:59:17.195Z,1554821957.195 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-09T14:59:17.601Z,1554821957.601 [CBIT](FAULT): Main Battery Failure. Count: 941
2019-04-09T14:59:20.394Z,1554821960.394 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:59:21.618Z,1554821961.618 [CBIT](FAULT): Main Battery Failure. Count: 951
2019-04-09T14:59:22.841Z,1554821962.841 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T14:59:22.841Z,1554821962.841 [CTD_Seabird] No Fault, FailCount= 3
2019-04-09T14:59:22.942Z,1554821962.942 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T14:59:22.944Z,1554821962.944 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T14:59:23.222Z,1554821963.222 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:59:25.667Z,1554821965.667 [CBIT](FAULT): Main Battery Failure. Count: 961
2019-04-09T14:59:26.054Z,1554821966.054 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:59:27.650Z,1554821967.650 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T14:59:27.650Z,1554821967.650 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-09T14:59:27.650Z,1554821967.650 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T14:59:27.695Z,1554821967.695 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T14:59:28.174Z,1554821968.174 [Aanderaa_O2](INFO): Powering down
2019-04-09T14:59:28.190Z,1554821968.190 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:59:28.911Z,1554821968.911 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T14:59:28.911Z,1554821968.911 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-09T14:59:29.717Z,1554821969.717 [CBIT](FAULT): Main Battery Failure. Count: 971
2019-04-09T14:59:31.306Z,1554821971.306 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:59:33.745Z,1554821973.745 [CBIT](FAULT): Main Battery Failure. Count: 981
2019-04-09T14:59:34.134Z,1554821974.134 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:59:35.994Z,1554821975.994 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T14:59:35.994Z,1554821975.994 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-09T14:59:35.994Z,1554821975.994 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T14:59:36.173Z,1554821976.173 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T14:59:36.398Z,1554821976.398 [CTD_Seabird](INFO): Powering down
2019-04-09T14:59:37.366Z,1554821977.366 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:59:37.409Z,1554821977.409 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T14:59:37.409Z,1554821977.409 [CTD_Seabird] No Fault, FailCount= 1
2019-04-09T14:59:37.742Z,1554821977.742 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T14:59:37.744Z,1554821977.744 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T14:59:37.795Z,1554821977.795 [CBIT](FAULT): Main Battery Failure. Count: 991
2019-04-09T14:59:39.366Z,1554821979.366 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T14:59:39.366Z,1554821979.366 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-09T14:59:39.366Z,1554821979.366 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T14:59:39.416Z,1554821979.416 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T14:59:39.417Z,1554821979.417 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-09T14:59:39.890Z,1554821979.890 [Aanderaa_O2](INFO): Powering down
2019-04-09T14:59:40.190Z,1554821980.190 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:59:41.825Z,1554821981.825 [CBIT](FAULT): Main Battery Failure. Count: 1001
2019-04-09T14:59:43.422Z,1554821983.422 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:59:45.865Z,1554821985.865 [CBIT](FAULT): Main Battery Failure. Count: 1011
2019-04-09T14:59:46.250Z,1554821986.250 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:59:49.082Z,1554821989.082 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:59:49.921Z,1554821989.921 [CBIT](FAULT): Main Battery Failure. Count: 1021
2019-04-09T14:59:50.794Z,1554821990.794 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T14:59:50.794Z,1554821990.794 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-09T14:59:50.794Z,1554821990.794 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T14:59:51.098Z,1554821991.098 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:59:51.132Z,1554821991.132 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T14:59:51.198Z,1554821991.198 [CTD_Seabird](INFO): Powering down
2019-04-09T14:59:52.333Z,1554821992.333 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T14:59:52.333Z,1554821992.333 [CTD_Seabird] No Fault, FailCount= 2
2019-04-09T14:59:52.538Z,1554821992.538 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T14:59:52.540Z,1554821992.540 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T14:59:53.126Z,1554821993.126 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:59:53.958Z,1554821993.958 [CBIT](FAULT): Main Battery Failure. Count: 1031
2019-04-09T14:59:56.354Z,1554821996.354 [NAL9602](DEBUG): Fix Requested
2019-04-09T14:59:57.985Z,1554821997.985 [CBIT](FAULT): Main Battery Failure. Count: 1041
2019-04-09T14:59:59.178Z,1554821999.178 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:00:02.026Z,1554822002.026 [CBIT](FAULT): Main Battery Failure. Count: 1051
2019-04-09T15:00:02.410Z,1554822002.410 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:00:05.238Z,1554822005.238 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:00:05.590Z,1554822005.590 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T15:00:05.590Z,1554822005.590 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-09T15:00:05.590Z,1554822005.590 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T15:00:05.684Z,1554822005.684 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T15:00:05.684Z,1554822005.684 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-09T15:00:05.994Z,1554822005.994 [CTD_Seabird](INFO): Powering down
2019-04-09T15:00:06.081Z,1554822006.081 [CBIT](FAULT): Main Battery Failure. Count: 1061
2019-04-09T15:00:08.004Z,1554822008.004 [CommandLine](IMPORTANT): got command restart application
2019-04-09T15:00:08.470Z,1554822008.470 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:00:09.030Z,1554822009.030 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T15:00:09.030Z,1554822009.030 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:00:09.050Z,1554822009.050 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-04-09T15:00:09.050Z,1554822009.050 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:00:09.051Z,1554822009.051 [CommandLine](INFO): Join timeout helper Thread ID is 1955
2019-04-09T15:00:09.051Z,1554822009.051 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-04-09T15:00:09.051Z,1554822009.051 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:00:09.052Z,1554822009.052 [NavChartDb](INFO): Join timeout helper Thread ID is 1956
2019-04-09T15:00:09.182Z,1554822009.182 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T15:00:09.182Z,1554822009.182 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:00:09.190Z,1554822009.190 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-04-09T15:00:09.190Z,1554822009.190 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:00:09.190Z,1554822009.190 [Radio_Surface](INFO): Join timeout helper Thread ID is 1957
2019-04-09T15:00:09.430Z,1554822009.430 [Radio_Surface](INFO): Powering down
2019-04-09T15:00:09.431Z,1554822009.431 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T15:00:09.431Z,1554822009.431 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:00:09.438Z,1554822009.438 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-04-09T15:00:09.438Z,1554822009.438 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:00:09.438Z,1554822009.438 [DataOverHttps](INFO): Join timeout helper Thread ID is 1958
2019-04-09T15:00:09.742Z,1554822009.742 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T15:00:09.742Z,1554822009.742 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:00:09.758Z,1554822009.758 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-04-09T15:00:09.758Z,1554822009.758 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:00:09.758Z,1554822009.758 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1959
2019-04-09T15:00:10.109Z,1554822010.109 [CBIT](FAULT): Main Battery Failure. Count: 1071
2019-04-09T15:00:10.154Z,1554822010.154 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T15:00:10.155Z,1554822010.155 [WetLabsBB2FL](INFO): Powering down
2019-04-09T15:00:10.155Z,1554822010.155 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:00:10.163Z,1554822010.163 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-04-09T15:00:10.163Z,1554822010.163 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:00:10.163Z,1554822010.163 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1960
2019-04-09T15:00:10.202Z,1554822010.202 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T15:00:10.203Z,1554822010.203 [CTD_Seabird](INFO): Powering down
2019-04-09T15:00:10.214Z,1554822010.214 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:00:10.227Z,1554822010.227 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-04-09T15:00:10.227Z,1554822010.227 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:00:10.227Z,1554822010.227 [logger](INFO): Join timeout helper Thread ID is 1961
2019-04-09T15:00:10.282Z,1554822010.282 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T15:00:10.282Z,1554822010.282 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:00:10.287Z,1554822010.287 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-04-09T15:00:10.287Z,1554822010.287 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:00:10.287Z,1554822010.287 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-04-09T15:00:10.287Z,1554822010.287 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:00:10.287Z,1554822010.287 [controlThread](INFO): Join timeout helper Thread ID is 1962
2019-04-09T15:00:10.470Z,1554822010.470 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T15:00:10.470Z,1554822010.470 [controlThread](DEBUG): Uninitializing ControlThread
2019-04-09T15:00:10.471Z,1554822010.471 [Aanderaa_O2](INFO): Powering down
2019-04-09T15:00:10.473Z,1554822010.473 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T15:00:10.474Z,1554822010.474 [NAL9602](INFO): Powering down
2019-04-09T15:00:10.475Z,1554822010.475 [BR_Ping1D](INFO): uninitialize
2019-04-09T15:00:10.476Z,1554822010.476 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-04-09T15:00:10.477Z,1554822010.477 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-04-09T15:00:10.477Z,1554822010.477 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-04-09T15:00:10.478Z,1554822010.478 [MissionManager](INFO): Uninitializing Mission Default
2019-04-09T15:00:10.478Z,1554822010.478 [Default] Stopped
2019-04-09T15:00:10.478Z,1554822010.478 [Default](DEBUG): Aggregate::uninitialize Default
2019-04-09T15:00:10.478Z,1554822010.478 [Default:B.GoToSurface] Stopped
2019-04-09T15:00:10.478Z,1554822010.478 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-09T15:00:10.478Z,1554822010.478 [Default:CheckIn] Stopped
2019-04-09T15:00:10.478Z,1554822010.478 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-09T15:00:10.478Z,1554822010.478 [Default:CheckIn:Read_GPS] Stopped
2019-04-09T15:00:10.481Z,1554822010.481 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-04-09T15:00:10.481Z,1554822010.481 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-04-09T15:00:10.481Z,1554822010.481 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-04-09T15:00:10.481Z,1554822010.481 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-04-09T15:00:10.482Z,1554822010.482 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-04-09T15:00:10.482Z,1554822010.482 [BuoyancyServo](INFO): Powering down
2019-04-09T15:00:10.494Z,1554822010.494 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-09T15:00:10.494Z,1554822010.494 [ElevatorServo](INFO): Powering down
2019-04-09T15:00:10.495Z,1554822010.495 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T15:00:10.495Z,1554822010.495 [MassServo](INFO): Powering down
2019-04-09T15:00:10.496Z,1554822010.496 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-09T15:00:10.496Z,1554822010.496 [RudderServo](INFO): Powering down
2019-04-09T15:00:10.497Z,1554822010.497 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-04-09T15:00:10.497Z,1554822010.497 [ThrusterServo](INFO): Powering down
2019-04-09T15:00:10.498Z,1554822010.498 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-04-09T15:00:10.498Z,1554822010.498 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-04-09T15:00:10.498Z,1554822010.498 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-04-09T15:00:10.498Z,1554822010.498 [CBIT](DEBUG): Powering off loads.
2019-04-09T15:00:10.510Z,1554822010.510 [CBIT](DEBUG): Disabling WDT.
2019-04-09T15:00:10.522Z,1554822010.522 [CBIT](DEBUG): Opening all GF detection circuits.
2019-04-09T15:00:10.523Z,1554822010.523 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:00:10.669Z,1554822010.669 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:00:10.674Z,1554822010.674 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:00:10.741Z,1554822010.741 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:00:10.746Z,1554822010.746 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:00:10.797Z,1554822010.797 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:00:10.855Z,1554822010.855 [logger ThreadHandler](INFO): Thread cancelled.