2019-04-01T22:22:58.647Z,1554157378.647 [Supervisor](DEBUG): Initializing supervisor. 2019-04-01T22:22:58.650Z,1554157378.650 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-04-01T22:22:58.651Z,1554157378.651 [SyncHandler](INFO): Protected caller Thread ID is 4560 2019-04-01T22:22:58.652Z,1554157378.652 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-04-01T22:22:58.653Z,1554157378.653 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-04-01T22:22:58.653Z,1554157378.653 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4561 2019-04-01T22:22:58.656Z,1554157378.656 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-04-01T22:22:58.668Z,1554157378.668 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-04-01T22:22:58.669Z,1554157378.669 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-04-01T22:22:58.669Z,1554157378.669 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4562 2019-04-01T22:22:58.670Z,1554157378.670 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-04-01T22:22:58.671Z,1554157378.671 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-04-01T22:22:58.671Z,1554157378.671 [logger ThreadHandler](INFO): Protected caller Thread ID is 4563 2019-04-01T22:22:58.673Z,1554157378.673 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-04-01T22:22:58.674Z,1554157378.674 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-04-01T22:22:58.675Z,1554157378.675 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-04-01T22:22:58.770Z,1554157378.770 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-04-01T22:22:58.771Z,1554157378.771 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-04-01T22:22:58.971Z,1554157378.971 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-04-01T22:22:58.972Z,1554157378.972 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-04-01T22:22:59.109Z,1554157379.109 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-04-01T22:22:59.109Z,1554157379.109 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-04-01T22:22:59.570Z,1554157379.570 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-04-01T22:22:59.570Z,1554157379.570 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-04-01T22:23:00.007Z,1554157380.007 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-04-01T22:23:00.008Z,1554157380.008 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-04-01T22:23:00.469Z,1554157380.469 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-04-01T22:23:00.470Z,1554157380.470 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-04-01T22:23:00.775Z,1554157380.775 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-04-01T22:23:00.775Z,1554157380.775 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-04-01T22:23:01.106Z,1554157381.106 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-04-01T22:23:01.107Z,1554157381.107 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-04-01T22:23:01.495Z,1554157381.495 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-04-01T22:23:01.496Z,1554157381.496 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-04-01T22:23:01.646Z,1554157381.646 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-04-01T22:23:01.647Z,1554157381.647 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-04-01T22:23:01.752Z,1554157381.752 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-04-01T22:23:01.752Z,1554157381.752 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-04-01T22:23:01.834Z,1554157381.834 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-04-01T22:23:01.936Z,1554157381.936 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-04-01T22:23:01.937Z,1554157381.937 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-04-01T22:23:02.133Z,1554157382.133 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-04-01T22:23:02.133Z,1554157382.133 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-04-01T22:23:02.339Z,1554157382.339 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-04-01T22:23:02.341Z,1554157382.341 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2019-04-01T22:23:02.342Z,1554157382.342 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2019-04-01T22:23:02.425Z,1554157382.425 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2019-04-01T22:23:02.654Z,1554157382.654 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-04-01T22:23:02.655Z,1554157382.655 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2019-04-01T22:23:02.750Z,1554157382.750 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2019-04-01T22:23:02.926Z,1554157382.926 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2019-04-01T22:23:03.682Z,1554157383.682 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2019-04-01T22:23:03.769Z,1554157383.769 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2019-04-01T22:23:03.867Z,1554157383.867 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2019-04-01T22:23:03.961Z,1554157383.961 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2019-04-01T22:23:04.113Z,1554157384.113 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2019-04-01T22:23:04.218Z,1554157384.218 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2019-04-01T22:23:04.317Z,1554157384.317 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/ 2019-04-01T22:23:04.318Z,1554157384.318 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-04-01T22:23:04.322Z,1554157384.322 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-04-01T22:23:04.418Z,1554157384.418 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-04-01T22:23:04.418Z,1554157384.418 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-04-01T22:23:04.543Z,1554157384.543 [BuoyancyServo] Loaded 2019-04-01T22:23:04.544Z,1554157384.544 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-04-01T22:23:04.559Z,1554157384.559 [ElevatorServo] Loaded 2019-04-01T22:23:04.559Z,1554157384.559 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-04-01T22:23:04.573Z,1554157384.573 [MassServo] Loaded 2019-04-01T22:23:04.573Z,1554157384.573 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-04-01T22:23:04.589Z,1554157384.589 [RudderServo] Loaded 2019-04-01T22:23:04.589Z,1554157384.589 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-04-01T22:23:04.603Z,1554157384.603 [ThrusterServo] Loaded 2019-04-01T22:23:04.603Z,1554157384.603 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-04-01T22:23:04.603Z,1554157384.603 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-04-01T22:23:04.604Z,1554157384.604 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-04-01T22:23:04.734Z,1554157384.734 [SBIT](DEBUG): Construct Startup Built In Test. 2019-04-01T22:23:04.761Z,1554157384.761 [SBIT] Loaded 2019-04-01T22:23:04.762Z,1554157384.762 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-04-01T22:23:04.762Z,1554157384.762 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-04-01T22:23:04.790Z,1554157384.790 [IBIT] Loaded 2019-04-01T22:23:04.790Z,1554157384.790 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-04-01T22:23:04.793Z,1554157384.793 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-04-01T22:23:05.170Z,1554157385.170 [CBIT] Loaded 2019-04-01T22:23:05.170Z,1554157385.170 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-04-01T22:23:05.171Z,1554157385.171 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-04-01T22:23:05.171Z,1554157385.171 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-04-01T22:23:05.347Z,1554157385.347 [Aanderaa_O2] Loaded 2019-04-01T22:23:05.348Z,1554157385.348 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2019-04-01T22:23:05.357Z,1554157385.357 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2019-04-01T22:23:05.362Z,1554157385.362 [CTD_Seabird](INFO): created writer for : sea_water_density 2019-04-01T22:23:05.364Z,1554157385.364 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2019-04-01T22:23:05.369Z,1554157385.369 [CTD_Seabird](INFO): created writer for : depth 2019-04-01T22:23:05.369Z,1554157385.369 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2019-04-01T22:23:05.374Z,1554157385.374 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2019-04-01T22:23:05.375Z,1554157385.375 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2019-04-01T22:23:05.380Z,1554157385.380 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2019-04-01T22:23:05.381Z,1554157385.381 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2019-04-01T22:23:05.386Z,1554157385.386 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2019-04-01T22:23:05.387Z,1554157385.387 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2019-04-01T22:23:05.392Z,1554157385.392 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2019-04-01T22:23:05.392Z,1554157385.392 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2019-04-01T22:23:05.397Z,1554157385.397 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2019-04-01T22:23:05.423Z,1554157385.423 [CTD_Seabird] Loaded 2019-04-01T22:23:05.423Z,1554157385.423 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2019-04-01T22:23:05.424Z,1554157385.424 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406654E0 2019-04-01T22:23:05.424Z,1554157385.424 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4642 2019-04-01T22:23:05.450Z,1554157385.450 [ESPComponent] Loaded 2019-04-01T22:23:05.450Z,1554157385.450 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2019-04-01T22:23:05.463Z,1554157385.463 [PAR_Licor] Loaded 2019-04-01T22:23:05.464Z,1554157385.464 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2019-04-01T22:23:05.510Z,1554157385.510 [WetLabsBB2FL] Loaded 2019-04-01T22:23:05.511Z,1554157385.511 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-04-01T22:23:05.511Z,1554157385.511 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406954E0 2019-04-01T22:23:05.512Z,1554157385.512 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4643 2019-04-01T22:23:05.513Z,1554157385.513 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-04-01T22:23:05.513Z,1554157385.513 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-04-01T22:23:05.553Z,1554157385.553 [DepthRateCalculator] Loaded 2019-04-01T22:23:05.553Z,1554157385.553 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-04-01T22:23:05.558Z,1554157385.558 [PitchRateCalculator] Loaded 2019-04-01T22:23:05.559Z,1554157385.559 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-04-01T22:23:05.574Z,1554157385.574 [SpeedCalculator] Loaded 2019-04-01T22:23:05.574Z,1554157385.574 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-04-01T22:23:05.595Z,1554157385.595 [TempGradientCalculator] Loaded 2019-04-01T22:23:05.595Z,1554157385.595 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-04-01T22:23:05.601Z,1554157385.601 [YawRateCalculator] Loaded 2019-04-01T22:23:05.601Z,1554157385.601 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-04-01T22:23:05.633Z,1554157385.633 [ElevatorOffsetCalculator] Loaded 2019-04-01T22:23:05.634Z,1554157385.634 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-04-01T22:23:05.634Z,1554157385.634 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-04-01T22:23:05.635Z,1554157385.635 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-04-01T22:23:05.660Z,1554157385.660 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-04-01T22:23:05.661Z,1554157385.661 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-04-01T22:23:05.760Z,1554157385.760 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-04-01T22:23:05.761Z,1554157385.761 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-04-01T22:23:05.818Z,1554157385.818 [DeadReckonUsingSpeedCalculator] Loaded 2019-04-01T22:23:05.819Z,1554157385.819 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2019-04-01T22:23:05.877Z,1554157385.877 [DeadReckonWithRespectToSeafloor] Loaded 2019-04-01T22:23:05.877Z,1554157385.877 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2019-04-01T22:23:05.897Z,1554157385.897 [NavChart] Loaded 2019-04-01T22:23:05.897Z,1554157385.897 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-04-01T22:23:05.901Z,1554157385.901 [UniversalFixResidualReporter] Loaded 2019-04-01T22:23:05.902Z,1554157385.902 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-04-01T22:23:05.902Z,1554157385.902 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-04-01T22:23:05.903Z,1554157385.903 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-04-01T22:23:06.307Z,1554157386.307 [AcousticModem_Benthos_ATM900] Loaded 2019-04-01T22:23:06.307Z,1554157386.307 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread. 2019-04-01T22:23:06.382Z,1554157386.382 [DataOverHttps] Loaded 2019-04-01T22:23:06.382Z,1554157386.382 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-04-01T22:23:06.383Z,1554157386.383 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4096C4E0 2019-04-01T22:23:06.383Z,1554157386.383 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4644 2019-04-01T22:23:06.396Z,1554157386.396 [Depth_Keller] Loaded 2019-04-01T22:23:06.396Z,1554157386.396 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-04-01T22:23:06.485Z,1554157386.485 [NAL9602] Loaded 2019-04-01T22:23:06.486Z,1554157386.486 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-04-01T22:23:06.491Z,1554157386.491 [Onboard] Loaded 2019-04-01T22:23:06.491Z,1554157386.491 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-04-01T22:23:06.494Z,1554157386.494 [Radio_Surface] Loaded 2019-04-01T22:23:06.495Z,1554157386.495 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-04-01T22:23:06.496Z,1554157386.496 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4099C4E0 2019-04-01T22:23:06.496Z,1554157386.496 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4645 2019-04-01T22:23:07.747Z,1554157387.747 [BPC1] Loaded 2019-04-01T22:23:07.747Z,1554157387.747 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-04-01T22:23:07.749Z,1554157387.749 [BR_Ping](INFO): constructor 2019-04-01T22:23:07.755Z,1554157387.755 [BR_Ping](CRITICAL): No configuration setting for: BR_Ping.verbosity 2019-04-01T22:23:07.757Z,1554157387.757 [BR_Ping] Loaded 2019-04-01T22:23:07.757Z,1554157387.757 [ComponentRegistry](DEBUG): SyncComponent "BR_Ping" handled in the control thread. 2019-04-01T22:23:07.757Z,1554157387.757 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-04-01T22:23:07.758Z,1554157387.758 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-04-01T22:23:07.820Z,1554157387.820 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-04-01T22:23:07.820Z,1554157387.820 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-04-01T22:23:07.886Z,1554157387.886 [VerticalControl](DEBUG): Construct VerticalControl. 2019-04-01T22:23:07.968Z,1554157387.968 [VerticalControl] Loaded 2019-04-01T22:23:07.968Z,1554157387.968 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-04-01T22:23:07.969Z,1554157387.969 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-04-01T22:23:08.025Z,1554157388.025 [HorizontalControl] Loaded 2019-04-01T22:23:08.026Z,1554157388.026 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-04-01T22:23:08.026Z,1554157388.026 [SpeedControl](DEBUG): Construct SpeedControl. 2019-04-01T22:23:08.028Z,1554157388.028 [SpeedControl] Loaded 2019-04-01T22:23:08.028Z,1554157388.028 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-04-01T22:23:08.029Z,1554157388.029 [LoopControl](DEBUG): Construct LoopControl. 2019-04-01T22:23:08.029Z,1554157388.029 [LoopControl] Loaded 2019-04-01T22:23:08.030Z,1554157388.030 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-04-01T22:23:08.030Z,1554157388.030 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-04-01T22:23:08.031Z,1554157388.031 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-04-01T22:23:08.087Z,1554157388.087 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-04-01T22:23:08.088Z,1554157388.088 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-04-01T22:23:08.479Z,1554157388.479 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-04-01T22:23:08.483Z,1554157388.483 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-04-01T22:23:08.484Z,1554157388.484 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-04-01T22:23:08.490Z,1554157388.490 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-04-01T22:23:08.491Z,1554157388.491 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AC94E0 2019-04-01T22:23:08.492Z,1554157388.492 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4646 2019-04-01T22:23:08.496Z,1554157388.496 [Supervisor](INFO): Main Thread ID is 803 2019-04-01T22:23:08.497Z,1554157388.497 [Supervisor](DEBUG): Running supervisor. 2019-04-01T22:23:08.497Z,1554157388.497 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4647 2019-04-01T22:23:08.499Z,1554157388.499 [controlThread ThreadHandler](INFO): Handler Thread ID is 4648 2019-04-01T22:23:08.500Z,1554157388.500 [controlThread](DEBUG): Initializing ControlThread 2019-04-01T22:23:08.502Z,1554157388.502 [SBIT](INFO): Initialize SBIT Component. 2019-04-01T22:23:08.502Z,1554157388.502 [SBIT](IMPORTANT): git: 2019-03-18-25-g41eb097 2019-04-01T22:23:08.502Z,1554157388.502 [SBIT](INFO): git hash: 41eb097d057d44a6b31f942ace2973a2253fba2e 2019-04-01T22:23:08.503Z,1554157388.503 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-04-01T22:23:08.504Z,1554157388.504 [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-01T22:23:08.505Z,1554157388.505 [SBIT](INFO): Beginning SBIT in 24.000000 seconds. 2019-04-01T22:23:08.506Z,1554157388.506 [IBIT](INFO): Initialize IBIT Component. 2019-04-01T22:23:08.506Z,1554157388.506 [CBIT](DEBUG): Initialize CBIT Component. 2019-04-01T22:23:08.507Z,1554157388.507 [logger ThreadHandler](INFO): Handler Thread ID is 4649 2019-04-01T22:23:08.521Z,1554157388.521 [CBIT](DEBUG): Initialized mux pins. 2019-04-01T22:23:08.521Z,1554157388.521 [CBIT](DEBUG): Initializing the watchdog timer. 2019-04-01T22:23:08.533Z,1554157388.533 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4650 2019-04-01T22:23:08.534Z,1554157388.534 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-04-01T22:23:08.538Z,1554157388.538 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-04-01T22:23:08.539Z,1554157388.539 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4652 2019-04-01T22:23:08.540Z,1554157388.540 [WetLabsBB2FL](INFO): Powering down 2019-04-01T22:23:08.545Z,1554157388.545 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-04-01T22:23:08.545Z,1554157388.545 [CBIT](DEBUG): Initializing heartbeat. 2019-04-01T22:23:08.569Z,1554157388.569 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4653 2019-04-01T22:23:08.570Z,1554157388.570 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-04-01T22:23:08.578Z,1554157388.578 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4654 2019-04-01T22:23:08.593Z,1554157388.593 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4655 2019-04-01T22:23:08.596Z,1554157388.596 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-04-01T22:23:08.596Z,1554157388.596 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-04-01T22:23:08.597Z,1554157388.597 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-04-01T22:23:08.597Z,1554157388.597 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-04-01T22:23:08.597Z,1554157388.597 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-04-01T22:23:08.597Z,1554157388.597 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-04-01T22:23:08.597Z,1554157388.597 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-04-01T22:23:08.598Z,1554157388.598 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-04-01T22:23:08.598Z,1554157388.598 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-04-01T22:23:08.598Z,1554157388.598 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-04-01T22:23:08.598Z,1554157388.598 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-04-01T22:23:08.598Z,1554157388.598 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-04-01T22:23:08.598Z,1554157388.598 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-04-01T22:23:08.599Z,1554157388.599 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-04-01T22:23:08.599Z,1554157388.599 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-04-01T22:23:08.599Z,1554157388.599 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-04-01T22:23:08.617Z,1554157388.617 [CBIT](DEBUG): Deactivating GF circuits. 2019-04-01T22:23:08.617Z,1554157388.617 [CBIT](DEBUG): Deactivating emergency mode. 2019-04-01T22:23:08.653Z,1554157388.653 [CBIT](DEBUG): Backplane powered. 2019-04-01T22:23:08.657Z,1554157388.657 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-04-01T22:23:08.657Z,1554157388.657 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-04-01T22:23:08.658Z,1554157388.658 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-04-01T22:23:08.658Z,1554157388.658 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-04-01T22:23:08.659Z,1554157388.659 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-04-01T22:23:08.659Z,1554157388.659 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-04-01T22:23:08.660Z,1554157388.660 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-04-01T22:23:08.661Z,1554157388.661 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-04-01T22:23:08.661Z,1554157388.661 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-04-01T22:23:08.666Z,1554157388.666 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-04-01T22:23:08.668Z,1554157388.668 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-04-01T22:23:08.668Z,1554157388.668 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-04-01T22:23:08.673Z,1554157388.673 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-04-01T22:23:08.674Z,1554157388.674 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-04-01T22:23:08.700Z,1554157388.700 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-04-01T22:23:08.739Z,1554157388.739 [MissionManager](DEBUG): 2019-04-01T22:23:08.740Z,1554157388.740 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-04-01T22:23:08.837Z,1554157388.837 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-04-01T22:23:08.839Z,1554157388.839 [Default:A.Wait](DEBUG): Construct Wait. 2019-04-01T22:23:08.840Z,1554157388.840 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-04-01T22:23:08.867Z,1554157388.867 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-04-01T22:23:08.870Z,1554157388.870 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-04-01T22:23:08.891Z,1554157388.891 [Default:E.Execute](DEBUG): Construct Execute. 2019-04-01T22:23:08.895Z,1554157388.895 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2019-04-01T22:23:08.925Z,1554157388.925 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,Depth_Keller,NAL9602,Onboard,BPC1,BR_Ping,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-01T22:23:08.938Z,1554157388.938 [ESPComponent](INFO): powering down ESP 2019-04-01T22:23:08.981Z,1554157388.981 [Radio_Surface](INFO): Powering up 2019-04-01T22:23:09.385Z,1554157389.385 [PAR_Licor](INFO): A/D timeout, 9 tries over 141 ms 2019-04-01T22:23:09.386Z,1554157389.386 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:09.386Z,1554157389.386 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:09.387Z,1554157389.387 [AcousticModem_Benthos_ATM900](INFO): Powering up 2019-04-01T22:23:09.387Z,1554157389.387 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2019-04-01T22:23:09.569Z,1554157389.569 [BR_Ping](INFO): Start 2019-04-01T22:23:09.619Z,1554157389.619 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-04-01T22:23:09.625Z,1554157389.625 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-04-01T22:23:09.626Z,1554157389.626 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-04-01T22:23:09.633Z,1554157389.633 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-04-01T22:23:09.634Z,1554157389.634 [MassServo](DEBUG): Initializing EZServoServo. 2019-04-01T22:23:09.641Z,1554157389.641 [MassServo](DEBUG): Initializing MassServo. 2019-04-01T22:23:09.642Z,1554157389.642 [RudderServo](DEBUG): Initializing EZServoServo. 2019-04-01T22:23:09.649Z,1554157389.649 [RudderServo](DEBUG): Initializing RudderServo. 2019-04-01T22:23:09.650Z,1554157389.650 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-04-01T22:23:09.657Z,1554157389.657 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-04-01T22:23:09.659Z,1554157389.659 [CommandLine](FAULT): Scheduling is paused 2019-04-01T22:23:09.660Z,1554157389.660 [CBIT](INFO): Critical error at 20190401T222307 2019-04-01T22:23:09.660Z,1554157389.660 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2019-04-01T22:23:09.661Z,1554157389.661 [CBIT](FAULT): Main Battery Failure. Count: 1 2019-04-01T22:23:09.665Z,1554157389.665 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:09.665Z,1554157389.665 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:09.665Z,1554157389.665 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:09.795Z,1554157389.795 [BR_Ping](INFO): Starting 2019-04-01T22:23:10.205Z,1554157390.205 [PAR_Licor](INFO): A/D timeout, 8 tries over 130 ms 2019-04-01T22:23:10.205Z,1554157390.205 [PAR_Licor] Data Fault, FailCount= 2 2019-04-01T22:23:10.205Z,1554157390.205 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:10.228Z,1554157390.228 [BR_Ping](INFO): Starting 2019-04-01T22:23:10.228Z,1554157390.228 [BR_Ping](INFO): UART setting interval 2019-04-01T22:23:10.573Z,1554157390.573 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2019-04-01T22:23:10.573Z,1554157390.573 [RudderServo](FAULT): Rudder failed to initialize 2019-04-01T22:23:10.573Z,1554157390.573 [RudderServo] Communications Fault, FailCount= 1 2019-04-01T22:23:10.573Z,1554157390.573 [RudderServo](ERROR): Communications Fault 2019-04-01T22:23:10.684Z,1554157390.684 [CBIT](ERROR): Communications Fault in component: RudderServo 2019-04-01T22:23:10.685Z,1554157390.685 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:10.685Z,1554157390.685 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:10.685Z,1554157390.685 [PAR_Licor] No Fault, FailCount= 2 2019-04-01T22:23:10.712Z,1554157390.712 [BR_Ping](INFO): Starting 2019-04-01T22:23:10.712Z,1554157390.712 [BR_Ping](INFO): UART requesting status 2019-04-01T22:23:10.866Z,1554157390.866 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-04-01T22:23:10.866Z,1554157390.866 [RudderServo](INFO): Powering down 2019-04-01T22:23:11.113Z,1554157391.113 [BR_Ping](INFO): Starting 2019-04-01T22:23:11.113Z,1554157391.113 [BR_Ping](INFO): UART waiting for response 2019-04-01T22:23:11.113Z,1554157391.113 [BR_Ping](INFO): UART data available 2019-04-01T22:23:11.114Z,1554157391.114 [BR_Ping](INFO): UART response: 42520A00BA04000003001800BB13F40102013D03 2019-04-01T22:23:11.625Z,1554157391.625 [PAR_Licor](INFO): A/D timeout, 9 tries over 126 ms 2019-04-01T22:23:11.625Z,1554157391.625 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:11.625Z,1554157391.625 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:11.660Z,1554157391.660 [BR_Ping](INFO): Starting 2019-04-01T22:23:11.699Z,1554157391.699 [RudderServo](DEBUG): Initializing EZServoServo. 2019-04-01T22:23:11.822Z,1554157391.822 [RudderServo](DEBUG): Initializing RudderServo. 2019-04-01T22:23:11.826Z,1554157391.826 [CBIT](INFO): Clearing failed state for component RudderServo 2019-04-01T22:23:11.826Z,1554157391.826 [RudderServo] No Fault, FailCount= 1 2019-04-01T22:23:11.826Z,1554157391.826 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:11.826Z,1554157391.826 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:11.826Z,1554157391.826 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:11.924Z,1554157391.924 [BR_Ping](INFO): Runnable 2019-04-01T22:23:11.927Z,1554157391.927 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:12.257Z,1554157392.257 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2019-04-01T22:23:12.257Z,1554157392.257 [MassServo] Communications Fault, FailCount= 1 2019-04-01T22:23:12.257Z,1554157392.257 [MassServo](ERROR): Communications Fault 2019-04-01T22:23:12.284Z,1554157392.284 [CBIT](ERROR): Communications Fault in component: MassServo 2019-04-01T22:23:12.341Z,1554157392.341 [BR_Ping](INFO): Runnable 2019-04-01T22:23:12.342Z,1554157392.342 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:12.421Z,1554157392.421 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-04-01T22:23:12.421Z,1554157392.421 [MassServo](INFO): Powering down 2019-04-01T22:23:12.732Z,1554157392.732 [BR_Ping](INFO): Runnable 2019-04-01T22:23:12.742Z,1554157392.742 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:13.241Z,1554157393.241 [PAR_Licor](INFO): A/D timeout, 9 tries over 126 ms 2019-04-01T22:23:13.241Z,1554157393.241 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:13.241Z,1554157393.241 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:13.277Z,1554157393.277 [BR_Ping](INFO): Runnable 2019-04-01T22:23:13.278Z,1554157393.278 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:13.371Z,1554157393.371 [MassServo](DEBUG): Initializing EZServoServo. 2019-04-01T22:23:13.490Z,1554157393.490 [MassServo](DEBUG): Initializing MassServo. 2019-04-01T22:23:13.653Z,1554157393.653 [CBIT](INFO): Clearing failed state for component MassServo 2019-04-01T22:23:13.653Z,1554157393.653 [MassServo] No Fault, FailCount= 1 2019-04-01T22:23:13.653Z,1554157393.653 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:13.654Z,1554157393.654 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:13.654Z,1554157393.654 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:13.684Z,1554157393.684 [BR_Ping](INFO): Runnable 2019-04-01T22:23:13.685Z,1554157393.685 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:13.738Z,1554157393.738 [CBIT](FAULT): Main Battery Failure. Count: 11 2019-04-01T22:23:14.205Z,1554157394.205 [PAR_Licor](INFO): A/D timeout, 8 tries over 142 ms 2019-04-01T22:23:14.205Z,1554157394.205 [PAR_Licor] Data Fault, FailCount= 2 2019-04-01T22:23:14.205Z,1554157394.205 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:14.243Z,1554157394.243 [BR_Ping](INFO): Runnable 2019-04-01T22:23:14.270Z,1554157394.270 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:14.347Z,1554157394.347 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:14.347Z,1554157394.347 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:14.347Z,1554157394.347 [PAR_Licor] No Fault, FailCount= 2 2019-04-01T22:23:14.488Z,1554157394.488 [BR_Ping](INFO): Runnable 2019-04-01T22:23:14.490Z,1554157394.490 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:14.892Z,1554157394.892 [BR_Ping](INFO): Runnable 2019-04-01T22:23:14.893Z,1554157394.893 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:15.409Z,1554157395.409 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms 2019-04-01T22:23:15.409Z,1554157395.409 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:15.409Z,1554157395.409 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:15.457Z,1554157395.457 [BR_Ping](INFO): Runnable 2019-04-01T22:23:15.458Z,1554157395.458 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:15.985Z,1554157395.985 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2019-04-01T22:23:15.985Z,1554157395.985 [MassServo] Communications Fault, FailCount= 2 2019-04-01T22:23:15.985Z,1554157395.985 [MassServo](ERROR): Communications Fault 2019-04-01T22:23:16.109Z,1554157396.109 [CBIT](ERROR): Communications Fault in component: MassServo 2019-04-01T22:23:16.109Z,1554157396.109 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:16.109Z,1554157396.109 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:16.109Z,1554157396.109 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:16.157Z,1554157396.157 [BR_Ping](INFO): Runnable 2019-04-01T22:23:16.158Z,1554157396.158 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:16.202Z,1554157396.202 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-04-01T22:23:16.202Z,1554157396.202 [MassServo](INFO): Powering down 2019-04-01T22:23:16.541Z,1554157396.541 [BR_Ping](INFO): Runnable 2019-04-01T22:23:16.541Z,1554157396.541 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:17.049Z,1554157397.049 [PAR_Licor](INFO): A/D timeout, 8 tries over 126 ms 2019-04-01T22:23:17.049Z,1554157397.049 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:17.049Z,1554157397.049 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:17.082Z,1554157397.082 [BR_Ping](INFO): Runnable 2019-04-01T22:23:17.082Z,1554157397.082 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:17.129Z,1554157397.129 [MassServo](DEBUG): Initializing EZServoServo. 2019-04-01T22:23:17.250Z,1554157397.250 [MassServo](DEBUG): Initializing MassServo. 2019-04-01T22:23:17.254Z,1554157397.254 [CBIT](INFO): Clearing failed state for component MassServo 2019-04-01T22:23:17.254Z,1554157397.254 [MassServo] No Fault, FailCount= 2 2019-04-01T22:23:17.254Z,1554157397.254 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:17.255Z,1554157397.255 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:17.255Z,1554157397.255 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:17.349Z,1554157397.349 [BR_Ping](INFO): Runnable 2019-04-01T22:23:17.349Z,1554157397.349 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:17.752Z,1554157397.752 [BR_Ping](INFO): Runnable 2019-04-01T22:23:17.753Z,1554157397.753 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:18.265Z,1554157398.265 [PAR_Licor](INFO): A/D timeout, 8 tries over 131 ms 2019-04-01T22:23:18.266Z,1554157398.266 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:18.266Z,1554157398.266 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:18.304Z,1554157398.304 [BR_Ping](INFO): Runnable 2019-04-01T22:23:18.306Z,1554157398.306 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:18.365Z,1554157398.365 [CBIT](FAULT): Main Battery Failure. Count: 21 2019-04-01T22:23:18.367Z,1554157398.367 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:18.367Z,1554157398.367 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:18.367Z,1554157398.367 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:18.561Z,1554157398.561 [BR_Ping](INFO): Runnable 2019-04-01T22:23:18.561Z,1554157398.561 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:18.588Z,1554157398.588 [CBIT](CRITICAL): Environmental Failure. Press:14.629962 PSI. Humidity:39%. Temp:26 C. ABORTING MISSION 2019-04-01T22:23:18.941Z,1554157398.941 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2019-04-01T22:23:18.941Z,1554157398.941 [Aanderaa_O2] Communications Fault, FailCount= 1 2019-04-01T22:23:18.941Z,1554157398.941 [Aanderaa_O2](ERROR): Communications Fault 2019-04-01T22:23:18.964Z,1554157398.964 [BR_Ping](INFO): Runnable 2019-04-01T22:23:18.964Z,1554157398.964 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:18.999Z,1554157398.999 [CBIT](INFO): Critical error at 20190401T222318 2019-04-01T22:23:19.002Z,1554157399.002 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2019-04-01T22:23:19.465Z,1554157399.465 [Aanderaa_O2](INFO): Powering down 2019-04-01T22:23:19.539Z,1554157399.539 [BR_Ping](INFO): Runnable 2019-04-01T22:23:19.539Z,1554157399.539 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:19.885Z,1554157399.885 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2019-04-01T22:23:19.885Z,1554157399.885 [MassServo] Communications Fault, FailCount= 3 2019-04-01T22:23:19.885Z,1554157399.885 [MassServo](ERROR): Communications Fault 2019-04-01T22:23:19.890Z,1554157399.890 [CBIT](ERROR): Communications Fault in component: MassServo 2019-04-01T22:23:20.037Z,1554157400.037 [PAR_Licor](INFO): A/D timeout, 7 tries over 140 ms 2019-04-01T22:23:20.037Z,1554157400.037 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:20.037Z,1554157400.037 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:20.072Z,1554157400.072 [BR_Ping](INFO): Runnable 2019-04-01T22:23:20.073Z,1554157400.073 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:20.094Z,1554157400.094 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-04-01T22:23:20.094Z,1554157400.094 [MassServo](INFO): Powering down 2019-04-01T22:23:20.106Z,1554157400.106 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:20.107Z,1554157400.107 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:20.107Z,1554157400.107 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:20.321Z,1554157400.321 [BR_Ping](INFO): Runnable 2019-04-01T22:23:20.321Z,1554157400.321 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:20.370Z,1554157400.370 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2019-04-01T22:23:20.370Z,1554157400.370 [Aanderaa_O2] No Fault, FailCount= 1 2019-04-01T22:23:20.720Z,1554157400.720 [BR_Ping](INFO): Runnable 2019-04-01T22:23:20.722Z,1554157400.722 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:20.762Z,1554157400.762 [MassServo](DEBUG): Initializing EZServoServo. 2019-04-01T22:23:20.882Z,1554157400.882 [MassServo](DEBUG): Initializing MassServo. 2019-04-01T22:23:20.886Z,1554157400.886 [CBIT](INFO): Clearing failed state for component MassServo 2019-04-01T22:23:20.886Z,1554157400.886 [MassServo] No Fault, FailCount= 3 2019-04-01T22:23:21.148Z,1554157401.148 [BR_Ping](INFO): Runnable 2019-04-01T22:23:21.149Z,1554157401.149 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:21.533Z,1554157401.533 [BR_Ping](INFO): Runnable 2019-04-01T22:23:21.533Z,1554157401.533 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:21.585Z,1554157401.585 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-04-01T22:23:21.585Z,1554157401.585 [CTD_Seabird] Communications Fault, FailCount= 1 2019-04-01T22:23:21.585Z,1554157401.585 [CTD_Seabird](ERROR): Communications Fault 2019-04-01T22:23:21.989Z,1554157401.989 [CTD_Seabird](INFO): Powering down 2019-04-01T22:23:22.039Z,1554157402.039 [BR_Ping](INFO): Runnable 2019-04-01T22:23:22.039Z,1554157402.039 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:22.103Z,1554157402.103 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2019-04-01T22:23:22.341Z,1554157402.341 [BR_Ping](INFO): Runnable 2019-04-01T22:23:22.342Z,1554157402.342 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:22.355Z,1554157402.355 [CBIT](FAULT): Main Battery Failure. Count: 31 2019-04-01T22:23:22.752Z,1554157402.752 [BR_Ping](INFO): Runnable 2019-04-01T22:23:22.753Z,1554157402.753 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:23.257Z,1554157403.257 [PAR_Licor](INFO): A/D timeout, 8 tries over 130 ms 2019-04-01T22:23:23.257Z,1554157403.257 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:23.257Z,1554157403.257 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:23.279Z,1554157403.279 [BR_Ping](INFO): Runnable 2019-04-01T22:23:23.279Z,1554157403.279 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:23.613Z,1554157403.613 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2019-04-01T22:23:23.613Z,1554157403.613 [MassServo] Communications Fault, FailCount= 4 2019-04-01T22:23:23.613Z,1554157403.613 [MassServo](ERROR): Communications Fault 2019-04-01T22:23:23.617Z,1554157403.617 [CBIT](ERROR): Communications Fault in component: MassServo 2019-04-01T22:23:23.617Z,1554157403.617 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2019-04-01T22:23:23.617Z,1554157403.617 [CTD_Seabird] No Fault, FailCount= 1 2019-04-01T22:23:23.618Z,1554157403.618 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:23.618Z,1554157403.618 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:23.618Z,1554157403.618 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:23.664Z,1554157403.664 [BR_Ping](INFO): Runnable 2019-04-01T22:23:23.665Z,1554157403.665 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:23.703Z,1554157403.703 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-04-01T22:23:23.704Z,1554157403.704 [MassServo](INFO): Powering down 2019-04-01T22:23:23.757Z,1554157403.757 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-04-01T22:23:23.758Z,1554157403.758 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-04-01T22:23:24.048Z,1554157404.048 [BR_Ping](INFO): Runnable 2019-04-01T22:23:24.048Z,1554157404.048 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:24.565Z,1554157404.565 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms 2019-04-01T22:23:24.565Z,1554157404.565 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:24.565Z,1554157404.565 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:24.585Z,1554157404.585 [BR_Ping](INFO): Runnable 2019-04-01T22:23:24.586Z,1554157404.586 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:24.614Z,1554157404.614 [MassServo](DEBUG): Initializing EZServoServo. 2019-04-01T22:23:24.734Z,1554157404.734 [MassServo](DEBUG): Initializing MassServo. 2019-04-01T22:23:24.738Z,1554157404.738 [CBIT](INFO): Clearing failed state for component MassServo 2019-04-01T22:23:24.738Z,1554157404.738 [MassServo] No Fault, FailCount= 4 2019-04-01T22:23:24.738Z,1554157404.738 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:24.739Z,1554157404.739 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:24.739Z,1554157404.739 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:24.856Z,1554157404.856 [BR_Ping](INFO): Runnable 2019-04-01T22:23:24.858Z,1554157404.858 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:25.260Z,1554157405.260 [BR_Ping](INFO): Runnable 2019-04-01T22:23:25.261Z,1554157405.261 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:25.785Z,1554157405.785 [PAR_Licor](INFO): A/D timeout, 9 tries over 142 ms 2019-04-01T22:23:25.785Z,1554157405.785 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:25.785Z,1554157405.785 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:25.821Z,1554157405.821 [BR_Ping](INFO): Runnable 2019-04-01T22:23:25.822Z,1554157405.822 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:25.864Z,1554157405.864 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:25.864Z,1554157405.864 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:25.864Z,1554157405.864 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:26.070Z,1554157406.070 [BR_Ping](INFO): Runnable 2019-04-01T22:23:26.071Z,1554157406.071 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:26.472Z,1554157406.472 [BR_Ping](INFO): Runnable 2019-04-01T22:23:26.472Z,1554157406.472 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:26.505Z,1554157406.505 [CBIT](FAULT): Main Battery Failure. Count: 41 2019-04-01T22:23:26.989Z,1554157406.989 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms 2019-04-01T22:23:26.989Z,1554157406.989 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:26.989Z,1554157406.989 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:27.028Z,1554157407.028 [BR_Ping](INFO): Runnable 2019-04-01T22:23:27.029Z,1554157407.029 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:27.397Z,1554157407.397 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2019-04-01T22:23:27.397Z,1554157407.397 [MassServo] Communications Fault, FailCount= 5 2019-04-01T22:23:27.397Z,1554157407.397 [MassServo](ERROR): Communications Fault 2019-04-01T22:23:27.401Z,1554157407.401 [CBIT](ERROR): Communications Fault in component: MassServo 2019-04-01T22:23:27.402Z,1554157407.402 [CBIT](CRITICAL): Communications Fault in component: MassServo 2019-04-01T22:23:27.402Z,1554157407.402 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:27.402Z,1554157407.402 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:27.402Z,1554157407.402 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:27.454Z,1554157407.454 [BR_Ping](INFO): Runnable 2019-04-01T22:23:27.455Z,1554157407.455 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:27.489Z,1554157407.489 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-04-01T22:23:27.489Z,1554157407.489 [MassServo](INFO): Powering down 2019-04-01T22:23:27.492Z,1554157407.492 [CBIT](INFO): Critical error at 20190401T222327 2019-04-01T22:23:27.833Z,1554157407.833 [BR_Ping](INFO): Runnable 2019-04-01T22:23:27.834Z,1554157407.834 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:28.366Z,1554157408.366 [PAR_Licor](INFO): A/D timeout, 8 tries over 151 ms 2019-04-01T22:23:28.366Z,1554157408.366 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:28.366Z,1554157408.366 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:28.414Z,1554157408.414 [BR_Ping](INFO): Runnable 2019-04-01T22:23:28.415Z,1554157408.415 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:28.432Z,1554157408.432 [MassServo](DEBUG): Initializing EZServoServo. 2019-04-01T22:23:28.550Z,1554157408.550 [MassServo](DEBUG): Initializing MassServo. 2019-04-01T22:23:28.554Z,1554157408.554 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:28.555Z,1554157408.555 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:28.555Z,1554157408.555 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:28.641Z,1554157408.641 [BR_Ping](INFO): Runnable 2019-04-01T22:23:28.642Z,1554157408.642 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:29.044Z,1554157409.044 [BR_Ping](INFO): Runnable 2019-04-01T22:23:29.045Z,1554157409.045 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:29.557Z,1554157409.557 [PAR_Licor](INFO): A/D timeout, 8 tries over 131 ms 2019-04-01T22:23:29.557Z,1554157409.557 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:29.557Z,1554157409.557 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:29.558Z,1554157409.558 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface 2019-04-01T22:23:29.558Z,1554157409.558 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1 2019-04-01T22:23:29.558Z,1554157409.558 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2019-04-01T22:23:29.584Z,1554157409.584 [BR_Ping](INFO): Runnable 2019-04-01T22:23:29.585Z,1554157409.585 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:29.634Z,1554157409.634 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:29.634Z,1554157409.634 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:29.634Z,1554157409.634 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:29.634Z,1554157409.634 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2019-04-01T22:23:29.833Z,1554157409.833 [AcousticModem_Benthos_ATM900](INFO): Powering down 2019-04-01T22:23:29.997Z,1554157409.997 [BR_Ping](INFO): Runnable 2019-04-01T22:23:29.997Z,1554157409.997 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:30.256Z,1554157410.256 [BR_Ping](INFO): Runnable 2019-04-01T22:23:30.257Z,1554157410.257 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:30.773Z,1554157410.773 [PAR_Licor](INFO): A/D timeout, 9 tries over 135 ms 2019-04-01T22:23:30.773Z,1554157410.773 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:30.773Z,1554157410.773 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:30.790Z,1554157410.790 [BR_Ping](INFO): Runnable 2019-04-01T22:23:30.791Z,1554157410.791 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:30.815Z,1554157410.815 [CBIT](FAULT): Main Battery Failure. Count: 51 2019-04-01T22:23:30.818Z,1554157410.818 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:30.818Z,1554157410.818 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:30.818Z,1554157410.818 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:30.818Z,1554157410.818 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2019-04-01T22:23:30.818Z,1554157410.818 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1 2019-04-01T22:23:31.041Z,1554157411.041 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2019-04-01T22:23:31.041Z,1554157411.041 [Aanderaa_O2] Communications Fault, FailCount= 2 2019-04-01T22:23:31.041Z,1554157411.041 [Aanderaa_O2](ERROR): Communications Fault 2019-04-01T22:23:31.079Z,1554157411.079 [BR_Ping](INFO): Runnable 2019-04-01T22:23:31.079Z,1554157411.079 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:31.115Z,1554157411.115 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2019-04-01T22:23:31.565Z,1554157411.565 [Aanderaa_O2](INFO): Powering down 2019-04-01T22:23:31.591Z,1554157411.591 [BR_Ping](INFO): Runnable 2019-04-01T22:23:31.592Z,1554157411.592 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:31.872Z,1554157411.872 [BR_Ping](INFO): Runnable 2019-04-01T22:23:31.873Z,1554157411.873 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:32.276Z,1554157412.276 [BR_Ping](INFO): Runnable 2019-04-01T22:23:32.277Z,1554157412.277 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:32.303Z,1554157412.303 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2019-04-01T22:23:32.303Z,1554157412.303 [Aanderaa_O2] No Fault, FailCount= 2 2019-04-01T22:23:32.697Z,1554157412.697 [BR_Ping](INFO): Runnable 2019-04-01T22:23:32.698Z,1554157412.698 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:33.077Z,1554157413.077 [AcousticModem_Benthos_ATM900](INFO): Powering up 2019-04-01T22:23:33.077Z,1554157413.077 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2019-04-01T22:23:33.116Z,1554157413.116 [BR_Ping](INFO): Runnable 2019-04-01T22:23:33.116Z,1554157413.116 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:33.166Z,1554157413.166 [SBIT](IMPORTANT): Beginning Startup BIT 2019-04-01T22:23:33.175Z,1554157413.175 [CBIT](IMPORTANT): Beginning ground fault scan 2019-04-01T22:23:33.488Z,1554157413.488 [BR_Ping](INFO): Runnable 2019-04-01T22:23:33.489Z,1554157413.489 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:34.017Z,1554157414.017 [PAR_Licor](INFO): A/D timeout, 7 tries over 146 ms 2019-04-01T22:23:34.017Z,1554157414.017 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:34.017Z,1554157414.017 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:34.054Z,1554157414.054 [BR_Ping](INFO): Runnable 2019-04-01T22:23:34.055Z,1554157414.055 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:34.186Z,1554157414.186 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:34.186Z,1554157414.186 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:34.186Z,1554157414.186 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:34.304Z,1554157414.304 [BR_Ping](INFO): Runnable 2019-04-01T22:23:34.306Z,1554157414.306 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:34.701Z,1554157414.701 [BR_Ping](INFO): Runnable 2019-04-01T22:23:34.702Z,1554157414.702 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:34.839Z,1554157414.839 [CBIT](FAULT): Main Battery Failure. Count: 61 2019-04-01T22:23:35.104Z,1554157415.104 [BR_Ping](INFO): Runnable 2019-04-01T22:23:35.105Z,1554157415.105 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:35.508Z,1554157415.508 [BR_Ping](INFO): Runnable 2019-04-01T22:23:35.510Z,1554157415.510 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:35.913Z,1554157415.913 [BR_Ping](INFO): Runnable 2019-04-01T22:23:35.914Z,1554157415.914 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:36.437Z,1554157416.437 [PAR_Licor](INFO): A/D timeout, 9 tries over 143 ms 2019-04-01T22:23:36.437Z,1554157416.437 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:36.437Z,1554157416.437 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:36.458Z,1554157416.458 [BR_Ping](INFO): Runnable 2019-04-01T22:23:36.458Z,1554157416.458 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:36.606Z,1554157416.606 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:36.606Z,1554157416.606 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:36.606Z,1554157416.606 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:36.726Z,1554157416.726 [NAL9602](INFO): Powering up NAL9602 2019-04-01T22:23:36.744Z,1554157416.744 [BR_Ping](INFO): Runnable 2019-04-01T22:23:36.744Z,1554157416.744 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:36.809Z,1554157416.809 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-04-01T22:23:36.809Z,1554157416.809 [CTD_Seabird] Communications Fault, FailCount= 2 2019-04-01T22:23:36.809Z,1554157416.809 [CTD_Seabird](ERROR): Communications Fault 2019-04-01T22:23:36.866Z,1554157416.866 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2019-04-01T22:23:37.133Z,1554157417.133 [BR_Ping](INFO): Runnable 2019-04-01T22:23:37.134Z,1554157417.134 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:37.213Z,1554157417.213 [CTD_Seabird](INFO): Powering down 2019-04-01T22:23:37.532Z,1554157417.532 [BR_Ping](INFO): Runnable 2019-04-01T22:23:37.533Z,1554157417.533 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:38.041Z,1554157418.041 [PAR_Licor](INFO): A/D timeout, 9 tries over 131 ms 2019-04-01T22:23:38.041Z,1554157418.041 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:38.042Z,1554157418.042 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:38.065Z,1554157418.065 [BR_Ping](INFO): Runnable 2019-04-01T22:23:38.066Z,1554157418.066 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:38.214Z,1554157418.214 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2019-04-01T22:23:38.214Z,1554157418.214 [CTD_Seabird] No Fault, FailCount= 2 2019-04-01T22:23:38.214Z,1554157418.214 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:38.214Z,1554157418.214 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:38.214Z,1554157418.214 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:38.337Z,1554157418.337 [BR_Ping](INFO): Runnable 2019-04-01T22:23:38.337Z,1554157418.337 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:38.561Z,1554157418.561 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-04-01T22:23:38.562Z,1554157418.562 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-04-01T22:23:38.740Z,1554157418.740 [BR_Ping](INFO): Runnable 2019-04-01T22:23:38.741Z,1554157418.741 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:38.766Z,1554157418.766 [CBIT](FAULT): Main Battery Failure. Count: 71 2019-04-01T22:23:39.265Z,1554157419.265 [PAR_Licor](INFO): A/D timeout, 9 tries over 143 ms 2019-04-01T22:23:39.265Z,1554157419.265 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:39.265Z,1554157419.265 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:39.287Z,1554157419.287 [BR_Ping](INFO): Runnable 2019-04-01T22:23:39.287Z,1554157419.287 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:39.346Z,1554157419.346 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:39.346Z,1554157419.346 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:39.346Z,1554157419.346 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:39.548Z,1554157419.548 [BR_Ping](INFO): Runnable 2019-04-01T22:23:39.550Z,1554157419.550 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:40.073Z,1554157420.073 [PAR_Licor](INFO): A/D timeout, 9 tries over 143 ms 2019-04-01T22:23:40.073Z,1554157420.073 [PAR_Licor] Data Fault, FailCount= 2 2019-04-01T22:23:40.073Z,1554157420.073 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:40.094Z,1554157420.094 [BR_Ping](INFO): Runnable 2019-04-01T22:23:40.094Z,1554157420.094 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:40.169Z,1554157420.169 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:40.169Z,1554157420.169 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:40.169Z,1554157420.169 [PAR_Licor] No Fault, FailCount= 2 2019-04-01T22:23:40.356Z,1554157420.356 [BR_Ping](INFO): Runnable 2019-04-01T22:23:40.358Z,1554157420.358 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:40.762Z,1554157420.762 [BR_Ping](INFO): Runnable 2019-04-01T22:23:40.763Z,1554157420.763 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:41.277Z,1554157421.277 [PAR_Licor](INFO): A/D timeout, 9 tries over 135 ms 2019-04-01T22:23:41.277Z,1554157421.277 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:41.277Z,1554157421.277 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:41.294Z,1554157421.294 [BR_Ping](INFO): Runnable 2019-04-01T22:23:41.295Z,1554157421.295 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:41.333Z,1554157421.333 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:41.333Z,1554157421.333 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:41.333Z,1554157421.333 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:41.569Z,1554157421.569 [BR_Ping](INFO): Runnable 2019-04-01T22:23:41.569Z,1554157421.569 [BR_Ping](INFO): Received Ping, distance: 0 2019-04-01T22:23:41.972Z,1554157421.972 [BR_Ping](INFO): Runnable 2019-04-01T22:23:41.972Z,1554157421.972 [BR_Ping](INFO): Received Ping, distance: 7033 2019-04-01T22:23:42.489Z,1554157422.489 [PAR_Licor](INFO): A/D timeout, 9 tries over 135 ms 2019-04-01T22:23:42.489Z,1554157422.489 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:42.489Z,1554157422.489 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:42.506Z,1554157422.506 [BR_Ping](INFO): Runnable 2019-04-01T22:23:42.507Z,1554157422.507 [BR_Ping](INFO): Received Ping, distance: 7030 2019-04-01T22:23:42.563Z,1554157422.563 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:42.563Z,1554157422.563 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:42.563Z,1554157422.563 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:42.757Z,1554157422.757 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2019-04-01T22:23:42.757Z,1554157422.757 [Aanderaa_O2] Communications Fault, FailCount= 3 2019-04-01T22:23:42.757Z,1554157422.757 [Aanderaa_O2](ERROR): Communications Fault 2019-04-01T22:23:42.780Z,1554157422.780 [BR_Ping](INFO): Runnable 2019-04-01T22:23:42.781Z,1554157422.781 [BR_Ping](INFO): Received Ping, distance: 7021 2019-04-01T22:23:42.808Z,1554157422.808 [CBIT](FAULT): Main Battery Failure. Count: 81 2019-04-01T22:23:42.815Z,1554157422.815 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2019-04-01T22:23:42.815Z,1554157422.815 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2019-04-01T22:23:43.281Z,1554157423.281 [Aanderaa_O2](INFO): Powering down 2019-04-01T22:23:43.299Z,1554157423.299 [BR_Ping](INFO): Runnable 2019-04-01T22:23:43.300Z,1554157423.300 [BR_Ping](INFO): Received Ping, distance: 7021 2019-04-01T22:23:43.588Z,1554157423.588 [BR_Ping](INFO): Runnable 2019-04-01T22:23:43.589Z,1554157423.589 [BR_Ping](INFO): Received Ping, distance: 7018 2019-04-01T22:23:43.997Z,1554157423.997 [BR_Ping](INFO): Runnable 2019-04-01T22:23:43.998Z,1554157423.998 [BR_Ping](INFO): Received Ping, distance: 6986 2019-04-01T22:23:44.517Z,1554157424.517 [PAR_Licor](INFO): A/D timeout, 9 tries over 143 ms 2019-04-01T22:23:44.517Z,1554157424.517 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:44.517Z,1554157424.517 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:44.538Z,1554157424.538 [BR_Ping](INFO): Runnable 2019-04-01T22:23:44.539Z,1554157424.539 [BR_Ping](INFO): Received Ping, distance: 6977 2019-04-01T22:23:44.581Z,1554157424.581 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:44.582Z,1554157424.582 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:44.582Z,1554157424.582 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:44.625Z,1554157424.625 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.006401 CHAN A1 (24V): -0.127593 CHAN A2 (12V): -0.005606 CHAN A3 (5V): -0.002536 CHAN B0 (3.3V): -0.000881 CHAN B1 (3.15aV): -0.001343 CHAN B2 (3.15bV): -0.001202 CHAN B3 (GND): 0.000268 OPEN: -0.000648 Full Scale Calc: 4.765 mA, -1.589 mA 2019-04-01T22:23:44.801Z,1554157424.801 [BR_Ping](INFO): Runnable 2019-04-01T22:23:44.801Z,1554157424.801 [BR_Ping](INFO): Received Ping, distance: 6965 2019-04-01T22:23:45.208Z,1554157425.208 [BR_Ping](INFO): Runnable 2019-04-01T22:23:45.210Z,1554157425.210 [BR_Ping](INFO): Received Ping, distance: 6965 2019-04-01T22:23:45.721Z,1554157425.721 [PAR_Licor](INFO): A/D timeout, 9 tries over 135 ms 2019-04-01T22:23:45.721Z,1554157425.721 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:45.721Z,1554157425.721 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:45.738Z,1554157425.738 [BR_Ping](INFO): Runnable 2019-04-01T22:23:45.739Z,1554157425.739 [BR_Ping](INFO): Received Ping, distance: 7018 2019-04-01T22:23:45.791Z,1554157425.791 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:45.791Z,1554157425.791 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:45.791Z,1554157425.791 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:46.013Z,1554157426.013 [BR_Ping](INFO): Runnable 2019-04-01T22:23:46.014Z,1554157426.014 [BR_Ping](INFO): Received Ping, distance: 7018 2019-04-01T22:23:46.416Z,1554157426.416 [BR_Ping](INFO): Runnable 2019-04-01T22:23:46.417Z,1554157426.417 [BR_Ping](INFO): Received Ping, distance: 7018 2019-04-01T22:23:46.459Z,1554157426.459 [SBIT](ERROR): Could not read massPosReader_. 2019-04-01T22:23:46.933Z,1554157426.933 [PAR_Licor](INFO): A/D timeout, 9 tries over 135 ms 2019-04-01T22:23:46.933Z,1554157426.933 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:46.933Z,1554157426.933 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:46.965Z,1554157426.965 [BR_Ping](INFO): Runnable 2019-04-01T22:23:46.965Z,1554157426.965 [BR_Ping](INFO): Received Ping, distance: 7018 2019-04-01T22:23:47.036Z,1554157427.036 [CBIT](FAULT): Main Battery Failure. Count: 91 2019-04-01T22:23:47.038Z,1554157427.038 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:47.038Z,1554157427.038 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:47.039Z,1554157427.039 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:47.236Z,1554157427.236 [BR_Ping](INFO): Runnable 2019-04-01T22:23:47.236Z,1554157427.236 [BR_Ping](INFO): Received Ping, distance: 7011 2019-04-01T22:23:47.618Z,1554157427.618 [NAL9602](INFO): NAL9602 initialized 2019-04-01T22:23:47.633Z,1554157427.633 [BR_Ping](INFO): Runnable 2019-04-01T22:23:47.633Z,1554157427.633 [BR_Ping](INFO): Received Ping, distance: 7011 2019-04-01T22:23:48.145Z,1554157428.145 [PAR_Licor](INFO): A/D timeout, 9 tries over 134 ms 2019-04-01T22:23:48.145Z,1554157428.145 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:48.145Z,1554157428.145 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:48.193Z,1554157428.193 [BR_Ping](INFO): Runnable 2019-04-01T22:23:48.194Z,1554157428.194 [BR_Ping](INFO): Received Ping, distance: 7011 2019-04-01T22:23:48.223Z,1554157428.223 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:48.223Z,1554157428.223 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:48.223Z,1554157428.223 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:48.433Z,1554157428.433 [NAL9602](DEBUG): Fix Requested 2019-04-01T22:23:48.451Z,1554157428.451 [BR_Ping](INFO): Runnable 2019-04-01T22:23:48.452Z,1554157428.452 [BR_Ping](INFO): Received Ping, distance: 8133 2019-04-01T22:23:48.855Z,1554157428.855 [BR_Ping](INFO): Runnable 2019-04-01T22:23:48.856Z,1554157428.856 [BR_Ping](INFO): Received Ping, distance: 8133 2019-04-01T22:23:49.353Z,1554157429.353 [PAR_Licor](INFO): A/D timeout, 9 tries over 131 ms 2019-04-01T22:23:49.353Z,1554157429.353 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:49.353Z,1554157429.353 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:49.431Z,1554157429.431 [BR_Ping](INFO): Runnable 2019-04-01T22:23:49.432Z,1554157429.432 [BR_Ping](INFO): Received Ping, distance: 8392 2019-04-01T22:23:49.533Z,1554157429.533 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:49.534Z,1554157429.534 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:49.534Z,1554157429.534 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:49.649Z,1554157429.649 [BR_Ping](INFO): Runnable 2019-04-01T22:23:49.650Z,1554157429.650 [BR_Ping](INFO): Received Ping, distance: 8554 2019-04-01T22:23:50.052Z,1554157430.052 [BR_Ping](INFO): Runnable 2019-04-01T22:23:50.053Z,1554157430.053 [BR_Ping](INFO): Received Ping, distance: 7001 2019-04-01T22:23:50.565Z,1554157430.565 [PAR_Licor](INFO): A/D timeout, 9 tries over 131 ms 2019-04-01T22:23:50.565Z,1554157430.565 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:50.565Z,1554157430.565 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:50.596Z,1554157430.596 [BR_Ping](INFO): Runnable 2019-04-01T22:23:50.596Z,1554157430.596 [BR_Ping](INFO): Received Ping, distance: 7001 2019-04-01T22:23:50.714Z,1554157430.714 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:50.714Z,1554157430.714 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:50.714Z,1554157430.714 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:50.867Z,1554157430.867 [BR_Ping](INFO): Runnable 2019-04-01T22:23:50.868Z,1554157430.868 [BR_Ping](INFO): Received Ping, distance: 7047 2019-04-01T22:23:50.952Z,1554157430.952 [CBIT](FAULT): Main Battery Failure. Count: 101 2019-04-01T22:23:51.284Z,1554157431.284 [BR_Ping](INFO): Runnable 2019-04-01T22:23:51.286Z,1554157431.286 [BR_Ping](INFO): Received Ping, distance: 7047 2019-04-01T22:23:51.613Z,1554157431.613 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-04-01T22:23:51.613Z,1554157431.613 [CTD_Seabird] Communications Fault, FailCount= 3 2019-04-01T22:23:51.613Z,1554157431.613 [CTD_Seabird](ERROR): Communications Fault 2019-04-01T22:23:51.785Z,1554157431.785 [PAR_Licor](INFO): A/D timeout, 9 tries over 139 ms 2019-04-01T22:23:51.785Z,1554157431.785 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:51.785Z,1554157431.785 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:51.817Z,1554157431.817 [BR_Ping](INFO): Runnable 2019-04-01T22:23:51.818Z,1554157431.818 [BR_Ping](INFO): Received Ping, distance: 7052 2019-04-01T22:23:51.898Z,1554157431.898 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2019-04-01T22:23:51.898Z,1554157431.898 [CBIT](FAULT): Communications Fault in component: CTD_Seabird 2019-04-01T22:23:51.898Z,1554157431.898 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:51.899Z,1554157431.899 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:51.899Z,1554157431.899 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:52.017Z,1554157432.017 [CTD_Seabird](INFO): Powering down 2019-04-01T22:23:52.077Z,1554157432.077 [BR_Ping](INFO): Runnable 2019-04-01T22:23:52.077Z,1554157432.077 [BR_Ping](INFO): Received Ping, distance: 7093 2019-04-01T22:23:52.476Z,1554157432.476 [BR_Ping](INFO): Runnable 2019-04-01T22:23:52.478Z,1554157432.478 [BR_Ping](INFO): Received Ping, distance: 7093 2019-04-01T22:23:52.989Z,1554157432.989 [PAR_Licor](INFO): A/D timeout, 9 tries over 131 ms 2019-04-01T22:23:52.989Z,1554157432.989 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:52.989Z,1554157432.989 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:53.017Z,1554157433.017 [BR_Ping](INFO): Runnable 2019-04-01T22:23:53.017Z,1554157433.017 [BR_Ping](INFO): Received Ping, distance: 7072 2019-04-01T22:23:53.126Z,1554157433.126 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:53.126Z,1554157433.126 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:53.126Z,1554157433.126 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:53.265Z,1554157433.265 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface 2019-04-01T22:23:53.265Z,1554157433.265 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2 2019-04-01T22:23:53.265Z,1554157433.265 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2019-04-01T22:23:53.285Z,1554157433.285 [BR_Ping](INFO): Runnable 2019-04-01T22:23:53.285Z,1554157433.285 [BR_Ping](INFO): Received Ping, distance: 7092 2019-04-01T22:23:53.378Z,1554157433.378 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2019-04-01T22:23:53.669Z,1554157433.669 [AcousticModem_Benthos_ATM900](INFO): Powering down 2019-04-01T22:23:53.809Z,1554157433.809 [BR_Ping](INFO): Runnable 2019-04-01T22:23:53.810Z,1554157433.810 [BR_Ping](INFO): Received Ping, distance: 7112 2019-04-01T22:23:54.097Z,1554157434.097 [BR_Ping](INFO): Runnable 2019-04-01T22:23:54.098Z,1554157434.098 [BR_Ping](INFO): Received Ping, distance: 7262 2019-04-01T22:23:54.496Z,1554157434.496 [BR_Ping](INFO): Runnable 2019-04-01T22:23:54.505Z,1554157434.505 [BR_Ping](INFO): Received Ping, distance: 7262 2019-04-01T22:23:54.546Z,1554157434.546 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2019-04-01T22:23:54.546Z,1554157434.546 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2 2019-04-01T22:23:55.013Z,1554157435.013 [PAR_Licor](INFO): A/D timeout, 9 tries over 135 ms 2019-04-01T22:23:55.013Z,1554157435.013 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:55.013Z,1554157435.013 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:55.047Z,1554157435.047 [BR_Ping](INFO): Runnable 2019-04-01T22:23:55.048Z,1554157435.048 [BR_Ping](INFO): Received Ping, distance: 7283 2019-04-01T22:23:55.076Z,1554157435.076 [CBIT](FAULT): Main Battery Failure. Count: 111 2019-04-01T22:23:55.079Z,1554157435.079 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:55.079Z,1554157435.079 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:55.079Z,1554157435.079 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:55.305Z,1554157435.305 [BR_Ping](INFO): Runnable 2019-04-01T22:23:55.306Z,1554157435.306 [BR_Ping](INFO): Received Ping, distance: 7093 2019-04-01T22:23:55.719Z,1554157435.719 [BR_Ping](INFO): Runnable 2019-04-01T22:23:55.719Z,1554157435.719 [BR_Ping](INFO): Received Ping, distance: 8572 2019-04-01T22:23:56.233Z,1554157436.233 [PAR_Licor](INFO): A/D timeout, 9 tries over 143 ms 2019-04-01T22:23:56.233Z,1554157436.233 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:56.233Z,1554157436.233 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:56.263Z,1554157436.263 [BR_Ping](INFO): Runnable 2019-04-01T22:23:56.264Z,1554157436.264 [BR_Ping](INFO): Received Ping, distance: 8572 2019-04-01T22:23:56.326Z,1554157436.326 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:56.326Z,1554157436.326 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:56.326Z,1554157436.326 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:56.527Z,1554157436.527 [BR_Ping](INFO): Runnable 2019-04-01T22:23:56.528Z,1554157436.528 [BR_Ping](INFO): Received Ping, distance: 8525 2019-04-01T22:23:56.901Z,1554157436.901 [AcousticModem_Benthos_ATM900](INFO): Powering up 2019-04-01T22:23:56.901Z,1554157436.901 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2019-04-01T22:23:56.944Z,1554157436.944 [BR_Ping](INFO): Runnable 2019-04-01T22:23:56.944Z,1554157436.944 [BR_Ping](INFO): Received Ping, distance: 8620 2019-04-01T22:23:57.437Z,1554157437.437 [PAR_Licor](INFO): A/D timeout, 9 tries over 135 ms 2019-04-01T22:23:57.437Z,1554157437.437 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:57.437Z,1554157437.437 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:57.454Z,1554157437.454 [BR_Ping](INFO): Runnable 2019-04-01T22:23:57.455Z,1554157437.455 [BR_Ping](INFO): Received Ping, distance: 8050 2019-04-01T22:23:57.486Z,1554157437.486 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:57.487Z,1554157437.487 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:57.487Z,1554157437.487 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:57.728Z,1554157437.728 [BR_Ping](INFO): Runnable 2019-04-01T22:23:57.730Z,1554157437.730 [BR_Ping](INFO): Received Ping, distance: 6996 2019-04-01T22:23:58.132Z,1554157438.132 [BR_Ping](INFO): Runnable 2019-04-01T22:23:58.133Z,1554157438.133 [BR_Ping](INFO): Received Ping, distance: 6996 2019-04-01T22:23:58.649Z,1554157438.649 [PAR_Licor](INFO): A/D timeout, 9 tries over 135 ms 2019-04-01T22:23:58.649Z,1554157438.649 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:58.649Z,1554157438.649 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:58.690Z,1554157438.690 [BR_Ping](INFO): Runnable 2019-04-01T22:23:58.691Z,1554157438.691 [BR_Ping](INFO): Received Ping, distance: 9784 2019-04-01T22:23:58.718Z,1554157438.718 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:58.718Z,1554157438.718 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:58.718Z,1554157438.718 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:23:58.941Z,1554157438.941 [BR_Ping](INFO): Runnable 2019-04-01T22:23:58.942Z,1554157438.942 [BR_Ping](INFO): Received Ping, distance: 7014 2019-04-01T22:23:58.968Z,1554157438.968 [CBIT](FAULT): Main Battery Failure. Count: 121 2019-04-01T22:23:59.345Z,1554157439.345 [BR_Ping](INFO): Runnable 2019-04-01T22:23:59.345Z,1554157439.345 [BR_Ping](INFO): Received Ping, distance: 6992 2019-04-01T22:23:59.869Z,1554157439.869 [PAR_Licor](INFO): A/D timeout, 9 tries over 143 ms 2019-04-01T22:23:59.869Z,1554157439.869 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:23:59.869Z,1554157439.869 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:23:59.890Z,1554157439.890 [BR_Ping](INFO): Runnable 2019-04-01T22:23:59.890Z,1554157439.890 [BR_Ping](INFO): Received Ping, distance: 6992 2019-04-01T22:23:59.953Z,1554157439.953 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:23:59.953Z,1554157439.953 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:23:59.954Z,1554157439.954 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:00.153Z,1554157440.153 [BR_Ping](INFO): Runnable 2019-04-01T22:24:00.153Z,1554157440.153 [BR_Ping](INFO): Received Ping, distance: 6987 2019-04-01T22:24:00.564Z,1554157440.564 [BR_Ping](INFO): Runnable 2019-04-01T22:24:00.566Z,1554157440.566 [BR_Ping](INFO): Received Ping, distance: 7096 2019-04-01T22:24:01.069Z,1554157441.069 [PAR_Licor](INFO): A/D timeout, 15 tries over 131 ms 2019-04-01T22:24:01.069Z,1554157441.069 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:01.069Z,1554157441.069 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:01.100Z,1554157441.100 [BR_Ping](INFO): Runnable 2019-04-01T22:24:01.101Z,1554157441.101 [BR_Ping](INFO): Received Ping, distance: 7047 2019-04-01T22:24:01.117Z,1554157441.117 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:01.117Z,1554157441.117 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:01.117Z,1554157441.117 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:01.377Z,1554157441.377 [BR_Ping](INFO): Runnable 2019-04-01T22:24:01.379Z,1554157441.379 [BR_Ping](INFO): Received Ping, distance: 7106 2019-04-01T22:24:01.783Z,1554157441.783 [BR_Ping](INFO): Runnable 2019-04-01T22:24:01.785Z,1554157441.785 [BR_Ping](INFO): Received Ping, distance: 7106 2019-04-01T22:24:02.277Z,1554157442.277 [PAR_Licor](INFO): A/D timeout, 16 tries over 127 ms 2019-04-01T22:24:02.277Z,1554157442.277 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:02.277Z,1554157442.277 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:02.307Z,1554157442.307 [BR_Ping](INFO): Runnable 2019-04-01T22:24:02.309Z,1554157442.309 [BR_Ping](INFO): Received Ping, distance: 7096 2019-04-01T22:24:02.350Z,1554157442.350 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:02.350Z,1554157442.350 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:02.350Z,1554157442.350 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:02.581Z,1554157442.581 [BR_Ping](INFO): Runnable 2019-04-01T22:24:02.582Z,1554157442.582 [BR_Ping](INFO): Received Ping, distance: 8778 2019-04-01T22:24:02.985Z,1554157442.985 [BR_Ping](INFO): Runnable 2019-04-01T22:24:02.986Z,1554157442.986 [BR_Ping](INFO): Received Ping, distance: 8576 2019-04-01T22:24:03.004Z,1554157443.004 [CBIT](FAULT): Main Battery Failure. Count: 131 2019-04-01T22:24:03.493Z,1554157443.493 [PAR_Licor](INFO): A/D timeout, 16 tries over 131 ms 2019-04-01T22:24:03.493Z,1554157443.493 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:03.493Z,1554157443.493 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:03.510Z,1554157443.510 [BR_Ping](INFO): Runnable 2019-04-01T22:24:03.512Z,1554157443.512 [BR_Ping](INFO): Received Ping, distance: 8576 2019-04-01T22:24:03.530Z,1554157443.530 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:03.530Z,1554157443.530 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:03.530Z,1554157443.530 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:03.786Z,1554157443.786 [BR_Ping](INFO): Runnable 2019-04-01T22:24:03.786Z,1554157443.786 [BR_Ping](INFO): Received Ping, distance: 8374 2019-04-01T22:24:04.197Z,1554157444.197 [BR_Ping](INFO): Runnable 2019-04-01T22:24:04.199Z,1554157444.199 [BR_Ping](INFO): Received Ping, distance: 8202 2019-04-01T22:24:04.701Z,1554157444.701 [PAR_Licor](INFO): A/D timeout, 16 tries over 127 ms 2019-04-01T22:24:04.701Z,1554157444.701 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:04.701Z,1554157444.701 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:04.731Z,1554157444.731 [BR_Ping](INFO): Runnable 2019-04-01T22:24:04.733Z,1554157444.733 [BR_Ping](INFO): Received Ping, distance: 7083 2019-04-01T22:24:04.747Z,1554157444.747 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:04.748Z,1554157444.748 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:04.748Z,1554157444.748 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:04.999Z,1554157444.999 [BR_Ping](INFO): Runnable 2019-04-01T22:24:04.000Z,1554157445.000 [BR_Ping](INFO): Received Ping, distance: 7085 2019-04-01T22:24:05.408Z,1554157445.408 [BR_Ping](INFO): Runnable 2019-04-01T22:24:05.409Z,1554157445.409 [BR_Ping](INFO): Received Ping, distance: 7085 2019-04-01T22:24:05.913Z,1554157445.913 [PAR_Licor](INFO): A/D timeout, 16 tries over 127 ms 2019-04-01T22:24:05.913Z,1554157445.913 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:05.913Z,1554157445.913 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:05.944Z,1554157445.944 [BR_Ping](INFO): Runnable 2019-04-01T22:24:05.946Z,1554157445.946 [BR_Ping](INFO): Received Ping, distance: 7938 2019-04-01T22:24:05.979Z,1554157445.979 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:05.979Z,1554157445.979 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:05.979Z,1554157445.979 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:06.223Z,1554157446.223 [BR_Ping](INFO): Runnable 2019-04-01T22:24:06.224Z,1554157446.224 [BR_Ping](INFO): Received Ping, distance: 9072 2019-04-01T22:24:06.627Z,1554157446.627 [BR_Ping](INFO): Runnable 2019-04-01T22:24:06.629Z,1554157446.629 [BR_Ping](INFO): Received Ping, distance: 7113 2019-04-01T22:24:07.125Z,1554157447.125 [PAR_Licor](INFO): A/D timeout, 15 tries over 126 ms 2019-04-01T22:24:07.125Z,1554157447.125 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:07.125Z,1554157447.125 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:07.155Z,1554157447.155 [BR_Ping](INFO): Runnable 2019-04-01T22:24:07.157Z,1554157447.157 [BR_Ping](INFO): Received Ping, distance: 7113 2019-04-01T22:24:07.171Z,1554157447.171 [CBIT](FAULT): Main Battery Failure. Count: 141 2019-04-01T22:24:07.175Z,1554157447.175 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:07.175Z,1554157447.175 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:07.175Z,1554157447.175 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:07.425Z,1554157447.425 [BR_Ping](INFO): Runnable 2019-04-01T22:24:07.427Z,1554157447.427 [BR_Ping](INFO): Received Ping, distance: 7102 2019-04-01T22:24:07.836Z,1554157447.836 [BR_Ping](INFO): Runnable 2019-04-01T22:24:07.837Z,1554157447.837 [BR_Ping](INFO): Received Ping, distance: 7114 2019-04-01T22:24:08.337Z,1554157448.337 [PAR_Licor](INFO): A/D timeout, 16 tries over 127 ms 2019-04-01T22:24:08.337Z,1554157448.337 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:08.337Z,1554157448.337 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:08.369Z,1554157448.369 [BR_Ping](INFO): Runnable 2019-04-01T22:24:08.371Z,1554157448.371 [BR_Ping](INFO): Received Ping, distance: 7097 2019-04-01T22:24:08.386Z,1554157448.386 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:08.386Z,1554157448.386 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:08.386Z,1554157448.386 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:08.633Z,1554157448.633 [BR_Ping](INFO): Runnable 2019-04-01T22:24:08.635Z,1554157448.635 [BR_Ping](INFO): Received Ping, distance: 7135 2019-04-01T22:24:09.049Z,1554157449.049 [BR_Ping](INFO): Runnable 2019-04-01T22:24:09.050Z,1554157449.050 [BR_Ping](INFO): Received Ping, distance: 7135 2019-04-01T22:24:09.549Z,1554157449.549 [PAR_Licor](INFO): A/D timeout, 16 tries over 127 ms 2019-04-01T22:24:09.549Z,1554157449.549 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:09.549Z,1554157449.549 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:09.566Z,1554157449.566 [BR_Ping](INFO): Runnable 2019-04-01T22:24:09.567Z,1554157449.567 [BR_Ping](INFO): Received Ping, distance: 7122 2019-04-01T22:24:09.582Z,1554157449.582 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:09.583Z,1554157449.583 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:09.583Z,1554157449.583 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:09.846Z,1554157449.846 [BR_Ping](INFO): Runnable 2019-04-01T22:24:09.848Z,1554157449.848 [BR_Ping](INFO): Received Ping, distance: 7182 2019-04-01T22:24:10.256Z,1554157450.256 [BR_Ping](INFO): Runnable 2019-04-01T22:24:10.257Z,1554157450.257 [BR_Ping](INFO): Received Ping, distance: 7169 2019-04-01T22:24:10.761Z,1554157450.761 [PAR_Licor](INFO): A/D timeout, 16 tries over 127 ms 2019-04-01T22:24:10.761Z,1554157450.761 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:10.761Z,1554157450.761 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:10.792Z,1554157450.792 [BR_Ping](INFO): Runnable 2019-04-01T22:24:10.794Z,1554157450.794 [BR_Ping](INFO): Received Ping, distance: 7169 2019-04-01T22:24:10.810Z,1554157450.810 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:10.811Z,1554157450.811 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:10.811Z,1554157450.811 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:11.074Z,1554157451.074 [BR_Ping](INFO): Runnable 2019-04-01T22:24:11.075Z,1554157451.075 [BR_Ping](INFO): Received Ping, distance: 7128 2019-04-01T22:24:11.093Z,1554157451.093 [CBIT](FAULT): Main Battery Failure. Count: 151 2019-04-01T22:24:11.484Z,1554157451.484 [BR_Ping](INFO): Runnable 2019-04-01T22:24:11.485Z,1554157451.485 [BR_Ping](INFO): Received Ping, distance: 7111 2019-04-01T22:24:11.977Z,1554157451.977 [PAR_Licor](INFO): A/D timeout, 16 tries over 131 ms 2019-04-01T22:24:11.977Z,1554157451.977 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:11.977Z,1554157451.977 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:12.021Z,1554157452.021 [BR_Ping](INFO): Runnable 2019-04-01T22:24:12.022Z,1554157452.022 [BR_Ping](INFO): Received Ping, distance: 7080 2019-04-01T22:24:12.040Z,1554157452.040 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:12.040Z,1554157452.040 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:12.040Z,1554157452.040 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:12.270Z,1554157452.270 [BR_Ping](INFO): Runnable 2019-04-01T22:24:12.271Z,1554157452.271 [BR_Ping](INFO): Received Ping, distance: 7086 2019-04-01T22:24:12.685Z,1554157452.685 [BR_Ping](INFO): Runnable 2019-04-01T22:24:12.686Z,1554157452.686 [BR_Ping](INFO): Received Ping, distance: 7086 2019-04-01T22:24:13.185Z,1554157453.185 [PAR_Licor](INFO): A/D timeout, 16 tries over 126 ms 2019-04-01T22:24:13.185Z,1554157453.185 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:13.185Z,1554157453.185 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:13.202Z,1554157453.202 [BR_Ping](INFO): Runnable 2019-04-01T22:24:13.204Z,1554157453.204 [BR_Ping](INFO): Received Ping, distance: 7090 2019-04-01T22:24:13.218Z,1554157453.218 [SBIT](ERROR): Could not read massPosReader_. 2019-04-01T22:24:13.218Z,1554157453.218 [SBIT](FAULT): Control surface position failure. 2019-04-01T22:24:13.222Z,1554157453.222 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:13.222Z,1554157453.222 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:13.222Z,1554157453.222 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:13.482Z,1554157453.482 [BR_Ping](INFO): Runnable 2019-04-01T22:24:13.483Z,1554157453.483 [BR_Ping](INFO): Received Ping, distance: 7803 2019-04-01T22:24:13.891Z,1554157453.891 [BR_Ping](INFO): Runnable 2019-04-01T22:24:13.892Z,1554157453.892 [BR_Ping](INFO): Received Ping, distance: 8009 2019-04-01T22:24:14.397Z,1554157454.397 [PAR_Licor](INFO): A/D timeout, 16 tries over 127 ms 2019-04-01T22:24:14.397Z,1554157454.397 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:14.397Z,1554157454.397 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:14.414Z,1554157454.414 [BR_Ping](INFO): Runnable 2019-04-01T22:24:14.416Z,1554157454.416 [BR_Ping](INFO): Received Ping, distance: 8009 2019-04-01T22:24:14.435Z,1554157454.435 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:14.435Z,1554157454.435 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:14.435Z,1554157454.435 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:14.697Z,1554157454.697 [BR_Ping](INFO): Runnable 2019-04-01T22:24:14.698Z,1554157454.698 [BR_Ping](INFO): Received Ping, distance: 8216 2019-04-01T22:24:15.101Z,1554157455.101 [BR_Ping](INFO): Runnable 2019-04-01T22:24:15.103Z,1554157455.103 [BR_Ping](INFO): Received Ping, distance: 8423 2019-04-01T22:24:15.239Z,1554157455.239 [CBIT](FAULT): Main Battery Failure. Count: 161 2019-04-01T22:24:15.509Z,1554157455.509 [BR_Ping](INFO): Runnable 2019-04-01T22:24:15.511Z,1554157455.511 [BR_Ping](INFO): Received Ping, distance: 8764 2019-04-01T22:24:16.017Z,1554157456.017 [PAR_Licor](INFO): A/D timeout, 17 tries over 131 ms 2019-04-01T22:24:16.017Z,1554157456.017 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:16.017Z,1554157456.017 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:16.048Z,1554157456.048 [BR_Ping](INFO): Runnable 2019-04-01T22:24:16.049Z,1554157456.049 [BR_Ping](INFO): Received Ping, distance: 8975 2019-04-01T22:24:16.150Z,1554157456.150 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:16.150Z,1554157456.150 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:16.150Z,1554157456.150 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:16.328Z,1554157456.328 [BR_Ping](INFO): Runnable 2019-04-01T22:24:16.329Z,1554157456.329 [BR_Ping](INFO): Received Ping, distance: 7040 2019-04-01T22:24:16.825Z,1554157456.825 [PAR_Licor](INFO): A/D timeout, 10 tries over 131 ms 2019-04-01T22:24:16.825Z,1554157456.825 [PAR_Licor] Data Fault, FailCount= 2 2019-04-01T22:24:16.826Z,1554157456.826 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:16.863Z,1554157456.863 [BR_Ping](INFO): Runnable 2019-04-01T22:24:16.865Z,1554157456.865 [BR_Ping](INFO): Received Ping, distance: 7040 2019-04-01T22:24:16.942Z,1554157456.942 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:16.942Z,1554157456.942 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:16.942Z,1554157456.942 [PAR_Licor] No Fault, FailCount= 2 2019-04-01T22:24:17.101Z,1554157457.101 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface 2019-04-01T22:24:17.101Z,1554157457.101 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3 2019-04-01T22:24:17.101Z,1554157457.101 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2019-04-01T22:24:17.135Z,1554157457.135 [BR_Ping](INFO): Runnable 2019-04-01T22:24:17.137Z,1554157457.137 [BR_Ping](INFO): Received Ping, distance: 7044 2019-04-01T22:24:17.238Z,1554157457.238 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2019-04-01T22:24:17.505Z,1554157457.505 [AcousticModem_Benthos_ATM900](INFO): Powering down 2019-04-01T22:24:17.649Z,1554157457.649 [BR_Ping](INFO): Runnable 2019-04-01T22:24:17.651Z,1554157457.651 [BR_Ping](INFO): Received Ping, distance: 7043 2019-04-01T22:24:17.937Z,1554157457.937 [BR_Ping](INFO): Runnable 2019-04-01T22:24:17.939Z,1554157457.939 [BR_Ping](INFO): Received Ping, distance: 7078 2019-04-01T22:24:18.334Z,1554157458.334 [BR_Ping](INFO): Runnable 2019-04-01T22:24:18.335Z,1554157458.335 [BR_Ping](INFO): Received Ping, distance: 7078 2019-04-01T22:24:18.606Z,1554157458.606 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2019-04-01T22:24:18.606Z,1554157458.606 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3 2019-04-01T22:24:18.841Z,1554157458.841 [PAR_Licor](INFO): A/D timeout, 16 tries over 127 ms 2019-04-01T22:24:18.841Z,1554157458.841 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:18.841Z,1554157458.841 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:18.859Z,1554157458.859 [BR_Ping](INFO): Runnable 2019-04-01T22:24:18.861Z,1554157458.861 [BR_Ping](INFO): Received Ping, distance: 7070 2019-04-01T22:24:18.907Z,1554157458.907 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:18.907Z,1554157458.907 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:18.907Z,1554157458.907 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:19.145Z,1554157459.145 [BR_Ping](INFO): Runnable 2019-04-01T22:24:19.146Z,1554157459.146 [BR_Ping](INFO): Received Ping, distance: 7024 2019-04-01T22:24:19.158Z,1554157459.158 [CBIT](FAULT): Main Battery Failure. Count: 171 2019-04-01T22:24:19.545Z,1554157459.545 [BR_Ping](INFO): Runnable 2019-04-01T22:24:19.546Z,1554157459.546 [BR_Ping](INFO): Received Ping, distance: 7026 2019-04-01T22:24:20.053Z,1554157460.053 [PAR_Licor](INFO): A/D timeout, 17 tries over 127 ms 2019-04-01T22:24:20.053Z,1554157460.053 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:20.053Z,1554157460.053 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:20.070Z,1554157460.070 [BR_Ping](INFO): Runnable 2019-04-01T22:24:20.072Z,1554157460.072 [BR_Ping](INFO): Received Ping, distance: 7026 2019-04-01T22:24:20.087Z,1554157460.087 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:20.087Z,1554157460.087 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:20.087Z,1554157460.087 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:20.353Z,1554157460.353 [BR_Ping](INFO): Runnable 2019-04-01T22:24:20.354Z,1554157460.354 [BR_Ping](INFO): Received Ping, distance: 7011 2019-04-01T22:24:20.743Z,1554157460.743 [AcousticModem_Benthos_ATM900](INFO): Powering up 2019-04-01T22:24:20.743Z,1554157460.743 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2019-04-01T22:24:20.781Z,1554157460.781 [BR_Ping](INFO): Runnable 2019-04-01T22:24:20.782Z,1554157460.782 [BR_Ping](INFO): Received Ping, distance: 6960 2019-04-01T22:24:21.269Z,1554157461.269 [PAR_Licor](INFO): A/D timeout, 16 tries over 131 ms 2019-04-01T22:24:21.269Z,1554157461.269 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:21.269Z,1554157461.269 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:21.300Z,1554157461.300 [BR_Ping](INFO): Runnable 2019-04-01T22:24:21.302Z,1554157461.302 [BR_Ping](INFO): Received Ping, distance: 8308 2019-04-01T22:24:21.316Z,1554157461.316 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:21.316Z,1554157461.316 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:21.316Z,1554157461.316 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:21.586Z,1554157461.586 [BR_Ping](INFO): Runnable 2019-04-01T22:24:21.587Z,1554157461.587 [BR_Ping](INFO): Received Ping, distance: 8146 2019-04-01T22:24:21.983Z,1554157461.983 [BR_Ping](INFO): Runnable 2019-04-01T22:24:21.985Z,1554157461.985 [BR_Ping](INFO): Received Ping, distance: 8146 2019-04-01T22:24:22.477Z,1554157462.477 [PAR_Licor](INFO): A/D timeout, 16 tries over 127 ms 2019-04-01T22:24:22.477Z,1554157462.477 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:22.477Z,1554157462.477 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:22.508Z,1554157462.508 [BR_Ping](INFO): Runnable 2019-04-01T22:24:22.509Z,1554157462.509 [BR_Ping](INFO): Received Ping, distance: 7956 2019-04-01T22:24:22.552Z,1554157462.552 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:22.553Z,1554157462.553 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:22.553Z,1554157462.553 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:22.778Z,1554157462.778 [BR_Ping](INFO): Runnable 2019-04-01T22:24:22.779Z,1554157462.779 [BR_Ping](INFO): Received Ping, distance: 7340 2019-04-01T22:24:23.182Z,1554157463.182 [BR_Ping](INFO): Runnable 2019-04-01T22:24:23.183Z,1554157463.183 [BR_Ping](INFO): Received Ping, distance: 6983 2019-04-01T22:24:23.196Z,1554157463.196 [CBIT](FAULT): Main Battery Failure. Count: 181 2019-04-01T22:24:23.689Z,1554157463.689 [PAR_Licor](INFO): A/D timeout, 16 tries over 127 ms 2019-04-01T22:24:23.689Z,1554157463.689 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:23.689Z,1554157463.689 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:23.707Z,1554157463.707 [BR_Ping](INFO): Runnable 2019-04-01T22:24:23.708Z,1554157463.708 [BR_Ping](INFO): Received Ping, distance: 6983 2019-04-01T22:24:23.723Z,1554157463.723 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:23.723Z,1554157463.723 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:23.723Z,1554157463.723 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:23.993Z,1554157463.993 [BR_Ping](INFO): Runnable 2019-04-01T22:24:23.994Z,1554157463.994 [BR_Ping](INFO): Received Ping, distance: 6994 2019-04-01T22:24:24.401Z,1554157464.401 [BR_Ping](INFO): Runnable 2019-04-01T22:24:24.402Z,1554157464.402 [BR_Ping](INFO): Received Ping, distance: 7005 2019-04-01T22:24:24.905Z,1554157464.905 [PAR_Licor](INFO): A/D timeout, 17 tries over 131 ms 2019-04-01T22:24:24.906Z,1554157464.906 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:24.906Z,1554157464.906 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:24.937Z,1554157464.937 [BR_Ping](INFO): Runnable 2019-04-01T22:24:24.939Z,1554157464.939 [BR_Ping](INFO): Received Ping, distance: 7558 2019-04-01T22:24:24.956Z,1554157464.956 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:24.956Z,1554157464.956 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:24.956Z,1554157464.956 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:25.205Z,1554157465.205 [BR_Ping](INFO): Runnable 2019-04-01T22:24:25.206Z,1554157465.206 [BR_Ping](INFO): Received Ping, distance: 7519 2019-04-01T22:24:25.605Z,1554157465.605 [BR_Ping](INFO): Runnable 2019-04-01T22:24:25.607Z,1554157465.607 [BR_Ping](INFO): Received Ping, distance: 7519 2019-04-01T22:24:26.113Z,1554157466.113 [PAR_Licor](INFO): A/D timeout, 16 tries over 127 ms 2019-04-01T22:24:26.113Z,1554157466.113 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:26.113Z,1554157466.113 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:26.144Z,1554157466.144 [BR_Ping](INFO): Runnable 2019-04-01T22:24:26.146Z,1554157466.146 [BR_Ping](INFO): Received Ping, distance: 7079 2019-04-01T22:24:26.191Z,1554157466.191 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:26.192Z,1554157466.192 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:26.192Z,1554157466.192 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:26.427Z,1554157466.427 [BR_Ping](INFO): Runnable 2019-04-01T22:24:26.428Z,1554157466.428 [BR_Ping](INFO): Received Ping, distance: 7079 2019-04-01T22:24:26.835Z,1554157466.835 [BR_Ping](INFO): Runnable 2019-04-01T22:24:26.837Z,1554157466.837 [BR_Ping](INFO): Received Ping, distance: 7112 2019-04-01T22:24:26.848Z,1554157466.848 [SBIT](ERROR): Could not read massPosReader_. 2019-04-01T22:24:26.848Z,1554157466.848 [SBIT](FAULT): Control surface position failure. 2019-04-01T22:24:27.325Z,1554157467.325 [PAR_Licor](INFO): A/D timeout, 16 tries over 126 ms 2019-04-01T22:24:27.325Z,1554157467.325 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:27.325Z,1554157467.325 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:27.356Z,1554157467.356 [BR_Ping](INFO): Runnable 2019-04-01T22:24:27.357Z,1554157467.357 [BR_Ping](INFO): Received Ping, distance: 7112 2019-04-01T22:24:27.370Z,1554157467.370 [SBIT](CRITICAL): SBIT FAILED 2019-04-01T22:24:27.385Z,1554157467.385 [CommandLine](IMPORTANT): got command configSet list 2019-04-01T22:24:27.385Z,1554157467.385 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-04-01T22:24:27.386Z,1554157467.386 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2019-04-01T22:24:27.386Z,1554157467.386 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool; 2019-04-01T22:24:27.389Z,1554157467.389 [CBIT](INFO): Critical error at 20190401T222427 2019-04-01T22:24:27.389Z,1554157467.389 [CBIT](FAULT): Main Battery Failure. Count: 191 2019-04-01T22:24:27.392Z,1554157467.392 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:27.392Z,1554157467.392 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:27.392Z,1554157467.392 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:27.628Z,1554157467.628 [BR_Ping](INFO): Runnable 2019-04-01T22:24:27.630Z,1554157467.630 [BR_Ping](INFO): Received Ping, distance: 7098 2019-04-01T22:24:27.642Z,1554157467.642 [MissionManager](IMPORTANT): Started mission Startup 2019-04-01T22:24:27.643Z,1554157467.643 [Startup] Running Loop=1 2019-04-01T22:24:27.643Z,1554157467.643 [Startup](DEBUG): Aggregate::initialize Startup 2019-04-01T22:24:27.643Z,1554157467.643 [Startup:A.GoToSurface] Running Loop=1 2019-04-01T22:24:27.643Z,1554157467.643 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-04-01T22:24:27.644Z,1554157467.644 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-04-01T22:24:27.644Z,1554157467.644 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-04-01T22:24:27.644Z,1554157467.644 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-04-01T22:24:27.645Z,1554157467.645 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-04-01T22:24:27.645Z,1554157467.645 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-04-01T22:24:27.647Z,1554157467.647 [Startup:StartupSatComms] Running Loop=1 2019-04-01T22:24:27.647Z,1554157467.647 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-04-01T22:24:27.647Z,1554157467.647 [Startup:StartupSatComms:A] Running Loop=1 2019-04-01T22:24:28.044Z,1554157468.044 [BR_Ping](INFO): Runnable 2019-04-01T22:24:28.045Z,1554157468.045 [BR_Ping](INFO): Received Ping, distance: 7117 2019-04-01T22:24:28.060Z,1554157468.060 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-04-01T22:24:28.549Z,1554157468.549 [PAR_Licor](INFO): A/D timeout, 17 tries over 139 ms 2019-04-01T22:24:28.549Z,1554157468.549 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:28.549Z,1554157468.549 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:28.567Z,1554157468.567 [BR_Ping](INFO): Runnable 2019-04-01T22:24:28.569Z,1554157468.569 [BR_Ping](INFO): Received Ping, distance: 7087 2019-04-01T22:24:28.584Z,1554157468.584 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:28.584Z,1554157468.584 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:28.584Z,1554157468.584 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:28.837Z,1554157468.837 [BR_Ping](INFO): Runnable 2019-04-01T22:24:28.838Z,1554157468.838 [BR_Ping](INFO): Received Ping, distance: 7059 2019-04-01T22:24:29.245Z,1554157469.245 [BR_Ping](INFO): Runnable 2019-04-01T22:24:29.246Z,1554157469.246 [BR_Ping](INFO): Received Ping, distance: 7059 2019-04-01T22:24:29.749Z,1554157469.749 [PAR_Licor](INFO): A/D timeout, 17 tries over 127 ms 2019-04-01T22:24:29.749Z,1554157469.749 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:29.749Z,1554157469.749 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:29.766Z,1554157469.766 [BR_Ping](INFO): Runnable 2019-04-01T22:24:29.768Z,1554157469.768 [BR_Ping](INFO): Received Ping, distance: 7042 2019-04-01T22:24:29.786Z,1554157469.786 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:29.786Z,1554157469.786 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:29.786Z,1554157469.786 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:30.049Z,1554157470.049 [BR_Ping](INFO): Runnable 2019-04-01T22:24:30.050Z,1554157470.050 [BR_Ping](INFO): Received Ping, distance: 7059 2019-04-01T22:24:30.456Z,1554157470.456 [BR_Ping](INFO): Runnable 2019-04-01T22:24:30.457Z,1554157470.457 [BR_Ping](INFO): Received Ping, distance: 7011 2019-04-01T22:24:30.965Z,1554157470.965 [PAR_Licor](INFO): A/D timeout, 17 tries over 131 ms 2019-04-01T22:24:30.965Z,1554157470.965 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:30.965Z,1554157470.965 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:30.996Z,1554157470.996 [BR_Ping](INFO): Runnable 2019-04-01T22:24:30.998Z,1554157470.998 [BR_Ping](INFO): Received Ping, distance: 7011 2019-04-01T22:24:31.015Z,1554157471.015 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:31.015Z,1554157471.015 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:31.015Z,1554157471.015 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:31.271Z,1554157471.271 [BR_Ping](INFO): Runnable 2019-04-01T22:24:31.273Z,1554157471.273 [BR_Ping](INFO): Received Ping, distance: 7060 2019-04-01T22:24:31.293Z,1554157471.293 [CBIT](FAULT): Main Battery Failure. Count: 201 2019-04-01T22:24:31.684Z,1554157471.684 [BR_Ping](INFO): Runnable 2019-04-01T22:24:31.685Z,1554157471.685 [BR_Ping](INFO): Received Ping, distance: 7088 2019-04-01T22:24:32.173Z,1554157472.173 [PAR_Licor](INFO): A/D timeout, 17 tries over 127 ms 2019-04-01T22:24:32.173Z,1554157472.173 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:32.173Z,1554157472.173 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:32.211Z,1554157472.211 [BR_Ping](INFO): Runnable 2019-04-01T22:24:32.212Z,1554157472.212 [BR_Ping](INFO): Received Ping, distance: 7139 2019-04-01T22:24:32.239Z,1554157472.239 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:32.239Z,1554157472.239 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:32.239Z,1554157472.239 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:32.477Z,1554157472.477 [BR_Ping](INFO): Runnable 2019-04-01T22:24:32.478Z,1554157472.478 [BR_Ping](INFO): Received Ping, distance: 7107 2019-04-01T22:24:32.881Z,1554157472.881 [BR_Ping](INFO): Runnable 2019-04-01T22:24:32.882Z,1554157472.882 [BR_Ping](INFO): Received Ping, distance: 7107 2019-04-01T22:24:33.389Z,1554157473.389 [PAR_Licor](INFO): A/D timeout, 16 tries over 131 ms 2019-04-01T22:24:33.389Z,1554157473.389 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:33.389Z,1554157473.389 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:33.406Z,1554157473.406 [BR_Ping](INFO): Runnable 2019-04-01T22:24:33.407Z,1554157473.407 [BR_Ping](INFO): Received Ping, distance: 7050 2019-04-01T22:24:33.425Z,1554157473.425 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:33.425Z,1554157473.425 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:33.425Z,1554157473.425 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:33.689Z,1554157473.689 [BR_Ping](INFO): Runnable 2019-04-01T22:24:33.690Z,1554157473.690 [BR_Ping](INFO): Received Ping, distance: 7007 2019-04-01T22:24:34.093Z,1554157474.093 [BR_Ping](INFO): Runnable 2019-04-01T22:24:34.094Z,1554157474.094 [BR_Ping](INFO): Received Ping, distance: 6984 2019-04-01T22:24:34.597Z,1554157474.597 [PAR_Licor](INFO): A/D timeout, 16 tries over 127 ms 2019-04-01T22:24:34.597Z,1554157474.597 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:34.597Z,1554157474.597 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:34.614Z,1554157474.614 [BR_Ping](INFO): Runnable 2019-04-01T22:24:34.616Z,1554157474.616 [BR_Ping](INFO): Received Ping, distance: 6984 2019-04-01T22:24:34.632Z,1554157474.632 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:34.633Z,1554157474.633 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:34.633Z,1554157474.633 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:34.900Z,1554157474.900 [BR_Ping](INFO): Runnable 2019-04-01T22:24:34.902Z,1554157474.902 [BR_Ping](INFO): Received Ping, distance: 6950 2019-04-01T22:24:35.304Z,1554157475.304 [BR_Ping](INFO): Runnable 2019-04-01T22:24:35.306Z,1554157475.306 [BR_Ping](INFO): Received Ping, distance: 6947 2019-04-01T22:24:35.333Z,1554157475.333 [CBIT](FAULT): Main Battery Failure. Count: 211 2019-04-01T22:24:35.809Z,1554157475.809 [PAR_Licor](INFO): A/D timeout, 16 tries over 127 ms 2019-04-01T22:24:35.809Z,1554157475.809 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:35.809Z,1554157475.809 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:35.855Z,1554157475.855 [BR_Ping](INFO): Runnable 2019-04-01T22:24:35.856Z,1554157475.856 [BR_Ping](INFO): Received Ping, distance: 7030 2019-04-01T22:24:35.871Z,1554157475.871 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:35.871Z,1554157475.871 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:35.871Z,1554157475.871 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:36.127Z,1554157476.127 [BR_Ping](INFO): Runnable 2019-04-01T22:24:36.129Z,1554157476.129 [BR_Ping](INFO): Received Ping, distance: 7008 2019-04-01T22:24:36.528Z,1554157476.528 [BR_Ping](INFO): Runnable 2019-04-01T22:24:36.529Z,1554157476.529 [BR_Ping](INFO): Received Ping, distance: 7008 2019-04-01T22:24:37.021Z,1554157477.021 [PAR_Licor](INFO): A/D timeout, 17 tries over 127 ms 2019-04-01T22:24:37.021Z,1554157477.021 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:37.021Z,1554157477.021 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:37.052Z,1554157477.052 [BR_Ping](INFO): Runnable 2019-04-01T22:24:37.054Z,1554157477.054 [BR_Ping](INFO): Received Ping, distance: 6956 2019-04-01T22:24:37.071Z,1554157477.071 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:37.071Z,1554157477.071 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:37.071Z,1554157477.071 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:37.325Z,1554157477.325 [BR_Ping](INFO): Runnable 2019-04-01T22:24:37.327Z,1554157477.327 [BR_Ping](INFO): Received Ping, distance: 6991 2019-04-01T22:24:37.728Z,1554157477.728 [BR_Ping](INFO): Runnable 2019-04-01T22:24:37.730Z,1554157477.730 [BR_Ping](INFO): Received Ping, distance: 6987 2019-04-01T22:24:38.233Z,1554157478.233 [PAR_Licor](INFO): A/D timeout, 16 tries over 127 ms 2019-04-01T22:24:38.233Z,1554157478.233 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:38.233Z,1554157478.233 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:38.249Z,1554157478.249 [BR_Ping](INFO): Runnable 2019-04-01T22:24:38.251Z,1554157478.251 [BR_Ping](INFO): Received Ping, distance: 6987 2019-04-01T22:24:38.270Z,1554157478.270 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:38.270Z,1554157478.270 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:38.270Z,1554157478.270 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:38.547Z,1554157478.547 [BR_Ping](INFO): Runnable 2019-04-01T22:24:38.549Z,1554157478.549 [BR_Ping](INFO): Received Ping, distance: 7032 2019-04-01T22:24:38.941Z,1554157478.941 [BR_Ping](INFO): Runnable 2019-04-01T22:24:38.942Z,1554157478.942 [BR_Ping](INFO): Received Ping, distance: 7052 2019-04-01T22:24:39.445Z,1554157479.445 [PAR_Licor](INFO): A/D timeout, 17 tries over 127 ms 2019-04-01T22:24:39.445Z,1554157479.445 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:39.445Z,1554157479.445 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:39.462Z,1554157479.462 [BR_Ping](INFO): Runnable 2019-04-01T22:24:39.464Z,1554157479.464 [BR_Ping](INFO): Received Ping, distance: 7049 2019-04-01T22:24:39.489Z,1554157479.489 [CBIT](FAULT): Main Battery Failure. Count: 221 2019-04-01T22:24:39.491Z,1554157479.491 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:39.492Z,1554157479.492 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:39.492Z,1554157479.492 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:39.753Z,1554157479.753 [BR_Ping](INFO): Runnable 2019-04-01T22:24:39.754Z,1554157479.754 [BR_Ping](INFO): Received Ping, distance: 7051 2019-04-01T22:24:40.157Z,1554157480.157 [BR_Ping](INFO): Runnable 2019-04-01T22:24:40.158Z,1554157480.158 [BR_Ping](INFO): Received Ping, distance: 7051 2019-04-01T22:24:40.657Z,1554157480.657 [PAR_Licor](INFO): A/D timeout, 17 tries over 127 ms 2019-04-01T22:24:40.657Z,1554157480.657 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:40.657Z,1554157480.657 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:40.689Z,1554157480.689 [BR_Ping](INFO): Runnable 2019-04-01T22:24:40.691Z,1554157480.691 [BR_Ping](INFO): Received Ping, distance: 7050 2019-04-01T22:24:40.705Z,1554157480.705 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:40.706Z,1554157480.706 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:40.706Z,1554157480.706 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:40.937Z,1554157480.937 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface 2019-04-01T22:24:40.937Z,1554157480.937 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4 2019-04-01T22:24:40.937Z,1554157480.937 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2019-04-01T22:24:40.971Z,1554157480.971 [BR_Ping](INFO): Runnable 2019-04-01T22:24:40.972Z,1554157480.972 [BR_Ping](INFO): Received Ping, distance: 7093 2019-04-01T22:24:40.998Z,1554157480.998 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2019-04-01T22:24:41.341Z,1554157481.341 [AcousticModem_Benthos_ATM900](INFO): Powering down 2019-04-01T22:24:41.496Z,1554157481.496 [BR_Ping](INFO): Runnable 2019-04-01T22:24:41.498Z,1554157481.498 [BR_Ping](INFO): Received Ping, distance: 7112 2019-04-01T22:24:41.774Z,1554157481.774 [BR_Ping](INFO): Runnable 2019-04-01T22:24:41.776Z,1554157481.776 [BR_Ping](INFO): Received Ping, distance: 7096 2019-04-01T22:24:42.173Z,1554157482.173 [BR_Ping](INFO): Runnable 2019-04-01T22:24:42.174Z,1554157482.174 [BR_Ping](INFO): Received Ping, distance: 7096 2019-04-01T22:24:42.206Z,1554157482.206 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2019-04-01T22:24:42.206Z,1554157482.206 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4 2019-04-01T22:24:42.681Z,1554157482.681 [PAR_Licor](INFO): A/D timeout, 16 tries over 131 ms 2019-04-01T22:24:42.681Z,1554157482.681 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:42.681Z,1554157482.681 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:42.712Z,1554157482.712 [BR_Ping](INFO): Runnable 2019-04-01T22:24:42.713Z,1554157482.713 [BR_Ping](INFO): Received Ping, distance: 7089 2019-04-01T22:24:42.730Z,1554157482.730 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:42.730Z,1554157482.730 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:42.730Z,1554157482.730 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:42.974Z,1554157482.974 [BR_Ping](INFO): Runnable 2019-04-01T22:24:42.975Z,1554157482.975 [BR_Ping](INFO): Received Ping, distance: 7124 2019-04-01T22:24:43.385Z,1554157483.385 [BR_Ping](INFO): Runnable 2019-04-01T22:24:43.386Z,1554157483.386 [BR_Ping](INFO): Received Ping, distance: 7101 2019-04-01T22:24:43.398Z,1554157483.398 [CBIT](FAULT): Main Battery Failure. Count: 231 2019-04-01T22:24:43.893Z,1554157483.893 [PAR_Licor](INFO): A/D timeout, 15 tries over 131 ms 2019-04-01T22:24:43.893Z,1554157483.893 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:43.893Z,1554157483.893 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:43.911Z,1554157483.911 [BR_Ping](INFO): Runnable 2019-04-01T22:24:43.912Z,1554157483.912 [BR_Ping](INFO): Received Ping, distance: 7101 2019-04-01T22:24:43.927Z,1554157483.927 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:43.927Z,1554157483.927 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:43.928Z,1554157483.928 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:44.189Z,1554157484.189 [BR_Ping](INFO): Runnable 2019-04-01T22:24:44.190Z,1554157484.190 [BR_Ping](INFO): Received Ping, distance: 7075 2019-04-01T22:24:44.577Z,1554157484.577 [AcousticModem_Benthos_ATM900](INFO): Powering up 2019-04-01T22:24:44.578Z,1554157484.578 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2019-04-01T22:24:44.617Z,1554157484.617 [BR_Ping](INFO): Runnable 2019-04-01T22:24:44.618Z,1554157484.618 [BR_Ping](INFO): Received Ping, distance: 7080 2019-04-01T22:24:45.101Z,1554157485.101 [PAR_Licor](INFO): A/D timeout, 14 tries over 127 ms 2019-04-01T22:24:45.101Z,1554157485.101 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:45.101Z,1554157485.101 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:45.118Z,1554157485.118 [BR_Ping](INFO): Runnable 2019-04-01T22:24:45.119Z,1554157485.119 [BR_Ping](INFO): Received Ping, distance: 7061 2019-04-01T22:24:45.138Z,1554157485.138 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:45.138Z,1554157485.138 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:45.138Z,1554157485.138 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:45.398Z,1554157485.398 [BR_Ping](INFO): Runnable 2019-04-01T22:24:45.399Z,1554157485.399 [BR_Ping](INFO): Received Ping, distance: 7070 2019-04-01T22:24:45.820Z,1554157485.820 [BR_Ping](INFO): Runnable 2019-04-01T22:24:45.821Z,1554157485.821 [BR_Ping](INFO): Received Ping, distance: 7070 2019-04-01T22:24:46.313Z,1554157486.313 [PAR_Licor](INFO): A/D timeout, 16 tries over 127 ms 2019-04-01T22:24:46.313Z,1554157486.313 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:46.313Z,1554157486.313 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:46.345Z,1554157486.345 [BR_Ping](INFO): Runnable 2019-04-01T22:24:46.347Z,1554157486.347 [BR_Ping](INFO): Received Ping, distance: 7040 2019-04-01T22:24:46.396Z,1554157486.396 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:46.396Z,1554157486.396 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:46.396Z,1554157486.396 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:46.631Z,1554157486.631 [BR_Ping](INFO): Runnable 2019-04-01T22:24:46.632Z,1554157486.632 [BR_Ping](INFO): Received Ping, distance: 7045 2019-04-01T22:24:47.031Z,1554157487.031 [BR_Ping](INFO): Runnable 2019-04-01T22:24:47.033Z,1554157487.033 [BR_Ping](INFO): Received Ping, distance: 7013 2019-04-01T22:24:47.529Z,1554157487.529 [PAR_Licor](INFO): A/D timeout, 16 tries over 131 ms 2019-04-01T22:24:47.529Z,1554157487.529 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:47.529Z,1554157487.529 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:47.546Z,1554157487.546 [BR_Ping](INFO): Runnable 2019-04-01T22:24:47.548Z,1554157487.548 [BR_Ping](INFO): Received Ping, distance: 7013 2019-04-01T22:24:47.561Z,1554157487.561 [CBIT](FAULT): Main Battery Failure. Count: 241 2019-04-01T22:24:47.564Z,1554157487.564 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:47.564Z,1554157487.564 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:47.564Z,1554157487.564 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:47.823Z,1554157487.823 [BR_Ping](INFO): Runnable 2019-04-01T22:24:47.825Z,1554157487.825 [BR_Ping](INFO): Received Ping, distance: 7006 2019-04-01T22:24:48.238Z,1554157488.238 [BR_Ping](INFO): Runnable 2019-04-01T22:24:48.239Z,1554157488.239 [BR_Ping](INFO): Received Ping, distance: 7005 2019-04-01T22:24:48.741Z,1554157488.741 [PAR_Licor](INFO): A/D timeout, 16 tries over 131 ms 2019-04-01T22:24:48.741Z,1554157488.741 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:48.741Z,1554157488.741 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:48.770Z,1554157488.770 [BR_Ping](INFO): Runnable 2019-04-01T22:24:48.771Z,1554157488.771 [BR_Ping](INFO): Received Ping, distance: 7041 2019-04-01T22:24:48.788Z,1554157488.788 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:48.788Z,1554157488.788 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:48.788Z,1554157488.788 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:49.034Z,1554157489.034 [BR_Ping](INFO): Runnable 2019-04-01T22:24:49.035Z,1554157489.035 [BR_Ping](INFO): Received Ping, distance: 7042 2019-04-01T22:24:49.442Z,1554157489.442 [BR_Ping](INFO): Runnable 2019-04-01T22:24:49.444Z,1554157489.444 [BR_Ping](INFO): Received Ping, distance: 7042 2019-04-01T22:24:49.953Z,1554157489.953 [PAR_Licor](INFO): A/D timeout, 16 tries over 131 ms 2019-04-01T22:24:49.953Z,1554157489.953 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:49.953Z,1554157489.953 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:49.970Z,1554157489.970 [BR_Ping](INFO): Runnable 2019-04-01T22:24:49.971Z,1554157489.971 [BR_Ping](INFO): Received Ping, distance: 7036 2019-04-01T22:24:49.990Z,1554157489.990 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:49.990Z,1554157489.990 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:49.990Z,1554157489.990 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:50.253Z,1554157490.253 [BR_Ping](INFO): Runnable 2019-04-01T22:24:50.255Z,1554157490.255 [BR_Ping](INFO): Received Ping, distance: 7030 2019-04-01T22:24:50.657Z,1554157490.657 [BR_Ping](INFO): Runnable 2019-04-01T22:24:50.658Z,1554157490.658 [BR_Ping](INFO): Received Ping, distance: 7070 2019-04-01T22:24:51.165Z,1554157491.165 [PAR_Licor](INFO): A/D timeout, 16 tries over 131 ms 2019-04-01T22:24:51.165Z,1554157491.165 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:51.165Z,1554157491.165 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:51.196Z,1554157491.196 [BR_Ping](INFO): Runnable 2019-04-01T22:24:51.198Z,1554157491.198 [BR_Ping](INFO): Received Ping, distance: 7070 2019-04-01T22:24:51.215Z,1554157491.215 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:51.215Z,1554157491.215 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:51.215Z,1554157491.215 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:51.471Z,1554157491.471 [BR_Ping](INFO): Runnable 2019-04-01T22:24:51.473Z,1554157491.473 [BR_Ping](INFO): Received Ping, distance: 7035 2019-04-01T22:24:51.493Z,1554157491.493 [CBIT](FAULT): Main Battery Failure. Count: 251 2019-04-01T22:24:51.884Z,1554157491.884 [BR_Ping](INFO): Runnable 2019-04-01T22:24:51.885Z,1554157491.885 [BR_Ping](INFO): Received Ping, distance: 7031 2019-04-01T22:24:52.377Z,1554157492.377 [PAR_Licor](INFO): A/D timeout, 16 tries over 131 ms 2019-04-01T22:24:52.377Z,1554157492.377 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:52.377Z,1554157492.377 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:52.415Z,1554157492.415 [BR_Ping](INFO): Runnable 2019-04-01T22:24:52.417Z,1554157492.417 [BR_Ping](INFO): Received Ping, distance: 7026 2019-04-01T22:24:52.431Z,1554157492.431 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:52.431Z,1554157492.431 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:52.431Z,1554157492.431 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:52.677Z,1554157492.677 [BR_Ping](INFO): Runnable 2019-04-01T22:24:52.678Z,1554157492.678 [BR_Ping](INFO): Received Ping, distance: 7036 2019-04-01T22:24:53.074Z,1554157493.074 [BR_Ping](INFO): Runnable 2019-04-01T22:24:53.075Z,1554157493.075 [BR_Ping](INFO): Received Ping, distance: 7036 2019-04-01T22:24:53.589Z,1554157493.589 [PAR_Licor](INFO): A/D timeout, 16 tries over 131 ms 2019-04-01T22:24:53.589Z,1554157493.589 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:53.589Z,1554157493.589 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:53.606Z,1554157493.606 [BR_Ping](INFO): Runnable 2019-04-01T22:24:53.608Z,1554157493.608 [BR_Ping](INFO): Received Ping, distance: 7055 2019-04-01T22:24:53.626Z,1554157493.626 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:53.626Z,1554157493.626 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:53.626Z,1554157493.626 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:53.883Z,1554157493.883 [BR_Ping](INFO): Runnable 2019-04-01T22:24:53.884Z,1554157493.884 [BR_Ping](INFO): Received Ping, distance: 7049 2019-04-01T22:24:54.293Z,1554157494.293 [BR_Ping](INFO): Runnable 2019-04-01T22:24:54.294Z,1554157494.294 [BR_Ping](INFO): Received Ping, distance: 7042 2019-04-01T22:24:54.797Z,1554157494.797 [PAR_Licor](INFO): A/D timeout, 16 tries over 127 ms 2019-04-01T22:24:54.797Z,1554157494.797 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:54.797Z,1554157494.797 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:54.814Z,1554157494.814 [BR_Ping](INFO): Runnable 2019-04-01T22:24:54.815Z,1554157494.815 [BR_Ping](INFO): Received Ping, distance: 7042 2019-04-01T22:24:54.831Z,1554157494.831 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:54.831Z,1554157494.831 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:54.831Z,1554157494.831 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:55.108Z,1554157495.108 [BR_Ping](INFO): Runnable 2019-04-01T22:24:55.141Z,1554157495.141 [BR_Ping](INFO): Received Ping, distance: 7041 2019-04-01T22:24:55.505Z,1554157495.505 [BR_Ping](INFO): Runnable 2019-04-01T22:24:55.506Z,1554157495.506 [BR_Ping](INFO): Received Ping, distance: 7028 2019-04-01T22:24:55.535Z,1554157495.535 [CBIT](FAULT): Main Battery Failure. Count: 261 2019-04-01T22:24:56.009Z,1554157496.009 [PAR_Licor](INFO): A/D timeout, 15 tries over 127 ms 2019-04-01T22:24:56.009Z,1554157496.009 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:56.009Z,1554157496.009 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:56.054Z,1554157496.054 [BR_Ping](INFO): Runnable 2019-04-01T22:24:56.055Z,1554157496.055 [BR_Ping](INFO): Received Ping, distance: 7023 2019-04-01T22:24:56.070Z,1554157496.070 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:56.070Z,1554157496.070 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:56.070Z,1554157496.070 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:56.319Z,1554157496.319 [BR_Ping](INFO): Runnable 2019-04-01T22:24:56.320Z,1554157496.320 [BR_Ping](INFO): Received Ping, distance: 7014 2019-04-01T22:24:56.727Z,1554157496.727 [BR_Ping](INFO): Runnable 2019-04-01T22:24:56.729Z,1554157496.729 [BR_Ping](INFO): Received Ping, distance: 7014 2019-04-01T22:24:57.225Z,1554157497.225 [PAR_Licor](INFO): A/D timeout, 16 tries over 131 ms 2019-04-01T22:24:57.225Z,1554157497.225 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:57.225Z,1554157497.225 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:57.256Z,1554157497.256 [BR_Ping](INFO): Runnable 2019-04-01T22:24:57.258Z,1554157497.258 [BR_Ping](INFO): Received Ping, distance: 7010 2019-04-01T22:24:57.272Z,1554157497.272 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:57.272Z,1554157497.272 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:57.273Z,1554157497.273 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:57.518Z,1554157497.518 [BR_Ping](INFO): Runnable 2019-04-01T22:24:57.519Z,1554157497.519 [BR_Ping](INFO): Received Ping, distance: 7046 2019-04-01T22:24:57.933Z,1554157497.933 [BR_Ping](INFO): Runnable 2019-04-01T22:24:57.934Z,1554157497.934 [BR_Ping](INFO): Received Ping, distance: 7033 2019-04-01T22:24:58.437Z,1554157498.437 [PAR_Licor](INFO): A/D timeout, 16 tries over 131 ms 2019-04-01T22:24:58.437Z,1554157498.437 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:58.437Z,1554157498.437 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:58.454Z,1554157498.454 [BR_Ping](INFO): Runnable 2019-04-01T22:24:58.456Z,1554157498.456 [BR_Ping](INFO): Received Ping, distance: 7033 2019-04-01T22:24:58.474Z,1554157498.474 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:58.474Z,1554157498.474 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:58.474Z,1554157498.474 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:58.733Z,1554157498.733 [BR_Ping](INFO): Runnable 2019-04-01T22:24:58.734Z,1554157498.734 [BR_Ping](INFO): Received Ping, distance: 7022 2019-04-01T22:24:59.138Z,1554157499.138 [BR_Ping](INFO): Runnable 2019-04-01T22:24:59.139Z,1554157499.139 [BR_Ping](INFO): Received Ping, distance: 7001 2019-04-01T22:24:59.645Z,1554157499.645 [PAR_Licor](INFO): A/D timeout, 16 tries over 127 ms 2019-04-01T22:24:59.645Z,1554157499.645 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:24:59.645Z,1554157499.645 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:24:59.662Z,1554157499.662 [BR_Ping](INFO): Runnable 2019-04-01T22:24:59.664Z,1554157499.664 [BR_Ping](INFO): Received Ping, distance: 6997 2019-04-01T22:24:59.677Z,1554157499.677 [CBIT](FAULT): Main Battery Failure. Count: 271 2019-04-01T22:24:59.680Z,1554157499.680 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:24:59.680Z,1554157499.680 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:24:59.680Z,1554157499.680 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:24:59.945Z,1554157499.945 [BR_Ping](INFO): Runnable 2019-04-01T22:24:59.946Z,1554157499.946 [BR_Ping](INFO): Received Ping, distance: 6995 2019-04-01T22:25:00.357Z,1554157500.357 [BR_Ping](INFO): Runnable 2019-04-01T22:25:00.358Z,1554157500.358 [BR_Ping](INFO): Received Ping, distance: 6995 2019-04-01T22:25:00.857Z,1554157500.857 [PAR_Licor](INFO): A/D timeout, 14 tries over 127 ms 2019-04-01T22:25:00.857Z,1554157500.857 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:00.857Z,1554157500.857 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:00.889Z,1554157500.889 [BR_Ping](INFO): Runnable 2019-04-01T22:25:00.890Z,1554157500.890 [BR_Ping](INFO): Received Ping, distance: 6981 2019-04-01T22:25:00.907Z,1554157500.907 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:00.907Z,1554157500.907 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:00.907Z,1554157500.907 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:01.171Z,1554157501.171 [BR_Ping](INFO): Runnable 2019-04-01T22:25:01.173Z,1554157501.173 [BR_Ping](INFO): Received Ping, distance: 6962 2019-04-01T22:25:01.575Z,1554157501.575 [BR_Ping](INFO): Runnable 2019-04-01T22:25:01.577Z,1554157501.577 [BR_Ping](INFO): Received Ping, distance: 6963 2019-04-01T22:25:02.073Z,1554157502.073 [PAR_Licor](INFO): A/D timeout, 15 tries over 130 ms 2019-04-01T22:25:02.073Z,1554157502.073 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:02.073Z,1554157502.073 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:02.104Z,1554157502.104 [BR_Ping](INFO): Runnable 2019-04-01T22:25:02.106Z,1554157502.106 [BR_Ping](INFO): Received Ping, distance: 6963 2019-04-01T22:25:02.121Z,1554157502.121 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:02.121Z,1554157502.121 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:02.121Z,1554157502.121 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:02.366Z,1554157502.366 [BR_Ping](INFO): Runnable 2019-04-01T22:25:02.367Z,1554157502.367 [BR_Ping](INFO): Received Ping, distance: 6964 2019-04-01T22:25:02.777Z,1554157502.777 [BR_Ping](INFO): Runnable 2019-04-01T22:25:02.778Z,1554157502.778 [BR_Ping](INFO): Received Ping, distance: 6972 2019-04-01T22:25:03.285Z,1554157503.285 [PAR_Licor](INFO): A/D timeout, 16 tries over 131 ms 2019-04-01T22:25:03.285Z,1554157503.285 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:03.285Z,1554157503.285 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:03.302Z,1554157503.302 [BR_Ping](INFO): Runnable 2019-04-01T22:25:03.304Z,1554157503.304 [BR_Ping](INFO): Received Ping, distance: 6938 2019-04-01T22:25:03.320Z,1554157503.320 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:03.320Z,1554157503.320 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:03.320Z,1554157503.320 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:03.578Z,1554157503.578 [BR_Ping](INFO): Runnable 2019-04-01T22:25:03.579Z,1554157503.579 [BR_Ping](INFO): Received Ping, distance: 6995 2019-04-01T22:25:03.594Z,1554157503.594 [CBIT](FAULT): Main Battery Failure. Count: 281 2019-04-01T22:25:03.989Z,1554157503.989 [BR_Ping](INFO): Runnable 2019-04-01T22:25:03.990Z,1554157503.990 [BR_Ping](INFO): Received Ping, distance: 6995 2019-04-01T22:25:04.497Z,1554157504.497 [PAR_Licor](INFO): A/D timeout, 16 tries over 131 ms 2019-04-01T22:25:04.497Z,1554157504.497 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:04.497Z,1554157504.497 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:04.514Z,1554157504.514 [BR_Ping](INFO): Runnable 2019-04-01T22:25:04.515Z,1554157504.515 [BR_Ping](INFO): Received Ping, distance: 7001 2019-04-01T22:25:04.531Z,1554157504.531 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:04.531Z,1554157504.531 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:04.531Z,1554157504.531 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:04.773Z,1554157504.773 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface 2019-04-01T22:25:04.773Z,1554157504.773 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5 2019-04-01T22:25:04.773Z,1554157504.773 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2019-04-01T22:25:04.805Z,1554157504.805 [BR_Ping](INFO): Runnable 2019-04-01T22:25:04.806Z,1554157504.806 [BR_Ping](INFO): Received Ping, distance: 7049 2019-04-01T22:25:04.824Z,1554157504.824 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2019-04-01T22:25:05.181Z,1554157505.181 [AcousticModem_Benthos_ATM900](INFO): Powering down 2019-04-01T22:25:05.325Z,1554157505.325 [BR_Ping](INFO): Runnable 2019-04-01T22:25:05.327Z,1554157505.327 [BR_Ping](INFO): Received Ping, distance: 7069 2019-04-01T22:25:05.595Z,1554157505.595 [BR_Ping](INFO): Runnable 2019-04-01T22:25:05.596Z,1554157505.596 [BR_Ping](INFO): Received Ping, distance: 7059 2019-04-01T22:25:06.019Z,1554157506.019 [BR_Ping](INFO): Runnable 2019-04-01T22:25:06.020Z,1554157506.020 [BR_Ping](INFO): Received Ping, distance: 7059 2019-04-01T22:25:06.057Z,1554157506.057 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2019-04-01T22:25:06.057Z,1554157506.057 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5 2019-04-01T22:25:06.517Z,1554157506.517 [PAR_Licor](INFO): A/D timeout, 16 tries over 131 ms 2019-04-01T22:25:06.517Z,1554157506.517 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:06.517Z,1554157506.517 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:06.548Z,1554157506.548 [BR_Ping](INFO): Runnable 2019-04-01T22:25:06.550Z,1554157506.550 [BR_Ping](INFO): Received Ping, distance: 7096 2019-04-01T22:25:06.592Z,1554157506.592 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:06.592Z,1554157506.592 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:06.592Z,1554157506.592 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:06.819Z,1554157506.819 [BR_Ping](INFO): Runnable 2019-04-01T22:25:06.820Z,1554157506.820 [BR_Ping](INFO): Received Ping, distance: 7092 2019-04-01T22:25:07.231Z,1554157507.231 [BR_Ping](INFO): Runnable 2019-04-01T22:25:07.233Z,1554157507.233 [BR_Ping](INFO): Received Ping, distance: 7082 2019-04-01T22:25:07.725Z,1554157507.725 [PAR_Licor](INFO): A/D timeout, 16 tries over 125 ms 2019-04-01T22:25:07.725Z,1554157507.725 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:07.725Z,1554157507.725 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:07.742Z,1554157507.742 [BR_Ping](INFO): Runnable 2019-04-01T22:25:07.743Z,1554157507.743 [BR_Ping](INFO): Received Ping, distance: 7082 2019-04-01T22:25:07.756Z,1554157507.756 [CBIT](FAULT): Main Battery Failure. Count: 291 2019-04-01T22:25:07.759Z,1554157507.759 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:07.759Z,1554157507.759 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:07.759Z,1554157507.759 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:08.025Z,1554157508.025 [BR_Ping](INFO): Runnable 2019-04-01T22:25:08.027Z,1554157508.027 [BR_Ping](INFO): Received Ping, distance: 7027 2019-04-01T22:25:08.409Z,1554157508.409 [AcousticModem_Benthos_ATM900](INFO): Powering up 2019-04-01T22:25:08.410Z,1554157508.410 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2019-04-01T22:25:08.452Z,1554157508.452 [BR_Ping](INFO): Runnable 2019-04-01T22:25:08.454Z,1554157508.454 [BR_Ping](INFO): Received Ping, distance: 7031 2019-04-01T22:25:08.937Z,1554157508.937 [PAR_Licor](INFO): A/D timeout, 16 tries over 127 ms 2019-04-01T22:25:08.937Z,1554157508.937 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:08.937Z,1554157508.937 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:08.969Z,1554157508.969 [BR_Ping](INFO): Runnable 2019-04-01T22:25:08.970Z,1554157508.970 [BR_Ping](INFO): Received Ping, distance: 7894 2019-04-01T22:25:08.977Z,1554157508.977 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-04-01T22:25:08.977Z,1554157508.977 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-04-01T22:25:08.977Z,1554157508.977 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-04-01T22:25:08.979Z,1554157508.979 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-04-01T22:25:08.979Z,1554157508.979 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2019-04-01T22:25:08.979Z,1554157508.979 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-04-01T22:25:08.980Z,1554157508.980 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-04-01T22:25:08.980Z,1554157508.980 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2019-04-01T22:25:08.980Z,1554157508.980 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-04-01T22:25:08.991Z,1554157508.991 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:08.991Z,1554157508.991 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:08.991Z,1554157508.991 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:08.991Z,1554157508.991 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-04-01T22:25:08.991Z,1554157508.991 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-04-01T22:25:08.992Z,1554157508.992 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-04-01T22:25:09.237Z,1554157509.237 [BR_Ping](INFO): Runnable 2019-04-01T22:25:09.238Z,1554157509.238 [BR_Ping](INFO): Received Ping, distance: 7078 2019-04-01T22:25:09.254Z,1554157509.254 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-04-01T22:25:09.254Z,1554157509.254 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-04-01T22:25:09.255Z,1554157509.255 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-04-01T22:25:09.255Z,1554157509.255 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2019-04-01T22:25:09.256Z,1554157509.256 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-04-01T22:25:09.256Z,1554157509.256 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2019-04-01T22:25:09.641Z,1554157509.641 [BR_Ping](INFO): Runnable 2019-04-01T22:25:09.642Z,1554157509.642 [BR_Ping](INFO): Received Ping, distance: 7078 2019-04-01T22:25:09.654Z,1554157509.654 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-04-01T22:25:10.149Z,1554157510.149 [PAR_Licor](INFO): A/D timeout, 16 tries over 127 ms 2019-04-01T22:25:10.149Z,1554157510.149 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:10.149Z,1554157510.149 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:10.166Z,1554157510.166 [BR_Ping](INFO): Runnable 2019-04-01T22:25:10.167Z,1554157510.167 [BR_Ping](INFO): Received Ping, distance: 7073 2019-04-01T22:25:10.186Z,1554157510.186 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:10.186Z,1554157510.186 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:10.186Z,1554157510.186 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:10.446Z,1554157510.446 [BR_Ping](INFO): Runnable 2019-04-01T22:25:10.447Z,1554157510.447 [BR_Ping](INFO): Received Ping, distance: 7073 2019-04-01T22:25:10.852Z,1554157510.852 [BR_Ping](INFO): Runnable 2019-04-01T22:25:10.853Z,1554157510.853 [BR_Ping](INFO): Received Ping, distance: 7111 2019-04-01T22:25:11.365Z,1554157511.365 [PAR_Licor](INFO): A/D timeout, 16 tries over 131 ms 2019-04-01T22:25:11.365Z,1554157511.365 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:11.365Z,1554157511.365 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:11.397Z,1554157511.397 [BR_Ping](INFO): Runnable 2019-04-01T22:25:11.399Z,1554157511.399 [BR_Ping](INFO): Received Ping, distance: 7111 2019-04-01T22:25:11.416Z,1554157511.416 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:11.416Z,1554157511.416 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:11.416Z,1554157511.416 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:11.674Z,1554157511.674 [BR_Ping](INFO): Runnable 2019-04-01T22:25:11.675Z,1554157511.675 [BR_Ping](INFO): Received Ping, distance: 7112 2019-04-01T22:25:11.692Z,1554157511.692 [CBIT](FAULT): Main Battery Failure. Count: 301 2019-04-01T22:25:12.088Z,1554157512.088 [BR_Ping](INFO): Runnable 2019-04-01T22:25:12.089Z,1554157512.089 [BR_Ping](INFO): Received Ping, distance: 7109 2019-04-01T22:25:12.577Z,1554157512.577 [PAR_Licor](INFO): A/D timeout, 15 tries over 131 ms 2019-04-01T22:25:12.577Z,1554157512.577 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:12.577Z,1554157512.577 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:12.621Z,1554157512.621 [BR_Ping](INFO): Runnable 2019-04-01T22:25:12.622Z,1554157512.622 [BR_Ping](INFO): Received Ping, distance: 7106 2019-04-01T22:25:12.637Z,1554157512.637 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:12.637Z,1554157512.637 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:12.637Z,1554157512.637 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:12.870Z,1554157512.870 [BR_Ping](INFO): Runnable 2019-04-01T22:25:12.871Z,1554157512.871 [BR_Ping](INFO): Received Ping, distance: 7109 2019-04-01T22:25:13.285Z,1554157513.285 [BR_Ping](INFO): Runnable 2019-04-01T22:25:13.286Z,1554157513.286 [BR_Ping](INFO): Received Ping, distance: 7109 2019-04-01T22:25:13.789Z,1554157513.789 [PAR_Licor](INFO): A/D timeout, 16 tries over 131 ms 2019-04-01T22:25:13.789Z,1554157513.789 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:13.789Z,1554157513.789 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:13.806Z,1554157513.806 [BR_Ping](INFO): Runnable 2019-04-01T22:25:13.808Z,1554157513.808 [BR_Ping](INFO): Received Ping, distance: 7117 2019-04-01T22:25:13.826Z,1554157513.826 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:13.826Z,1554157513.826 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:13.826Z,1554157513.826 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:14.082Z,1554157514.082 [BR_Ping](INFO): Runnable 2019-04-01T22:25:14.083Z,1554157514.083 [BR_Ping](INFO): Received Ping, distance: 7088 2019-04-01T22:25:14.491Z,1554157514.491 [BR_Ping](INFO): Runnable 2019-04-01T22:25:14.492Z,1554157514.492 [BR_Ping](INFO): Received Ping, distance: 7077 2019-04-01T22:25:14.997Z,1554157514.997 [PAR_Licor](INFO): A/D timeout, 16 tries over 127 ms 2019-04-01T22:25:14.997Z,1554157514.997 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:14.997Z,1554157514.997 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:15.014Z,1554157515.014 [BR_Ping](INFO): Runnable 2019-04-01T22:25:15.016Z,1554157515.016 [BR_Ping](INFO): Received Ping, distance: 7077 2019-04-01T22:25:15.031Z,1554157515.031 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:15.032Z,1554157515.032 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:15.032Z,1554157515.032 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:15.294Z,1554157515.294 [BR_Ping](INFO): Runnable 2019-04-01T22:25:15.295Z,1554157515.295 [BR_Ping](INFO): Received Ping, distance: 7102 2019-04-01T22:25:15.705Z,1554157515.705 [BR_Ping](INFO): Runnable 2019-04-01T22:25:15.706Z,1554157515.706 [BR_Ping](INFO): Received Ping, distance: 7107 2019-04-01T22:25:15.739Z,1554157515.739 [CBIT](FAULT): Main Battery Failure. Count: 311 2019-04-01T22:25:16.209Z,1554157516.209 [PAR_Licor](INFO): A/D timeout, 15 tries over 127 ms 2019-04-01T22:25:16.209Z,1554157516.209 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:16.209Z,1554157516.209 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:16.240Z,1554157516.240 [BR_Ping](INFO): Runnable 2019-04-01T22:25:16.261Z,1554157516.261 [BR_Ping](INFO): Received Ping, distance: 7102 2019-04-01T22:25:16.275Z,1554157516.275 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:16.275Z,1554157516.275 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:16.275Z,1554157516.275 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:16.519Z,1554157516.519 [BR_Ping](INFO): Runnable 2019-04-01T22:25:16.521Z,1554157516.521 [BR_Ping](INFO): Received Ping, distance: 7069 2019-04-01T22:25:16.931Z,1554157516.931 [BR_Ping](INFO): Runnable 2019-04-01T22:25:16.934Z,1554157516.934 [BR_Ping](INFO): Received Ping, distance: 7069 2019-04-01T22:25:17.421Z,1554157517.421 [PAR_Licor](INFO): A/D timeout, 16 tries over 127 ms 2019-04-01T22:25:17.421Z,1554157517.421 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:17.421Z,1554157517.421 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:17.452Z,1554157517.452 [BR_Ping](INFO): Runnable 2019-04-01T22:25:17.454Z,1554157517.454 [BR_Ping](INFO): Received Ping, distance: 7073 2019-04-01T22:25:17.470Z,1554157517.470 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:17.470Z,1554157517.470 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:17.470Z,1554157517.470 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:17.715Z,1554157517.715 [BR_Ping](INFO): Runnable 2019-04-01T22:25:17.716Z,1554157517.716 [BR_Ping](INFO): Received Ping, distance: 7068 2019-04-01T22:25:18.129Z,1554157518.129 [BR_Ping](INFO): Runnable 2019-04-01T22:25:18.130Z,1554157518.130 [BR_Ping](INFO): Received Ping, distance: 7070 2019-04-01T22:25:18.633Z,1554157518.633 [PAR_Licor](INFO): A/D timeout, 16 tries over 127 ms 2019-04-01T22:25:18.633Z,1554157518.633 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:18.633Z,1554157518.633 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:18.662Z,1554157518.662 [BR_Ping](INFO): Runnable 2019-04-01T22:25:18.663Z,1554157518.663 [BR_Ping](INFO): Received Ping, distance: 7070 2019-04-01T22:25:18.680Z,1554157518.680 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:18.681Z,1554157518.681 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:18.681Z,1554157518.681 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:18.930Z,1554157518.930 [BR_Ping](INFO): Runnable 2019-04-01T22:25:18.931Z,1554157518.931 [BR_Ping](INFO): Received Ping, distance: 7056 2019-04-01T22:25:19.337Z,1554157519.337 [BR_Ping](INFO): Runnable 2019-04-01T22:25:19.338Z,1554157519.338 [BR_Ping](INFO): Received Ping, distance: 7029 2019-04-01T22:25:19.845Z,1554157519.845 [PAR_Licor](INFO): A/D timeout, 16 tries over 127 ms 2019-04-01T22:25:19.845Z,1554157519.845 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:19.845Z,1554157519.845 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:19.862Z,1554157519.862 [BR_Ping](INFO): Runnable 2019-04-01T22:25:19.864Z,1554157519.864 [BR_Ping](INFO): Received Ping, distance: 7011 2019-04-01T22:25:19.877Z,1554157519.877 [CBIT](FAULT): Main Battery Failure. Count: 321 2019-04-01T22:25:19.879Z,1554157519.879 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:19.880Z,1554157519.880 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:19.880Z,1554157519.880 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:20.153Z,1554157520.153 [BR_Ping](INFO): Runnable 2019-04-01T22:25:20.154Z,1554157520.154 [BR_Ping](INFO): Received Ping, distance: 7073 2019-04-01T22:25:20.554Z,1554157520.554 [BR_Ping](INFO): Runnable 2019-04-01T22:25:20.555Z,1554157520.555 [BR_Ping](INFO): Received Ping, distance: 7073 2019-04-01T22:25:21.057Z,1554157521.057 [PAR_Licor](INFO): A/D timeout, 15 tries over 127 ms 2019-04-01T22:25:21.057Z,1554157521.057 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:21.057Z,1554157521.057 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:21.088Z,1554157521.088 [BR_Ping](INFO): Runnable 2019-04-01T22:25:21.090Z,1554157521.090 [BR_Ping](INFO): Received Ping, distance: 7117 2019-04-01T22:25:21.111Z,1554157521.111 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:21.111Z,1554157521.111 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:21.111Z,1554157521.111 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:21.367Z,1554157521.367 [BR_Ping](INFO): Runnable 2019-04-01T22:25:21.368Z,1554157521.368 [BR_Ping](INFO): Received Ping, distance: 7075 2019-04-01T22:25:21.775Z,1554157521.775 [BR_Ping](INFO): Runnable 2019-04-01T22:25:21.777Z,1554157521.777 [BR_Ping](INFO): Received Ping, distance: 7092 2019-04-01T22:25:22.269Z,1554157522.269 [PAR_Licor](INFO): A/D timeout, 16 tries over 127 ms 2019-04-01T22:25:22.269Z,1554157522.269 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:22.269Z,1554157522.269 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:22.300Z,1554157522.300 [BR_Ping](INFO): Runnable 2019-04-01T22:25:22.302Z,1554157522.302 [BR_Ping](INFO): Received Ping, distance: 7092 2019-04-01T22:25:22.319Z,1554157522.319 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:22.319Z,1554157522.319 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:22.319Z,1554157522.319 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:22.566Z,1554157522.566 [BR_Ping](INFO): Runnable 2019-04-01T22:25:22.567Z,1554157522.567 [BR_Ping](INFO): Received Ping, distance: 7115 2019-04-01T22:25:22.976Z,1554157522.976 [BR_Ping](INFO): Runnable 2019-04-01T22:25:22.977Z,1554157522.977 [BR_Ping](INFO): Received Ping, distance: 7115 2019-04-01T22:25:23.481Z,1554157523.481 [PAR_Licor](INFO): A/D timeout, 16 tries over 127 ms 2019-04-01T22:25:23.481Z,1554157523.481 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:23.481Z,1554157523.481 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:23.498Z,1554157523.498 [BR_Ping](INFO): Runnable 2019-04-01T22:25:23.500Z,1554157523.500 [BR_Ping](INFO): Received Ping, distance: 7148 2019-04-01T22:25:23.515Z,1554157523.515 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:23.516Z,1554157523.516 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:23.516Z,1554157523.516 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:23.778Z,1554157523.778 [BR_Ping](INFO): Runnable 2019-04-01T22:25:23.779Z,1554157523.779 [BR_Ping](INFO): Received Ping, distance: 7143 2019-04-01T22:25:23.794Z,1554157523.794 [CBIT](FAULT): Main Battery Failure. Count: 331 2019-04-01T22:25:24.221Z,1554157524.221 [BR_Ping](INFO): Runnable 2019-04-01T22:25:24.222Z,1554157524.222 [BR_Ping](INFO): Received Ping, distance: 7143 2019-04-01T22:25:24.693Z,1554157524.693 [PAR_Licor](INFO): A/D timeout, 15 tries over 126 ms 2019-04-01T22:25:24.693Z,1554157524.693 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:24.693Z,1554157524.693 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:24.712Z,1554157524.712 [BR_Ping](INFO): Runnable 2019-04-01T22:25:24.713Z,1554157524.713 [BR_Ping](INFO): Received Ping, distance: 7163 2019-04-01T22:25:24.729Z,1554157524.729 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:24.729Z,1554157524.729 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:24.729Z,1554157524.729 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:24.995Z,1554157524.995 [BR_Ping](INFO): Runnable 2019-04-01T22:25:24.996Z,1554157524.996 [BR_Ping](INFO): Received Ping, distance: 7194 2019-04-01T22:25:25.401Z,1554157525.401 [BR_Ping](INFO): Runnable 2019-04-01T22:25:25.403Z,1554157525.403 [BR_Ping](INFO): Received Ping, distance: 7179 2019-04-01T22:25:25.913Z,1554157525.913 [PAR_Licor](INFO): A/D timeout, 11 tries over 134 ms 2019-04-01T22:25:25.913Z,1554157525.913 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:25.913Z,1554157525.913 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:25.949Z,1554157525.949 [BR_Ping](INFO): Runnable 2019-04-01T22:25:25.951Z,1554157525.951 [BR_Ping](INFO): Received Ping, distance: 7179 2019-04-01T22:25:25.972Z,1554157525.972 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:25.973Z,1554157525.973 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:25.973Z,1554157525.973 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:26.217Z,1554157526.217 [BR_Ping](INFO): Runnable 2019-04-01T22:25:26.218Z,1554157526.218 [BR_Ping](INFO): Received Ping, distance: 7171 2019-04-01T22:25:26.619Z,1554157526.619 [BR_Ping](INFO): Runnable 2019-04-01T22:25:26.621Z,1554157526.621 [BR_Ping](INFO): Received Ping, distance: 7171 2019-04-01T22:25:27.146Z,1554157527.146 [PAR_Licor](INFO): A/D timeout, 7 tries over 156 ms 2019-04-01T22:25:27.146Z,1554157527.146 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:27.146Z,1554157527.146 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:27.203Z,1554157527.203 [BR_Ping](INFO): Runnable 2019-04-01T22:25:27.204Z,1554157527.204 [BR_Ping](INFO): Received Ping, distance: 7196 2019-04-01T22:25:27.239Z,1554157527.239 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:27.239Z,1554157527.239 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:27.239Z,1554157527.239 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:27.416Z,1554157527.416 [BR_Ping](INFO): Runnable 2019-04-01T22:25:27.417Z,1554157527.417 [BR_Ping](INFO): Received Ping, distance: 7226 2019-04-01T22:25:27.820Z,1554157527.820 [BR_Ping](INFO): Runnable 2019-04-01T22:25:27.822Z,1554157527.822 [BR_Ping](INFO): Received Ping, distance: 7226 2019-04-01T22:25:27.834Z,1554157527.834 [Startup:StartupSatComms:A](INFO): Timed out from 2019-04-01T22:24:27.6Z 2019-04-01T22:25:27.835Z,1554157527.835 [Startup:StartupSatComms:A] Stopped 2019-04-01T22:25:27.835Z,1554157527.835 [Startup:StartupSatComms:B] Running Loop=1 2019-04-01T22:25:27.841Z,1554157527.841 [CBIT](FAULT): Main Battery Failure. Count: 341 2019-04-01T22:25:28.330Z,1554157528.330 [PAR_Licor](INFO): A/D timeout, 8 tries over 126 ms 2019-04-01T22:25:28.330Z,1554157528.330 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:28.330Z,1554157528.330 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:28.358Z,1554157528.358 [BR_Ping](INFO): Runnable 2019-04-01T22:25:28.360Z,1554157528.360 [BR_Ping](INFO): Received Ping, distance: 7248 2019-04-01T22:25:28.398Z,1554157528.398 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-04-01T22:25:28.407Z,1554157528.407 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:28.407Z,1554157528.407 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:28.407Z,1554157528.407 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:28.945Z,1554157528.945 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface 2019-04-01T22:25:28.945Z,1554157528.945 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6 2019-04-01T22:25:28.945Z,1554157528.945 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault 2019-04-01T22:25:28.962Z,1554157528.962 [BR_Ping](INFO): Runnable 2019-04-01T22:25:28.963Z,1554157528.963 [BR_Ping](INFO): Received Ping, distance: 7214 2019-04-01T22:25:28.979Z,1554157528.979 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900 2019-04-01T22:25:29.355Z,1554157529.355 [AcousticModem_Benthos_ATM900](INFO): Powering down 2019-04-01T22:25:29.497Z,1554157529.497 [BR_Ping](INFO): Runnable 2019-04-01T22:25:29.499Z,1554157529.499 [BR_Ping](INFO): Received Ping, distance: 7189 2019-04-01T22:25:29.773Z,1554157529.773 [BR_Ping](INFO): Runnable 2019-04-01T22:25:29.774Z,1554157529.774 [BR_Ping](INFO): Received Ping, distance: 7168 2019-04-01T22:25:30.181Z,1554157530.181 [BR_Ping](INFO): Runnable 2019-04-01T22:25:30.182Z,1554157530.182 [BR_Ping](INFO): Received Ping, distance: 7222 2019-04-01T22:25:30.215Z,1554157530.215 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900 2019-04-01T22:25:30.215Z,1554157530.215 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6 2019-04-01T22:25:30.685Z,1554157530.685 [PAR_Licor](INFO): A/D timeout, 11 tries over 127 ms 2019-04-01T22:25:30.685Z,1554157530.685 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:30.685Z,1554157530.685 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:30.729Z,1554157530.729 [BR_Ping](INFO): Runnable 2019-04-01T22:25:30.730Z,1554157530.730 [BR_Ping](INFO): Received Ping, distance: 7194 2019-04-01T22:25:30.745Z,1554157530.745 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:30.745Z,1554157530.745 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:30.745Z,1554157530.745 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:30.995Z,1554157530.995 [BR_Ping](INFO): Runnable 2019-04-01T22:25:30.997Z,1554157530.997 [BR_Ping](INFO): Received Ping, distance: 7167 2019-04-01T22:25:31.403Z,1554157531.403 [BR_Ping](INFO): Runnable 2019-04-01T22:25:31.404Z,1554157531.404 [BR_Ping](INFO): Received Ping, distance: 7167 2019-04-01T22:25:31.897Z,1554157531.897 [PAR_Licor](INFO): A/D timeout, 10 tries over 127 ms 2019-04-01T22:25:31.897Z,1554157531.897 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:31.897Z,1554157531.897 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:31.927Z,1554157531.927 [BR_Ping](INFO): Runnable 2019-04-01T22:25:31.929Z,1554157531.929 [BR_Ping](INFO): Received Ping, distance: 7204 2019-04-01T22:25:31.946Z,1554157531.946 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:31.947Z,1554157531.947 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:31.947Z,1554157531.947 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:32.202Z,1554157532.202 [BR_Ping](INFO): Runnable 2019-04-01T22:25:32.203Z,1554157532.203 [BR_Ping](INFO): Received Ping, distance: 7182 2019-04-01T22:25:32.216Z,1554157532.216 [CBIT](FAULT): Main Battery Failure. Count: 351 2019-04-01T22:25:32.587Z,1554157532.587 [AcousticModem_Benthos_ATM900](INFO): Powering up 2019-04-01T22:25:32.587Z,1554157532.587 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2019-04-01T22:25:32.625Z,1554157532.625 [BR_Ping](INFO): Runnable 2019-04-01T22:25:32.626Z,1554157532.626 [BR_Ping](INFO): Received Ping, distance: 7160 2019-04-01T22:25:33.113Z,1554157533.113 [PAR_Licor](INFO): A/D timeout, 11 tries over 131 ms 2019-04-01T22:25:33.113Z,1554157533.113 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:33.113Z,1554157533.113 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:33.130Z,1554157533.130 [BR_Ping](INFO): Runnable 2019-04-01T22:25:33.132Z,1554157533.132 [BR_Ping](INFO): Received Ping, distance: 7160 2019-04-01T22:25:33.146Z,1554157533.146 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:33.147Z,1554157533.147 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:33.147Z,1554157533.147 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:33.406Z,1554157533.406 [BR_Ping](INFO): Runnable 2019-04-01T22:25:33.407Z,1554157533.407 [BR_Ping](INFO): Received Ping, distance: 7157 2019-04-01T22:25:33.812Z,1554157533.812 [BR_Ping](INFO): Runnable 2019-04-01T22:25:33.813Z,1554157533.813 [BR_Ping](INFO): Received Ping, distance: 7167 2019-04-01T22:25:34.325Z,1554157534.325 [PAR_Licor](INFO): A/D timeout, 12 tries over 131 ms 2019-04-01T22:25:34.325Z,1554157534.325 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:34.325Z,1554157534.325 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:34.342Z,1554157534.342 [BR_Ping](INFO): Runnable 2019-04-01T22:25:34.344Z,1554157534.344 [BR_Ping](INFO): Received Ping, distance: 7177 2019-04-01T22:25:34.403Z,1554157534.403 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:34.404Z,1554157534.404 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:34.404Z,1554157534.404 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:34.625Z,1554157534.625 [BR_Ping](INFO): Runnable 2019-04-01T22:25:34.626Z,1554157534.626 [BR_Ping](INFO): Received Ping, distance: 7209 2019-04-01T22:25:35.029Z,1554157535.029 [BR_Ping](INFO): Runnable 2019-04-01T22:25:35.030Z,1554157535.030 [BR_Ping](INFO): Received Ping, distance: 7209 2019-04-01T22:25:35.533Z,1554157535.533 [PAR_Licor](INFO): A/D timeout, 11 tries over 127 ms 2019-04-01T22:25:35.533Z,1554157535.533 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:35.533Z,1554157535.533 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:35.563Z,1554157535.563 [BR_Ping](INFO): Runnable 2019-04-01T22:25:35.564Z,1554157535.564 [BR_Ping](INFO): Received Ping, distance: 7200 2019-04-01T22:25:35.582Z,1554157535.582 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:35.582Z,1554157535.582 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:35.582Z,1554157535.582 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:35.848Z,1554157535.848 [BR_Ping](INFO): Runnable 2019-04-01T22:25:35.850Z,1554157535.850 [BR_Ping](INFO): Received Ping, distance: 7209 2019-04-01T22:25:36.255Z,1554157536.255 [BR_Ping](INFO): Runnable 2019-04-01T22:25:36.256Z,1554157536.256 [BR_Ping](INFO): Received Ping, distance: 7169 2019-04-01T22:25:36.271Z,1554157536.271 [CBIT](FAULT): Main Battery Failure. Count: 361 2019-04-01T22:25:36.749Z,1554157536.749 [PAR_Licor](INFO): A/D timeout, 10 tries over 131 ms 2019-04-01T22:25:36.749Z,1554157536.749 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:36.749Z,1554157536.749 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:36.793Z,1554157536.793 [BR_Ping](INFO): Runnable 2019-04-01T22:25:36.795Z,1554157536.795 [BR_Ping](INFO): Received Ping, distance: 7169 2019-04-01T22:25:36.812Z,1554157536.812 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:36.812Z,1554157536.812 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:36.812Z,1554157536.812 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:37.045Z,1554157537.045 [BR_Ping](INFO): Runnable 2019-04-01T22:25:37.047Z,1554157537.047 [BR_Ping](INFO): Received Ping, distance: 7182 2019-04-01T22:25:37.453Z,1554157537.453 [BR_Ping](INFO): Runnable 2019-04-01T22:25:37.455Z,1554157537.455 [BR_Ping](INFO): Received Ping, distance: 7162 2019-04-01T22:25:37.961Z,1554157537.961 [PAR_Licor](INFO): A/D timeout, 12 tries over 129 ms 2019-04-01T22:25:37.961Z,1554157537.961 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:37.961Z,1554157537.961 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:37.978Z,1554157537.978 [BR_Ping](INFO): Runnable 2019-04-01T22:25:37.979Z,1554157537.979 [BR_Ping](INFO): Received Ping, distance: 7120 2019-04-01T22:25:37.997Z,1554157537.997 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:37.997Z,1554157537.997 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:37.997Z,1554157537.997 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:38.258Z,1554157538.258 [BR_Ping](INFO): Runnable 2019-04-01T22:25:38.260Z,1554157538.260 [BR_Ping](INFO): Received Ping, distance: 7104 2019-04-01T22:25:38.660Z,1554157538.660 [BR_Ping](INFO): Runnable 2019-04-01T22:25:38.662Z,1554157538.662 [BR_Ping](INFO): Received Ping, distance: 7104 2019-04-01T22:25:38.730Z,1554157538.730 [CommandLine](IMPORTANT): got command restart application 2019-04-01T22:25:39.169Z,1554157539.169 [PAR_Licor](INFO): A/D timeout, 9 tries over 127 ms 2019-04-01T22:25:39.169Z,1554157539.169 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:39.169Z,1554157539.169 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:39.186Z,1554157539.186 [BR_Ping](INFO): Runnable 2019-04-01T22:25:39.188Z,1554157539.188 [BR_Ping](INFO): Received Ping, distance: 7118 2019-04-01T22:25:39.205Z,1554157539.205 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:39.205Z,1554157539.205 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:39.205Z,1554157539.205 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:39.462Z,1554157539.462 [BR_Ping](INFO): Runnable 2019-04-01T22:25:39.464Z,1554157539.464 [BR_Ping](INFO): Received Ping, distance: 7137 2019-04-01T22:25:39.753Z,1554157539.753 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-01T22:25:39.753Z,1554157539.753 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-04-01T22:25:39.857Z,1554157539.857 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2019-04-01T22:25:39.857Z,1554157539.857 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-04-01T22:25:39.857Z,1554157539.857 [CommandLine](INFO): Join timeout helper Thread ID is 4695 2019-04-01T22:25:39.861Z,1554157539.861 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2019-04-01T22:25:39.861Z,1554157539.861 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-04-01T22:25:39.861Z,1554157539.861 [NavChartDb](INFO): Join timeout helper Thread ID is 4696 2019-04-01T22:25:39.878Z,1554157539.878 [BR_Ping](INFO): Runnable 2019-04-01T22:25:39.879Z,1554157539.879 [BR_Ping](INFO): Received Ping, distance: 7197 2019-04-01T22:25:40.077Z,1554157540.077 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-01T22:25:40.077Z,1554157540.077 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-04-01T22:25:40.081Z,1554157540.081 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2019-04-01T22:25:40.081Z,1554157540.081 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-04-01T22:25:40.081Z,1554157540.081 [Radio_Surface](INFO): Join timeout helper Thread ID is 4697 2019-04-01T22:25:40.121Z,1554157540.121 [Radio_Surface](INFO): Powering down 2019-04-01T22:25:40.122Z,1554157540.122 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-01T22:25:40.122Z,1554157540.122 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-04-01T22:25:40.141Z,1554157540.141 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2019-04-01T22:25:40.141Z,1554157540.141 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-04-01T22:25:40.141Z,1554157540.141 [DataOverHttps](INFO): Join timeout helper Thread ID is 4698 2019-04-01T22:25:40.385Z,1554157540.385 [PAR_Licor](INFO): A/D timeout, 15 tries over 131 ms 2019-04-01T22:25:40.385Z,1554157540.385 [PAR_Licor] Data Fault, FailCount= 1 2019-04-01T22:25:40.385Z,1554157540.385 [PAR_Licor](ERROR): Data Fault 2019-04-01T22:25:40.422Z,1554157540.422 [BR_Ping](INFO): Runnable 2019-04-01T22:25:40.424Z,1554157540.424 [BR_Ping](INFO): Received Ping, distance: 7197 2019-04-01T22:25:40.438Z,1554157540.438 [CBIT](FAULT): Main Battery Failure. Count: 371 2019-04-01T22:25:40.441Z,1554157540.441 [CBIT](ERROR): Data Fault in component: PAR_Licor 2019-04-01T22:25:40.441Z,1554157540.441 [CBIT](INFO): Clearing failed state for component PAR_Licor 2019-04-01T22:25:40.442Z,1554157540.442 [PAR_Licor] No Fault, FailCount= 1 2019-04-01T22:25:40.477Z,1554157540.477 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-01T22:25:40.477Z,1554157540.477 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-04-01T22:25:40.489Z,1554157540.489 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2019-04-01T22:25:40.489Z,1554157540.489 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-04-01T22:25:40.489Z,1554157540.489 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4699 2019-04-01T22:25:40.692Z,1554157540.692 [BR_Ping](INFO): Runnable 2019-04-01T22:25:40.693Z,1554157540.693 [BR_Ping](INFO): Received Ping, distance: 7172 2019-04-01T22:25:40.777Z,1554157540.777 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-01T22:25:40.777Z,1554157540.777 [WetLabsBB2FL](INFO): Powering down 2019-04-01T22:25:40.778Z,1554157540.778 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-04-01T22:25:40.794Z,1554157540.794 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2019-04-01T22:25:40.794Z,1554157540.794 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-04-01T22:25:40.794Z,1554157540.794 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4700 2019-04-01T22:25:40.833Z,1554157540.833 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-01T22:25:40.833Z,1554157540.833 [CTD_Seabird](INFO): Powering down 2019-04-01T22:25:40.845Z,1554157540.845 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-04-01T22:25:40.853Z,1554157540.853 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2019-04-01T22:25:40.854Z,1554157540.854 [logger ThreadHandler](INFO): Thread cancelled. 2019-04-01T22:25:40.854Z,1554157540.854 [logger](INFO): Join timeout helper Thread ID is 4701 2019-04-01T22:25:40.925Z,1554157540.925 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-01T22:25:40.925Z,1554157540.925 [logger ThreadHandler](INFO): Thread cancelled. 2019-04-01T22:25:40.933Z,1554157540.933 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2019-04-01T22:25:40.933Z,1554157540.933 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-04-01T22:25:40.934Z,1554157540.934 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2019-04-01T22:25:40.934Z,1554157540.934 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-04-01T22:25:40.934Z,1554157540.934 [controlThread](INFO): Join timeout helper Thread ID is 4702 2019-04-01T22:25:41.057Z,1554157541.057 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2019-04-01T22:25:41.057Z,1554157541.057 [controlThread](DEBUG): Uninitializing ControlThread 2019-04-01T22:25:41.058Z,1554157541.058 [Aanderaa_O2](INFO): Powering down 2019-04-01T22:25:41.060Z,1554157541.060 [AcousticModem_Benthos_ATM900](INFO): Powering down 2019-04-01T22:25:41.177Z,1554157541.177 [NAL9602](INFO): Powering down 2019-04-01T22:25:41.179Z,1554157541.179 [BR_Ping](INFO): uninitialize 2019-04-01T22:25:41.179Z,1554157541.179 [BR_Ping](INFO): Powering down 2019-04-01T22:25:41.249Z,1554157541.249 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2019-04-01T22:25:41.250Z,1554157541.250 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2019-04-01T22:25:41.251Z,1554157541.251 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2019-04-01T22:25:41.251Z,1554157541.251 [MissionManager](INFO): Uninitializing Mission Default 2019-04-01T22:25:41.254Z,1554157541.254 [MissionManager](INFO): Uninitializing Mission Startup 2019-04-01T22:25:41.254Z,1554157541.254 [Startup] Stopped 2019-04-01T22:25:41.254Z,1554157541.254 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-04-01T22:25:41.254Z,1554157541.254 [Startup:A.GoToSurface] Stopped 2019-04-01T22:25:41.254Z,1554157541.254 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-04-01T22:25:41.254Z,1554157541.254 [Startup:StartupSatComms] Stopped 2019-04-01T22:25:41.254Z,1554157541.254 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-04-01T22:25:41.254Z,1554157541.254 [Startup:StartupSatComms:B] Stopped 2019-04-01T22:25:41.256Z,1554157541.256 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2019-04-01T22:25:41.256Z,1554157541.256 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2019-04-01T22:25:41.256Z,1554157541.256 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2019-04-01T22:25:41.256Z,1554157541.256 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2019-04-01T22:25:41.257Z,1554157541.257 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-04-01T22:25:41.257Z,1554157541.257 [BuoyancyServo](INFO): Powering down 2019-04-01T22:25:41.269Z,1554157541.269 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-04-01T22:25:41.269Z,1554157541.269 [ElevatorServo](INFO): Powering down 2019-04-01T22:25:41.270Z,1554157541.270 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-04-01T22:25:41.270Z,1554157541.270 [MassServo](INFO): Powering down 2019-04-01T22:25:41.270Z,1554157541.270 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-04-01T22:25:41.271Z,1554157541.271 [RudderServo](INFO): Powering down 2019-04-01T22:25:41.271Z,1554157541.271 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-04-01T22:25:41.271Z,1554157541.271 [ThrusterServo](INFO): Powering down 2019-04-01T22:25:41.272Z,1554157541.272 [SBIT](DEBUG): Uninitialize SBIT Component. 2019-04-01T22:25:41.273Z,1554157541.273 [IBIT](DEBUG): Uninitialize IBIT Component. 2019-04-01T22:25:41.273Z,1554157541.273 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-04-01T22:25:41.273Z,1554157541.273 [CBIT](DEBUG): Powering off loads. 2019-04-01T22:25:41.285Z,1554157541.285 [CBIT](DEBUG): Disabling WDT. 2019-04-01T22:25:41.297Z,1554157541.297 [CBIT](DEBUG): Opening all GF detection circuits. 2019-04-01T22:25:41.297Z,1554157541.297 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-04-01T22:25:41.456Z,1554157541.456 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-04-01T22:25:41.461Z,1554157541.461 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-04-01T22:25:41.527Z,1554157541.527 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-04-01T22:25:41.531Z,1554157541.531 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-04-01T22:25:41.581Z,1554157541.581 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-04-01T22:25:41.637Z,1554157541.637 [logger ThreadHandler](INFO): Thread cancelled.