2019-04-09T16:01:44.268Z,1554825704.268 [Supervisor](DEBUG): Initializing supervisor.
2019-04-09T16:01:44.271Z,1554825704.271 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-09T16:01:44.272Z,1554825704.272 [SyncHandler](INFO): Protected caller Thread ID is 3740
2019-04-09T16:01:44.273Z,1554825704.273 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-04-09T16:01:44.274Z,1554825704.274 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-04-09T16:01:44.274Z,1554825704.274 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3741
2019-04-09T16:01:44.277Z,1554825704.277 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-04-09T16:01:44.290Z,1554825704.290 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-04-09T16:01:44.291Z,1554825704.291 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-04-09T16:01:44.291Z,1554825704.291 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3742
2019-04-09T16:01:44.292Z,1554825704.292 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-04-09T16:01:44.293Z,1554825704.293 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-04-09T16:01:44.293Z,1554825704.293 [logger ThreadHandler](INFO): Protected caller Thread ID is 3743
2019-04-09T16:01:44.295Z,1554825704.295 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-04-09T16:01:44.295Z,1554825704.295 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-04-09T16:01:44.297Z,1554825704.297 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-04-09T16:01:44.393Z,1554825704.393 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-04-09T16:01:44.393Z,1554825704.393 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-04-09T16:01:44.595Z,1554825704.595 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-04-09T16:01:44.595Z,1554825704.595 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-04-09T16:01:44.732Z,1554825704.732 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-04-09T16:01:44.733Z,1554825704.733 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-04-09T16:01:45.193Z,1554825705.193 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-04-09T16:01:45.194Z,1554825705.194 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-04-09T16:01:45.636Z,1554825705.636 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-04-09T16:01:45.637Z,1554825705.637 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-04-09T16:01:46.343Z,1554825706.343 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-04-09T16:01:46.343Z,1554825706.343 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-04-09T16:01:46.651Z,1554825706.651 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-04-09T16:01:46.652Z,1554825706.652 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-04-09T16:01:46.985Z,1554825706.985 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-04-09T16:01:46.985Z,1554825706.985 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-04-09T16:01:47.380Z,1554825707.380 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-04-09T16:01:47.380Z,1554825707.380 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-04-09T16:01:47.531Z,1554825707.531 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-04-09T16:01:47.531Z,1554825707.531 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-04-09T16:01:47.636Z,1554825707.636 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-04-09T16:01:47.637Z,1554825707.637 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-04-09T16:01:47.718Z,1554825707.718 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-04-09T16:01:47.820Z,1554825707.820 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-04-09T16:01:47.821Z,1554825707.821 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-04-09T16:01:48.016Z,1554825708.016 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-04-09T16:01:48.016Z,1554825708.016 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-04-09T16:01:48.224Z,1554825708.224 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-04-09T16:01:48.226Z,1554825708.226 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-04-09T16:01:48.227Z,1554825708.227 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-04-09T16:01:48.310Z,1554825708.310 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-04-09T16:01:48.544Z,1554825708.544 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-04-09T16:01:48.545Z,1554825708.545 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-04-09T16:01:48.641Z,1554825708.641 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-04-09T16:01:48.815Z,1554825708.815 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-04-09T16:01:49.028Z,1554825709.028 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-04-09T16:01:49.113Z,1554825709.113 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-04-09T16:01:49.212Z,1554825709.212 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-04-09T16:01:49.305Z,1554825709.305 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-04-09T16:01:49.457Z,1554825709.457 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-04-09T16:01:49.561Z,1554825709.561 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-04-09T16:01:49.660Z,1554825709.660 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2019-04-09T16:01:49.661Z,1554825709.661 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-04-09T16:01:49.665Z,1554825709.665 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-04-09T16:01:49.766Z,1554825709.766 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-04-09T16:01:49.767Z,1554825709.767 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-04-09T16:01:49.893Z,1554825709.893 [BuoyancyServo] Loaded
2019-04-09T16:01:49.893Z,1554825709.893 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-04-09T16:01:49.908Z,1554825709.908 [ElevatorServo] Loaded
2019-04-09T16:01:49.909Z,1554825709.909 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-04-09T16:01:49.924Z,1554825709.924 [MassServo] Loaded
2019-04-09T16:01:49.924Z,1554825709.924 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-04-09T16:01:49.939Z,1554825709.939 [RudderServo] Loaded
2019-04-09T16:01:49.940Z,1554825709.940 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-04-09T16:01:49.954Z,1554825709.954 [ThrusterServo] Loaded
2019-04-09T16:01:49.954Z,1554825709.954 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-04-09T16:01:49.955Z,1554825709.955 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-04-09T16:01:49.955Z,1554825709.955 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-04-09T16:01:50.094Z,1554825710.094 [SBIT](DEBUG): Construct Startup Built In Test.
2019-04-09T16:01:50.123Z,1554825710.123 [SBIT] Loaded
2019-04-09T16:01:50.123Z,1554825710.123 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-04-09T16:01:50.124Z,1554825710.124 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-04-09T16:01:50.152Z,1554825710.152 [IBIT] Loaded
2019-04-09T16:01:50.152Z,1554825710.152 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-04-09T16:01:50.155Z,1554825710.155 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-04-09T16:01:50.549Z,1554825710.549 [CBIT] Loaded
2019-04-09T16:01:50.549Z,1554825710.549 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-04-09T16:01:50.549Z,1554825710.549 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-04-09T16:01:50.550Z,1554825710.550 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-04-09T16:01:50.737Z,1554825710.737 [Aanderaa_O2] Loaded
2019-04-09T16:01:50.737Z,1554825710.737 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-04-09T16:01:50.747Z,1554825710.747 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-04-09T16:01:50.752Z,1554825710.752 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-04-09T16:01:50.754Z,1554825710.754 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-04-09T16:01:50.759Z,1554825710.759 [CTD_Seabird](INFO): created writer for : depth
2019-04-09T16:01:50.760Z,1554825710.760 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-04-09T16:01:50.765Z,1554825710.765 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-04-09T16:01:50.766Z,1554825710.766 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-04-09T16:01:50.771Z,1554825710.771 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-04-09T16:01:50.771Z,1554825710.771 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-04-09T16:01:50.777Z,1554825710.777 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-04-09T16:01:50.778Z,1554825710.778 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-04-09T16:01:50.783Z,1554825710.783 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-04-09T16:01:50.784Z,1554825710.784 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-04-09T16:01:50.789Z,1554825710.789 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-04-09T16:01:50.816Z,1554825710.816 [CTD_Seabird] Loaded
2019-04-09T16:01:50.816Z,1554825710.816 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-04-09T16:01:50.817Z,1554825710.817 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406654E0
2019-04-09T16:01:50.818Z,1554825710.818 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3822
2019-04-09T16:01:50.844Z,1554825710.844 [ESPComponent] Loaded
2019-04-09T16:01:50.844Z,1554825710.844 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-04-09T16:01:50.858Z,1554825710.858 [PAR_Licor] Loaded
2019-04-09T16:01:50.859Z,1554825710.859 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-04-09T16:01:50.907Z,1554825710.907 [WetLabsBB2FL] Loaded
2019-04-09T16:01:50.907Z,1554825710.907 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-04-09T16:01:50.908Z,1554825710.908 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406954E0
2019-04-09T16:01:50.909Z,1554825710.909 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3823
2019-04-09T16:01:50.910Z,1554825710.910 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-04-09T16:01:50.910Z,1554825710.910 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-04-09T16:01:50.951Z,1554825710.951 [DepthRateCalculator] Loaded
2019-04-09T16:01:50.952Z,1554825710.952 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-04-09T16:01:50.957Z,1554825710.957 [PitchRateCalculator] Loaded
2019-04-09T16:01:50.958Z,1554825710.958 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-04-09T16:01:50.974Z,1554825710.974 [SpeedCalculator] Loaded
2019-04-09T16:01:50.974Z,1554825710.974 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-04-09T16:01:50.996Z,1554825710.996 [TempGradientCalculator] Loaded
2019-04-09T16:01:50.996Z,1554825710.996 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-04-09T16:01:51.002Z,1554825711.002 [YawRateCalculator] Loaded
2019-04-09T16:01:51.002Z,1554825711.002 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-04-09T16:01:51.035Z,1554825711.035 [ElevatorOffsetCalculator] Loaded
2019-04-09T16:01:51.036Z,1554825711.036 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-04-09T16:01:51.036Z,1554825711.036 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-04-09T16:01:51.037Z,1554825711.037 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-04-09T16:01:51.065Z,1554825711.065 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-04-09T16:01:51.065Z,1554825711.065 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-04-09T16:01:51.169Z,1554825711.169 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-04-09T16:01:51.170Z,1554825711.170 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-04-09T16:01:51.230Z,1554825711.230 [DeadReckonUsingSpeedCalculator] Loaded
2019-04-09T16:01:51.230Z,1554825711.230 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-04-09T16:01:51.292Z,1554825711.292 [DeadReckonWithRespectToSeafloor] Loaded
2019-04-09T16:01:51.292Z,1554825711.292 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-04-09T16:01:51.313Z,1554825711.313 [NavChart] Loaded
2019-04-09T16:01:51.313Z,1554825711.313 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-04-09T16:01:51.317Z,1554825711.317 [UniversalFixResidualReporter] Loaded
2019-04-09T16:01:51.318Z,1554825711.318 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-04-09T16:01:51.318Z,1554825711.318 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-04-09T16:01:51.319Z,1554825711.319 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-04-09T16:01:52.012Z,1554825712.012 [AcousticModem_Benthos_ATM900] Loaded
2019-04-09T16:01:52.012Z,1554825712.012 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-04-09T16:01:52.084Z,1554825712.084 [DataOverHttps] Loaded
2019-04-09T16:01:52.084Z,1554825712.084 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-04-09T16:01:52.085Z,1554825712.085 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409714E0
2019-04-09T16:01:52.086Z,1554825712.086 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3824
2019-04-09T16:01:52.131Z,1554825712.131 [Depth_Keller] Loaded
2019-04-09T16:01:52.131Z,1554825712.131 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-04-09T16:01:52.342Z,1554825712.342 [NAL9602] Loaded
2019-04-09T16:01:52.342Z,1554825712.342 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-04-09T16:01:52.348Z,1554825712.348 [Onboard] Loaded
2019-04-09T16:01:52.348Z,1554825712.348 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-04-09T16:01:52.351Z,1554825712.351 [Radio_Surface] Loaded
2019-04-09T16:01:52.352Z,1554825712.352 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-04-09T16:01:52.353Z,1554825712.353 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A14E0
2019-04-09T16:01:52.353Z,1554825712.353 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3825
2019-04-09T16:01:53.669Z,1554825713.669 [BPC1] Loaded
2019-04-09T16:01:53.670Z,1554825713.670 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-04-09T16:01:53.700Z,1554825713.700 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minrange
2019-04-09T16:01:53.702Z,1554825713.702 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.maxrange
2019-04-09T16:01:53.704Z,1554825713.704 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.soundspeed
2019-04-09T16:01:53.706Z,1554825713.706 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.automode
2019-04-09T16:01:53.709Z,1554825713.709 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.gainindex
2019-04-09T16:01:53.711Z,1554825713.711 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minconfidence
2019-04-09T16:01:53.713Z,1554825713.713 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.pingenable
2019-04-09T16:01:53.715Z,1554825713.715 [BR_Ping1D] Loaded
2019-04-09T16:01:53.715Z,1554825713.715 [ComponentRegistry](DEBUG): SyncComponent "BR_Ping1D" handled in the control thread.
2019-04-09T16:01:53.716Z,1554825713.716 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-04-09T16:01:53.716Z,1554825713.716 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-04-09T16:01:53.781Z,1554825713.781 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-04-09T16:01:53.782Z,1554825713.782 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-04-09T16:01:53.850Z,1554825713.850 [VerticalControl](DEBUG): Construct VerticalControl.
2019-04-09T16:01:53.935Z,1554825713.935 [VerticalControl] Loaded
2019-04-09T16:01:53.936Z,1554825713.936 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-04-09T16:01:53.936Z,1554825713.936 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-04-09T16:01:53.994Z,1554825713.994 [HorizontalControl] Loaded
2019-04-09T16:01:53.995Z,1554825713.995 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-04-09T16:01:53.995Z,1554825713.995 [SpeedControl](DEBUG): Construct SpeedControl.
2019-04-09T16:01:53.997Z,1554825713.997 [SpeedControl] Loaded
2019-04-09T16:01:53.997Z,1554825713.997 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-04-09T16:01:53.998Z,1554825713.998 [LoopControl](DEBUG): Construct LoopControl.
2019-04-09T16:01:53.999Z,1554825713.999 [LoopControl] Loaded
2019-04-09T16:01:53.999Z,1554825713.999 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-04-09T16:01:53.999Z,1554825713.999 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-04-09T16:01:53.000Z,1554825714.000 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-04-09T16:01:54.013Z,1554825714.013 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-04-09T16:01:54.014Z,1554825714.014 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-04-09T16:01:54.298Z,1554825714.298 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-04-09T16:01:54.302Z,1554825714.302 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-04-09T16:01:54.303Z,1554825714.303 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-04-09T16:01:54.309Z,1554825714.309 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-04-09T16:01:54.310Z,1554825714.310 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACE4E0
2019-04-09T16:01:54.311Z,1554825714.311 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3826
2019-04-09T16:01:54.316Z,1554825714.316 [Supervisor](INFO): Main Thread ID is 4347
2019-04-09T16:01:54.316Z,1554825714.316 [Supervisor](DEBUG): Running supervisor.
2019-04-09T16:01:54.317Z,1554825714.317 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3827
2019-04-09T16:01:54.319Z,1554825714.319 [controlThread ThreadHandler](INFO): Handler Thread ID is 3828
2019-04-09T16:01:54.319Z,1554825714.319 [controlThread](DEBUG): Initializing ControlThread
2019-04-09T16:01:54.321Z,1554825714.321 [SBIT](INFO): Initialize SBIT Component.
2019-04-09T16:01:54.322Z,1554825714.322 [SBIT](IMPORTANT): git: 2019-03-18-31-ge98d5dc
2019-04-09T16:01:54.322Z,1554825714.322 [SBIT](INFO): git hash: e98d5dc50cbc39e402f19b9946ff4dc0776c8725
2019-04-09T16:01:54.322Z,1554825714.322 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-04-09T16:01:54.324Z,1554825714.324 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-04-09T16:01:54.325Z,1554825714.325 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-04-09T16:01:54.325Z,1554825714.325 [IBIT](INFO): Initialize IBIT Component.
2019-04-09T16:01:54.326Z,1554825714.326 [CBIT](DEBUG): Initialize CBIT Component.
2019-04-09T16:01:54.327Z,1554825714.327 [logger ThreadHandler](INFO): Handler Thread ID is 3829
2019-04-09T16:01:54.338Z,1554825714.338 [CBIT](DEBUG): Initialized mux pins.
2019-04-09T16:01:54.338Z,1554825714.338 [CBIT](DEBUG): Initializing the watchdog timer.
2019-04-09T16:01:54.346Z,1554825714.346 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3830
2019-04-09T16:01:54.347Z,1554825714.347 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T16:01:54.351Z,1554825714.351 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T16:01:54.352Z,1554825714.352 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3832
2019-04-09T16:01:54.353Z,1554825714.353 [WetLabsBB2FL](INFO): Powering down
2019-04-09T16:01:54.362Z,1554825714.362 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-04-09T16:01:54.362Z,1554825714.362 [CBIT](DEBUG): Initializing heartbeat.
2019-04-09T16:01:54.382Z,1554825714.382 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3833
2019-04-09T16:01:54.383Z,1554825714.383 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-04-09T16:01:54.392Z,1554825714.392 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3834
2019-04-09T16:01:54.410Z,1554825714.410 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3835
2019-04-09T16:01:54.413Z,1554825714.413 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-09T16:01:54.413Z,1554825714.413 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-04-09T16:01:54.414Z,1554825714.414 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-09T16:01:54.414Z,1554825714.414 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-04-09T16:01:54.414Z,1554825714.414 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-09T16:01:54.414Z,1554825714.414 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-04-09T16:01:54.415Z,1554825714.415 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-09T16:01:54.415Z,1554825714.415 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-04-09T16:01:54.415Z,1554825714.415 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-09T16:01:54.415Z,1554825714.415 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-04-09T16:01:54.415Z,1554825714.415 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-09T16:01:54.415Z,1554825714.415 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-04-09T16:01:54.416Z,1554825714.416 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-09T16:01:54.416Z,1554825714.416 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-04-09T16:01:54.416Z,1554825714.416 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-09T16:01:54.416Z,1554825714.416 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-04-09T16:01:54.434Z,1554825714.434 [CBIT](DEBUG): Deactivating GF circuits.
2019-04-09T16:01:54.434Z,1554825714.434 [CBIT](DEBUG): Deactivating emergency mode.
2019-04-09T16:01:54.470Z,1554825714.470 [CBIT](DEBUG): Backplane powered.
2019-04-09T16:01:54.474Z,1554825714.474 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-04-09T16:01:54.475Z,1554825714.475 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-04-09T16:01:54.475Z,1554825714.475 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-04-09T16:01:54.475Z,1554825714.475 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-04-09T16:01:54.476Z,1554825714.476 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-04-09T16:01:54.476Z,1554825714.476 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-04-09T16:01:54.477Z,1554825714.477 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T16:01:54.478Z,1554825714.478 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-04-09T16:01:54.479Z,1554825714.479 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-04-09T16:01:54.483Z,1554825714.483 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-04-09T16:01:54.485Z,1554825714.485 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-04-09T16:01:54.486Z,1554825714.486 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-04-09T16:01:54.486Z,1554825714.486 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-04-09T16:01:54.487Z,1554825714.487 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-04-09T16:01:54.509Z,1554825714.509 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T16:01:54.554Z,1554825714.554 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2019-04-09T16:01:54.555Z,1554825714.555 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-04-09T16:01:54.634Z,1554825714.634 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-04-09T16:01:54.635Z,1554825714.635 [Default:A.Wait](DEBUG): Construct Wait.
2019-04-09T16:01:54.637Z,1554825714.637 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T16:01:54.663Z,1554825714.663 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-04-09T16:01:54.666Z,1554825714.666 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-04-09T16:01:54.691Z,1554825714.691 [Default:E.Execute](DEBUG): Construct Execute.
2019-04-09T16:01:54.711Z,1554825714.711 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2019-04-09T16:01:54.716Z,1554825714.716 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,Depth_Keller,NAL9602,Onboard,BPC1,BR_Ping1D,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-04-09T16:01:54.747Z,1554825714.747 [ESPComponent](INFO): powering down ESP
2019-04-09T16:01:54.795Z,1554825714.795 [Radio_Surface](INFO): Powering up
2019-04-09T16:01:55.199Z,1554825715.199 [PAR_Licor](INFO): A/D timeout, 7 tries over 135 ms

2019-04-09T16:01:55.199Z,1554825715.199 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T16:01:55.199Z,1554825715.199 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:01:55.200Z,1554825715.200 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:01:55.200Z,1554825715.200 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:01:55.397Z,1554825715.397 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:01:55.402Z,1554825715.402 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-04-09T16:01:55.404Z,1554825715.404 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:01:55.410Z,1554825715.410 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-09T16:01:55.411Z,1554825715.411 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:01:55.418Z,1554825715.418 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:01:55.419Z,1554825715.419 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:01:55.426Z,1554825715.426 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-09T16:01:55.427Z,1554825715.427 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:01:55.434Z,1554825715.434 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-04-09T16:01:55.437Z,1554825715.437 [CommandLine](FAULT): Scheduling is paused
2019-04-09T16:01:55.437Z,1554825715.437 [CBIT](INFO): Critical error at 20190409T160153
2019-04-09T16:01:55.437Z,1554825715.437 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-04-09T16:01:55.438Z,1554825715.438 [CBIT](FAULT): Main Battery Failure. Count: 1
2019-04-09T16:01:55.442Z,1554825715.442 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:01:55.442Z,1554825715.442 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T16:01:55.442Z,1554825715.442 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T16:01:55.986Z,1554825715.986 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-09T16:01:55.986Z,1554825715.986 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T16:01:55.986Z,1554825715.986 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:01:56.027Z,1554825716.027 [BR_Ping1D](INFO): startup: 0
2019-04-09T16:01:56.027Z,1554825716.027 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T16:01:56.038Z,1554825716.038 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T16:01:56.038Z,1554825716.038 [BR_Ping1D](INFO): data 1: 244
2019-04-09T16:01:56.038Z,1554825716.038 [BR_Ping1D](INFO): data 2: 245
2019-04-09T16:01:56.038Z,1554825716.038 [BR_Ping1D](INFO): test: 500
2019-04-09T16:01:56.039Z,1554825716.039 [BR_Ping1D](INFO): 66
2019-04-09T16:01:56.039Z,1554825716.039 [BR_Ping1D](INFO): 82
2019-04-09T16:01:56.039Z,1554825716.039 [BR_Ping1D](INFO): 2
2019-04-09T16:01:56.039Z,1554825716.039 [BR_Ping1D](INFO): 0
2019-04-09T16:01:56.039Z,1554825716.039 [BR_Ping1D](INFO): 236
2019-04-09T16:01:56.039Z,1554825716.039 [BR_Ping1D](INFO): 3
2019-04-09T16:01:56.039Z,1554825716.039 [BR_Ping1D](INFO): 0
2019-04-09T16:01:56.039Z,1554825716.039 [BR_Ping1D](INFO): 0
2019-04-09T16:01:56.040Z,1554825716.040 [BR_Ping1D](INFO): 244
2019-04-09T16:01:56.040Z,1554825716.040 [BR_Ping1D](INFO): 1
2019-04-09T16:01:56.040Z,1554825716.040 [BR_Ping1D](INFO): 122
2019-04-09T16:01:56.040Z,1554825716.040 [BR_Ping1D](INFO): 2
2019-04-09T16:01:56.040Z,1554825716.040 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T16:01:56.040Z,1554825716.040 [BR_Ping1D](INFO): 66
2019-04-09T16:01:56.040Z,1554825716.040 [BR_Ping1D](INFO): 82
2019-04-09T16:01:56.041Z,1554825716.041 [BR_Ping1D](INFO): 1
2019-04-09T16:01:56.041Z,1554825716.041 [BR_Ping1D](INFO): 0
2019-04-09T16:01:56.041Z,1554825716.041 [BR_Ping1D](INFO): 235
2019-04-09T16:01:56.041Z,1554825716.041 [BR_Ping1D](INFO): 3
2019-04-09T16:01:56.041Z,1554825716.041 [BR_Ping1D](INFO): 0
2019-04-09T16:01:56.041Z,1554825716.041 [BR_Ping1D](INFO): 0
2019-04-09T16:01:56.041Z,1554825716.041 [BR_Ping1D](INFO): 132
2019-04-09T16:01:56.041Z,1554825716.041 [BR_Ping1D](INFO): 1
2019-04-09T16:01:56.041Z,1554825716.041 [BR_Ping1D](INFO): 21
2019-04-09T16:01:56.042Z,1554825716.042 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T16:01:56.043Z,1554825716.043 [BR_Ping1D](INFO): 66
2019-04-09T16:01:56.043Z,1554825716.043 [BR_Ping1D](INFO): 82
2019-04-09T16:01:56.044Z,1554825716.044 [BR_Ping1D](INFO): 4
2019-04-09T16:01:56.044Z,1554825716.044 [BR_Ping1D](INFO): 0
2019-04-09T16:01:56.045Z,1554825716.045 [BR_Ping1D](INFO): 234
2019-04-09T16:01:56.045Z,1554825716.045 [BR_Ping1D](INFO): 3
2019-04-09T16:01:56.046Z,1554825716.046 [BR_Ping1D](INFO): 0
2019-04-09T16:01:56.046Z,1554825716.046 [BR_Ping1D](INFO): 0
2019-04-09T16:01:56.047Z,1554825716.047 [BR_Ping1D](INFO): 96
2019-04-09T16:01:56.047Z,1554825716.047 [BR_Ping1D](INFO): 227
2019-04-09T16:01:56.048Z,1554825716.048 [BR_Ping1D](INFO): 22
2019-04-09T16:01:56.048Z,1554825716.048 [BR_Ping1D](INFO): 0
2019-04-09T16:01:56.049Z,1554825716.049 [BR_Ping1D](INFO): 222
2019-04-09T16:01:56.049Z,1554825716.049 [BR_Ping1D](INFO): 2
2019-04-09T16:01:56.051Z,1554825716.050 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T16:01:56.051Z,1554825716.051 [BR_Ping1D](INFO): 66
2019-04-09T16:01:56.052Z,1554825716.052 [BR_Ping1D](INFO): 82
2019-04-09T16:01:56.052Z,1554825716.052 [BR_Ping1D](INFO): 1
2019-04-09T16:01:56.053Z,1554825716.053 [BR_Ping1D](INFO): 0
2019-04-09T16:01:56.053Z,1554825716.053 [BR_Ping1D](INFO): 238
2019-04-09T16:01:56.054Z,1554825716.054 [BR_Ping1D](INFO): 3
2019-04-09T16:01:56.070Z,1554825716.070 [BR_Ping1D](INFO): 0
2019-04-09T16:01:56.070Z,1554825716.070 [BR_Ping1D](INFO): 0
2019-04-09T16:01:56.070Z,1554825716.070 [BR_Ping1D](INFO): 135
2019-04-09T16:01:56.070Z,1554825716.070 [BR_Ping1D](INFO): 1
2019-04-09T16:01:56.070Z,1554825716.070 [BR_Ping1D](INFO): 21
2019-04-09T16:01:56.393Z,1554825716.393 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:01:56.394Z,1554825716.394 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T16:01:56.394Z,1554825716.394 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T16:01:56.437Z,1554825716.437 [BR_Ping1D](INFO): Starting
2019-04-09T16:01:56.826Z,1554825716.826 [BR_Ping1D](INFO): Starting
2019-04-09T16:01:56.826Z,1554825716.826 [BR_Ping1D](INFO): readConfig
2019-04-09T16:01:56.826Z,1554825716.826 [BR_Ping1D](INFO): startup: 1
2019-04-09T16:01:56.826Z,1554825716.826 [BR_Ping1D](INFO): UART requesting status
2019-04-09T16:01:56.826Z,1554825716.826 [BR_Ping1D](INFO): 66
2019-04-09T16:01:56.826Z,1554825716.826 [BR_Ping1D](INFO): 82
2019-04-09T16:01:56.827Z,1554825716.827 [BR_Ping1D](INFO): 0
2019-04-09T16:01:56.827Z,1554825716.827 [BR_Ping1D](INFO): 0
2019-04-09T16:01:56.827Z,1554825716.827 [BR_Ping1D](INFO): 186
2019-04-09T16:01:56.827Z,1554825716.827 [BR_Ping1D](INFO): 4
2019-04-09T16:01:56.827Z,1554825716.827 [BR_Ping1D](INFO): 0
2019-04-09T16:01:56.827Z,1554825716.827 [BR_Ping1D](INFO): 0
2019-04-09T16:01:56.827Z,1554825716.827 [BR_Ping1D](INFO): 82
2019-04-09T16:01:56.827Z,1554825716.827 [BR_Ping1D](INFO): 1
2019-04-09T16:01:56.834Z,1554825716.834 [BR_Ping1D](INFO): 66
2019-04-09T16:01:56.834Z,1554825716.834 [BR_Ping1D](INFO): 82
2019-04-09T16:01:56.834Z,1554825716.834 [BR_Ping1D](INFO): 0
2019-04-09T16:01:56.834Z,1554825716.834 [BR_Ping1D](INFO): 0
2019-04-09T16:01:56.834Z,1554825716.834 [BR_Ping1D](INFO): 191
2019-04-09T16:01:56.834Z,1554825716.834 [BR_Ping1D](INFO): 4
2019-04-09T16:01:56.835Z,1554825716.835 [BR_Ping1D](INFO): 0
2019-04-09T16:01:56.835Z,1554825716.835 [BR_Ping1D](INFO): 0
2019-04-09T16:01:56.835Z,1554825716.835 [BR_Ping1D](INFO): 87
2019-04-09T16:01:56.835Z,1554825716.835 [BR_Ping1D](INFO): 1
2019-04-09T16:01:56.842Z,1554825716.842 [BR_Ping1D](INFO): 66
2019-04-09T16:01:56.842Z,1554825716.842 [BR_Ping1D](INFO): 82
2019-04-09T16:01:56.842Z,1554825716.842 [BR_Ping1D](INFO): 0
2019-04-09T16:01:56.842Z,1554825716.842 [BR_Ping1D](INFO): 0
2019-04-09T16:01:56.842Z,1554825716.842 [BR_Ping1D](INFO): 179
2019-04-09T16:01:56.842Z,1554825716.842 [BR_Ping1D](INFO): 4
2019-04-09T16:01:56.842Z,1554825716.842 [BR_Ping1D](INFO): 0
2019-04-09T16:01:56.843Z,1554825716.843 [BR_Ping1D](INFO): 0
2019-04-09T16:01:56.843Z,1554825716.843 [BR_Ping1D](INFO): 75
2019-04-09T16:01:56.843Z,1554825716.843 [BR_Ping1D](INFO): 1
2019-04-09T16:01:57.334Z,1554825717.334 [PAR_Licor](INFO): A/D timeout, 8 tries over 126 ms

2019-04-09T16:01:57.334Z,1554825717.334 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T16:01:57.334Z,1554825717.334 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:01:57.370Z,1554825717.370 [BR_Ping1D](INFO): Starting
2019-04-09T16:01:57.370Z,1554825717.370 [BR_Ping1D](INFO): readConfig
2019-04-09T16:01:57.370Z,1554825717.370 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:01:57.371Z,1554825717.371 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:01:57.371Z,1554825717.371 [BR_Ping1D](INFO): elapsed 1.3436
2019-04-09T16:01:57.371Z,1554825717.371 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:01:57.371Z,1554825717.371 [BR_Ping1D](INFO): No UART data
2019-04-09T16:01:57.372Z,1554825717.372 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:01:57.372Z,1554825717.372 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:01:57.387Z,1554825717.387 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:01:57.387Z,1554825717.387 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T16:01:57.387Z,1554825717.387 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T16:01:57.634Z,1554825717.634 [BR_Ping1D](INFO): Starting
2019-04-09T16:01:57.634Z,1554825717.634 [BR_Ping1D](INFO): readConfig
2019-04-09T16:01:57.634Z,1554825717.634 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:01:57.634Z,1554825717.634 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:01:57.634Z,1554825717.634 [BR_Ping1D](INFO): elapsed 1.6072
2019-04-09T16:01:57.635Z,1554825717.635 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:01:57.635Z,1554825717.635 [BR_Ping1D](INFO): No UART data
2019-04-09T16:01:57.635Z,1554825717.635 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:01:57.635Z,1554825717.635 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:01:57.978Z,1554825717.978 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T16:01:57.978Z,1554825717.978 [MassServo] Communications Fault, FailCount= 1
2019-04-09T16:01:57.978Z,1554825717.978 [MassServo](ERROR): Communications Fault
2019-04-09T16:01:58.006Z,1554825718.006 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T16:01:58.058Z,1554825718.058 [BR_Ping1D](INFO): Starting
2019-04-09T16:01:58.059Z,1554825718.059 [BR_Ping1D](INFO): readConfig
2019-04-09T16:01:58.059Z,1554825718.059 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:01:58.059Z,1554825718.059 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:01:58.059Z,1554825718.059 [BR_Ping1D](INFO): elapsed 2.0319
2019-04-09T16:01:58.059Z,1554825718.059 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:01:58.060Z,1554825718.059 [BR_Ping1D](INFO): No UART data
2019-04-09T16:01:58.060Z,1554825718.060 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:01:58.060Z,1554825718.060 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:01:58.174Z,1554825718.174 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:01:58.175Z,1554825718.175 [MassServo](INFO): Powering down
2019-04-09T16:01:58.438Z,1554825718.438 [BR_Ping1D](INFO): Starting
2019-04-09T16:01:58.438Z,1554825718.438 [BR_Ping1D](INFO): readConfig
2019-04-09T16:01:58.438Z,1554825718.438 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:01:58.439Z,1554825718.439 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:01:58.439Z,1554825718.439 [BR_Ping1D](INFO): elapsed 2.4115
2019-04-09T16:01:58.439Z,1554825718.439 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:01:58.439Z,1554825718.439 [BR_Ping1D](INFO): No UART data
2019-04-09T16:01:58.440Z,1554825718.440 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:01:58.440Z,1554825718.440 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:01:58.958Z,1554825718.958 [PAR_Licor](INFO): A/D timeout, 9 tries over 138 ms

2019-04-09T16:01:58.958Z,1554825718.958 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T16:01:58.958Z,1554825718.958 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:01:58.979Z,1554825718.979 [BR_Ping1D](INFO): Starting
2019-04-09T16:01:58.979Z,1554825718.979 [BR_Ping1D](INFO): readConfig
2019-04-09T16:01:58.979Z,1554825718.979 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:01:58.979Z,1554825718.979 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:01:58.979Z,1554825718.979 [BR_Ping1D](INFO): elapsed 2.9521
2019-04-09T16:01:58.980Z,1554825718.980 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:01:58.980Z,1554825718.980 [BR_Ping1D](INFO): No UART data
2019-04-09T16:01:58.980Z,1554825718.980 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:01:58.980Z,1554825718.980 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:01:59.060Z,1554825719.060 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:01:59.179Z,1554825719.179 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:01:59.386Z,1554825719.386 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T16:01:59.386Z,1554825719.386 [MassServo] No Fault, FailCount= 1
2019-04-09T16:01:59.386Z,1554825719.386 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:01:59.386Z,1554825719.386 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T16:01:59.387Z,1554825719.387 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T16:01:59.441Z,1554825719.441 [BR_Ping1D](INFO): Starting
2019-04-09T16:01:59.441Z,1554825719.441 [BR_Ping1D](INFO): readConfig
2019-04-09T16:01:59.442Z,1554825719.442 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:01:59.442Z,1554825719.442 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:01:59.442Z,1554825719.442 [BR_Ping1D](INFO): elapsed 3.4148
2019-04-09T16:01:59.442Z,1554825719.442 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:01:59.442Z,1554825719.442 [BR_Ping1D](INFO): No UART data
2019-04-09T16:01:59.443Z,1554825719.443 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:01:59.443Z,1554825719.443 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:01:59.672Z,1554825719.672 [CBIT](FAULT): Main Battery Failure. Count: 11
2019-04-09T16:01:59.930Z,1554825719.930 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-09T16:01:59.930Z,1554825719.930 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T16:01:59.930Z,1554825719.930 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:01:59.970Z,1554825719.970 [BR_Ping1D](INFO): Starting
2019-04-09T16:01:59.970Z,1554825719.970 [BR_Ping1D](INFO): readConfig
2019-04-09T16:01:59.970Z,1554825719.970 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:01:59.970Z,1554825719.970 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:01:59.970Z,1554825719.970 [BR_Ping1D](INFO): elapsed 3.9432
2019-04-09T16:01:59.971Z,1554825719.971 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:01:59.971Z,1554825719.971 [BR_Ping1D](INFO): No UART data
2019-04-09T16:01:59.971Z,1554825719.971 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:01:59.971Z,1554825719.971 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:00.051Z,1554825720.051 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:02:00.051Z,1554825720.051 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T16:02:00.051Z,1554825720.051 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T16:02:00.222Z,1554825720.222 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:00.222Z,1554825720.222 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:00.222Z,1554825720.222 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:00.222Z,1554825720.222 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:00.223Z,1554825720.223 [BR_Ping1D](INFO): elapsed 4.1953
2019-04-09T16:02:00.223Z,1554825720.223 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:00.223Z,1554825720.223 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:00.223Z,1554825720.223 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:00.223Z,1554825720.223 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:00.738Z,1554825720.738 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-09T16:02:00.738Z,1554825720.738 [PAR_Licor] Data Fault, FailCount= 3
2019-04-09T16:02:00.738Z,1554825720.738 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:02:00.759Z,1554825720.759 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:00.759Z,1554825720.759 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:00.759Z,1554825720.759 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:00.759Z,1554825720.759 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:00.759Z,1554825720.759 [BR_Ping1D](INFO): elapsed 4.7320
2019-04-09T16:02:00.759Z,1554825720.759 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:00.760Z,1554825720.760 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:00.760Z,1554825720.760 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:00.760Z,1554825720.760 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:00.795Z,1554825720.795 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:02:00.795Z,1554825720.795 [CBIT](CRITICAL): Data Fault in component: PAR_Licor
2019-04-09T16:02:01.026Z,1554825721.026 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:01.026Z,1554825721.026 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:01.026Z,1554825721.026 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:01.026Z,1554825721.026 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:01.027Z,1554825721.027 [BR_Ping1D](INFO): elapsed 4.9993
2019-04-09T16:02:01.027Z,1554825721.027 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:01.027Z,1554825721.027 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:01.027Z,1554825721.027 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:01.027Z,1554825721.027 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:01.550Z,1554825721.550 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T16:02:01.550Z,1554825721.550 [MassServo] Communications Fault, FailCount= 2
2019-04-09T16:02:01.550Z,1554825721.550 [MassServo](ERROR): Communications Fault
2019-04-09T16:02:01.552Z,1554825721.552 [CBIT](INFO): Critical error at 20190409T160200
2019-04-09T16:02:01.555Z,1554825721.555 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T16:02:01.583Z,1554825721.583 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:01.583Z,1554825721.583 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:01.583Z,1554825721.583 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:01.583Z,1554825721.583 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:01.583Z,1554825721.583 [BR_Ping1D](INFO): elapsed 5.5560
2019-04-09T16:02:01.583Z,1554825721.583 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:01.584Z,1554825721.584 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T16:02:01.584Z,1554825721.584 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-09T16:02:01.584Z,1554825721.584 [BR_Ping1D](ERROR): Data Fault
2019-04-09T16:02:01.607Z,1554825721.607 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:02:01.607Z,1554825721.607 [MassServo](INFO): Powering down
2019-04-09T16:02:01.616Z,1554825721.616 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T16:02:01.981Z,1554825721.981 [BR_Ping1D](INFO): Stop
2019-04-09T16:02:01.981Z,1554825721.981 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:02:01.981Z,1554825721.981 [BR_Ping1D](INFO): Powering down
2019-04-09T16:02:02.387Z,1554825722.387 [BR_Ping1D](INFO): Stopping
2019-04-09T16:02:02.399Z,1554825722.399 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:02:02.523Z,1554825722.523 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:02:02.527Z,1554825722.527 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T16:02:02.527Z,1554825722.527 [MassServo] No Fault, FailCount= 2
2019-04-09T16:02:02.789Z,1554825722.789 [BR_Ping1D](INFO): Stopping
2019-04-09T16:02:03.193Z,1554825723.193 [BR_Ping1D](INFO): Stopped
2019-04-09T16:02:03.227Z,1554825723.227 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T16:02:03.227Z,1554825723.227 [BR_Ping1D] No Fault, FailCount= 1
2019-04-09T16:02:03.597Z,1554825723.597 [BR_Ping1D](INFO): Start
2019-04-09T16:02:03.650Z,1554825723.650 [CBIT](FAULT): Main Battery Failure. Count: 21
2019-04-09T16:02:04.002Z,1554825724.002 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:04.405Z,1554825724.405 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:04.405Z,1554825724.405 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:04.406Z,1554825724.406 [BR_Ping1D](INFO): startup: 0
2019-04-09T16:02:04.406Z,1554825724.406 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T16:02:04.418Z,1554825724.418 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T16:02:04.418Z,1554825724.418 [BR_Ping1D](INFO): data 1: 244
2019-04-09T16:02:04.418Z,1554825724.418 [BR_Ping1D](INFO): data 2: 245
2019-04-09T16:02:04.418Z,1554825724.418 [BR_Ping1D](INFO): test: 500
2019-04-09T16:02:04.418Z,1554825724.418 [BR_Ping1D](INFO): 66
2019-04-09T16:02:04.419Z,1554825724.419 [BR_Ping1D](INFO): 82
2019-04-09T16:02:04.419Z,1554825724.419 [BR_Ping1D](INFO): 2
2019-04-09T16:02:04.419Z,1554825724.419 [BR_Ping1D](INFO): 0
2019-04-09T16:02:04.419Z,1554825724.419 [BR_Ping1D](INFO): 236
2019-04-09T16:02:04.419Z,1554825724.419 [BR_Ping1D](INFO): 3
2019-04-09T16:02:04.419Z,1554825724.419 [BR_Ping1D](INFO): 0
2019-04-09T16:02:04.419Z,1554825724.419 [BR_Ping1D](INFO): 0
2019-04-09T16:02:04.419Z,1554825724.419 [BR_Ping1D](INFO): 244
2019-04-09T16:02:04.419Z,1554825724.419 [BR_Ping1D](INFO): 1
2019-04-09T16:02:04.419Z,1554825724.419 [BR_Ping1D](INFO): 122
2019-04-09T16:02:04.420Z,1554825724.420 [BR_Ping1D](INFO): 2
2019-04-09T16:02:04.420Z,1554825724.420 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T16:02:04.420Z,1554825724.420 [BR_Ping1D](INFO): 66
2019-04-09T16:02:04.420Z,1554825724.420 [BR_Ping1D](INFO): 82
2019-04-09T16:02:04.420Z,1554825724.420 [BR_Ping1D](INFO): 1
2019-04-09T16:02:04.420Z,1554825724.420 [BR_Ping1D](INFO): 0
2019-04-09T16:02:04.420Z,1554825724.420 [BR_Ping1D](INFO): 235
2019-04-09T16:02:04.420Z,1554825724.420 [BR_Ping1D](INFO): 3
2019-04-09T16:02:04.421Z,1554825724.421 [BR_Ping1D](INFO): 0
2019-04-09T16:02:04.421Z,1554825724.421 [BR_Ping1D](INFO): 0
2019-04-09T16:02:04.421Z,1554825724.421 [BR_Ping1D](INFO): 132
2019-04-09T16:02:04.421Z,1554825724.421 [BR_Ping1D](INFO): 1
2019-04-09T16:02:04.421Z,1554825724.421 [BR_Ping1D](INFO): 21
2019-04-09T16:02:04.421Z,1554825724.421 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T16:02:04.421Z,1554825724.421 [BR_Ping1D](INFO): 66
2019-04-09T16:02:04.421Z,1554825724.421 [BR_Ping1D](INFO): 82
2019-04-09T16:02:04.421Z,1554825724.421 [BR_Ping1D](INFO): 4
2019-04-09T16:02:04.422Z,1554825724.422 [BR_Ping1D](INFO): 0
2019-04-09T16:02:04.422Z,1554825724.422 [BR_Ping1D](INFO): 234
2019-04-09T16:02:04.423Z,1554825724.423 [BR_Ping1D](INFO): 3
2019-04-09T16:02:04.423Z,1554825724.423 [BR_Ping1D](INFO): 0
2019-04-09T16:02:04.424Z,1554825724.424 [BR_Ping1D](INFO): 0
2019-04-09T16:02:04.424Z,1554825724.424 [BR_Ping1D](INFO): 96
2019-04-09T16:02:04.425Z,1554825724.425 [BR_Ping1D](INFO): 227
2019-04-09T16:02:04.425Z,1554825724.425 [BR_Ping1D](INFO): 22
2019-04-09T16:02:04.426Z,1554825724.426 [BR_Ping1D](INFO): 0
2019-04-09T16:02:04.426Z,1554825724.426 [BR_Ping1D](INFO): 222
2019-04-09T16:02:04.427Z,1554825724.427 [BR_Ping1D](INFO): 2
2019-04-09T16:02:04.428Z,1554825724.428 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T16:02:04.429Z,1554825724.429 [BR_Ping1D](INFO): 66
2019-04-09T16:02:04.429Z,1554825724.429 [BR_Ping1D](INFO): 82
2019-04-09T16:02:04.429Z,1554825724.429 [BR_Ping1D](INFO): 1
2019-04-09T16:02:04.438Z,1554825724.438 [BR_Ping1D](INFO): 0
2019-04-09T16:02:04.438Z,1554825724.438 [BR_Ping1D](INFO): 238
2019-04-09T16:02:04.438Z,1554825724.438 [BR_Ping1D](INFO): 3
2019-04-09T16:02:04.438Z,1554825724.438 [BR_Ping1D](INFO): 0
2019-04-09T16:02:04.438Z,1554825724.438 [BR_Ping1D](INFO): 0
2019-04-09T16:02:04.438Z,1554825724.438 [BR_Ping1D](INFO): 135
2019-04-09T16:02:04.438Z,1554825724.438 [BR_Ping1D](INFO): 1
2019-04-09T16:02:04.439Z,1554825724.439 [BR_Ping1D](INFO): 21
2019-04-09T16:02:04.483Z,1554825724.483 [CBIT](CRITICAL): Environmental Failure. Press:14.900361 PSI. Humidity:35%. Temp:26 C. ABORTING MISSION
2019-04-09T16:02:04.790Z,1554825724.790 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T16:02:04.790Z,1554825724.790 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T16:02:04.791Z,1554825724.791 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T16:02:04.810Z,1554825724.810 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:05.146Z,1554825725.146 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T16:02:05.146Z,1554825725.146 [MassServo] Communications Fault, FailCount= 3
2019-04-09T16:02:05.146Z,1554825725.146 [MassServo](ERROR): Communications Fault
2019-04-09T16:02:05.148Z,1554825725.148 [CBIT](INFO): Critical error at 20190409T160204
2019-04-09T16:02:05.151Z,1554825725.151 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T16:02:05.151Z,1554825725.151 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T16:02:05.314Z,1554825725.314 [Aanderaa_O2](INFO): Powering down
2019-04-09T16:02:05.385Z,1554825725.385 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:05.385Z,1554825725.385 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:05.386Z,1554825725.386 [BR_Ping1D](INFO): startup: 1
2019-04-09T16:02:05.386Z,1554825725.386 [BR_Ping1D](INFO): UART requesting status
2019-04-09T16:02:05.386Z,1554825725.386 [BR_Ping1D](INFO): 66
2019-04-09T16:02:05.386Z,1554825725.386 [BR_Ping1D](INFO): 82
2019-04-09T16:02:05.386Z,1554825725.386 [BR_Ping1D](INFO): 0
2019-04-09T16:02:05.386Z,1554825725.386 [BR_Ping1D](INFO): 0
2019-04-09T16:02:05.386Z,1554825725.386 [BR_Ping1D](INFO): 186
2019-04-09T16:02:05.387Z,1554825725.387 [BR_Ping1D](INFO): 4
2019-04-09T16:02:05.387Z,1554825725.387 [BR_Ping1D](INFO): 0
2019-04-09T16:02:05.387Z,1554825725.387 [BR_Ping1D](INFO): 0
2019-04-09T16:02:05.387Z,1554825725.387 [BR_Ping1D](INFO): 82
2019-04-09T16:02:05.387Z,1554825725.387 [BR_Ping1D](INFO): 1
2019-04-09T16:02:05.398Z,1554825725.398 [BR_Ping1D](INFO): 66
2019-04-09T16:02:05.398Z,1554825725.398 [BR_Ping1D](INFO): 82
2019-04-09T16:02:05.398Z,1554825725.398 [BR_Ping1D](INFO): 0
2019-04-09T16:02:05.398Z,1554825725.398 [BR_Ping1D](INFO): 0
2019-04-09T16:02:05.398Z,1554825725.398 [BR_Ping1D](INFO): 191
2019-04-09T16:02:05.398Z,1554825725.398 [BR_Ping1D](INFO): 4
2019-04-09T16:02:05.399Z,1554825725.399 [BR_Ping1D](INFO): 0
2019-04-09T16:02:05.399Z,1554825725.399 [BR_Ping1D](INFO): 0
2019-04-09T16:02:05.399Z,1554825725.399 [BR_Ping1D](INFO): 87
2019-04-09T16:02:05.399Z,1554825725.399 [BR_Ping1D](INFO): 1
2019-04-09T16:02:05.406Z,1554825725.406 [BR_Ping1D](INFO): 66
2019-04-09T16:02:05.406Z,1554825725.406 [BR_Ping1D](INFO): 82
2019-04-09T16:02:05.406Z,1554825725.406 [BR_Ping1D](INFO): 0
2019-04-09T16:02:05.406Z,1554825725.406 [BR_Ping1D](INFO): 0
2019-04-09T16:02:05.406Z,1554825725.406 [BR_Ping1D](INFO): 179
2019-04-09T16:02:05.406Z,1554825725.406 [BR_Ping1D](INFO): 4
2019-04-09T16:02:05.407Z,1554825725.407 [BR_Ping1D](INFO): 0
2019-04-09T16:02:05.407Z,1554825725.407 [BR_Ping1D](INFO): 0
2019-04-09T16:02:05.407Z,1554825725.407 [BR_Ping1D](INFO): 75
2019-04-09T16:02:05.407Z,1554825725.407 [BR_Ping1D](INFO): 1
2019-04-09T16:02:05.427Z,1554825725.427 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:02:05.427Z,1554825725.427 [MassServo](INFO): Powering down
2019-04-09T16:02:05.618Z,1554825725.618 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:05.618Z,1554825725.618 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:05.618Z,1554825725.618 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:05.618Z,1554825725.618 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:05.618Z,1554825725.618 [BR_Ping1D](INFO): elapsed 1.2125
2019-04-09T16:02:05.619Z,1554825725.619 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:05.619Z,1554825725.619 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:05.619Z,1554825725.619 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:05.619Z,1554825725.619 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:06.021Z,1554825726.021 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:06.021Z,1554825726.021 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:06.022Z,1554825726.022 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:06.022Z,1554825726.022 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:06.022Z,1554825726.022 [BR_Ping1D](INFO): elapsed 1.6160
2019-04-09T16:02:06.022Z,1554825726.022 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:06.022Z,1554825726.022 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:06.023Z,1554825726.023 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:06.023Z,1554825726.023 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:06.051Z,1554825726.051 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:02:06.171Z,1554825726.171 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:02:06.175Z,1554825726.175 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T16:02:06.175Z,1554825726.175 [MassServo] No Fault, FailCount= 3
2019-04-09T16:02:06.176Z,1554825726.176 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T16:02:06.176Z,1554825726.176 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-09T16:02:06.426Z,1554825726.426 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:06.426Z,1554825726.426 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:06.426Z,1554825726.426 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:06.427Z,1554825726.427 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:06.427Z,1554825726.427 [BR_Ping1D](INFO): elapsed 2.0208
2019-04-09T16:02:06.427Z,1554825726.427 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:06.427Z,1554825726.427 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:06.427Z,1554825726.427 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:06.427Z,1554825726.427 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:06.841Z,1554825726.841 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:06.842Z,1554825726.842 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:06.842Z,1554825726.842 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:06.842Z,1554825726.842 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:06.842Z,1554825726.842 [BR_Ping1D](INFO): elapsed 2.4362
2019-04-09T16:02:06.842Z,1554825726.842 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:06.843Z,1554825726.843 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:06.843Z,1554825726.843 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:06.843Z,1554825726.843 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:07.234Z,1554825727.234 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:07.234Z,1554825727.234 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:07.234Z,1554825727.234 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:07.234Z,1554825727.234 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:07.235Z,1554825727.235 [BR_Ping1D](INFO): elapsed 2.8286
2019-04-09T16:02:07.235Z,1554825727.235 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:07.235Z,1554825727.235 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:07.235Z,1554825727.235 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:07.235Z,1554825727.235 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:07.398Z,1554825727.398 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T16:02:07.398Z,1554825727.398 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-09T16:02:07.398Z,1554825727.398 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T16:02:07.637Z,1554825727.637 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:07.637Z,1554825727.637 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:07.638Z,1554825727.638 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:07.638Z,1554825727.638 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:07.638Z,1554825727.638 [BR_Ping1D](INFO): elapsed 3.2322
2019-04-09T16:02:07.638Z,1554825727.638 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:07.639Z,1554825727.639 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:07.639Z,1554825727.639 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:07.639Z,1554825727.639 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:07.669Z,1554825727.669 [CBIT](FAULT): Main Battery Failure. Count: 31
2019-04-09T16:02:07.671Z,1554825727.671 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T16:02:07.802Z,1554825727.802 [CTD_Seabird](INFO): Powering down
2019-04-09T16:02:08.042Z,1554825728.042 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:08.042Z,1554825728.042 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:08.042Z,1554825728.042 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:08.042Z,1554825728.042 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:08.042Z,1554825728.042 [BR_Ping1D](INFO): elapsed 3.6365
2019-04-09T16:02:08.043Z,1554825728.043 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:08.043Z,1554825728.043 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:08.043Z,1554825728.043 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:08.043Z,1554825728.043 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:08.449Z,1554825728.449 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:08.449Z,1554825728.449 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:08.450Z,1554825728.450 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:08.450Z,1554825728.450 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:08.450Z,1554825728.450 [BR_Ping1D](INFO): elapsed 4.0440
2019-04-09T16:02:08.450Z,1554825728.450 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:08.450Z,1554825728.450 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:08.451Z,1554825728.451 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:08.451Z,1554825728.451 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:08.774Z,1554825728.774 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T16:02:08.774Z,1554825728.774 [MassServo] Communications Fault, FailCount= 4
2019-04-09T16:02:08.774Z,1554825728.774 [MassServo](ERROR): Communications Fault
2019-04-09T16:02:08.778Z,1554825728.778 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T16:02:08.850Z,1554825728.850 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:08.850Z,1554825728.850 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:08.850Z,1554825728.850 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:08.850Z,1554825728.850 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:08.850Z,1554825728.850 [BR_Ping1D](INFO): elapsed 4.4445
2019-04-09T16:02:08.851Z,1554825728.851 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:08.851Z,1554825728.851 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:08.851Z,1554825728.851 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:08.851Z,1554825728.851 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:08.863Z,1554825728.863 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:02:08.863Z,1554825728.863 [MassServo](INFO): Powering down
2019-04-09T16:02:08.880Z,1554825728.880 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T16:02:08.880Z,1554825728.880 [CTD_Seabird] No Fault, FailCount= 1
2019-04-09T16:02:09.182Z,1554825729.182 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T16:02:09.184Z,1554825729.184 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T16:02:09.253Z,1554825729.253 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:09.253Z,1554825729.253 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:09.254Z,1554825729.254 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:09.254Z,1554825729.254 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:09.254Z,1554825729.254 [BR_Ping1D](INFO): elapsed 4.8480
2019-04-09T16:02:09.254Z,1554825729.254 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:09.254Z,1554825729.254 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:09.255Z,1554825729.255 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:09.255Z,1554825729.255 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:09.658Z,1554825729.658 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:09.658Z,1554825729.658 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:09.658Z,1554825729.658 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:09.658Z,1554825729.658 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:09.659Z,1554825729.659 [BR_Ping1D](INFO): elapsed 5.2526
2019-04-09T16:02:09.659Z,1554825729.659 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:09.659Z,1554825729.659 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T16:02:09.659Z,1554825729.659 [BR_Ping1D] Data Fault, FailCount= 2
2019-04-09T16:02:09.659Z,1554825729.659 [BR_Ping1D](ERROR): Data Fault
2019-04-09T16:02:09.671Z,1554825729.671 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:02:09.791Z,1554825729.791 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:02:09.795Z,1554825729.795 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T16:02:09.796Z,1554825729.796 [MassServo] No Fault, FailCount= 4
2019-04-09T16:02:09.796Z,1554825729.796 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T16:02:10.061Z,1554825730.061 [BR_Ping1D](INFO): Stop
2019-04-09T16:02:10.061Z,1554825730.061 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:02:10.061Z,1554825730.061 [BR_Ping1D](INFO): Powering down
2019-04-09T16:02:10.466Z,1554825730.466 [BR_Ping1D](INFO): Stopping
2019-04-09T16:02:10.870Z,1554825730.870 [BR_Ping1D](INFO): Stopping
2019-04-09T16:02:11.274Z,1554825731.274 [BR_Ping1D](INFO): Stopped
2019-04-09T16:02:11.307Z,1554825731.307 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T16:02:11.307Z,1554825731.307 [BR_Ping1D] No Fault, FailCount= 2
2019-04-09T16:02:11.677Z,1554825731.677 [BR_Ping1D](INFO): Start
2019-04-09T16:02:11.722Z,1554825731.722 [CBIT](FAULT): Main Battery Failure. Count: 41
2019-04-09T16:02:12.082Z,1554825732.082 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:12.406Z,1554825732.406 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T16:02:12.406Z,1554825732.406 [MassServo] Communications Fault, FailCount= 5
2019-04-09T16:02:12.406Z,1554825732.406 [MassServo](ERROR): Communications Fault
2019-04-09T16:02:12.410Z,1554825732.410 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T16:02:12.411Z,1554825732.411 [CBIT](CRITICAL): Communications Fault in component: MassServo
2019-04-09T16:02:12.486Z,1554825732.486 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:12.486Z,1554825732.486 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:12.486Z,1554825732.486 [BR_Ping1D](INFO): startup: 0
2019-04-09T16:02:12.486Z,1554825732.486 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T16:02:12.498Z,1554825732.498 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T16:02:12.498Z,1554825732.498 [BR_Ping1D](INFO): data 1: 244
2019-04-09T16:02:12.498Z,1554825732.498 [BR_Ping1D](INFO): data 2: 245
2019-04-09T16:02:12.499Z,1554825732.499 [BR_Ping1D](INFO): test: 500
2019-04-09T16:02:12.499Z,1554825732.499 [BR_Ping1D](INFO): 66
2019-04-09T16:02:12.499Z,1554825732.499 [BR_Ping1D](INFO): 82
2019-04-09T16:02:12.499Z,1554825732.499 [BR_Ping1D](INFO): 2
2019-04-09T16:02:12.499Z,1554825732.499 [BR_Ping1D](INFO): 0
2019-04-09T16:02:12.499Z,1554825732.499 [BR_Ping1D](INFO): 236
2019-04-09T16:02:12.499Z,1554825732.499 [BR_Ping1D](INFO): 3
2019-04-09T16:02:12.499Z,1554825732.499 [BR_Ping1D](INFO): 0
2019-04-09T16:02:12.499Z,1554825732.499 [BR_Ping1D](INFO): 0
2019-04-09T16:02:12.499Z,1554825732.499 [BR_Ping1D](INFO): 244
2019-04-09T16:02:12.500Z,1554825732.500 [BR_Ping1D](INFO): 1
2019-04-09T16:02:12.500Z,1554825732.500 [BR_Ping1D](INFO): 122
2019-04-09T16:02:12.500Z,1554825732.500 [BR_Ping1D](INFO): 2
2019-04-09T16:02:12.501Z,1554825732.501 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T16:02:12.502Z,1554825732.502 [BR_Ping1D](INFO): 66
2019-04-09T16:02:12.502Z,1554825732.502 [BR_Ping1D](INFO): 82
2019-04-09T16:02:12.503Z,1554825732.503 [BR_Ping1D](INFO): 1
2019-04-09T16:02:12.503Z,1554825732.503 [BR_Ping1D](INFO): 0
2019-04-09T16:02:12.503Z,1554825732.503 [BR_Ping1D](INFO): 235
2019-04-09T16:02:12.503Z,1554825732.503 [BR_Ping1D](INFO): 3
2019-04-09T16:02:12.503Z,1554825732.503 [BR_Ping1D](INFO): 0
2019-04-09T16:02:12.503Z,1554825732.503 [BR_Ping1D](INFO): 0
2019-04-09T16:02:12.503Z,1554825732.503 [BR_Ping1D](INFO): 132
2019-04-09T16:02:12.504Z,1554825732.504 [BR_Ping1D](INFO): 1
2019-04-09T16:02:12.504Z,1554825732.504 [BR_Ping1D](INFO): 21
2019-04-09T16:02:12.504Z,1554825732.504 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T16:02:12.504Z,1554825732.504 [BR_Ping1D](INFO): 66
2019-04-09T16:02:12.504Z,1554825732.504 [BR_Ping1D](INFO): 82
2019-04-09T16:02:12.504Z,1554825732.504 [BR_Ping1D](INFO): 4
2019-04-09T16:02:12.504Z,1554825732.504 [BR_Ping1D](INFO): 0
2019-04-09T16:02:12.504Z,1554825732.504 [BR_Ping1D](INFO): 234
2019-04-09T16:02:12.504Z,1554825732.504 [BR_Ping1D](INFO): 3
2019-04-09T16:02:12.505Z,1554825732.505 [BR_Ping1D](INFO): 0
2019-04-09T16:02:12.505Z,1554825732.505 [BR_Ping1D](INFO): 0
2019-04-09T16:02:12.505Z,1554825732.505 [BR_Ping1D](INFO): 96
2019-04-09T16:02:12.505Z,1554825732.505 [BR_Ping1D](INFO): 227
2019-04-09T16:02:12.505Z,1554825732.505 [BR_Ping1D](INFO): 22
2019-04-09T16:02:12.505Z,1554825732.505 [BR_Ping1D](INFO): 0
2019-04-09T16:02:12.505Z,1554825732.505 [BR_Ping1D](INFO): 222
2019-04-09T16:02:12.505Z,1554825732.505 [BR_Ping1D](INFO): 2
2019-04-09T16:02:12.505Z,1554825732.505 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T16:02:12.506Z,1554825732.506 [BR_Ping1D](INFO): 66
2019-04-09T16:02:12.506Z,1554825732.506 [BR_Ping1D](INFO): 82
2019-04-09T16:02:12.507Z,1554825732.507 [BR_Ping1D](INFO): 1
2019-04-09T16:02:12.507Z,1554825732.507 [BR_Ping1D](INFO): 0
2019-04-09T16:02:12.508Z,1554825732.508 [BR_Ping1D](INFO): 238
2019-04-09T16:02:12.508Z,1554825732.508 [BR_Ping1D](INFO): 3
2019-04-09T16:02:12.509Z,1554825732.509 [BR_Ping1D](INFO): 0
2019-04-09T16:02:12.509Z,1554825732.509 [BR_Ping1D](INFO): 0
2019-04-09T16:02:12.510Z,1554825732.510 [BR_Ping1D](INFO): 135
2019-04-09T16:02:12.510Z,1554825732.510 [BR_Ping1D](INFO): 1
2019-04-09T16:02:12.511Z,1554825732.511 [BR_Ping1D](INFO): 21
2019-04-09T16:02:12.545Z,1554825732.545 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:02:12.545Z,1554825732.545 [MassServo](INFO): Powering down
2019-04-09T16:02:12.560Z,1554825732.560 [CBIT](INFO): Critical error at 20190409T160212
2019-04-09T16:02:12.890Z,1554825732.890 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:13.293Z,1554825733.293 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:13.293Z,1554825733.293 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:13.294Z,1554825733.294 [BR_Ping1D](INFO): startup: 1
2019-04-09T16:02:13.294Z,1554825733.294 [BR_Ping1D](INFO): UART requesting status
2019-04-09T16:02:13.294Z,1554825733.294 [BR_Ping1D](INFO): 66
2019-04-09T16:02:13.294Z,1554825733.294 [BR_Ping1D](INFO): 82
2019-04-09T16:02:13.294Z,1554825733.294 [BR_Ping1D](INFO): 0
2019-04-09T16:02:13.294Z,1554825733.294 [BR_Ping1D](INFO): 0
2019-04-09T16:02:13.294Z,1554825733.294 [BR_Ping1D](INFO): 186
2019-04-09T16:02:13.294Z,1554825733.294 [BR_Ping1D](INFO): 4
2019-04-09T16:02:13.294Z,1554825733.294 [BR_Ping1D](INFO): 0
2019-04-09T16:02:13.295Z,1554825733.295 [BR_Ping1D](INFO): 0
2019-04-09T16:02:13.295Z,1554825733.295 [BR_Ping1D](INFO): 82
2019-04-09T16:02:13.295Z,1554825733.295 [BR_Ping1D](INFO): 1
2019-04-09T16:02:13.302Z,1554825733.302 [BR_Ping1D](INFO): 66
2019-04-09T16:02:13.302Z,1554825733.302 [BR_Ping1D](INFO): 82
2019-04-09T16:02:13.302Z,1554825733.302 [BR_Ping1D](INFO): 0
2019-04-09T16:02:13.302Z,1554825733.302 [BR_Ping1D](INFO): 0
2019-04-09T16:02:13.302Z,1554825733.302 [BR_Ping1D](INFO): 191
2019-04-09T16:02:13.302Z,1554825733.302 [BR_Ping1D](INFO): 4
2019-04-09T16:02:13.303Z,1554825733.303 [BR_Ping1D](INFO): 0
2019-04-09T16:02:13.303Z,1554825733.303 [BR_Ping1D](INFO): 0
2019-04-09T16:02:13.303Z,1554825733.303 [BR_Ping1D](INFO): 87
2019-04-09T16:02:13.303Z,1554825733.303 [BR_Ping1D](INFO): 1
2019-04-09T16:02:13.310Z,1554825733.310 [BR_Ping1D](INFO): 66
2019-04-09T16:02:13.310Z,1554825733.310 [BR_Ping1D](INFO): 82
2019-04-09T16:02:13.310Z,1554825733.310 [BR_Ping1D](INFO): 0
2019-04-09T16:02:13.310Z,1554825733.310 [BR_Ping1D](INFO): 0
2019-04-09T16:02:13.310Z,1554825733.310 [BR_Ping1D](INFO): 179
2019-04-09T16:02:13.310Z,1554825733.310 [BR_Ping1D](INFO): 4
2019-04-09T16:02:13.311Z,1554825733.311 [BR_Ping1D](INFO): 0
2019-04-09T16:02:13.311Z,1554825733.311 [BR_Ping1D](INFO): 0
2019-04-09T16:02:13.311Z,1554825733.311 [BR_Ping1D](INFO): 75
2019-04-09T16:02:13.311Z,1554825733.311 [BR_Ping1D](INFO): 1
2019-04-09T16:02:13.325Z,1554825733.325 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:02:13.443Z,1554825733.443 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:02:13.698Z,1554825733.698 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:13.698Z,1554825733.698 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:13.699Z,1554825733.699 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:13.699Z,1554825733.699 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:13.699Z,1554825733.699 [BR_Ping1D](INFO): elapsed 1.2124
2019-04-09T16:02:13.699Z,1554825733.699 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:13.699Z,1554825733.699 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:13.699Z,1554825733.699 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:13.700Z,1554825733.700 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:14.102Z,1554825734.102 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:14.102Z,1554825734.102 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:14.103Z,1554825734.103 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:14.103Z,1554825734.103 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:14.103Z,1554825734.103 [BR_Ping1D](INFO): elapsed 1.6166
2019-04-09T16:02:14.103Z,1554825734.103 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:14.103Z,1554825734.103 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:14.104Z,1554825734.104 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:14.104Z,1554825734.104 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:14.506Z,1554825734.506 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:14.506Z,1554825734.506 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:14.506Z,1554825734.506 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:14.506Z,1554825734.506 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:14.507Z,1554825734.507 [BR_Ping1D](INFO): elapsed 2.0202
2019-04-09T16:02:14.507Z,1554825734.507 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:14.507Z,1554825734.507 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:14.507Z,1554825734.507 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:14.507Z,1554825734.507 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:14.909Z,1554825734.909 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:14.909Z,1554825734.909 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:14.910Z,1554825734.910 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:14.910Z,1554825734.910 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:14.910Z,1554825734.910 [BR_Ping1D](INFO): elapsed 2.4238
2019-04-09T16:02:14.911Z,1554825734.911 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:14.911Z,1554825734.911 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:14.911Z,1554825734.911 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:14.911Z,1554825734.911 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:15.295Z,1554825735.295 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:02:15.295Z,1554825735.295 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-04-09T16:02:15.295Z,1554825735.295 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:02:15.314Z,1554825735.314 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:15.314Z,1554825735.314 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:15.315Z,1554825735.315 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:15.315Z,1554825735.315 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:15.315Z,1554825735.315 [BR_Ping1D](INFO): elapsed 2.8286
2019-04-09T16:02:15.315Z,1554825735.315 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:15.315Z,1554825735.315 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:15.316Z,1554825735.316 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:15.316Z,1554825735.316 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:15.354Z,1554825735.354 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:02:15.699Z,1554825735.699 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:02:15.861Z,1554825735.861 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:15.861Z,1554825735.861 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:15.862Z,1554825735.862 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:15.862Z,1554825735.862 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:15.862Z,1554825735.862 [BR_Ping1D](INFO): elapsed 3.3757
2019-04-09T16:02:15.862Z,1554825735.862 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:15.863Z,1554825735.863 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:15.863Z,1554825735.863 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:15.863Z,1554825735.863 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:15.909Z,1554825735.909 [CBIT](FAULT): Main Battery Failure. Count: 51
2019-04-09T16:02:16.123Z,1554825736.123 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:16.123Z,1554825736.123 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:16.123Z,1554825736.123 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:16.123Z,1554825736.123 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:16.124Z,1554825736.124 [BR_Ping1D](INFO): elapsed 3.6371
2019-04-09T16:02:16.124Z,1554825736.124 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:16.124Z,1554825736.124 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:16.124Z,1554825736.124 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:16.124Z,1554825736.124 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:16.506Z,1554825736.506 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T16:02:16.506Z,1554825736.506 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-09T16:02:16.507Z,1554825736.507 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T16:02:16.527Z,1554825736.527 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:16.527Z,1554825736.527 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:16.528Z,1554825736.528 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:16.528Z,1554825736.528 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:16.528Z,1554825736.528 [BR_Ping1D](INFO): elapsed 4.0415
2019-04-09T16:02:16.528Z,1554825736.528 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:16.528Z,1554825736.528 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:16.529Z,1554825736.529 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:16.529Z,1554825736.529 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:16.543Z,1554825736.543 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T16:02:16.543Z,1554825736.543 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:02:16.543Z,1554825736.543 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-04-09T16:02:17.030Z,1554825737.030 [Aanderaa_O2](INFO): Powering down
2019-04-09T16:02:17.048Z,1554825737.048 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:17.048Z,1554825737.048 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:17.049Z,1554825737.049 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:17.049Z,1554825737.049 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:17.049Z,1554825737.049 [BR_Ping1D](INFO): elapsed 4.5625
2019-04-09T16:02:17.049Z,1554825737.049 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:17.049Z,1554825737.049 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:17.050Z,1554825737.050 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:17.050Z,1554825737.050 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:17.334Z,1554825737.334 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:17.334Z,1554825737.334 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:17.334Z,1554825737.334 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:17.335Z,1554825737.335 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:17.335Z,1554825737.335 [BR_Ping1D](INFO): elapsed 4.8483
2019-04-09T16:02:17.335Z,1554825737.335 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:17.335Z,1554825737.335 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:17.335Z,1554825737.335 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:17.335Z,1554825737.335 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:17.739Z,1554825737.739 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:17.739Z,1554825737.739 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:17.739Z,1554825737.739 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:17.739Z,1554825737.739 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:17.739Z,1554825737.739 [BR_Ping1D](INFO): elapsed 5.2528
2019-04-09T16:02:17.739Z,1554825737.739 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:17.740Z,1554825737.740 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T16:02:17.740Z,1554825737.740 [BR_Ping1D] Data Fault, FailCount= 3
2019-04-09T16:02:17.740Z,1554825737.740 [BR_Ping1D](ERROR): Data Fault
2019-04-09T16:02:17.755Z,1554825737.755 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T16:02:17.755Z,1554825737.755 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-09T16:02:17.755Z,1554825737.755 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T16:02:18.142Z,1554825738.142 [BR_Ping1D](INFO): Stop
2019-04-09T16:02:18.142Z,1554825738.142 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:02:18.142Z,1554825738.142 [BR_Ping1D](INFO): Powering down
2019-04-09T16:02:18.564Z,1554825738.564 [BR_Ping1D](INFO): Stopping
2019-04-09T16:02:18.930Z,1554825738.930 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:02:18.931Z,1554825738.931 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:02:18.969Z,1554825738.969 [BR_Ping1D](INFO): Stopping
2019-04-09T16:02:18.980Z,1554825738.980 [SBIT](IMPORTANT): Beginning Startup BIT
2019-04-09T16:02:18.984Z,1554825738.984 [CBIT](IMPORTANT): Beginning ground fault scan
2019-04-09T16:02:19.354Z,1554825739.354 [BR_Ping1D](INFO): Stopped
2019-04-09T16:02:19.453Z,1554825739.453 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T16:02:19.453Z,1554825739.453 [BR_Ping1D] No Fault, FailCount= 3
2019-04-09T16:02:19.760Z,1554825739.760 [BR_Ping1D](INFO): Start
2019-04-09T16:02:19.820Z,1554825739.820 [CBIT](FAULT): Main Battery Failure. Count: 61
2019-04-09T16:02:20.161Z,1554825740.161 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:20.566Z,1554825740.566 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:20.566Z,1554825740.566 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:20.566Z,1554825740.566 [BR_Ping1D](INFO): startup: 0
2019-04-09T16:02:20.566Z,1554825740.566 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T16:02:20.578Z,1554825740.578 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T16:02:20.578Z,1554825740.578 [BR_Ping1D](INFO): data 1: 244
2019-04-09T16:02:20.578Z,1554825740.578 [BR_Ping1D](INFO): data 2: 245
2019-04-09T16:02:20.578Z,1554825740.578 [BR_Ping1D](INFO): test: 500
2019-04-09T16:02:20.578Z,1554825740.578 [BR_Ping1D](INFO): 66
2019-04-09T16:02:20.579Z,1554825740.579 [BR_Ping1D](INFO): 82
2019-04-09T16:02:20.579Z,1554825740.579 [BR_Ping1D](INFO): 2
2019-04-09T16:02:20.579Z,1554825740.579 [BR_Ping1D](INFO): 0
2019-04-09T16:02:20.579Z,1554825740.579 [BR_Ping1D](INFO): 236
2019-04-09T16:02:20.579Z,1554825740.579 [BR_Ping1D](INFO): 3
2019-04-09T16:02:20.579Z,1554825740.579 [BR_Ping1D](INFO): 0
2019-04-09T16:02:20.579Z,1554825740.579 [BR_Ping1D](INFO): 0
2019-04-09T16:02:20.579Z,1554825740.579 [BR_Ping1D](INFO): 244
2019-04-09T16:02:20.579Z,1554825740.579 [BR_Ping1D](INFO): 1
2019-04-09T16:02:20.579Z,1554825740.579 [BR_Ping1D](INFO): 122
2019-04-09T16:02:20.580Z,1554825740.580 [BR_Ping1D](INFO): 2
2019-04-09T16:02:20.600Z,1554825740.600 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T16:02:20.600Z,1554825740.600 [BR_Ping1D](INFO): 66
2019-04-09T16:02:20.600Z,1554825740.600 [BR_Ping1D](INFO): 82
2019-04-09T16:02:20.600Z,1554825740.600 [BR_Ping1D](INFO): 1
2019-04-09T16:02:20.600Z,1554825740.600 [BR_Ping1D](INFO): 0
2019-04-09T16:02:20.601Z,1554825740.601 [BR_Ping1D](INFO): 235
2019-04-09T16:02:20.601Z,1554825740.601 [BR_Ping1D](INFO): 3
2019-04-09T16:02:20.601Z,1554825740.601 [BR_Ping1D](INFO): 0
2019-04-09T16:02:20.601Z,1554825740.601 [BR_Ping1D](INFO): 0
2019-04-09T16:02:20.601Z,1554825740.601 [BR_Ping1D](INFO): 132
2019-04-09T16:02:20.601Z,1554825740.601 [BR_Ping1D](INFO): 1
2019-04-09T16:02:20.601Z,1554825740.601 [BR_Ping1D](INFO): 21
2019-04-09T16:02:20.603Z,1554825740.603 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T16:02:20.603Z,1554825740.603 [BR_Ping1D](INFO): 66
2019-04-09T16:02:20.604Z,1554825740.604 [BR_Ping1D](INFO): 82
2019-04-09T16:02:20.604Z,1554825740.604 [BR_Ping1D](INFO): 4
2019-04-09T16:02:20.604Z,1554825740.604 [BR_Ping1D](INFO): 0
2019-04-09T16:02:20.605Z,1554825740.605 [BR_Ping1D](INFO): 234
2019-04-09T16:02:20.605Z,1554825740.605 [BR_Ping1D](INFO): 3
2019-04-09T16:02:20.605Z,1554825740.605 [BR_Ping1D](INFO): 0
2019-04-09T16:02:20.605Z,1554825740.605 [BR_Ping1D](INFO): 0
2019-04-09T16:02:20.605Z,1554825740.605 [BR_Ping1D](INFO): 96
2019-04-09T16:02:20.605Z,1554825740.605 [BR_Ping1D](INFO): 227
2019-04-09T16:02:20.605Z,1554825740.605 [BR_Ping1D](INFO): 22
2019-04-09T16:02:20.605Z,1554825740.605 [BR_Ping1D](INFO): 0
2019-04-09T16:02:20.605Z,1554825740.605 [BR_Ping1D](INFO): 222
2019-04-09T16:02:20.605Z,1554825740.605 [BR_Ping1D](INFO): 2
2019-04-09T16:02:20.610Z,1554825740.610 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T16:02:20.610Z,1554825740.610 [BR_Ping1D](INFO): 66
2019-04-09T16:02:20.611Z,1554825740.611 [BR_Ping1D](INFO): 82
2019-04-09T16:02:20.611Z,1554825740.611 [BR_Ping1D](INFO): 1
2019-04-09T16:02:20.611Z,1554825740.611 [BR_Ping1D](INFO): 0
2019-04-09T16:02:20.611Z,1554825740.611 [BR_Ping1D](INFO): 238
2019-04-09T16:02:20.611Z,1554825740.611 [BR_Ping1D](INFO): 3
2019-04-09T16:02:20.611Z,1554825740.611 [BR_Ping1D](INFO): 0
2019-04-09T16:02:20.611Z,1554825740.611 [BR_Ping1D](INFO): 0
2019-04-09T16:02:20.611Z,1554825740.611 [BR_Ping1D](INFO): 135
2019-04-09T16:02:20.611Z,1554825740.611 [BR_Ping1D](INFO): 1
2019-04-09T16:02:20.611Z,1554825740.611 [BR_Ping1D](INFO): 21
2019-04-09T16:02:20.967Z,1554825740.967 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:21.373Z,1554825741.373 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:21.373Z,1554825741.373 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:21.374Z,1554825741.374 [BR_Ping1D](INFO): startup: 1
2019-04-09T16:02:21.374Z,1554825741.374 [BR_Ping1D](INFO): UART requesting status
2019-04-09T16:02:21.374Z,1554825741.374 [BR_Ping1D](INFO): 66
2019-04-09T16:02:21.374Z,1554825741.374 [BR_Ping1D](INFO): 82
2019-04-09T16:02:21.374Z,1554825741.374 [BR_Ping1D](INFO): 0
2019-04-09T16:02:21.374Z,1554825741.374 [BR_Ping1D](INFO): 0
2019-04-09T16:02:21.374Z,1554825741.374 [BR_Ping1D](INFO): 186
2019-04-09T16:02:21.375Z,1554825741.375 [BR_Ping1D](INFO): 4
2019-04-09T16:02:21.375Z,1554825741.375 [BR_Ping1D](INFO): 0
2019-04-09T16:02:21.375Z,1554825741.375 [BR_Ping1D](INFO): 0
2019-04-09T16:02:21.375Z,1554825741.375 [BR_Ping1D](INFO): 82
2019-04-09T16:02:21.375Z,1554825741.375 [BR_Ping1D](INFO): 1
2019-04-09T16:02:21.382Z,1554825741.382 [BR_Ping1D](INFO): 66
2019-04-09T16:02:21.382Z,1554825741.382 [BR_Ping1D](INFO): 82
2019-04-09T16:02:21.382Z,1554825741.382 [BR_Ping1D](INFO): 0
2019-04-09T16:02:21.382Z,1554825741.382 [BR_Ping1D](INFO): 0
2019-04-09T16:02:21.382Z,1554825741.382 [BR_Ping1D](INFO): 191
2019-04-09T16:02:21.383Z,1554825741.383 [BR_Ping1D](INFO): 4
2019-04-09T16:02:21.383Z,1554825741.383 [BR_Ping1D](INFO): 0
2019-04-09T16:02:21.383Z,1554825741.383 [BR_Ping1D](INFO): 0
2019-04-09T16:02:21.383Z,1554825741.383 [BR_Ping1D](INFO): 87
2019-04-09T16:02:21.383Z,1554825741.383 [BR_Ping1D](INFO): 1
2019-04-09T16:02:21.390Z,1554825741.390 [BR_Ping1D](INFO): 66
2019-04-09T16:02:21.390Z,1554825741.390 [BR_Ping1D](INFO): 82
2019-04-09T16:02:21.390Z,1554825741.390 [BR_Ping1D](INFO): 0
2019-04-09T16:02:21.390Z,1554825741.390 [BR_Ping1D](INFO): 0
2019-04-09T16:02:21.390Z,1554825741.390 [BR_Ping1D](INFO): 179
2019-04-09T16:02:21.390Z,1554825741.390 [BR_Ping1D](INFO): 4
2019-04-09T16:02:21.391Z,1554825741.391 [BR_Ping1D](INFO): 0
2019-04-09T16:02:21.391Z,1554825741.391 [BR_Ping1D](INFO): 0
2019-04-09T16:02:21.391Z,1554825741.391 [BR_Ping1D](INFO): 75
2019-04-09T16:02:21.391Z,1554825741.391 [BR_Ping1D](INFO): 1
2019-04-09T16:02:21.777Z,1554825741.777 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:21.778Z,1554825741.778 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:21.778Z,1554825741.778 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:21.778Z,1554825741.778 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:21.778Z,1554825741.778 [BR_Ping1D](INFO): elapsed 1.2116
2019-04-09T16:02:21.778Z,1554825741.778 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:21.779Z,1554825741.779 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:21.779Z,1554825741.779 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:21.779Z,1554825741.779 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:22.181Z,1554825742.181 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:22.181Z,1554825742.181 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:22.181Z,1554825742.181 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:22.182Z,1554825742.182 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:22.182Z,1554825742.182 [BR_Ping1D](INFO): elapsed 1.6152
2019-04-09T16:02:22.182Z,1554825742.182 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:22.182Z,1554825742.182 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:22.182Z,1554825742.182 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:22.183Z,1554825742.183 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:22.234Z,1554825742.234 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T16:02:22.234Z,1554825742.234 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-09T16:02:22.234Z,1554825742.234 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T16:02:22.296Z,1554825742.296 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T16:02:22.571Z,1554825742.571 [NAL9602](INFO): Powering up NAL9602
2019-04-09T16:02:22.588Z,1554825742.588 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:22.588Z,1554825742.588 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:22.588Z,1554825742.588 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:22.588Z,1554825742.588 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:22.589Z,1554825742.589 [BR_Ping1D](INFO): elapsed 2.0219
2019-04-09T16:02:22.589Z,1554825742.589 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:22.589Z,1554825742.589 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:22.589Z,1554825742.589 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:22.589Z,1554825742.589 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:22.638Z,1554825742.638 [CTD_Seabird](INFO): Powering down
2019-04-09T16:02:22.989Z,1554825742.989 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:22.989Z,1554825742.989 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:22.989Z,1554825742.989 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:22.989Z,1554825742.989 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:22.990Z,1554825742.990 [BR_Ping1D](INFO): elapsed 2.4230
2019-04-09T16:02:22.990Z,1554825742.990 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:22.990Z,1554825742.990 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:22.990Z,1554825742.990 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:22.990Z,1554825742.990 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:23.395Z,1554825743.395 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:23.395Z,1554825743.395 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:23.395Z,1554825743.395 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:23.395Z,1554825743.395 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:23.396Z,1554825743.396 [BR_Ping1D](INFO): elapsed 2.8290
2019-04-09T16:02:23.396Z,1554825743.396 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:23.396Z,1554825743.396 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:23.396Z,1554825743.396 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:23.396Z,1554825743.396 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:23.800Z,1554825743.800 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:23.800Z,1554825743.800 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:23.800Z,1554825743.800 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:23.800Z,1554825743.800 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:23.801Z,1554825743.801 [BR_Ping1D](INFO): elapsed 3.2341
2019-04-09T16:02:23.801Z,1554825743.801 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:23.801Z,1554825743.801 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:23.801Z,1554825743.801 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:23.801Z,1554825743.801 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:23.948Z,1554825743.948 [CBIT](FAULT): Main Battery Failure. Count: 71
2019-04-09T16:02:23.951Z,1554825743.951 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T16:02:23.951Z,1554825743.951 [CTD_Seabird] No Fault, FailCount= 2
2019-04-09T16:02:23.982Z,1554825743.982 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T16:02:23.984Z,1554825743.984 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T16:02:24.201Z,1554825744.201 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:24.202Z,1554825744.202 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:24.202Z,1554825744.202 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:24.202Z,1554825744.202 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:24.202Z,1554825744.202 [BR_Ping1D](INFO): elapsed 3.6356
2019-04-09T16:02:24.202Z,1554825744.202 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:24.203Z,1554825744.203 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:24.203Z,1554825744.203 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:24.203Z,1554825744.203 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:24.606Z,1554825744.606 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:24.606Z,1554825744.606 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:24.606Z,1554825744.606 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:24.606Z,1554825744.606 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:24.607Z,1554825744.607 [BR_Ping1D](INFO): elapsed 4.0399
2019-04-09T16:02:24.607Z,1554825744.607 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:24.607Z,1554825744.607 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:24.607Z,1554825744.607 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:24.607Z,1554825744.607 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:25.009Z,1554825745.009 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:25.010Z,1554825745.010 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:25.010Z,1554825745.010 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:25.010Z,1554825745.010 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:25.010Z,1554825745.010 [BR_Ping1D](INFO): elapsed 4.4436
2019-04-09T16:02:25.010Z,1554825745.010 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:25.011Z,1554825745.011 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:25.011Z,1554825745.011 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:25.011Z,1554825745.011 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:25.413Z,1554825745.413 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:25.413Z,1554825745.413 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:25.413Z,1554825745.413 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:25.413Z,1554825745.413 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:25.414Z,1554825745.414 [BR_Ping1D](INFO): elapsed 4.8470
2019-04-09T16:02:25.414Z,1554825745.414 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:25.414Z,1554825745.414 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:25.414Z,1554825745.414 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:25.415Z,1554825745.415 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:25.818Z,1554825745.818 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:25.818Z,1554825745.818 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:25.818Z,1554825745.818 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:25.818Z,1554825745.818 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:25.818Z,1554825745.818 [BR_Ping1D](INFO): elapsed 5.2519
2019-04-09T16:02:25.819Z,1554825745.819 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:25.819Z,1554825745.819 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T16:02:25.819Z,1554825745.819 [BR_Ping1D] Data Fault, FailCount= 4
2019-04-09T16:02:25.819Z,1554825745.819 [BR_Ping1D](ERROR): Data Fault
2019-04-09T16:02:25.847Z,1554825745.847 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T16:02:26.221Z,1554825746.221 [BR_Ping1D](INFO): Stop
2019-04-09T16:02:26.221Z,1554825746.221 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:02:26.221Z,1554825746.221 [BR_Ping1D](INFO): Powering down
2019-04-09T16:02:26.625Z,1554825746.625 [BR_Ping1D](INFO): Stopping
2019-04-09T16:02:27.031Z,1554825747.031 [BR_Ping1D](INFO): Stopping
2019-04-09T16:02:27.434Z,1554825747.434 [BR_Ping1D](INFO): Stopped
2019-04-09T16:02:27.451Z,1554825747.451 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T16:02:27.451Z,1554825747.451 [BR_Ping1D] No Fault, FailCount= 4
2019-04-09T16:02:27.837Z,1554825747.837 [BR_Ping1D](INFO): Start
2019-04-09T16:02:27.865Z,1554825747.865 [CBIT](FAULT): Main Battery Failure. Count: 81
2019-04-09T16:02:28.222Z,1554825748.222 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T16:02:28.222Z,1554825748.222 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-09T16:02:28.222Z,1554825748.222 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T16:02:28.241Z,1554825748.241 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:28.291Z,1554825748.291 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T16:02:28.292Z,1554825748.292 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-09T16:02:28.746Z,1554825748.746 [Aanderaa_O2](INFO): Powering down
2019-04-09T16:02:28.765Z,1554825748.765 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:28.766Z,1554825748.766 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:28.766Z,1554825748.766 [BR_Ping1D](INFO): startup: 0
2019-04-09T16:02:28.766Z,1554825748.766 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T16:02:28.778Z,1554825748.778 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T16:02:28.778Z,1554825748.778 [BR_Ping1D](INFO): data 1: 244
2019-04-09T16:02:28.778Z,1554825748.778 [BR_Ping1D](INFO): data 2: 245
2019-04-09T16:02:28.778Z,1554825748.778 [BR_Ping1D](INFO): test: 500
2019-04-09T16:02:28.778Z,1554825748.778 [BR_Ping1D](INFO): 66
2019-04-09T16:02:28.779Z,1554825748.779 [BR_Ping1D](INFO): 82
2019-04-09T16:02:28.779Z,1554825748.779 [BR_Ping1D](INFO): 2
2019-04-09T16:02:28.779Z,1554825748.779 [BR_Ping1D](INFO): 0
2019-04-09T16:02:28.779Z,1554825748.779 [BR_Ping1D](INFO): 236
2019-04-09T16:02:28.779Z,1554825748.779 [BR_Ping1D](INFO): 3
2019-04-09T16:02:28.779Z,1554825748.779 [BR_Ping1D](INFO): 0
2019-04-09T16:02:28.779Z,1554825748.779 [BR_Ping1D](INFO): 0
2019-04-09T16:02:28.779Z,1554825748.779 [BR_Ping1D](INFO): 244
2019-04-09T16:02:28.779Z,1554825748.779 [BR_Ping1D](INFO): 1
2019-04-09T16:02:28.779Z,1554825748.779 [BR_Ping1D](INFO): 122
2019-04-09T16:02:28.780Z,1554825748.780 [BR_Ping1D](INFO): 2
2019-04-09T16:02:28.780Z,1554825748.780 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T16:02:28.780Z,1554825748.780 [BR_Ping1D](INFO): 66
2019-04-09T16:02:28.780Z,1554825748.780 [BR_Ping1D](INFO): 82
2019-04-09T16:02:28.780Z,1554825748.780 [BR_Ping1D](INFO): 1
2019-04-09T16:02:28.780Z,1554825748.780 [BR_Ping1D](INFO): 0
2019-04-09T16:02:28.780Z,1554825748.780 [BR_Ping1D](INFO): 235
2019-04-09T16:02:28.780Z,1554825748.780 [BR_Ping1D](INFO): 3
2019-04-09T16:02:28.781Z,1554825748.781 [BR_Ping1D](INFO): 0
2019-04-09T16:02:28.781Z,1554825748.781 [BR_Ping1D](INFO): 0
2019-04-09T16:02:28.781Z,1554825748.781 [BR_Ping1D](INFO): 132
2019-04-09T16:02:28.781Z,1554825748.781 [BR_Ping1D](INFO): 1
2019-04-09T16:02:28.781Z,1554825748.781 [BR_Ping1D](INFO): 21
2019-04-09T16:02:28.781Z,1554825748.781 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T16:02:28.781Z,1554825748.781 [BR_Ping1D](INFO): 66
2019-04-09T16:02:28.781Z,1554825748.781 [BR_Ping1D](INFO): 82
2019-04-09T16:02:28.781Z,1554825748.781 [BR_Ping1D](INFO): 4
2019-04-09T16:02:28.782Z,1554825748.782 [BR_Ping1D](INFO): 0
2019-04-09T16:02:28.812Z,1554825748.812 [BR_Ping1D](INFO): 234
2019-04-09T16:02:28.812Z,1554825748.812 [BR_Ping1D](INFO): 3
2019-04-09T16:02:28.812Z,1554825748.812 [BR_Ping1D](INFO): 0
2019-04-09T16:02:28.812Z,1554825748.812 [BR_Ping1D](INFO): 0
2019-04-09T16:02:28.812Z,1554825748.812 [BR_Ping1D](INFO): 96
2019-04-09T16:02:28.812Z,1554825748.812 [BR_Ping1D](INFO): 227
2019-04-09T16:02:28.812Z,1554825748.812 [BR_Ping1D](INFO): 22
2019-04-09T16:02:28.813Z,1554825748.813 [BR_Ping1D](INFO): 0
2019-04-09T16:02:28.813Z,1554825748.813 [BR_Ping1D](INFO): 222
2019-04-09T16:02:28.813Z,1554825748.813 [BR_Ping1D](INFO): 2
2019-04-09T16:02:28.813Z,1554825748.813 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T16:02:28.813Z,1554825748.813 [BR_Ping1D](INFO): 66
2019-04-09T16:02:28.813Z,1554825748.813 [BR_Ping1D](INFO): 82
2019-04-09T16:02:28.813Z,1554825748.813 [BR_Ping1D](INFO): 1
2019-04-09T16:02:28.813Z,1554825748.813 [BR_Ping1D](INFO): 0
2019-04-09T16:02:28.813Z,1554825748.813 [BR_Ping1D](INFO): 238
2019-04-09T16:02:28.814Z,1554825748.814 [BR_Ping1D](INFO): 3
2019-04-09T16:02:28.830Z,1554825748.830 [BR_Ping1D](INFO): 0
2019-04-09T16:02:28.830Z,1554825748.830 [BR_Ping1D](INFO): 0
2019-04-09T16:02:28.830Z,1554825748.830 [BR_Ping1D](INFO): 135
2019-04-09T16:02:28.830Z,1554825748.830 [BR_Ping1D](INFO): 1
2019-04-09T16:02:28.830Z,1554825748.830 [BR_Ping1D](INFO): 21
2019-04-09T16:02:29.053Z,1554825749.053 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:29.470Z,1554825749.470 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:29.470Z,1554825749.470 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:29.471Z,1554825749.471 [BR_Ping1D](INFO): startup: 1
2019-04-09T16:02:29.471Z,1554825749.471 [BR_Ping1D](INFO): UART requesting status
2019-04-09T16:02:29.471Z,1554825749.471 [BR_Ping1D](INFO): 66
2019-04-09T16:02:29.471Z,1554825749.471 [BR_Ping1D](INFO): 82
2019-04-09T16:02:29.471Z,1554825749.471 [BR_Ping1D](INFO): 0
2019-04-09T16:02:29.471Z,1554825749.471 [BR_Ping1D](INFO): 0
2019-04-09T16:02:29.471Z,1554825749.471 [BR_Ping1D](INFO): 186
2019-04-09T16:02:29.471Z,1554825749.471 [BR_Ping1D](INFO): 4
2019-04-09T16:02:29.471Z,1554825749.471 [BR_Ping1D](INFO): 0
2019-04-09T16:02:29.472Z,1554825749.472 [BR_Ping1D](INFO): 0
2019-04-09T16:02:29.472Z,1554825749.472 [BR_Ping1D](INFO): 82
2019-04-09T16:02:29.472Z,1554825749.472 [BR_Ping1D](INFO): 1
2019-04-09T16:02:29.478Z,1554825749.478 [BR_Ping1D](INFO): 66
2019-04-09T16:02:29.478Z,1554825749.478 [BR_Ping1D](INFO): 82
2019-04-09T16:02:29.478Z,1554825749.478 [BR_Ping1D](INFO): 0
2019-04-09T16:02:29.478Z,1554825749.478 [BR_Ping1D](INFO): 0
2019-04-09T16:02:29.478Z,1554825749.478 [BR_Ping1D](INFO): 191
2019-04-09T16:02:29.478Z,1554825749.478 [BR_Ping1D](INFO): 4
2019-04-09T16:02:29.478Z,1554825749.478 [BR_Ping1D](INFO): 0
2019-04-09T16:02:29.479Z,1554825749.478 [BR_Ping1D](INFO): 0
2019-04-09T16:02:29.479Z,1554825749.479 [BR_Ping1D](INFO): 87
2019-04-09T16:02:29.479Z,1554825749.479 [BR_Ping1D](INFO): 1
2019-04-09T16:02:29.486Z,1554825749.486 [BR_Ping1D](INFO): 66
2019-04-09T16:02:29.486Z,1554825749.486 [BR_Ping1D](INFO): 82
2019-04-09T16:02:29.486Z,1554825749.486 [BR_Ping1D](INFO): 0
2019-04-09T16:02:29.486Z,1554825749.486 [BR_Ping1D](INFO): 0
2019-04-09T16:02:29.486Z,1554825749.486 [BR_Ping1D](INFO): 179
2019-04-09T16:02:29.486Z,1554825749.486 [BR_Ping1D](INFO): 4
2019-04-09T16:02:29.486Z,1554825749.486 [BR_Ping1D](INFO): 0
2019-04-09T16:02:29.487Z,1554825749.487 [BR_Ping1D](INFO): 0
2019-04-09T16:02:29.487Z,1554825749.487 [BR_Ping1D](INFO): 75
2019-04-09T16:02:29.487Z,1554825749.487 [BR_Ping1D](INFO): 1
2019-04-09T16:02:29.858Z,1554825749.858 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:29.858Z,1554825749.858 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:29.858Z,1554825749.858 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:29.858Z,1554825749.858 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:29.858Z,1554825749.858 [BR_Ping1D](INFO): elapsed 1.0922
2019-04-09T16:02:29.859Z,1554825749.859 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:29.859Z,1554825749.859 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:29.859Z,1554825749.859 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:29.859Z,1554825749.859 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:30.261Z,1554825750.261 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:30.261Z,1554825750.261 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:30.262Z,1554825750.262 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:30.262Z,1554825750.262 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:30.262Z,1554825750.262 [BR_Ping1D](INFO): elapsed 1.4957
2019-04-09T16:02:30.262Z,1554825750.262 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:30.262Z,1554825750.262 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:30.263Z,1554825750.263 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:30.263Z,1554825750.263 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:30.665Z,1554825750.665 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:30.665Z,1554825750.665 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:30.666Z,1554825750.666 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:30.666Z,1554825750.666 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:30.666Z,1554825750.666 [BR_Ping1D](INFO): elapsed 1.8998
2019-04-09T16:02:30.666Z,1554825750.666 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:30.666Z,1554825750.666 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:30.667Z,1554825750.667 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:30.667Z,1554825750.667 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:30.699Z,1554825750.699 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.006605
CHAN A1 (24V): -0.127852
CHAN A2 (12V): -0.005108
CHAN A3 (5V): -0.002102
CHAN B0 (3.3V): -0.001102
CHAN B1 (3.15aV): -0.000764
CHAN B2 (3.15bV): -0.001382
CHAN B3 (GND): -0.000162
OPEN: -0.000857
Full Scale Calc: 4.765 mA, -1.589 mA
2019-04-09T16:02:31.069Z,1554825751.069 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:31.069Z,1554825751.069 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:31.069Z,1554825751.069 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:31.070Z,1554825751.070 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:31.070Z,1554825751.070 [BR_Ping1D](INFO): elapsed 2.3036
2019-04-09T16:02:31.070Z,1554825751.070 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:31.070Z,1554825751.070 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:31.070Z,1554825751.070 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:31.071Z,1554825751.071 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:31.473Z,1554825751.473 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:31.474Z,1554825751.474 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:31.474Z,1554825751.474 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:31.474Z,1554825751.474 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:31.474Z,1554825751.474 [BR_Ping1D](INFO): elapsed 2.7080
2019-04-09T16:02:31.474Z,1554825751.474 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:31.475Z,1554825751.475 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:31.475Z,1554825751.475 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:31.475Z,1554825751.475 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:31.877Z,1554825751.877 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:31.877Z,1554825751.877 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:31.877Z,1554825751.877 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:31.877Z,1554825751.877 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:31.878Z,1554825751.878 [BR_Ping1D](INFO): elapsed 3.1113
2019-04-09T16:02:31.878Z,1554825751.878 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:31.878Z,1554825751.878 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:31.878Z,1554825751.878 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:31.878Z,1554825751.878 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:31.905Z,1554825751.905 [CBIT](FAULT): Main Battery Failure. Count: 91
2019-04-09T16:02:32.285Z,1554825752.285 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:32.285Z,1554825752.285 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:32.285Z,1554825752.285 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:32.285Z,1554825752.285 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:32.285Z,1554825752.285 [BR_Ping1D](INFO): elapsed 3.5192
2019-04-09T16:02:32.286Z,1554825752.286 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:32.286Z,1554825752.286 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:32.286Z,1554825752.286 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:32.286Z,1554825752.286 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:32.312Z,1554825752.312 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T16:02:32.685Z,1554825752.685 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:32.685Z,1554825752.685 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:32.686Z,1554825752.686 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:32.686Z,1554825752.686 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:32.686Z,1554825752.686 [BR_Ping1D](INFO): elapsed 3.9198
2019-04-09T16:02:32.686Z,1554825752.686 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:32.686Z,1554825752.686 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:32.687Z,1554825752.687 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:32.687Z,1554825752.687 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:33.101Z,1554825753.101 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:33.101Z,1554825753.101 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:33.101Z,1554825753.101 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:33.101Z,1554825753.101 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:33.102Z,1554825753.102 [BR_Ping1D](INFO): elapsed 4.3353
2019-04-09T16:02:33.102Z,1554825753.102 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:33.102Z,1554825753.102 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:33.102Z,1554825753.102 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:33.102Z,1554825753.102 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:33.479Z,1554825753.479 [NAL9602](INFO): NAL9602 initialized
2019-04-09T16:02:33.494Z,1554825753.494 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:33.494Z,1554825753.494 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:33.495Z,1554825753.495 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:33.495Z,1554825753.495 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:33.495Z,1554825753.495 [BR_Ping1D](INFO): elapsed 4.7286
2019-04-09T16:02:33.495Z,1554825753.495 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:33.495Z,1554825753.495 [BR_Ping1D](INFO): No UART data
2019-04-09T16:02:33.495Z,1554825753.495 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:02:33.496Z,1554825753.496 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:02:33.907Z,1554825753.907 [BR_Ping1D](INFO): Starting
2019-04-09T16:02:33.907Z,1554825753.907 [BR_Ping1D](INFO): readConfig
2019-04-09T16:02:33.907Z,1554825753.907 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:02:33.907Z,1554825753.907 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:02:33.908Z,1554825753.908 [BR_Ping1D](INFO): elapsed 5.1412
2019-04-09T16:02:33.908Z,1554825753.908 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:02:33.908Z,1554825753.908 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T16:02:33.908Z,1554825753.908 [BR_Ping1D] Data Fault, FailCount= 5
2019-04-09T16:02:33.908Z,1554825753.908 [BR_Ping1D](ERROR): Data Fault
2019-04-09T16:02:33.936Z,1554825753.936 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T16:02:33.936Z,1554825753.936 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-04-09T16:02:34.298Z,1554825754.298 [NAL9602](DEBUG): Fix Requested
2019-04-09T16:02:34.313Z,1554825754.313 [BR_Ping1D](INFO): Stop
2019-04-09T16:02:34.313Z,1554825754.313 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:02:34.313Z,1554825754.313 [BR_Ping1D](INFO): Powering down
2019-04-09T16:02:34.528Z,1554825754.528 [CBIT](INFO): Critical error at 20190409T160233
2019-04-09T16:02:34.721Z,1554825754.721 [BR_Ping1D](INFO): Stopping
2019-04-09T16:02:35.132Z,1554825755.132 [BR_Ping1D](INFO): Stopping
2019-04-09T16:02:35.515Z,1554825755.515 [BR_Ping1D](INFO): Stopped
2019-04-09T16:02:35.985Z,1554825755.985 [CBIT](FAULT): Main Battery Failure. Count: 101
2019-04-09T16:02:37.034Z,1554825757.034 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T16:02:37.034Z,1554825757.034 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-09T16:02:37.034Z,1554825757.034 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T16:02:37.207Z,1554825757.207 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T16:02:37.208Z,1554825757.208 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-09T16:02:37.438Z,1554825757.438 [CTD_Seabird](INFO): Powering down
2019-04-09T16:02:39.130Z,1554825759.130 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:02:39.130Z,1554825759.130 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2019-04-09T16:02:39.130Z,1554825759.130 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:02:39.227Z,1554825759.227 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:02:39.534Z,1554825759.534 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:02:40.220Z,1554825760.220 [CBIT](FAULT): Main Battery Failure. Count: 111
2019-04-09T16:02:40.380Z,1554825760.380 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:02:40.380Z,1554825760.380 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2019-04-09T16:02:42.766Z,1554825762.766 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:02:42.766Z,1554825762.766 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:02:44.009Z,1554825764.009 [CBIT](FAULT): Main Battery Failure. Count: 121
2019-04-09T16:02:48.061Z,1554825768.061 [CBIT](FAULT): Main Battery Failure. Count: 131
2019-04-09T16:02:52.086Z,1554825772.086 [CBIT](FAULT): Main Battery Failure. Count: 141
2019-04-09T16:02:56.133Z,1554825776.133 [CBIT](FAULT): Main Battery Failure. Count: 151
2019-04-09T16:02:58.971Z,1554825778.971 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T16:02:58.971Z,1554825778.971 [SBIT](FAULT): Control surface position failure.
2019-04-09T16:03:00.178Z,1554825780.178 [CBIT](FAULT): Main Battery Failure. Count: 161
2019-04-09T16:03:02.967Z,1554825782.967 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:03:02.967Z,1554825782.967 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2019-04-09T16:03:02.967Z,1554825782.967 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:03:03.088Z,1554825783.088 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:03:03.370Z,1554825783.370 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:03:04.476Z,1554825784.476 [CBIT](FAULT): Main Battery Failure. Count: 171
2019-04-09T16:03:04.479Z,1554825784.479 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:03:04.479Z,1554825784.479 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2019-04-09T16:03:06.604Z,1554825786.604 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:03:06.604Z,1554825786.604 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:03:08.263Z,1554825788.263 [CBIT](FAULT): Main Battery Failure. Count: 181
2019-04-09T16:03:12.294Z,1554825792.294 [CBIT](FAULT): Main Battery Failure. Count: 191
2019-04-09T16:03:12.700Z,1554825792.700 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T16:03:12.700Z,1554825792.700 [SBIT](FAULT): Control surface position failure.
2019-04-09T16:03:13.093Z,1554825793.093 [SBIT](CRITICAL): SBIT FAILED
2019-04-09T16:03:13.108Z,1554825793.108 [CBIT](INFO): Critical error at 20190409T160313
2019-04-09T16:03:13.175Z,1554825793.175 [CommandLine](IMPORTANT): got command configSet list
2019-04-09T16:03:13.175Z,1554825793.175 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-04-09T16:03:13.176Z,1554825793.176 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-04-09T16:03:13.176Z,1554825793.176 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2019-04-09T16:03:13.519Z,1554825793.519 [MissionManager](IMPORTANT): Started mission Startup
2019-04-09T16:03:13.519Z,1554825793.519 [Startup] Running Loop=1
2019-04-09T16:03:13.520Z,1554825793.520 [Startup](DEBUG): Aggregate::initialize Startup
2019-04-09T16:03:13.520Z,1554825793.520 [Startup:A.GoToSurface] Running Loop=1
2019-04-09T16:03:13.520Z,1554825793.520 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-09T16:03:13.520Z,1554825793.520 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-09T16:03:13.521Z,1554825793.521 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-09T16:03:13.521Z,1554825793.521 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-09T16:03:13.521Z,1554825793.521 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-09T16:03:13.522Z,1554825793.522 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-09T16:03:13.523Z,1554825793.523 [Startup:StartupSatComms] Running Loop=1
2019-04-09T16:03:13.524Z,1554825793.524 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-04-09T16:03:13.524Z,1554825793.524 [Startup:StartupSatComms:A] Running Loop=1
2019-04-09T16:03:13.919Z,1554825793.919 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-04-09T16:03:16.333Z,1554825796.333 [CBIT](FAULT): Main Battery Failure. Count: 201
2019-04-09T16:03:20.368Z,1554825800.368 [CBIT](FAULT): Main Battery Failure. Count: 211
2019-04-09T16:03:24.424Z,1554825804.424 [CBIT](FAULT): Main Battery Failure. Count: 221
2019-04-09T16:03:26.802Z,1554825806.802 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:03:26.803Z,1554825806.803 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2019-04-09T16:03:26.803Z,1554825806.803 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:03:26.840Z,1554825806.840 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:03:27.206Z,1554825807.206 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:03:28.055Z,1554825808.055 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:03:28.055Z,1554825808.055 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2019-04-09T16:03:28.453Z,1554825808.453 [CBIT](FAULT): Main Battery Failure. Count: 231
2019-04-09T16:03:30.438Z,1554825810.438 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:03:30.439Z,1554825810.439 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:03:32.499Z,1554825812.499 [CBIT](FAULT): Main Battery Failure. Count: 241
2019-04-09T16:03:36.537Z,1554825816.537 [CBIT](FAULT): Main Battery Failure. Count: 251
2019-04-09T16:03:40.584Z,1554825820.584 [CBIT](FAULT): Main Battery Failure. Count: 261
2019-04-09T16:03:44.614Z,1554825824.614 [CBIT](FAULT): Main Battery Failure. Count: 271
2019-04-09T16:03:48.655Z,1554825828.655 [CBIT](FAULT): Main Battery Failure. Count: 281
2019-04-09T16:03:50.638Z,1554825830.638 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:03:50.639Z,1554825830.639 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2019-04-09T16:03:50.639Z,1554825830.639 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:03:50.688Z,1554825830.688 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:03:51.042Z,1554825831.042 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:03:51.890Z,1554825831.890 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:03:51.890Z,1554825831.890 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2019-04-09T16:03:52.687Z,1554825832.687 [CBIT](FAULT): Main Battery Failure. Count: 291
2019-04-09T16:03:54.274Z,1554825834.274 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:03:54.275Z,1554825834.275 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:03:54.705Z,1554825834.705 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T16:03:54.705Z,1554825834.705 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-04-09T16:03:54.706Z,1554825834.706 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T16:03:54.707Z,1554825834.707 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T16:03:54.707Z,1554825834.707 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-04-09T16:03:54.707Z,1554825834.707 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T16:03:54.709Z,1554825834.709 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T16:03:54.709Z,1554825834.709 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-04-09T16:03:54.709Z,1554825834.709 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T16:03:54.719Z,1554825834.719 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T16:03:54.720Z,1554825834.720 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T16:03:54.720Z,1554825834.720 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T16:03:55.116Z,1554825835.116 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T16:03:55.116Z,1554825835.116 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-04-09T16:03:55.117Z,1554825835.117 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T16:03:55.117Z,1554825835.117 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-04-09T16:03:55.117Z,1554825835.117 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T16:03:55.118Z,1554825835.118 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-04-09T16:03:55.529Z,1554825835.529 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T16:03:56.744Z,1554825836.744 [CBIT](FAULT): Main Battery Failure. Count: 301
2019-04-09T16:04:00.783Z,1554825840.783 [CBIT](FAULT): Main Battery Failure. Count: 311
2019-04-09T16:04:04.807Z,1554825844.807 [CBIT](FAULT): Main Battery Failure. Count: 321
2019-04-09T16:04:08.853Z,1554825848.853 [CBIT](FAULT): Main Battery Failure. Count: 331
2019-04-09T16:04:12.896Z,1554825852.896 [CBIT](FAULT): Main Battery Failure. Count: 341
2019-04-09T16:04:13.695Z,1554825853.695 [Startup:StartupSatComms:A](INFO): Timed out from 2019-04-09T16:03:13.5Z
2019-04-09T16:04:13.695Z,1554825853.695 [Startup:StartupSatComms:A] Stopped
2019-04-09T16:04:13.695Z,1554825853.695 [Startup:StartupSatComms:B] Running Loop=1
2019-04-09T16:04:14.100Z,1554825854.100 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-09T16:04:14.475Z,1554825854.475 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:04:14.475Z,1554825854.475 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2019-04-09T16:04:14.475Z,1554825854.475 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:04:14.512Z,1554825854.512 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:04:14.878Z,1554825854.878 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:04:15.724Z,1554825855.724 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:04:15.724Z,1554825855.724 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2019-04-09T16:04:16.948Z,1554825856.948 [CBIT](FAULT): Main Battery Failure. Count: 351
2019-04-09T16:04:18.110Z,1554825858.110 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:04:18.111Z,1554825858.111 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:04:20.977Z,1554825860.977 [CBIT](FAULT): Main Battery Failure. Count: 361
2019-04-09T16:04:25.017Z,1554825865.017 [CBIT](FAULT): Main Battery Failure. Count: 371
2019-04-09T16:04:29.070Z,1554825869.070 [CBIT](FAULT): Main Battery Failure. Count: 381
2019-04-09T16:04:33.117Z,1554825873.117 [CBIT](FAULT): Main Battery Failure. Count: 391
2019-04-09T16:04:36.173Z,1554825876.173 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004937
2019-04-09T16:04:37.142Z,1554825877.142 [CBIT](FAULT): Main Battery Failure. Count: 401
2019-04-09T16:04:38.318Z,1554825878.318 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:04:38.318Z,1554825878.318 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7
2019-04-09T16:04:38.318Z,1554825878.318 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:04:38.376Z,1554825878.376 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:04:38.722Z,1554825878.722 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:04:39.580Z,1554825879.580 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:04:39.580Z,1554825879.580 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7
2019-04-09T16:04:40.271Z,1554825880.271 [CommandLine](IMPORTANT): got command restart application
2019-04-09T16:04:41.005Z,1554825881.005 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190409T155653/Courier0004.lzma
2019-04-09T16:04:41.174Z,1554825881.174 [CBIT](FAULT): Main Battery Failure. Count: 411
2019-04-09T16:04:41.274Z,1554825881.274 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:04:41.274Z,1554825881.274 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:04:41.426Z,1554825881.426 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-04-09T16:04:41.426Z,1554825881.426 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:04:41.427Z,1554825881.427 [CommandLine](INFO): Join timeout helper Thread ID is 3882
2019-04-09T16:04:41.427Z,1554825881.427 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-04-09T16:04:41.427Z,1554825881.427 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:04:41.428Z,1554825881.428 [NavChartDb](INFO): Join timeout helper Thread ID is 3883
2019-04-09T16:04:41.690Z,1554825881.690 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:04:41.690Z,1554825881.690 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:04:41.706Z,1554825881.706 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-04-09T16:04:41.706Z,1554825881.706 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:04:41.706Z,1554825881.706 [Radio_Surface](INFO): Join timeout helper Thread ID is 3884
2019-04-09T16:04:41.711Z,1554825881.711 [Radio_Surface](INFO): Powering down
2019-04-09T16:04:41.712Z,1554825881.712 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:04:41.712Z,1554825881.712 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:04:41.726Z,1554825881.726 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-04-09T16:04:41.726Z,1554825881.726 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:04:41.726Z,1554825881.726 [DataOverHttps](INFO): Join timeout helper Thread ID is 3885
2019-04-09T16:04:41.810Z,1554825881.810 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:04:41.814Z,1554825881.814 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:04:41.830Z,1554825881.830 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-04-09T16:04:41.830Z,1554825881.830 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:04:41.831Z,1554825881.831 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3886
2019-04-09T16:04:41.954Z,1554825881.954 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:04:41.955Z,1554825881.955 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:04:42.214Z,1554825882.214 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:04:42.215Z,1554825882.215 [WetLabsBB2FL](INFO): Powering down
2019-04-09T16:04:42.215Z,1554825882.215 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:04:42.219Z,1554825882.219 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-04-09T16:04:42.219Z,1554825882.219 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:04:42.219Z,1554825882.219 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3888
2019-04-09T16:04:42.434Z,1554825882.434 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:04:42.434Z,1554825882.434 [CTD_Seabird](INFO): Powering down
2019-04-09T16:04:42.446Z,1554825882.446 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:04:42.463Z,1554825882.463 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-04-09T16:04:42.463Z,1554825882.463 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:04:42.463Z,1554825882.463 [logger](INFO): Join timeout helper Thread ID is 3889
2019-04-09T16:04:42.466Z,1554825882.466 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:04:42.466Z,1554825882.466 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:04:42.483Z,1554825882.483 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-04-09T16:04:42.483Z,1554825882.483 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:04:42.483Z,1554825882.483 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-04-09T16:04:42.483Z,1554825882.483 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:04:42.483Z,1554825882.483 [controlThread](INFO): Join timeout helper Thread ID is 3890
2019-04-09T16:04:42.758Z,1554825882.758 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:04:42.758Z,1554825882.758 [controlThread](DEBUG): Uninitializing ControlThread
2019-04-09T16:04:42.759Z,1554825882.759 [Aanderaa_O2](INFO): Powering down
2019-04-09T16:04:42.761Z,1554825882.761 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:04:42.878Z,1554825882.878 [NAL9602](INFO): Powering down
2019-04-09T16:04:42.880Z,1554825882.880 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:04:42.880Z,1554825882.880 [BR_Ping1D](INFO): Powering down
2019-04-09T16:04:42.882Z,1554825882.882 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-04-09T16:04:42.883Z,1554825882.883 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-04-09T16:04:42.884Z,1554825882.884 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-04-09T16:04:42.884Z,1554825882.884 [MissionManager](INFO): Uninitializing Mission Default
2019-04-09T16:04:42.886Z,1554825882.886 [MissionManager](INFO): Uninitializing Mission Startup
2019-04-09T16:04:42.886Z,1554825882.886 [Startup] Stopped
2019-04-09T16:04:42.887Z,1554825882.887 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-04-09T16:04:42.887Z,1554825882.887 [Startup:A.GoToSurface] Stopped
2019-04-09T16:04:42.887Z,1554825882.887 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-09T16:04:42.887Z,1554825882.887 [Startup:StartupSatComms] Stopped
2019-04-09T16:04:42.887Z,1554825882.887 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-04-09T16:04:42.887Z,1554825882.887 [Startup:StartupSatComms:B] Stopped
2019-04-09T16:04:42.888Z,1554825882.888 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-04-09T16:04:42.889Z,1554825882.889 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-04-09T16:04:42.889Z,1554825882.889 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-04-09T16:04:42.889Z,1554825882.889 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-04-09T16:04:42.889Z,1554825882.889 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-04-09T16:04:42.890Z,1554825882.890 [BuoyancyServo](INFO): Powering down
2019-04-09T16:04:42.902Z,1554825882.902 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-09T16:04:42.902Z,1554825882.902 [ElevatorServo](INFO): Powering down
2019-04-09T16:04:42.903Z,1554825882.903 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:04:42.903Z,1554825882.903 [MassServo](INFO): Powering down
2019-04-09T16:04:42.904Z,1554825882.904 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-09T16:04:42.904Z,1554825882.904 [RudderServo](INFO): Powering down
2019-04-09T16:04:42.905Z,1554825882.905 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-04-09T16:04:42.905Z,1554825882.905 [ThrusterServo](INFO): Powering down
2019-04-09T16:04:42.906Z,1554825882.906 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-04-09T16:04:42.906Z,1554825882.906 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-04-09T16:04:42.906Z,1554825882.906 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-04-09T16:04:42.907Z,1554825882.907 [CBIT](DEBUG): Powering off loads.
2019-04-09T16:04:42.918Z,1554825882.918 [CBIT](DEBUG): Disabling WDT.
2019-04-09T16:04:42.930Z,1554825882.930 [CBIT](DEBUG): Opening all GF detection circuits.
2019-04-09T16:04:42.931Z,1554825882.931 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:04:43.085Z,1554825883.085 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:04:43.090Z,1554825883.090 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:04:43.157Z,1554825883.157 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:04:43.162Z,1554825883.162 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:04:43.213Z,1554825883.213 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:04:43.271Z,1554825883.271 [logger ThreadHandler](INFO): Thread cancelled.