2019-04-09T15:21:56.949Z,1554823316.949 [Supervisor](DEBUG): Initializing supervisor.
2019-04-09T15:21:56.952Z,1554823316.952 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-09T15:21:56.953Z,1554823316.953 [SyncHandler](INFO): Protected caller Thread ID is 2605
2019-04-09T15:21:56.953Z,1554823316.953 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-04-09T15:21:56.954Z,1554823316.954 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-04-09T15:21:56.954Z,1554823316.954 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2606
2019-04-09T15:21:56.957Z,1554823316.957 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-04-09T15:21:56.969Z,1554823316.969 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-04-09T15:21:56.970Z,1554823316.970 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-04-09T15:21:56.970Z,1554823316.970 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2607
2019-04-09T15:21:56.971Z,1554823316.971 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-04-09T15:21:56.972Z,1554823316.972 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-04-09T15:21:56.973Z,1554823316.973 [logger ThreadHandler](INFO): Protected caller Thread ID is 2608
2019-04-09T15:21:56.975Z,1554823316.975 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-04-09T15:21:56.975Z,1554823316.975 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-04-09T15:21:56.977Z,1554823316.977 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-04-09T15:21:57.072Z,1554823317.072 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-04-09T15:21:57.072Z,1554823317.072 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-04-09T15:21:57.273Z,1554823317.273 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-04-09T15:21:57.274Z,1554823317.274 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-04-09T15:21:57.411Z,1554823317.411 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-04-09T15:21:57.411Z,1554823317.411 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-04-09T15:21:57.870Z,1554823317.870 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-04-09T15:21:57.871Z,1554823317.871 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-04-09T15:21:58.313Z,1554823318.313 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-04-09T15:21:58.314Z,1554823318.314 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-04-09T15:21:58.781Z,1554823318.781 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-04-09T15:21:58.782Z,1554823318.782 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-04-09T15:21:59.089Z,1554823319.089 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-04-09T15:21:59.090Z,1554823319.090 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-04-09T15:21:59.422Z,1554823319.422 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-04-09T15:21:59.423Z,1554823319.423 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-04-09T15:21:59.818Z,1554823319.818 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-04-09T15:21:59.818Z,1554823319.818 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-04-09T15:21:59.968Z,1554823319.968 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-04-09T15:21:59.969Z,1554823319.969 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-04-09T15:22:00.074Z,1554823320.074 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-04-09T15:22:00.074Z,1554823320.074 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-04-09T15:22:00.155Z,1554823320.155 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-04-09T15:22:00.257Z,1554823320.257 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-04-09T15:22:00.257Z,1554823320.257 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-04-09T15:22:00.452Z,1554823320.452 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-04-09T15:22:00.453Z,1554823320.453 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-04-09T15:22:00.662Z,1554823320.662 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-04-09T15:22:00.664Z,1554823320.664 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-04-09T15:22:00.665Z,1554823320.665 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-04-09T15:22:00.747Z,1554823320.747 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-04-09T15:22:00.980Z,1554823320.980 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-04-09T15:22:00.981Z,1554823320.981 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-04-09T15:22:01.083Z,1554823321.083 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-04-09T15:22:01.543Z,1554823321.543 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-04-09T15:22:02.027Z,1554823322.027 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-04-09T15:22:02.120Z,1554823322.120 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-04-09T15:22:02.218Z,1554823322.218 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-04-09T15:22:02.311Z,1554823322.311 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-04-09T15:22:02.463Z,1554823322.463 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-04-09T15:22:02.567Z,1554823322.567 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-04-09T15:22:02.665Z,1554823322.665 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-04-09T15:22:02.669Z,1554823322.669 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-04-09T15:22:02.767Z,1554823322.767 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-04-09T15:22:02.768Z,1554823322.768 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-04-09T15:22:02.897Z,1554823322.897 [BuoyancyServo] Loaded
2019-04-09T15:22:02.897Z,1554823322.897 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-04-09T15:22:02.912Z,1554823322.912 [ElevatorServo] Loaded
2019-04-09T15:22:02.913Z,1554823322.913 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-04-09T15:22:02.928Z,1554823322.928 [MassServo] Loaded
2019-04-09T15:22:02.928Z,1554823322.928 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-04-09T15:22:02.943Z,1554823322.943 [RudderServo] Loaded
2019-04-09T15:22:02.943Z,1554823322.943 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-04-09T15:22:02.957Z,1554823322.957 [ThrusterServo] Loaded
2019-04-09T15:22:02.958Z,1554823322.958 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-04-09T15:22:02.958Z,1554823322.958 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-04-09T15:22:02.959Z,1554823322.959 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-04-09T15:22:03.093Z,1554823323.093 [SBIT](DEBUG): Construct Startup Built In Test.
2019-04-09T15:22:03.122Z,1554823323.122 [SBIT] Loaded
2019-04-09T15:22:03.122Z,1554823323.122 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-04-09T15:22:03.123Z,1554823323.123 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-04-09T15:22:03.151Z,1554823323.151 [IBIT] Loaded
2019-04-09T15:22:03.151Z,1554823323.151 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-04-09T15:22:03.154Z,1554823323.154 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-04-09T15:22:03.540Z,1554823323.540 [CBIT] Loaded
2019-04-09T15:22:03.540Z,1554823323.540 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-04-09T15:22:03.541Z,1554823323.541 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-04-09T15:22:03.541Z,1554823323.541 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-04-09T15:22:03.723Z,1554823323.723 [Aanderaa_O2] Loaded
2019-04-09T15:22:03.724Z,1554823323.724 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-04-09T15:22:03.733Z,1554823323.733 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-04-09T15:22:03.739Z,1554823323.739 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-04-09T15:22:03.740Z,1554823323.740 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-04-09T15:22:03.745Z,1554823323.745 [CTD_Seabird](INFO): created writer for : depth
2019-04-09T15:22:03.746Z,1554823323.746 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-04-09T15:22:03.751Z,1554823323.751 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-04-09T15:22:03.752Z,1554823323.752 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-04-09T15:22:03.757Z,1554823323.757 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-04-09T15:22:03.757Z,1554823323.757 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-04-09T15:22:03.763Z,1554823323.763 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-04-09T15:22:03.763Z,1554823323.763 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-04-09T15:22:03.769Z,1554823323.769 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-04-09T15:22:03.769Z,1554823323.769 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-04-09T15:22:03.774Z,1554823323.774 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-04-09T15:22:03.801Z,1554823323.801 [CTD_Seabird] Loaded
2019-04-09T15:22:03.801Z,1554823323.801 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-04-09T15:22:03.802Z,1554823323.802 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406654E0
2019-04-09T15:22:03.803Z,1554823323.803 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2687
2019-04-09T15:22:03.828Z,1554823323.828 [ESPComponent] Loaded
2019-04-09T15:22:03.829Z,1554823323.829 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-04-09T15:22:03.843Z,1554823323.843 [PAR_Licor] Loaded
2019-04-09T15:22:03.843Z,1554823323.843 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-04-09T15:22:03.891Z,1554823323.891 [WetLabsBB2FL] Loaded
2019-04-09T15:22:03.891Z,1554823323.891 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-04-09T15:22:03.892Z,1554823323.892 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406954E0
2019-04-09T15:22:03.892Z,1554823323.892 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2688
2019-04-09T15:22:03.893Z,1554823323.893 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-04-09T15:22:03.894Z,1554823323.894 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-04-09T15:22:03.934Z,1554823323.934 [DepthRateCalculator] Loaded
2019-04-09T15:22:03.934Z,1554823323.934 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-04-09T15:22:03.940Z,1554823323.940 [PitchRateCalculator] Loaded
2019-04-09T15:22:03.940Z,1554823323.940 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-04-09T15:22:03.957Z,1554823323.957 [SpeedCalculator] Loaded
2019-04-09T15:22:03.957Z,1554823323.957 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-04-09T15:22:03.979Z,1554823323.979 [TempGradientCalculator] Loaded
2019-04-09T15:22:03.979Z,1554823323.979 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-04-09T15:22:03.985Z,1554823323.985 [YawRateCalculator] Loaded
2019-04-09T15:22:03.985Z,1554823323.985 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-04-09T15:22:04.018Z,1554823324.018 [ElevatorOffsetCalculator] Loaded
2019-04-09T15:22:04.019Z,1554823324.019 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-04-09T15:22:04.019Z,1554823324.019 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-04-09T15:22:04.020Z,1554823324.020 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-04-09T15:22:04.046Z,1554823324.046 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-04-09T15:22:04.047Z,1554823324.047 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-04-09T15:22:04.150Z,1554823324.150 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-04-09T15:22:04.150Z,1554823324.150 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-04-09T15:22:04.209Z,1554823324.209 [DeadReckonUsingSpeedCalculator] Loaded
2019-04-09T15:22:04.210Z,1554823324.210 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-04-09T15:22:04.270Z,1554823324.270 [DeadReckonWithRespectToSeafloor] Loaded
2019-04-09T15:22:04.270Z,1554823324.270 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-04-09T15:22:04.291Z,1554823324.291 [NavChart] Loaded
2019-04-09T15:22:04.292Z,1554823324.292 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-04-09T15:22:04.296Z,1554823324.296 [UniversalFixResidualReporter] Loaded
2019-04-09T15:22:04.296Z,1554823324.296 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-04-09T15:22:04.297Z,1554823324.297 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-04-09T15:22:04.297Z,1554823324.297 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-04-09T15:22:04.607Z,1554823324.607 [AcousticModem_Benthos_ATM900] Loaded
2019-04-09T15:22:04.607Z,1554823324.607 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-04-09T15:22:04.680Z,1554823324.680 [DataOverHttps] Loaded
2019-04-09T15:22:04.680Z,1554823324.680 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-04-09T15:22:04.681Z,1554823324.681 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409714E0
2019-04-09T15:22:04.682Z,1554823324.682 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2689
2019-04-09T15:22:04.695Z,1554823324.695 [Depth_Keller] Loaded
2019-04-09T15:22:04.695Z,1554823324.695 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-04-09T15:22:04.787Z,1554823324.787 [NAL9602] Loaded
2019-04-09T15:22:04.787Z,1554823324.787 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-04-09T15:22:04.793Z,1554823324.793 [Onboard] Loaded
2019-04-09T15:22:04.793Z,1554823324.793 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-04-09T15:22:04.796Z,1554823324.796 [Radio_Surface] Loaded
2019-04-09T15:22:04.797Z,1554823324.797 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-04-09T15:22:04.798Z,1554823324.798 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A14E0
2019-04-09T15:22:04.798Z,1554823324.798 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2690
2019-04-09T15:22:06.090Z,1554823326.090 [BPC1] Loaded
2019-04-09T15:22:06.090Z,1554823326.090 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-04-09T15:22:06.121Z,1554823326.121 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minrange
2019-04-09T15:22:06.123Z,1554823326.123 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.maxrange
2019-04-09T15:22:06.125Z,1554823326.125 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.soundspeed
2019-04-09T15:22:06.127Z,1554823326.127 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.automode
2019-04-09T15:22:06.129Z,1554823326.129 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.gainindex
2019-04-09T15:22:06.131Z,1554823326.131 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minconfidence
2019-04-09T15:22:06.133Z,1554823326.133 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.pingenable
2019-04-09T15:22:06.136Z,1554823326.136 [BR_Ping1D] Loaded
2019-04-09T15:22:06.136Z,1554823326.136 [ComponentRegistry](DEBUG): SyncComponent "BR_Ping1D" handled in the control thread.
2019-04-09T15:22:06.136Z,1554823326.136 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-04-09T15:22:06.137Z,1554823326.137 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-04-09T15:22:06.329Z,1554823326.329 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-04-09T15:22:06.329Z,1554823326.329 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-04-09T15:22:06.465Z,1554823326.465 [VerticalControl](DEBUG): Construct VerticalControl.
2019-04-09T15:22:06.550Z,1554823326.550 [VerticalControl] Loaded
2019-04-09T15:22:06.550Z,1554823326.550 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-04-09T15:22:06.551Z,1554823326.551 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-04-09T15:22:06.609Z,1554823326.609 [HorizontalControl] Loaded
2019-04-09T15:22:06.609Z,1554823326.609 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-04-09T15:22:06.610Z,1554823326.610 [SpeedControl](DEBUG): Construct SpeedControl.
2019-04-09T15:22:06.612Z,1554823326.612 [SpeedControl] Loaded
2019-04-09T15:22:06.612Z,1554823326.612 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-04-09T15:22:06.613Z,1554823326.613 [LoopControl](DEBUG): Construct LoopControl.
2019-04-09T15:22:06.613Z,1554823326.613 [LoopControl] Loaded
2019-04-09T15:22:06.613Z,1554823326.613 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-04-09T15:22:06.614Z,1554823326.614 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-04-09T15:22:06.615Z,1554823326.615 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-04-09T15:22:06.628Z,1554823326.628 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-04-09T15:22:06.628Z,1554823326.628 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-04-09T15:22:06.903Z,1554823326.903 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-04-09T15:22:06.907Z,1554823326.907 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-04-09T15:22:06.907Z,1554823326.907 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-04-09T15:22:06.914Z,1554823326.914 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-04-09T15:22:06.915Z,1554823326.915 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACE4E0
2019-04-09T15:22:06.916Z,1554823326.916 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2691
2019-04-09T15:22:06.921Z,1554823326.921 [Supervisor](INFO): Main Thread ID is 4347
2019-04-09T15:22:06.921Z,1554823326.921 [Supervisor](DEBUG): Running supervisor.
2019-04-09T15:22:06.922Z,1554823326.922 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2692
2019-04-09T15:22:06.924Z,1554823326.924 [controlThread ThreadHandler](INFO): Handler Thread ID is 2693
2019-04-09T15:22:06.924Z,1554823326.924 [controlThread](DEBUG): Initializing ControlThread
2019-04-09T15:22:06.926Z,1554823326.926 [SBIT](INFO): Initialize SBIT Component.
2019-04-09T15:22:06.927Z,1554823326.927 [SBIT](IMPORTANT): git: 2019-03-18-31-ge98d5dc
2019-04-09T15:22:06.927Z,1554823326.927 [SBIT](INFO): git hash: e98d5dc50cbc39e402f19b9946ff4dc0776c8725
2019-04-09T15:22:06.927Z,1554823326.927 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-04-09T15:22:06.929Z,1554823326.929 [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-09T15:22:06.930Z,1554823326.930 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-04-09T15:22:06.930Z,1554823326.930 [IBIT](INFO): Initialize IBIT Component.
2019-04-09T15:22:06.931Z,1554823326.931 [CBIT](DEBUG): Initialize CBIT Component.
2019-04-09T15:22:06.932Z,1554823326.932 [logger ThreadHandler](INFO): Handler Thread ID is 2694
2019-04-09T15:22:06.942Z,1554823326.942 [CBIT](DEBUG): Initialized mux pins.
2019-04-09T15:22:06.942Z,1554823326.942 [CBIT](DEBUG): Initializing the watchdog timer.
2019-04-09T15:22:06.950Z,1554823326.950 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2695
2019-04-09T15:22:06.951Z,1554823326.951 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T15:22:06.955Z,1554823326.955 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T15:22:06.956Z,1554823326.956 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2697
2019-04-09T15:22:06.957Z,1554823326.957 [WetLabsBB2FL](INFO): Powering down
2019-04-09T15:22:06.966Z,1554823326.966 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-04-09T15:22:06.966Z,1554823326.966 [CBIT](DEBUG): Initializing heartbeat.
2019-04-09T15:22:06.986Z,1554823326.986 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2698
2019-04-09T15:22:06.987Z,1554823326.987 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-04-09T15:22:06.996Z,1554823326.996 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2699
2019-04-09T15:22:07.006Z,1554823327.006 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2700
2019-04-09T15:22:07.010Z,1554823327.010 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-09T15:22:07.010Z,1554823327.010 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-04-09T15:22:07.010Z,1554823327.010 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-09T15:22:07.010Z,1554823327.010 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-04-09T15:22:07.010Z,1554823327.010 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-09T15:22:07.011Z,1554823327.011 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-04-09T15:22:07.011Z,1554823327.011 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-09T15:22:07.011Z,1554823327.011 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-04-09T15:22:07.011Z,1554823327.011 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-09T15:22:07.011Z,1554823327.011 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-04-09T15:22:07.012Z,1554823327.012 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-09T15:22:07.012Z,1554823327.012 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-04-09T15:22:07.012Z,1554823327.012 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-09T15:22:07.012Z,1554823327.012 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-04-09T15:22:07.012Z,1554823327.012 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-09T15:22:07.012Z,1554823327.012 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-04-09T15:22:07.038Z,1554823327.038 [CBIT](DEBUG): Deactivating GF circuits.
2019-04-09T15:22:07.038Z,1554823327.038 [CBIT](DEBUG): Deactivating emergency mode.
2019-04-09T15:22:07.074Z,1554823327.074 [CBIT](DEBUG): Backplane powered.
2019-04-09T15:22:07.078Z,1554823327.078 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-04-09T15:22:07.079Z,1554823327.079 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-04-09T15:22:07.079Z,1554823327.079 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-04-09T15:22:07.079Z,1554823327.079 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-04-09T15:22:07.080Z,1554823327.080 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-04-09T15:22:07.080Z,1554823327.080 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-04-09T15:22:07.081Z,1554823327.081 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T15:22:07.082Z,1554823327.082 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-04-09T15:22:07.083Z,1554823327.083 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-04-09T15:22:07.087Z,1554823327.087 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-04-09T15:22:07.089Z,1554823327.089 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-04-09T15:22:07.090Z,1554823327.090 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-04-09T15:22:07.090Z,1554823327.090 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-04-09T15:22:07.091Z,1554823327.091 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-04-09T15:22:07.117Z,1554823327.117 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T15:22:07.153Z,1554823327.153 [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-09T15:22:07.153Z,1554823327.153 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-04-09T15:22:07.224Z,1554823327.224 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-04-09T15:22:07.226Z,1554823327.226 [Default:A.Wait](DEBUG): Construct Wait.
2019-04-09T15:22:07.227Z,1554823327.227 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T15:22:07.274Z,1554823327.274 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-04-09T15:22:07.276Z,1554823327.276 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-04-09T15:22:07.298Z,1554823327.298 [Default:E.Execute](DEBUG): Construct Execute.
2019-04-09T15:22:07.301Z,1554823327.301 [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-09T15:22:07.316Z,1554823327.316 [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-09T15:22:07.339Z,1554823327.339 [ESPComponent](INFO): powering down ESP
2019-04-09T15:22:07.398Z,1554823327.398 [Radio_Surface](INFO): Powering up
2019-04-09T15:22:07.782Z,1554823327.782 [PAR_Licor](INFO): A/D timeout, 8 tries over 147 ms

2019-04-09T15:22:07.782Z,1554823327.782 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T15:22:07.783Z,1554823327.783 [PAR_Licor](ERROR): Data Fault
2019-04-09T15:22:07.783Z,1554823327.783 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T15:22:07.783Z,1554823327.783 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T15:22:07.000Z,1554823328.000 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:22:08.006Z,1554823328.006 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-04-09T15:22:08.008Z,1554823328.008 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:22:08.014Z,1554823328.014 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-09T15:22:08.015Z,1554823328.015 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:22:08.022Z,1554823328.022 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T15:22:08.023Z,1554823328.023 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:22:08.030Z,1554823328.030 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-09T15:22:08.031Z,1554823328.031 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:22:08.038Z,1554823328.038 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-04-09T15:22:08.041Z,1554823328.041 [CommandLine](FAULT): Scheduling is paused
2019-04-09T15:22:08.041Z,1554823328.041 [CBIT](INFO): Critical error at 20190409T152206
2019-04-09T15:22:08.041Z,1554823328.041 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-04-09T15:22:08.042Z,1554823328.042 [CBIT](FAULT): Main Battery Failure. Count: 1
2019-04-09T15:22:08.046Z,1554823328.046 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T15:22:08.046Z,1554823328.046 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T15:22:08.046Z,1554823328.046 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T15:22:08.582Z,1554823328.582 [PAR_Licor](INFO): A/D timeout, 9 tries over 126 ms

2019-04-09T15:22:08.582Z,1554823328.582 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T15:22:08.582Z,1554823328.582 [PAR_Licor](ERROR): Data Fault
2019-04-09T15:22:08.613Z,1554823328.613 [BR_Ping1D](INFO): startup: 0
2019-04-09T15:22:08.613Z,1554823328.613 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T15:22:08.626Z,1554823328.626 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T15:22:08.626Z,1554823328.626 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T15:22:08.627Z,1554823328.627 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T15:22:08.627Z,1554823328.627 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T15:22:08.958Z,1554823328.958 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-04-09T15:22:08.958Z,1554823328.958 [RudderServo](FAULT): Rudder failed to initialize
2019-04-09T15:22:08.958Z,1554823328.958 [RudderServo] Communications Fault, FailCount= 1
2019-04-09T15:22:08.958Z,1554823328.958 [RudderServo](ERROR): Communications Fault
2019-04-09T15:22:09.069Z,1554823329.069 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-04-09T15:22:09.070Z,1554823329.070 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T15:22:09.070Z,1554823329.070 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T15:22:09.070Z,1554823329.070 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T15:22:09.113Z,1554823329.113 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:09.113Z,1554823329.113 [BR_Ping1D](INFO): readConfig
2019-04-09T15:22:09.114Z,1554823329.114 [BR_Ping1D](INFO): startup: 1
2019-04-09T15:22:09.114Z,1554823329.114 [BR_Ping1D](INFO): UART requesting status
2019-04-09T15:22:09.275Z,1554823329.275 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-09T15:22:09.275Z,1554823329.275 [RudderServo](INFO): Powering down
2019-04-09T15:22:09.499Z,1554823329.499 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:09.499Z,1554823329.499 [BR_Ping1D](INFO): readConfig
2019-04-09T15:22:09.500Z,1554823329.500 [BR_Ping1D](INFO): startup: 2
2019-04-09T15:22:09.500Z,1554823329.500 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T15:22:09.500Z,1554823329.500 [BR_Ping1D](INFO): elapsed 0.8865
2019-04-09T15:22:09.500Z,1554823329.500 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T15:22:09.501Z,1554823329.501 [BR_Ping1D](INFO): received full packet, type: 1210
2019-04-09T15:22:09.501Z,1554823329.501 [BR_Ping1D](INFO): parsing data, res: 20
2019-04-09T15:22:09.501Z,1554823329.501 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-09T15:22:09.501Z,1554823329.501 [BR_Ping1D](INFO):  voltage: 5054
2019-04-09T15:22:09.502Z,1554823329.502 [BR_Ping1D](INFO):  interval: 7681
2019-04-09T15:22:09.502Z,1554823329.502 [BR_Ping1D](INFO):  gain: 1
2019-04-09T15:22:09.502Z,1554823329.502 [BR_Ping1D](INFO):  auto: 1
2019-04-09T15:22:09.502Z,1554823329.502 [BR_Ping1D](INFO): received full packet, type: 1215
2019-04-09T15:22:09.503Z,1554823329.503 [BR_Ping1D](INFO): CRC error in received UART packet
2019-04-09T15:22:09.503Z,1554823329.503 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T15:22:09.503Z,1554823329.503 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-04-09T15:22:09.503Z,1554823329.503 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-04-09T15:22:09.503Z,1554823329.503 [BR_Ping1D](INFO): (ping interval) 500 <-> 0
2019-04-09T15:22:09.504Z,1554823329.504 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-04-09T15:22:09.504Z,1554823329.504 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 65535
2019-04-09T15:22:09.504Z,1554823329.504 [BR_Ping1D](INFO): (ping enable) 1 <-> 0
2019-04-09T15:22:10.014Z,1554823330.014 [PAR_Licor](INFO): A/D timeout, 8 tries over 130 ms

2019-04-09T15:22:10.014Z,1554823330.014 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T15:22:10.014Z,1554823330.014 [PAR_Licor](ERROR): Data Fault
2019-04-09T15:22:10.054Z,1554823330.054 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:10.055Z,1554823330.055 [BR_Ping1D](INFO): readConfig
2019-04-09T15:22:10.055Z,1554823330.055 [BR_Ping1D](INFO): startup: 0
2019-04-09T15:22:10.055Z,1554823330.055 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T15:22:10.066Z,1554823330.066 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T15:22:10.066Z,1554823330.066 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T15:22:10.066Z,1554823330.066 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T15:22:10.067Z,1554823330.067 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T15:22:10.108Z,1554823330.108 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:22:10.227Z,1554823330.227 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-09T15:22:10.232Z,1554823330.232 [CBIT](INFO): Clearing failed state for component RudderServo
2019-04-09T15:22:10.232Z,1554823330.232 [RudderServo] No Fault, FailCount= 1
2019-04-09T15:22:10.232Z,1554823330.232 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T15:22:10.232Z,1554823330.232 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T15:22:10.232Z,1554823330.232 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T15:22:10.310Z,1554823330.310 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:10.666Z,1554823330.666 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T15:22:10.666Z,1554823330.666 [MassServo] Communications Fault, FailCount= 1
2019-04-09T15:22:10.666Z,1554823330.666 [MassServo](ERROR): Communications Fault
2019-04-09T15:22:10.693Z,1554823330.693 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T15:22:10.733Z,1554823330.733 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:10.733Z,1554823330.733 [BR_Ping1D](INFO): readConfig
2019-04-09T15:22:10.734Z,1554823330.734 [BR_Ping1D](INFO): startup: 1
2019-04-09T15:22:10.734Z,1554823330.734 [BR_Ping1D](INFO): UART requesting status
2019-04-09T15:22:10.826Z,1554823330.826 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T15:22:10.826Z,1554823330.826 [MassServo](INFO): Powering down
2019-04-09T15:22:11.125Z,1554823331.125 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:11.125Z,1554823331.125 [BR_Ping1D](INFO): readConfig
2019-04-09T15:22:11.126Z,1554823331.126 [BR_Ping1D](INFO): startup: 2
2019-04-09T15:22:11.126Z,1554823331.126 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T15:22:11.126Z,1554823331.126 [BR_Ping1D](INFO): elapsed 1.0712
2019-04-09T15:22:11.126Z,1554823331.126 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T15:22:11.127Z,1554823331.127 [BR_Ping1D](INFO): received full packet, type: 1210
2019-04-09T15:22:11.127Z,1554823331.127 [BR_Ping1D](INFO): parsing data, res: 20
2019-04-09T15:22:11.127Z,1554823331.127 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-09T15:22:11.127Z,1554823331.127 [BR_Ping1D](INFO):  voltage: 5056
2019-04-09T15:22:11.128Z,1554823331.128 [BR_Ping1D](INFO):  interval: 7681
2019-04-09T15:22:11.128Z,1554823331.128 [BR_Ping1D](INFO):  gain: 1
2019-04-09T15:22:11.128Z,1554823331.128 [BR_Ping1D](INFO):  auto: 1
2019-04-09T15:22:11.128Z,1554823331.128 [BR_Ping1D](INFO): received full packet, type: 1215
2019-04-09T15:22:11.128Z,1554823331.128 [BR_Ping1D](INFO): CRC error in received UART packet
2019-04-09T15:22:11.129Z,1554823331.129 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T15:22:11.129Z,1554823331.129 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-04-09T15:22:11.129Z,1554823331.129 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-04-09T15:22:11.129Z,1554823331.129 [BR_Ping1D](INFO): (ping interval) 500 <-> 0
2019-04-09T15:22:11.129Z,1554823331.129 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-04-09T15:22:11.130Z,1554823331.130 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 65535
2019-04-09T15:22:11.130Z,1554823331.130 [BR_Ping1D](INFO): (ping enable) 1 <-> 0
2019-04-09T15:22:11.642Z,1554823331.642 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-09T15:22:11.642Z,1554823331.642 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T15:22:11.642Z,1554823331.642 [PAR_Licor](ERROR): Data Fault
2019-04-09T15:22:11.663Z,1554823331.663 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:11.663Z,1554823331.663 [BR_Ping1D](INFO): readConfig
2019-04-09T15:22:11.663Z,1554823331.663 [BR_Ping1D](INFO): startup: 0
2019-04-09T15:22:11.663Z,1554823331.663 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T15:22:11.674Z,1554823331.674 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T15:22:11.674Z,1554823331.674 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T15:22:11.674Z,1554823331.674 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T15:22:11.675Z,1554823331.675 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T15:22:11.776Z,1554823331.776 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:22:11.895Z,1554823331.895 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T15:22:12.062Z,1554823332.062 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T15:22:12.062Z,1554823332.062 [MassServo] No Fault, FailCount= 1
2019-04-09T15:22:12.062Z,1554823332.062 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T15:22:12.063Z,1554823332.063 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T15:22:12.063Z,1554823332.063 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T15:22:12.105Z,1554823332.105 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:12.137Z,1554823332.137 [CBIT](FAULT): Main Battery Failure. Count: 11
2019-04-09T15:22:12.610Z,1554823332.610 [PAR_Licor](INFO): A/D timeout, 9 tries over 138 ms

2019-04-09T15:22:12.610Z,1554823332.610 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T15:22:12.610Z,1554823332.610 [PAR_Licor](ERROR): Data Fault
2019-04-09T15:22:12.628Z,1554823332.628 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:12.628Z,1554823332.628 [BR_Ping1D](INFO): readConfig
2019-04-09T15:22:12.629Z,1554823332.629 [BR_Ping1D](INFO): startup: 1
2019-04-09T15:22:12.629Z,1554823332.629 [BR_Ping1D](INFO): UART requesting status
2019-04-09T15:22:12.718Z,1554823332.718 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T15:22:12.718Z,1554823332.718 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T15:22:12.718Z,1554823332.718 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T15:22:12.897Z,1554823332.897 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:12.898Z,1554823332.898 [BR_Ping1D](INFO): readConfig
2019-04-09T15:22:12.898Z,1554823332.898 [BR_Ping1D](INFO): startup: 2
2019-04-09T15:22:12.898Z,1554823332.898 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T15:22:12.898Z,1554823332.898 [BR_Ping1D](INFO): elapsed 1.2351
2019-04-09T15:22:12.899Z,1554823332.899 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T15:22:12.899Z,1554823332.899 [BR_Ping1D](INFO): received full packet, type: 1210
2019-04-09T15:22:12.899Z,1554823332.899 [BR_Ping1D](INFO): parsing data, res: 20
2019-04-09T15:22:12.899Z,1554823332.899 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-09T15:22:12.900Z,1554823332.900 [BR_Ping1D](INFO):  voltage: 5054
2019-04-09T15:22:12.900Z,1554823332.900 [BR_Ping1D](INFO):  interval: 7681
2019-04-09T15:22:12.900Z,1554823332.900 [BR_Ping1D](INFO):  gain: 1
2019-04-09T15:22:12.900Z,1554823332.900 [BR_Ping1D](INFO):  auto: 1
2019-04-09T15:22:12.900Z,1554823332.900 [BR_Ping1D](INFO): received full packet, type: 1215
2019-04-09T15:22:12.901Z,1554823332.901 [BR_Ping1D](INFO): CRC error in received UART packet
2019-04-09T15:22:12.901Z,1554823332.901 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T15:22:12.901Z,1554823332.901 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-04-09T15:22:12.901Z,1554823332.901 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-04-09T15:22:12.901Z,1554823332.901 [BR_Ping1D](INFO): (ping interval) 500 <-> 0
2019-04-09T15:22:12.902Z,1554823332.902 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-04-09T15:22:12.902Z,1554823332.902 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 65535
2019-04-09T15:22:12.902Z,1554823332.902 [BR_Ping1D](INFO): (ping enable) 1 <-> 0
2019-04-09T15:22:13.414Z,1554823333.414 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-09T15:22:13.414Z,1554823333.414 [PAR_Licor] Data Fault, FailCount= 3
2019-04-09T15:22:13.414Z,1554823333.414 [PAR_Licor](ERROR): Data Fault
2019-04-09T15:22:13.454Z,1554823333.454 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:13.454Z,1554823333.454 [BR_Ping1D](INFO): readConfig
2019-04-09T15:22:13.455Z,1554823333.455 [BR_Ping1D](INFO): startup: 0
2019-04-09T15:22:13.455Z,1554823333.455 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T15:22:13.466Z,1554823333.466 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T15:22:13.466Z,1554823333.466 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T15:22:13.466Z,1554823333.466 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T15:22:13.467Z,1554823333.467 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T15:22:13.554Z,1554823333.554 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T15:22:13.555Z,1554823333.555 [CBIT](CRITICAL): Data Fault in component: PAR_Licor
2019-04-09T15:22:13.701Z,1554823333.701 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:14.036Z,1554823334.036 [CBIT](INFO): Critical error at 20190409T152213
2019-04-09T15:22:14.103Z,1554823334.103 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:14.103Z,1554823334.103 [BR_Ping1D](INFO): readConfig
2019-04-09T15:22:14.103Z,1554823334.103 [BR_Ping1D](INFO): startup: 1
2019-04-09T15:22:14.103Z,1554823334.103 [BR_Ping1D](INFO): UART requesting status
2019-04-09T15:22:14.434Z,1554823334.434 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T15:22:14.434Z,1554823334.434 [MassServo] Communications Fault, FailCount= 2
2019-04-09T15:22:14.434Z,1554823334.434 [MassServo](ERROR): Communications Fault
2019-04-09T15:22:14.438Z,1554823334.438 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T15:22:14.509Z,1554823334.509 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:14.509Z,1554823334.509 [BR_Ping1D](INFO): readConfig
2019-04-09T15:22:14.510Z,1554823334.510 [BR_Ping1D](INFO): startup: 2
2019-04-09T15:22:14.510Z,1554823334.510 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T15:22:14.510Z,1554823334.510 [BR_Ping1D](INFO): elapsed 1.0552
2019-04-09T15:22:14.510Z,1554823334.510 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T15:22:14.511Z,1554823334.511 [BR_Ping1D](INFO): received full packet, type: 1210
2019-04-09T15:22:14.511Z,1554823334.511 [BR_Ping1D](INFO): parsing data, res: 20
2019-04-09T15:22:14.511Z,1554823334.511 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-09T15:22:14.511Z,1554823334.511 [BR_Ping1D](INFO):  voltage: 5056
2019-04-09T15:22:14.512Z,1554823334.512 [BR_Ping1D](INFO):  interval: 7681
2019-04-09T15:22:14.512Z,1554823334.512 [BR_Ping1D](INFO):  gain: 1
2019-04-09T15:22:14.512Z,1554823334.512 [BR_Ping1D](INFO):  auto: 1
2019-04-09T15:22:14.512Z,1554823334.512 [BR_Ping1D](INFO): received full packet, type: 1215
2019-04-09T15:22:14.512Z,1554823334.512 [BR_Ping1D](INFO): CRC error in received UART packet
2019-04-09T15:22:14.513Z,1554823334.513 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T15:22:14.513Z,1554823334.513 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-04-09T15:22:14.513Z,1554823334.513 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-04-09T15:22:14.513Z,1554823334.513 [BR_Ping1D](INFO): (ping interval) 500 <-> 0
2019-04-09T15:22:14.513Z,1554823334.513 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-04-09T15:22:14.514Z,1554823334.514 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 65535
2019-04-09T15:22:14.514Z,1554823334.514 [BR_Ping1D](INFO): (ping enable) 1 <-> 0
2019-04-09T15:22:14.514Z,1554823334.514 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-04-09T15:22:14.514Z,1554823334.514 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-09T15:22:14.514Z,1554823334.514 [BR_Ping1D](ERROR): Data Fault
2019-04-09T15:22:14.547Z,1554823334.547 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T15:22:14.547Z,1554823334.547 [MassServo](INFO): Powering down
2019-04-09T15:22:14.557Z,1554823334.557 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T15:22:14.913Z,1554823334.913 [BR_Ping1D](INFO): Stop
2019-04-09T15:22:14.914Z,1554823334.914 [BR_Ping1D](INFO): uninitialize
2019-04-09T15:22:14.914Z,1554823334.914 [BR_Ping1D](INFO): Powering down
2019-04-09T15:22:15.317Z,1554823335.317 [BR_Ping1D](INFO): Stopping
2019-04-09T15:22:15.327Z,1554823335.327 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:22:15.447Z,1554823335.447 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T15:22:15.451Z,1554823335.451 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T15:22:15.451Z,1554823335.451 [MassServo] No Fault, FailCount= 2
2019-04-09T15:22:15.721Z,1554823335.721 [BR_Ping1D](INFO): Stopping
2019-04-09T15:22:16.125Z,1554823336.125 [BR_Ping1D](INFO): Stopped
2019-04-09T15:22:16.156Z,1554823336.156 [CBIT](FAULT): Main Battery Failure. Count: 21
2019-04-09T15:22:16.159Z,1554823336.159 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T15:22:16.159Z,1554823336.159 [BR_Ping1D] No Fault, FailCount= 1
2019-04-09T15:22:16.531Z,1554823336.531 [BR_Ping1D](INFO): Start
2019-04-09T15:22:16.941Z,1554823336.941 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:16.975Z,1554823336.975 [CBIT](CRITICAL): Environmental Failure. Press:14.866898 PSI. Humidity:35%. Temp:26 C. ABORTING MISSION
2019-04-09T15:22:17.338Z,1554823337.338 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:17.338Z,1554823337.338 [BR_Ping1D](INFO): readConfig
2019-04-09T15:22:17.338Z,1554823337.338 [BR_Ping1D](INFO): startup: 0
2019-04-09T15:22:17.339Z,1554823337.339 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T15:22:17.350Z,1554823337.350 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T15:22:17.350Z,1554823337.350 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T15:22:17.350Z,1554823337.350 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T15:22:17.351Z,1554823337.351 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T15:22:17.380Z,1554823337.380 [CBIT](INFO): Critical error at 20190409T152216
2019-04-09T15:22:17.722Z,1554823337.722 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T15:22:17.722Z,1554823337.722 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T15:22:17.722Z,1554823337.722 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T15:22:17.741Z,1554823337.741 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:18.062Z,1554823338.062 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T15:22:18.062Z,1554823338.062 [MassServo] Communications Fault, FailCount= 3
2019-04-09T15:22:18.062Z,1554823338.062 [MassServo](ERROR): Communications Fault
2019-04-09T15:22:18.067Z,1554823338.067 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T15:22:18.067Z,1554823338.067 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T15:22:18.246Z,1554823338.246 [Aanderaa_O2](INFO): Powering down
2019-04-09T15:22:18.312Z,1554823338.312 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:18.312Z,1554823338.312 [BR_Ping1D](INFO): readConfig
2019-04-09T15:22:18.312Z,1554823338.312 [BR_Ping1D](INFO): startup: 1
2019-04-09T15:22:18.312Z,1554823338.312 [BR_Ping1D](INFO): UART requesting status
2019-04-09T15:22:18.354Z,1554823338.354 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T15:22:18.355Z,1554823338.355 [MassServo](INFO): Powering down
2019-04-09T15:22:18.549Z,1554823338.549 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:18.549Z,1554823338.549 [BR_Ping1D](INFO): readConfig
2019-04-09T15:22:18.551Z,1554823338.551 [BR_Ping1D](INFO): startup: 2
2019-04-09T15:22:18.551Z,1554823338.551 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T15:22:18.551Z,1554823338.551 [BR_Ping1D](INFO): elapsed 1.2122
2019-04-09T15:22:18.551Z,1554823338.551 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T15:22:18.552Z,1554823338.552 [BR_Ping1D](INFO): received full packet, type: 1210
2019-04-09T15:22:18.552Z,1554823338.552 [BR_Ping1D](INFO): parsing data, res: 20
2019-04-09T15:22:18.552Z,1554823338.552 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-09T15:22:18.552Z,1554823338.552 [BR_Ping1D](INFO):  voltage: 5056
2019-04-09T15:22:18.552Z,1554823338.552 [BR_Ping1D](INFO):  interval: 7681
2019-04-09T15:22:18.552Z,1554823338.552 [BR_Ping1D](INFO):  gain: 1
2019-04-09T15:22:18.553Z,1554823338.553 [BR_Ping1D](INFO):  auto: 1
2019-04-09T15:22:18.553Z,1554823338.553 [BR_Ping1D](INFO): received full packet, type: 1215
2019-04-09T15:22:18.553Z,1554823338.553 [BR_Ping1D](INFO): CRC error in received UART packet
2019-04-09T15:22:18.553Z,1554823338.553 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T15:22:18.553Z,1554823338.553 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-04-09T15:22:18.553Z,1554823338.553 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-04-09T15:22:18.554Z,1554823338.554 [BR_Ping1D](INFO): (ping interval) 500 <-> 0
2019-04-09T15:22:18.554Z,1554823338.554 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-04-09T15:22:18.554Z,1554823338.554 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 65535
2019-04-09T15:22:18.555Z,1554823338.555 [BR_Ping1D](INFO): (ping enable) 1 <-> 0
2019-04-09T15:22:18.555Z,1554823338.555 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-04-09T15:22:18.555Z,1554823338.555 [BR_Ping1D] Data Fault, FailCount= 2
2019-04-09T15:22:18.555Z,1554823338.555 [BR_Ping1D](ERROR): Data Fault
2019-04-09T15:22:18.587Z,1554823338.587 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T15:22:18.954Z,1554823338.954 [BR_Ping1D](INFO): Stop
2019-04-09T15:22:18.954Z,1554823338.954 [BR_Ping1D](INFO): uninitialize
2019-04-09T15:22:18.954Z,1554823338.954 [BR_Ping1D](INFO): Powering down
2019-04-09T15:22:19.039Z,1554823339.039 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:22:19.159Z,1554823339.159 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T15:22:19.163Z,1554823339.163 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T15:22:19.163Z,1554823339.163 [MassServo] No Fault, FailCount= 3
2019-04-09T15:22:19.164Z,1554823339.164 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T15:22:19.164Z,1554823339.164 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-09T15:22:19.357Z,1554823339.357 [BR_Ping1D](INFO): Stopping
2019-04-09T15:22:19.774Z,1554823339.773 [BR_Ping1D](INFO): Stopping
2019-04-09T15:22:20.002Z,1554823340.002 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T15:22:20.002Z,1554823340.002 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-09T15:22:20.002Z,1554823340.002 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T15:22:20.165Z,1554823340.165 [BR_Ping1D](INFO): Stopped
2019-04-09T15:22:20.210Z,1554823340.210 [CBIT](FAULT): Main Battery Failure. Count: 31
2019-04-09T15:22:20.212Z,1554823340.212 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T15:22:20.213Z,1554823340.213 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T15:22:20.213Z,1554823340.213 [BR_Ping1D] No Fault, FailCount= 2
2019-04-09T15:22:20.406Z,1554823340.406 [CTD_Seabird](INFO): Powering down
2019-04-09T15:22:20.570Z,1554823340.570 [BR_Ping1D](INFO): Start
2019-04-09T15:22:20.974Z,1554823340.974 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:21.378Z,1554823341.378 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:21.378Z,1554823341.378 [BR_Ping1D](INFO): readConfig
2019-04-09T15:22:21.378Z,1554823341.378 [BR_Ping1D](INFO): startup: 0
2019-04-09T15:22:21.379Z,1554823341.379 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T15:22:21.390Z,1554823341.390 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T15:22:21.390Z,1554823341.390 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T15:22:21.390Z,1554823341.390 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T15:22:21.391Z,1554823341.391 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T15:22:21.718Z,1554823341.718 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T15:22:21.718Z,1554823341.718 [MassServo] Communications Fault, FailCount= 4
2019-04-09T15:22:21.718Z,1554823341.718 [MassServo](ERROR): Communications Fault
2019-04-09T15:22:21.722Z,1554823341.722 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T15:22:21.723Z,1554823341.723 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T15:22:21.723Z,1554823341.723 [CTD_Seabird] No Fault, FailCount= 1
2019-04-09T15:22:21.770Z,1554823341.770 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T15:22:21.776Z,1554823341.776 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T15:22:21.783Z,1554823341.783 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:21.795Z,1554823341.795 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T15:22:21.795Z,1554823341.795 [MassServo](INFO): Powering down
2019-04-09T15:22:22.194Z,1554823342.194 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:22.194Z,1554823342.194 [BR_Ping1D](INFO): readConfig
2019-04-09T15:22:22.194Z,1554823342.194 [BR_Ping1D](INFO): startup: 1
2019-04-09T15:22:22.194Z,1554823342.194 [BR_Ping1D](INFO): UART requesting status
2019-04-09T15:22:22.590Z,1554823342.590 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:22.590Z,1554823342.590 [BR_Ping1D](INFO): readConfig
2019-04-09T15:22:22.590Z,1554823342.590 [BR_Ping1D](INFO): startup: 2
2019-04-09T15:22:22.591Z,1554823342.591 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T15:22:22.591Z,1554823342.591 [BR_Ping1D](INFO): elapsed 1.2120
2019-04-09T15:22:22.591Z,1554823342.591 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T15:22:22.592Z,1554823342.592 [BR_Ping1D](INFO): received full packet, type: 1215
2019-04-09T15:22:22.592Z,1554823342.592 [BR_Ping1D](INFO): CRC error in received UART packet
2019-04-09T15:22:22.593Z,1554823342.593 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T15:22:22.593Z,1554823342.593 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-04-09T15:22:22.593Z,1554823342.593 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-04-09T15:22:22.593Z,1554823342.593 [BR_Ping1D](INFO): (ping interval) 500 <-> 0
2019-04-09T15:22:22.593Z,1554823342.593 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-04-09T15:22:22.594Z,1554823342.594 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 65535
2019-04-09T15:22:22.594Z,1554823342.594 [BR_Ping1D](INFO): (ping enable) 1 <-> 0
2019-04-09T15:22:22.594Z,1554823342.594 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-04-09T15:22:22.594Z,1554823342.594 [BR_Ping1D] Data Fault, FailCount= 3
2019-04-09T15:22:22.594Z,1554823342.594 [BR_Ping1D](ERROR): Data Fault
2019-04-09T15:22:22.607Z,1554823342.607 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:22:22.727Z,1554823342.727 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T15:22:22.731Z,1554823342.731 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T15:22:22.731Z,1554823342.731 [MassServo] No Fault, FailCount= 4
2019-04-09T15:22:22.732Z,1554823342.732 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T15:22:22.994Z,1554823342.994 [BR_Ping1D](INFO): Stop
2019-04-09T15:22:22.994Z,1554823342.994 [BR_Ping1D](INFO): uninitialize
2019-04-09T15:22:22.994Z,1554823342.994 [BR_Ping1D](INFO): Powering down
2019-04-09T15:22:23.398Z,1554823343.398 [BR_Ping1D](INFO): Stopping
2019-04-09T15:22:23.802Z,1554823343.802 [BR_Ping1D](INFO): Stopping
2019-04-09T15:22:24.206Z,1554823344.206 [BR_Ping1D](INFO): Stopped
2019-04-09T15:22:24.221Z,1554823344.221 [CBIT](FAULT): Main Battery Failure. Count: 41
2019-04-09T15:22:24.223Z,1554823344.223 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T15:22:24.223Z,1554823344.223 [BR_Ping1D] No Fault, FailCount= 3
2019-04-09T15:22:24.611Z,1554823344.611 [BR_Ping1D](INFO): Start
2019-04-09T15:22:25.014Z,1554823345.014 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:25.338Z,1554823345.338 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T15:22:25.338Z,1554823345.338 [MassServo] Communications Fault, FailCount= 5
2019-04-09T15:22:25.338Z,1554823345.338 [MassServo](ERROR): Communications Fault
2019-04-09T15:22:25.343Z,1554823345.343 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T15:22:25.343Z,1554823345.343 [CBIT](CRITICAL): Communications Fault in component: MassServo
2019-04-09T15:22:25.418Z,1554823345.418 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:25.418Z,1554823345.418 [BR_Ping1D](INFO): readConfig
2019-04-09T15:22:25.419Z,1554823345.419 [BR_Ping1D](INFO): startup: 0
2019-04-09T15:22:25.419Z,1554823345.419 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T15:22:25.430Z,1554823345.430 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T15:22:25.430Z,1554823345.430 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T15:22:25.430Z,1554823345.430 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T15:22:25.431Z,1554823345.431 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T15:22:25.488Z,1554823345.488 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T15:22:25.488Z,1554823345.488 [MassServo](INFO): Powering down
2019-04-09T15:22:25.491Z,1554823345.491 [CBIT](INFO): Critical error at 20190409T152225
2019-04-09T15:22:25.823Z,1554823345.823 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:26.226Z,1554823346.226 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:26.226Z,1554823346.226 [BR_Ping1D](INFO): readConfig
2019-04-09T15:22:26.226Z,1554823346.226 [BR_Ping1D](INFO): startup: 1
2019-04-09T15:22:26.226Z,1554823346.226 [BR_Ping1D](INFO): UART requesting status
2019-04-09T15:22:26.257Z,1554823346.257 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:22:26.375Z,1554823346.375 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T15:22:26.638Z,1554823346.638 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:26.638Z,1554823346.638 [BR_Ping1D](INFO): readConfig
2019-04-09T15:22:26.638Z,1554823346.638 [BR_Ping1D](INFO): startup: 2
2019-04-09T15:22:26.638Z,1554823346.638 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T15:22:26.638Z,1554823346.638 [BR_Ping1D](INFO): elapsed 1.2195
2019-04-09T15:22:26.639Z,1554823346.639 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T15:22:26.640Z,1554823346.640 [BR_Ping1D](INFO): received full packet, type: 1215
2019-04-09T15:22:26.640Z,1554823346.640 [BR_Ping1D](INFO): CRC error in received UART packet
2019-04-09T15:22:26.640Z,1554823346.640 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T15:22:26.640Z,1554823346.640 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-04-09T15:22:26.640Z,1554823346.640 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-04-09T15:22:26.641Z,1554823346.641 [BR_Ping1D](INFO): (ping interval) 500 <-> 0
2019-04-09T15:22:26.641Z,1554823346.641 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-04-09T15:22:26.641Z,1554823346.641 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 65535
2019-04-09T15:22:26.642Z,1554823346.642 [BR_Ping1D](INFO): (ping enable) 1 <-> 0
2019-04-09T15:22:26.642Z,1554823346.642 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-04-09T15:22:26.642Z,1554823346.642 [BR_Ping1D] Data Fault, FailCount= 4
2019-04-09T15:22:26.642Z,1554823346.642 [BR_Ping1D](ERROR): Data Fault
2019-04-09T15:22:26.687Z,1554823346.687 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T15:22:27.035Z,1554823347.035 [BR_Ping1D](INFO): Stop
2019-04-09T15:22:27.035Z,1554823347.035 [BR_Ping1D](INFO): uninitialize
2019-04-09T15:22:27.035Z,1554823347.035 [BR_Ping1D](INFO): Powering down
2019-04-09T15:22:27.438Z,1554823347.438 [BR_Ping1D](INFO): Stopping
2019-04-09T15:22:27.823Z,1554823347.823 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T15:22:27.823Z,1554823347.823 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-04-09T15:22:27.823Z,1554823347.823 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T15:22:27.842Z,1554823347.842 [BR_Ping1D](INFO): Stopping
2019-04-09T15:22:27.871Z,1554823347.871 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T15:22:28.227Z,1554823348.227 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T15:22:28.385Z,1554823348.385 [BR_Ping1D](INFO): Stopped
2019-04-09T15:22:28.405Z,1554823348.405 [CBIT](FAULT): Main Battery Failure. Count: 51
2019-04-09T15:22:28.424Z,1554823348.424 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T15:22:28.424Z,1554823348.424 [BR_Ping1D] No Fault, FailCount= 4
2019-04-09T15:22:28.650Z,1554823348.650 [BR_Ping1D](INFO): Start
2019-04-09T15:22:29.053Z,1554823349.053 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:29.067Z,1554823349.067 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T15:22:29.067Z,1554823349.067 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-04-09T15:22:29.438Z,1554823349.438 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T15:22:29.438Z,1554823349.438 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-09T15:22:29.438Z,1554823349.438 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T15:22:29.457Z,1554823349.457 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:29.457Z,1554823349.457 [BR_Ping1D](INFO): readConfig
2019-04-09T15:22:29.458Z,1554823349.458 [BR_Ping1D](INFO): startup: 0
2019-04-09T15:22:29.458Z,1554823349.458 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T15:22:29.470Z,1554823349.470 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T15:22:29.470Z,1554823349.470 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T15:22:29.471Z,1554823349.471 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T15:22:29.471Z,1554823349.471 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T15:22:29.504Z,1554823349.504 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T15:22:29.962Z,1554823349.962 [Aanderaa_O2](INFO): Powering down
2019-04-09T15:22:29.982Z,1554823349.982 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:29.982Z,1554823349.982 [BR_Ping1D](INFO): readConfig
2019-04-09T15:22:29.982Z,1554823349.982 [BR_Ping1D](INFO): startup: 1
2019-04-09T15:22:29.982Z,1554823349.982 [BR_Ping1D](INFO): UART requesting status
2019-04-09T15:22:30.269Z,1554823350.269 [BR_Ping1D](INFO): Starting
2019-04-09T15:22:30.269Z,1554823350.269 [BR_Ping1D](INFO): readConfig
2019-04-09T15:22:30.269Z,1554823350.269 [BR_Ping1D](INFO): startup: 2
2019-04-09T15:22:30.269Z,1554823350.269 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T15:22:30.270Z,1554823350.270 [BR_Ping1D](INFO): elapsed 0.8115
2019-04-09T15:22:30.270Z,1554823350.270 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T15:22:30.270Z,1554823350.270 [BR_Ping1D](INFO): received full packet, type: 1210
2019-04-09T15:22:30.271Z,1554823350.271 [BR_Ping1D](INFO): CRC error in received UART packet
2019-04-09T15:22:30.271Z,1554823350.271 [BR_Ping1D](INFO): Too many CRC errors, flushing UART
2019-04-09T15:22:30.282Z,1554823350.282 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T15:22:30.282Z,1554823350.282 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-04-09T15:22:30.282Z,1554823350.282 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-04-09T15:22:30.282Z,1554823350.282 [BR_Ping1D](INFO): (ping interval) 500 <-> 0
2019-04-09T15:22:30.283Z,1554823350.283 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-04-09T15:22:30.283Z,1554823350.283 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 65535
2019-04-09T15:22:30.283Z,1554823350.283 [BR_Ping1D](INFO): (ping enable) 1 <-> 0
2019-04-09T15:22:30.283Z,1554823350.283 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-04-09T15:22:30.283Z,1554823350.283 [BR_Ping1D] Data Fault, FailCount= 5
2019-04-09T15:22:30.284Z,1554823350.284 [BR_Ping1D](ERROR): Data Fault
2019-04-09T15:22:30.299Z,1554823350.299 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T15:22:30.299Z,1554823350.299 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-04-09T15:22:30.669Z,1554823350.669 [BR_Ping1D](INFO): Stop
2019-04-09T15:22:30.669Z,1554823350.669 [BR_Ping1D](INFO): uninitialize
2019-04-09T15:22:30.669Z,1554823350.669 [BR_Ping1D](INFO): Powering down
2019-04-09T15:22:30.769Z,1554823350.769 [CBIT](INFO): Critical error at 20190409T152230
2019-04-09T15:22:30.771Z,1554823350.771 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T15:22:30.771Z,1554823350.771 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-09T15:22:31.082Z,1554823351.082 [BR_Ping1D](INFO): Stopping
2019-04-09T15:22:31.470Z,1554823351.470 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T15:22:31.471Z,1554823351.471 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T15:22:31.509Z,1554823351.509 [BR_Ping1D](INFO): Stopping
2019-04-09T15:22:31.531Z,1554823351.531 [SBIT](IMPORTANT): Beginning Startup BIT
2019-04-09T15:22:31.536Z,1554823351.536 [CBIT](IMPORTANT): Beginning ground fault scan
2019-04-09T15:22:31.882Z,1554823351.882 [BR_Ping1D](INFO): Stopped
2019-04-09T15:22:32.332Z,1554823352.332 [CBIT](FAULT): Main Battery Failure. Count: 61
2019-04-09T15:22:34.822Z,1554823354.822 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T15:22:34.822Z,1554823354.822 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-09T15:22:34.822Z,1554823354.822 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T15:22:35.099Z,1554823355.099 [NAL9602](INFO): Powering up NAL9602
2019-04-09T15:22:35.226Z,1554823355.226 [CTD_Seabird](INFO): Powering down
2019-04-09T15:22:35.276Z,1554823355.276 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T15:22:36.456Z,1554823356.456 [CBIT](FAULT): Main Battery Failure. Count: 71
2019-04-09T15:22:36.459Z,1554823356.459 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T15:22:36.459Z,1554823356.459 [CTD_Seabird] No Fault, FailCount= 2
2019-04-09T15:22:36.570Z,1554823356.570 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T15:22:36.578Z,1554823356.578 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T15:22:40.381Z,1554823360.381 [CBIT](FAULT): Main Battery Failure. Count: 81
2019-04-09T15:22:41.154Z,1554823361.154 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T15:22:41.154Z,1554823361.154 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-09T15:22:41.154Z,1554823361.154 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T15:22:41.188Z,1554823361.188 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T15:22:41.188Z,1554823361.188 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-09T15:22:41.678Z,1554823361.678 [Aanderaa_O2](INFO): Powering down
2019-04-09T15:22:42.419Z,1554823362.419 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.006981
CHAN A1 (24V): -0.127828
CHAN A2 (12V): -0.005002
CHAN A3 (5V): -0.002140
CHAN B0 (3.3V): -0.000972
CHAN B1 (3.15aV): -0.001040
CHAN B2 (3.15bV): -0.000528
CHAN B3 (GND): 0.000100
OPEN: -0.000956
Full Scale Calc: 4.765 mA, -1.589 mA
2019-04-09T15:22:44.417Z,1554823364.417 [CBIT](FAULT): Main Battery Failure. Count: 91
2019-04-09T15:22:44.824Z,1554823364.824 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T15:22:46.007Z,1554823366.007 [NAL9602](INFO): NAL9602 initialized
2019-04-09T15:22:46.826Z,1554823366.826 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:22:48.513Z,1554823368.513 [CBIT](FAULT): Main Battery Failure. Count: 101
2019-04-09T15:22:49.642Z,1554823369.642 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T15:22:49.642Z,1554823369.642 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-09T15:22:49.642Z,1554823369.642 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T15:22:49.744Z,1554823369.744 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T15:22:49.744Z,1554823369.744 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-09T15:22:50.046Z,1554823370.046 [CTD_Seabird](INFO): Powering down
2019-04-09T15:22:51.658Z,1554823371.658 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T15:22:51.658Z,1554823371.658 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2019-04-09T15:22:51.658Z,1554823371.658 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T15:22:51.755Z,1554823371.755 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T15:22:52.062Z,1554823372.062 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T15:22:52.740Z,1554823372.740 [CBIT](FAULT): Main Battery Failure. Count: 111
2019-04-09T15:22:52.920Z,1554823372.920 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T15:22:52.920Z,1554823372.920 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2019-04-09T15:22:55.294Z,1554823375.294 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T15:22:55.294Z,1554823375.294 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T15:22:56.537Z,1554823376.537 [CBIT](FAULT): Main Battery Failure. Count: 121
2019-04-09T15:23:00.591Z,1554823380.591 [CBIT](FAULT): Main Battery Failure. Count: 131
2019-04-09T15:23:04.617Z,1554823384.617 [CBIT](FAULT): Main Battery Failure. Count: 141
2019-04-09T15:23:08.670Z,1554823388.670 [CBIT](FAULT): Main Battery Failure. Count: 151
2019-04-09T15:23:11.500Z,1554823391.500 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T15:23:11.500Z,1554823391.500 [SBIT](FAULT): Control surface position failure.
2019-04-09T15:23:12.703Z,1554823392.703 [CBIT](FAULT): Main Battery Failure. Count: 161
2019-04-09T15:23:15.495Z,1554823395.495 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T15:23:15.495Z,1554823395.495 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2019-04-09T15:23:15.495Z,1554823395.495 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T15:23:15.623Z,1554823395.623 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T15:23:15.898Z,1554823395.898 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T15:23:16.992Z,1554823396.992 [CBIT](FAULT): Main Battery Failure. Count: 171
2019-04-09T15:23:16.995Z,1554823396.995 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T15:23:16.995Z,1554823396.995 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2019-04-09T15:23:19.130Z,1554823399.130 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T15:23:19.131Z,1554823399.131 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T15:23:20.787Z,1554823400.787 [CBIT](FAULT): Main Battery Failure. Count: 181
2019-04-09T15:23:24.822Z,1554823404.822 [CBIT](FAULT): Main Battery Failure. Count: 191
2019-04-09T15:23:25.228Z,1554823405.228 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T15:23:25.228Z,1554823405.228 [SBIT](FAULT): Control surface position failure.
2019-04-09T15:23:25.631Z,1554823405.631 [SBIT](CRITICAL): SBIT FAILED
2019-04-09T15:23:25.646Z,1554823405.646 [CBIT](INFO): Critical error at 20190409T152325
2019-04-09T15:23:25.703Z,1554823405.703 [CommandLine](IMPORTANT): got command configSet list
2019-04-09T15:23:25.703Z,1554823405.703 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-04-09T15:23:25.704Z,1554823405.704 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-04-09T15:23:25.704Z,1554823405.704 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2019-04-09T15:23:26.051Z,1554823406.051 [MissionManager](IMPORTANT): Started mission Startup
2019-04-09T15:23:26.051Z,1554823406.051 [Startup] Running Loop=1
2019-04-09T15:23:26.052Z,1554823406.052 [Startup](DEBUG): Aggregate::initialize Startup
2019-04-09T15:23:26.052Z,1554823406.052 [Startup:A.GoToSurface] Running Loop=1
2019-04-09T15:23:26.052Z,1554823406.052 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-09T15:23:26.052Z,1554823406.052 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-09T15:23:26.053Z,1554823406.053 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-09T15:23:26.053Z,1554823406.053 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-09T15:23:26.054Z,1554823406.054 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-09T15:23:26.054Z,1554823406.054 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-09T15:23:26.056Z,1554823406.056 [Startup:StartupSatComms] Running Loop=1
2019-04-09T15:23:26.056Z,1554823406.056 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-04-09T15:23:26.056Z,1554823406.056 [Startup:StartupSatComms:A] Running Loop=1
2019-04-09T15:23:26.447Z,1554823406.447 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-04-09T15:23:28.863Z,1554823408.863 [CBIT](FAULT): Main Battery Failure. Count: 201
2019-04-09T15:23:32.894Z,1554823412.894 [CBIT](FAULT): Main Battery Failure. Count: 211
2019-04-09T15:23:36.957Z,1554823416.957 [CBIT](FAULT): Main Battery Failure. Count: 221
2019-04-09T15:23:39.330Z,1554823419.330 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T15:23:39.331Z,1554823419.331 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2019-04-09T15:23:39.331Z,1554823419.331 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T15:23:39.385Z,1554823419.385 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T15:23:39.734Z,1554823419.734 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T15:23:40.580Z,1554823420.580 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T15:23:40.580Z,1554823420.580 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2019-04-09T15:23:40.974Z,1554823420.974 [CBIT](FAULT): Main Battery Failure. Count: 231
2019-04-09T15:23:42.966Z,1554823422.966 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T15:23:42.967Z,1554823422.967 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T15:23:45.015Z,1554823425.015 [CBIT](FAULT): Main Battery Failure. Count: 241
2019-04-09T15:23:49.062Z,1554823429.062 [CBIT](FAULT): Main Battery Failure. Count: 251
2019-04-09T15:23:53.115Z,1554823433.115 [CBIT](FAULT): Main Battery Failure. Count: 261
2019-04-09T15:23:57.142Z,1554823437.142 [CBIT](FAULT): Main Battery Failure. Count: 271
2019-04-09T15:24:01.181Z,1554823441.181 [CBIT](FAULT): Main Battery Failure. Count: 281
2019-04-09T15:24:03.166Z,1554823443.166 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T15:24:03.167Z,1554823443.167 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2019-04-09T15:24:03.167Z,1554823443.167 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T15:24:03.232Z,1554823443.232 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T15:24:03.571Z,1554823443.571 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T15:24:04.416Z,1554823444.416 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T15:24:04.416Z,1554823444.416 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2019-04-09T15:24:05.222Z,1554823445.222 [CBIT](FAULT): Main Battery Failure. Count: 291
2019-04-09T15:24:06.802Z,1554823446.802 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T15:24:06.803Z,1554823446.803 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T15:24:07.233Z,1554823447.233 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T15:24:07.233Z,1554823447.233 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-04-09T15:24:07.234Z,1554823447.234 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T15:24:07.235Z,1554823447.235 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T15:24:07.235Z,1554823447.235 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-04-09T15:24:07.235Z,1554823447.235 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T15:24:07.237Z,1554823447.237 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T15:24:07.237Z,1554823447.237 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-04-09T15:24:07.237Z,1554823447.237 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T15:24:07.288Z,1554823447.288 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T15:24:07.288Z,1554823447.288 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T15:24:07.288Z,1554823447.288 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T15:24:07.651Z,1554823447.651 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T15:24:07.651Z,1554823447.651 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-04-09T15:24:07.652Z,1554823447.652 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T15:24:07.652Z,1554823447.652 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-04-09T15:24:07.652Z,1554823447.652 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T15:24:07.653Z,1554823447.653 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-04-09T15:24:08.053Z,1554823448.053 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T15:24:09.272Z,1554823449.272 [CBIT](FAULT): Main Battery Failure. Count: 301
2019-04-09T15:24:13.311Z,1554823453.311 [CBIT](FAULT): Main Battery Failure. Count: 311
2019-04-09T15:24:17.351Z,1554823457.351 [CBIT](FAULT): Main Battery Failure. Count: 321
2019-04-09T15:24:21.381Z,1554823461.381 [CBIT](FAULT): Main Battery Failure. Count: 331
2019-04-09T15:24:25.421Z,1554823465.421 [CBIT](FAULT): Main Battery Failure. Count: 341
2019-04-09T15:24:26.224Z,1554823466.224 [Startup:StartupSatComms:A](INFO): Timed out from 2019-04-09T15:23:26.1Z
2019-04-09T15:24:26.224Z,1554823466.224 [Startup:StartupSatComms:A] Stopped
2019-04-09T15:24:26.224Z,1554823466.224 [Startup:StartupSatComms:B] Running Loop=1
2019-04-09T15:24:26.627Z,1554823466.627 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-09T15:24:27.003Z,1554823467.003 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T15:24:27.003Z,1554823467.003 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2019-04-09T15:24:27.003Z,1554823467.003 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T15:24:27.048Z,1554823467.048 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T15:24:27.406Z,1554823467.406 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T15:24:28.252Z,1554823468.252 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T15:24:28.252Z,1554823468.252 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2019-04-09T15:24:29.472Z,1554823469.472 [CBIT](FAULT): Main Battery Failure. Count: 351
2019-04-09T15:24:30.638Z,1554823470.638 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T15:24:30.639Z,1554823470.639 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T15:24:33.496Z,1554823473.496 [CBIT](FAULT): Main Battery Failure. Count: 361
2019-04-09T15:24:37.544Z,1554823477.544 [CBIT](FAULT): Main Battery Failure. Count: 371
2019-04-09T15:24:41.581Z,1554823481.581 [CBIT](FAULT): Main Battery Failure. Count: 381
2019-04-09T15:24:45.656Z,1554823485.656 [CBIT](FAULT): Main Battery Failure. Count: 391
2019-04-09T15:24:48.792Z,1554823488.792 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004897
2019-04-09T15:24:49.658Z,1554823489.658 [CBIT](FAULT): Main Battery Failure. Count: 401
2019-04-09T15:24:50.838Z,1554823490.838 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T15:24:50.838Z,1554823490.838 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7
2019-04-09T15:24:50.838Z,1554823490.838 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T15:24:50.915Z,1554823490.915 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T15:24:51.242Z,1554823491.242 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T15:24:52.096Z,1554823492.096 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T15:24:52.096Z,1554823492.096 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7
2019-04-09T15:24:53.506Z,1554823493.506 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190409T151705/Courier0004.lzma
2019-04-09T15:24:53.701Z,1554823493.701 [CBIT](FAULT): Main Battery Failure. Count: 411
2019-04-09T15:24:54.312Z,1554823494.312 [DataOverHttps](INFO): Moved sent file to Logs/20190409T151705/Courier0004.lzma.bak
2019-04-09T15:24:54.312Z,1554823494.312 [DataOverHttps](INFO): SBD MOMSN=10512800
2019-04-09T15:24:54.474Z,1554823494.474 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T15:24:54.475Z,1554823494.475 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T15:24:57.754Z,1554823497.754 [CBIT](FAULT): Main Battery Failure. Count: 421
2019-04-09T15:25:01.795Z,1554823501.795 [CBIT](FAULT): Main Battery Failure. Count: 431
2019-04-09T15:25:05.630Z,1554823505.630 [DataOverHttps](INFO): Sending 358 bytes from file Logs/20190409T152156/Courier0000.lzma
2019-04-09T15:25:05.845Z,1554823505.845 [CBIT](FAULT): Main Battery Failure. Count: 441
2019-04-09T15:25:06.435Z,1554823506.435 [DataOverHttps](INFO): Moved sent file to Logs/20190409T152156/Courier0000.lzma.bak
2019-04-09T15:25:06.436Z,1554823506.436 [DataOverHttps](INFO): SBD MOMSN=10512802
2019-04-09T15:25:09.862Z,1554823509.862 [CBIT](FAULT): Main Battery Failure. Count: 451
2019-04-09T15:25:11.412Z,1554823511.412 [CommandLine](IMPORTANT): got command failComponent none BR_Ping1D
2019-04-09T15:25:11.412Z,1554823511.412 [BR_Ping1D] No Fault, FailCount= 5
2019-04-09T15:25:11.412Z,1554823511.412 [CommandLine](IMPORTANT): BR_Ping1D failureMode is No Fault
2019-04-09T15:25:11.493Z,1554823511.493 [BR_Ping1D](INFO): Start
2019-04-09T15:25:11.509Z,1554823511.509 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T15:25:11.509Z,1554823511.509 [Aanderaa_O2] No Fault, FailCount= 3
2019-04-09T15:25:11.881Z,1554823511.881 [BR_Ping1D](INFO): Starting
2019-04-09T15:25:12.296Z,1554823512.296 [BR_Ping1D](INFO): Starting
2019-04-09T15:25:12.297Z,1554823512.297 [BR_Ping1D](INFO): readConfig
2019-04-09T15:25:12.297Z,1554823512.297 [BR_Ping1D](INFO): startup: 0
2019-04-09T15:25:12.297Z,1554823512.297 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T15:25:12.310Z,1554823512.310 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T15:25:12.311Z,1554823512.311 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T15:25:12.312Z,1554823512.312 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T15:25:12.313Z,1554823512.313 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T15:25:12.674Z,1554823512.674 [BR_Ping1D](INFO): Starting
2019-04-09T15:25:13.082Z,1554823513.082 [BR_Ping1D](INFO): Starting
2019-04-09T15:25:13.082Z,1554823513.082 [BR_Ping1D](INFO): readConfig
2019-04-09T15:25:13.082Z,1554823513.082 [BR_Ping1D](INFO): startup: 1
2019-04-09T15:25:13.082Z,1554823513.082 [BR_Ping1D](INFO): UART requesting status
2019-04-09T15:25:13.480Z,1554823513.480 [BR_Ping1D](INFO): Starting
2019-04-09T15:25:13.480Z,1554823513.480 [BR_Ping1D](INFO): readConfig
2019-04-09T15:25:13.480Z,1554823513.480 [BR_Ping1D](INFO): startup: 2
2019-04-09T15:25:13.481Z,1554823513.481 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T15:25:13.481Z,1554823513.481 [BR_Ping1D](INFO): elapsed 1.1837
2019-04-09T15:25:13.481Z,1554823513.481 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T15:25:13.481Z,1554823513.481 [BR_Ping1D](INFO): received full packet, type: 1210
2019-04-09T15:25:13.482Z,1554823513.482 [BR_Ping1D](INFO): parsing data, res: 20
2019-04-09T15:25:13.482Z,1554823513.482 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-09T15:25:13.482Z,1554823513.482 [BR_Ping1D](INFO):  voltage: 5054
2019-04-09T15:25:13.482Z,1554823513.482 [BR_Ping1D](INFO):  interval: 7681
2019-04-09T15:25:13.482Z,1554823513.482 [BR_Ping1D](INFO):  gain: 1
2019-04-09T15:25:13.483Z,1554823513.483 [BR_Ping1D](INFO):  auto: 1
2019-04-09T15:25:13.483Z,1554823513.483 [BR_Ping1D](INFO): received full packet, type: 1215
2019-04-09T15:25:13.483Z,1554823513.483 [BR_Ping1D](INFO): CRC error in received UART packet
2019-04-09T15:25:13.483Z,1554823513.483 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T15:25:13.483Z,1554823513.483 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-04-09T15:25:13.483Z,1554823513.483 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-04-09T15:25:13.484Z,1554823513.484 [BR_Ping1D](INFO): (ping interval) 500 <-> 0
2019-04-09T15:25:13.484Z,1554823513.484 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-04-09T15:25:13.484Z,1554823513.484 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 65535
2019-04-09T15:25:13.485Z,1554823513.485 [BR_Ping1D](INFO): (ping enable) 1 <-> 0
2019-04-09T15:25:13.485Z,1554823513.485 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-04-09T15:25:13.485Z,1554823513.485 [BR_Ping1D] Data Fault, FailCount= 6
2019-04-09T15:25:13.485Z,1554823513.485 [BR_Ping1D](ERROR): Data Fault
2019-04-09T15:25:13.504Z,1554823513.504 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T15:25:13.505Z,1554823513.505 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-04-09T15:25:13.885Z,1554823513.885 [BR_Ping1D](INFO): Stop
2019-04-09T15:25:13.885Z,1554823513.885 [BR_Ping1D](INFO): uninitialize
2019-04-09T15:25:13.886Z,1554823513.886 [BR_Ping1D](INFO): Powering down
2019-04-09T15:25:13.977Z,1554823513.977 [CBIT](INFO): Critical error at 20190409T152513
2019-04-09T15:25:13.977Z,1554823513.977 [CBIT](FAULT): Main Battery Failure. Count: 461
2019-04-09T15:25:14.290Z,1554823514.290 [BR_Ping1D](INFO): Stopping
2019-04-09T15:25:14.675Z,1554823514.675 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T15:25:14.675Z,1554823514.675 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 8
2019-04-09T15:25:14.675Z,1554823514.675 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T15:25:14.692Z,1554823514.692 [BR_Ping1D](INFO): Stopping
2019-04-09T15:25:14.707Z,1554823514.707 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T15:25:14.707Z,1554823514.707 [CBIT](FAULT): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T15:25:15.079Z,1554823515.079 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T15:25:15.222Z,1554823515.222 [BR_Ping1D](INFO): Stopped
2019-04-09T15:25:17.946Z,1554823517.946 [CBIT](FAULT): Main Battery Failure. Count: 471
2019-04-09T15:25:19.964Z,1554823519.964 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T15:25:19.964Z,1554823519.964 [CTD_Seabird] No Fault, FailCount= 3
2019-04-09T15:25:20.086Z,1554823520.086 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T15:25:20.088Z,1554823520.088 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T15:25:21.946Z,1554823521.946 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T15:25:21.946Z,1554823521.946 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T15:25:21.946Z,1554823521.946 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T15:25:22.000Z,1554823522.000 [CBIT](FAULT): Main Battery Failure. Count: 481
2019-04-09T15:25:22.003Z,1554823522.003 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T15:25:22.470Z,1554823522.470 [Aanderaa_O2](INFO): Powering down
2019-04-09T15:25:23.196Z,1554823523.196 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T15:25:23.196Z,1554823523.196 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-09T15:25:26.032Z,1554823526.032 [CBIT](FAULT): Main Battery Failure. Count: 491
2019-04-09T15:25:26.413Z,1554823526.413 [Startup:StartupSatComms:B](INFO): Timed out from 2019-04-09T15:24:26.2Z
2019-04-09T15:25:26.413Z,1554823526.413 [Startup:StartupSatComms:B] Stopped
2019-04-09T15:25:26.413Z,1554823526.413 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-04-09T15:25:26.413Z,1554823526.413 [Startup:StartupSatComms] Stopped
2019-04-09T15:25:26.413Z,1554823526.413 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-04-09T15:25:26.414Z,1554823526.414 [Startup](INFO): Completed Startup
2019-04-09T15:25:26.415Z,1554823526.415 [MissionManager](INFO): Startup is completed.
2019-04-09T15:25:26.415Z,1554823526.415 [MissionManager](INFO): Uninitializing Mission Startup
2019-04-09T15:25:26.415Z,1554823526.415 [Startup] Stopped
2019-04-09T15:25:26.415Z,1554823526.415 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-04-09T15:25:26.415Z,1554823526.415 [Startup:A.GoToSurface] Stopped
2019-04-09T15:25:26.415Z,1554823526.415 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-09T15:25:26.835Z,1554823526.835 [MissionManager](IMPORTANT): Started mission Default
2019-04-09T15:25:26.835Z,1554823526.835 [Default] Running Loop=1
2019-04-09T15:25:26.835Z,1554823526.835 [Default](DEBUG): Aggregate::initialize Default
2019-04-09T15:25:26.835Z,1554823526.835 [Default:B.GoToSurface] Running Loop=1
2019-04-09T15:25:26.835Z,1554823526.835 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-09T15:25:26.836Z,1554823526.836 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-09T15:25:26.836Z,1554823526.836 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-09T15:25:26.836Z,1554823526.836 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-09T15:25:26.837Z,1554823526.837 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-09T15:25:26.837Z,1554823526.837 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-09T15:25:26.837Z,1554823526.837 [Default:A.Wait] Running Loop=1
2019-04-09T15:25:26.837Z,1554823526.837 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-04-09T15:25:30.055Z,1554823530.055 [CBIT](FAULT): Main Battery Failure. Count: 501
2019-04-09T15:25:33.138Z,1554823533.138 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T15:25:33.138Z,1554823533.138 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-09T15:25:33.138Z,1554823533.138 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T15:25:33.318Z,1554823533.318 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T15:25:33.542Z,1554823533.542 [CTD_Seabird](INFO): Powering down
2019-04-09T15:25:33.662Z,1554823533.662 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T15:25:33.662Z,1554823533.662 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-09T15:25:33.662Z,1554823533.662 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T15:25:33.698Z,1554823533.698 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T15:25:34.186Z,1554823534.186 [Aanderaa_O2](INFO): Powering down
2019-04-09T15:25:34.221Z,1554823534.221 [CBIT](FAULT): Main Battery Failure. Count: 511
2019-04-09T15:25:34.512Z,1554823534.512 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T15:25:34.512Z,1554823534.512 [CTD_Seabird] No Fault, FailCount= 1
2019-04-09T15:25:34.890Z,1554823534.890 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T15:25:34.892Z,1554823534.892 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T15:25:34.916Z,1554823534.916 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T15:25:34.916Z,1554823534.916 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-09T15:25:38.157Z,1554823538.157 [CBIT](FAULT): Main Battery Failure. Count: 521
2019-04-09T15:25:40.155Z,1554823540.155 [Default:A.Wait](INFO): Done Waiting.
2019-04-09T15:25:40.155Z,1554823540.155 [Default:A.Wait] Stopped
2019-04-09T15:25:40.155Z,1554823540.155 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-04-09T15:25:40.589Z,1554823540.589 [Default:CheckIn] Running Loop=1
2019-04-09T15:25:40.589Z,1554823540.589 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-09T15:25:40.589Z,1554823540.589 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-09T15:25:40.970Z,1554823540.970 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-04-09T15:25:42.179Z,1554823542.179 [CBIT](FAULT): Main Battery Failure. Count: 531
2019-04-09T15:25:44.351Z,1554823544.351 [DataOverHttps](INFO): Sending 339 bytes from file Logs/20190409T144919/Express0005.lzma
2019-04-09T15:25:45.155Z,1554823545.155 [DataOverHttps](INFO): Moved sent file to Logs/20190409T144919/Express0005.lzma.bak
2019-04-09T15:25:45.156Z,1554823545.156 [DataOverHttps](INFO): SBD MOMSN=10512817
2019-04-09T15:25:45.379Z,1554823545.379 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T15:25:45.379Z,1554823545.379 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-09T15:25:45.379Z,1554823545.379 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T15:25:45.413Z,1554823545.413 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T15:25:45.413Z,1554823545.413 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-09T15:25:45.902Z,1554823545.902 [Aanderaa_O2](INFO): Powering down
2019-04-09T15:25:46.225Z,1554823546.225 [CBIT](FAULT): Main Battery Failure. Count: 541
2019-04-09T15:25:47.942Z,1554823547.942 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T15:25:47.942Z,1554823547.942 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-09T15:25:47.942Z,1554823547.942 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T15:25:48.265Z,1554823548.265 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T15:25:48.346Z,1554823548.346 [CTD_Seabird](INFO): Powering down
2019-04-09T15:25:49.468Z,1554823549.468 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T15:25:49.468Z,1554823549.468 [CTD_Seabird] No Fault, FailCount= 2
2019-04-09T15:25:49.690Z,1554823549.690 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T15:25:49.698Z,1554823549.698 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T15:25:50.291Z,1554823550.291 [CBIT](FAULT): Main Battery Failure. Count: 551
2019-04-09T15:25:54.311Z,1554823554.311 [CBIT](FAULT): Main Battery Failure. Count: 561
2019-04-09T15:25:58.354Z,1554823558.354 [CBIT](FAULT): Main Battery Failure. Count: 571
2019-04-09T15:26:02.385Z,1554823562.385 [CBIT](FAULT): Main Battery Failure. Count: 581
2019-04-09T15:26:02.742Z,1554823562.742 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T15:26:02.742Z,1554823562.742 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-09T15:26:02.742Z,1554823562.742 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T15:26:02.801Z,1554823562.801 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T15:26:02.802Z,1554823562.802 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-09T15:26:03.146Z,1554823563.146 [CTD_Seabird](INFO): Powering down
2019-04-09T15:26:06.418Z,1554823566.418 [CBIT](FAULT): Main Battery Failure. Count: 591
2019-04-09T15:26:08.433Z,1554823568.433 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T15:26:08.433Z,1554823568.433 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-04-09T15:26:08.433Z,1554823568.433 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T15:26:08.435Z,1554823568.435 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T15:26:08.435Z,1554823568.435 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-04-09T15:26:08.435Z,1554823568.435 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T15:26:08.436Z,1554823568.436 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T15:26:08.436Z,1554823568.436 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-04-09T15:26:08.436Z,1554823568.436 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T15:26:08.447Z,1554823568.447 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T15:26:08.447Z,1554823568.447 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T15:26:08.447Z,1554823568.447 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T15:26:08.853Z,1554823568.853 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T15:26:08.853Z,1554823568.853 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-04-09T15:26:08.854Z,1554823568.854 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T15:26:08.854Z,1554823568.854 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-04-09T15:26:08.854Z,1554823568.854 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T15:26:08.854Z,1554823568.854 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-04-09T15:26:09.253Z,1554823569.253 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T15:26:10.465Z,1554823570.465 [CBIT](FAULT): Main Battery Failure. Count: 601
2019-04-09T15:26:14.522Z,1554823574.522 [CBIT](FAULT): Main Battery Failure. Count: 611
2019-04-09T15:26:18.553Z,1554823578.553 [CBIT](FAULT): Main Battery Failure. Count: 621
2019-04-09T15:26:22.575Z,1554823582.575 [CBIT](FAULT): Main Battery Failure. Count: 631
2019-04-09T15:26:26.621Z,1554823586.621 [CBIT](FAULT): Main Battery Failure. Count: 641
2019-04-09T15:26:30.676Z,1554823590.676 [CBIT](FAULT): Main Battery Failure. Count: 651
2019-04-09T15:26:34.702Z,1554823594.702 [CBIT](FAULT): Main Battery Failure. Count: 661
2019-04-09T15:26:38.741Z,1554823598.741 [CBIT](FAULT): Main Battery Failure. Count: 671
2019-04-09T15:26:42.774Z,1554823602.774 [CBIT](FAULT): Main Battery Failure. Count: 681
2019-04-09T15:26:46.833Z,1554823606.833 [CBIT](FAULT): Main Battery Failure. Count: 691
2019-04-09T15:26:50.873Z,1554823610.873 [CBIT](FAULT): Main Battery Failure. Count: 701
2019-04-09T15:26:54.897Z,1554823614.897 [CBIT](FAULT): Main Battery Failure. Count: 711
2019-04-09T15:26:58.943Z,1554823618.943 [CBIT](FAULT): Main Battery Failure. Count: 721
2019-04-09T15:27:02.981Z,1554823622.981 [CBIT](FAULT): Main Battery Failure. Count: 731
2019-04-09T15:27:07.028Z,1554823627.028 [CBIT](FAULT): Main Battery Failure. Count: 741
2019-04-09T15:27:08.222Z,1554823628.222 [BPC1](FAULT): Failed to initialize
2019-04-09T15:27:08.222Z,1554823628.222 [BPC1] Communications Fault, FailCount= 1
2019-04-09T15:27:08.222Z,1554823628.222 [BPC1](ERROR): Communications Fault
2019-04-09T15:27:08.237Z,1554823628.237 [CBIT](ERROR): Communications Fault in component: BPC1
2019-04-09T15:27:11.061Z,1554823631.061 [CBIT](FAULT): Main Battery Failure. Count: 751
2019-04-09T15:27:15.101Z,1554823635.101 [CBIT](FAULT): Main Battery Failure. Count: 761
2019-04-09T15:27:19.144Z,1554823639.144 [CBIT](FAULT): Main Battery Failure. Count: 771
2019-04-09T15:27:23.197Z,1554823643.197 [CBIT](FAULT): Main Battery Failure. Count: 781
2019-04-09T15:27:25.609Z,1554823645.609 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T15:27:25.609Z,1554823645.609 [MassServo] No Fault, FailCount= 5
2019-04-09T15:27:27.522Z,1554823647.522 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T15:27:27.522Z,1554823647.522 [MassServo] Communications Fault, FailCount= 1
2019-04-09T15:27:27.522Z,1554823647.522 [MassServo](ERROR): Communications Fault
2019-04-09T15:27:27.524Z,1554823647.524 [CBIT](FAULT): Main Battery Failure. Count: 791
2019-04-09T15:27:27.526Z,1554823647.526 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T15:27:27.634Z,1554823647.634 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T15:27:27.634Z,1554823647.634 [MassServo](INFO): Powering down
2019-04-09T15:27:28.443Z,1554823648.443 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:27:28.564Z,1554823648.564 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T15:27:28.568Z,1554823648.568 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T15:27:28.568Z,1554823648.568 [MassServo] No Fault, FailCount= 1
2019-04-09T15:27:31.162Z,1554823651.162 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T15:27:31.162Z,1554823651.162 [MassServo] Communications Fault, FailCount= 2
2019-04-09T15:27:31.162Z,1554823651.162 [MassServo](ERROR): Communications Fault
2019-04-09T15:27:31.167Z,1554823651.167 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T15:27:31.257Z,1554823651.257 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T15:27:31.257Z,1554823651.257 [MassServo](INFO): Powering down
2019-04-09T15:27:31.260Z,1554823651.260 [CBIT](FAULT): Main Battery Failure. Count: 801
2019-04-09T15:27:32.067Z,1554823652.067 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:27:32.183Z,1554823652.183 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T15:27:32.187Z,1554823652.187 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T15:27:32.187Z,1554823652.187 [MassServo] No Fault, FailCount= 2
2019-04-09T15:27:34.802Z,1554823654.802 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T15:27:34.802Z,1554823654.802 [MassServo] Communications Fault, FailCount= 3
2019-04-09T15:27:34.802Z,1554823654.802 [MassServo](ERROR): Communications Fault
2019-04-09T15:27:34.807Z,1554823654.807 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T15:27:34.894Z,1554823654.894 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T15:27:34.894Z,1554823654.894 [MassServo](INFO): Powering down
2019-04-09T15:27:35.298Z,1554823655.298 [CBIT](FAULT): Main Battery Failure. Count: 811
2019-04-09T15:27:35.708Z,1554823655.708 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:27:35.827Z,1554823655.827 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T15:27:35.831Z,1554823655.831 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T15:27:35.831Z,1554823655.831 [MassServo] No Fault, FailCount= 3
2019-04-09T15:27:38.442Z,1554823658.442 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T15:27:38.442Z,1554823658.442 [MassServo] Communications Fault, FailCount= 4
2019-04-09T15:27:38.442Z,1554823658.442 [MassServo](ERROR): Communications Fault
2019-04-09T15:27:38.446Z,1554823658.446 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T15:27:38.552Z,1554823658.552 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T15:27:38.552Z,1554823658.552 [MassServo](INFO): Powering down
2019-04-09T15:27:39.353Z,1554823659.353 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:27:39.471Z,1554823659.471 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T15:27:39.473Z,1554823659.473 [CBIT](FAULT): Main Battery Failure. Count: 821
2019-04-09T15:27:39.475Z,1554823659.475 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T15:27:39.476Z,1554823659.476 [MassServo] No Fault, FailCount= 4
2019-04-09T15:27:42.074Z,1554823662.074 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T15:27:42.074Z,1554823662.074 [MassServo] Communications Fault, FailCount= 5
2019-04-09T15:27:42.074Z,1554823662.074 [MassServo](ERROR): Communications Fault
2019-04-09T15:27:42.079Z,1554823662.079 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T15:27:42.079Z,1554823662.079 [CBIT](CRITICAL): Communications Fault in component: MassServo
2019-04-09T15:27:42.199Z,1554823662.199 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T15:27:42.199Z,1554823662.199 [MassServo](INFO): Powering down
2019-04-09T15:27:42.201Z,1554823662.201 [CBIT](INFO): Critical error at 20190409T152742
2019-04-09T15:27:42.976Z,1554823662.976 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:27:43.095Z,1554823663.095 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T15:27:43.387Z,1554823663.387 [CBIT](FAULT): Main Battery Failure. Count: 831
2019-04-09T15:27:47.414Z,1554823667.414 [CBIT](FAULT): Main Battery Failure. Count: 841
2019-04-09T15:27:49.815Z,1554823669.815 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-04-09T15:27:50.634Z,1554823670.634 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:27:51.472Z,1554823671.472 [CBIT](FAULT): Main Battery Failure. Count: 851
2019-04-09T15:27:53.462Z,1554823673.462 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:27:55.502Z,1554823675.502 [CBIT](FAULT): Main Battery Failure. Count: 861
2019-04-09T15:27:56.298Z,1554823676.298 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:27:59.526Z,1554823679.526 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:27:59.561Z,1554823679.561 [CBIT](FAULT): Main Battery Failure. Count: 871
2019-04-09T15:28:02.354Z,1554823682.354 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:28:03.581Z,1554823683.581 [CBIT](FAULT): Main Battery Failure. Count: 881
2019-04-09T15:28:05.586Z,1554823685.586 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:28:07.621Z,1554823687.621 [CBIT](FAULT): Main Battery Failure. Count: 891
2019-04-09T15:28:08.414Z,1554823688.414 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:28:09.636Z,1554823689.636 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T15:28:09.636Z,1554823689.636 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-04-09T15:28:09.636Z,1554823689.636 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T15:28:09.638Z,1554823689.638 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T15:28:09.638Z,1554823689.638 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-04-09T15:28:09.638Z,1554823689.638 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T15:28:09.639Z,1554823689.639 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T15:28:09.639Z,1554823689.639 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2019-04-09T15:28:09.639Z,1554823689.639 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T15:28:09.650Z,1554823689.650 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T15:28:09.650Z,1554823689.650 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T15:28:09.650Z,1554823689.650 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T15:28:10.047Z,1554823690.047 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T15:28:10.048Z,1554823690.048 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-04-09T15:28:10.048Z,1554823690.048 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T15:28:10.048Z,1554823690.048 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-04-09T15:28:10.049Z,1554823690.049 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T15:28:10.049Z,1554823690.049 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2019-04-09T15:28:10.438Z,1554823690.438 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T15:28:11.242Z,1554823691.242 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:28:11.681Z,1554823691.681 [CBIT](FAULT): Main Battery Failure. Count: 901
2019-04-09T15:28:13.262Z,1554823693.262 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:28:15.278Z,1554823695.278 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:28:15.711Z,1554823695.711 [CBIT](FAULT): Main Battery Failure. Count: 911
2019-04-09T15:28:15.714Z,1554823695.714 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T15:28:15.714Z,1554823695.714 [Aanderaa_O2] No Fault, FailCount= 3
2019-04-09T15:28:17.302Z,1554823697.302 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:28:19.741Z,1554823699.741 [CBIT](FAULT): Main Battery Failure. Count: 921
2019-04-09T15:28:20.530Z,1554823700.530 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:28:23.366Z,1554823703.366 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:28:23.801Z,1554823703.801 [CBIT](FAULT): Main Battery Failure. Count: 931
2019-04-09T15:28:26.170Z,1554823706.170 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T15:28:26.170Z,1554823706.170 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T15:28:26.170Z,1554823706.170 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T15:28:26.201Z,1554823706.201 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T15:28:26.694Z,1554823706.694 [Aanderaa_O2](INFO): Powering down
2019-04-09T15:28:26.714Z,1554823706.714 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:28:27.433Z,1554823707.433 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T15:28:27.433Z,1554823707.433 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-09T15:28:27.817Z,1554823707.817 [CBIT](FAULT): Main Battery Failure. Count: 941
2019-04-09T15:28:29.422Z,1554823709.422 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:28:31.869Z,1554823711.869 [CBIT](FAULT): Main Battery Failure. Count: 951
2019-04-09T15:28:32.654Z,1554823712.654 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:28:33.089Z,1554823713.089 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T15:28:33.089Z,1554823713.089 [CTD_Seabird] No Fault, FailCount= 3
2019-04-09T15:28:33.182Z,1554823713.182 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T15:28:33.184Z,1554823713.184 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T15:28:35.478Z,1554823715.478 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:28:35.915Z,1554823715.915 [CBIT](FAULT): Main Battery Failure. Count: 961
2019-04-09T15:28:37.886Z,1554823717.886 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T15:28:37.886Z,1554823717.886 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-09T15:28:37.886Z,1554823717.886 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T15:28:37.917Z,1554823717.917 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T15:28:38.410Z,1554823718.410 [Aanderaa_O2](INFO): Powering down
2019-04-09T15:28:38.430Z,1554823718.430 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:28:39.147Z,1554823719.147 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T15:28:39.147Z,1554823719.147 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-09T15:28:39.953Z,1554823719.953 [CBIT](FAULT): Main Battery Failure. Count: 971
2019-04-09T15:28:40.330Z,1554823720.330 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:28:43.562Z,1554823723.562 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:28:43.995Z,1554823723.995 [CBIT](FAULT): Main Battery Failure. Count: 981
2019-04-09T15:28:46.234Z,1554823726.234 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T15:28:46.234Z,1554823726.234 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-09T15:28:46.234Z,1554823726.234 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T15:28:46.390Z,1554823726.390 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:28:46.418Z,1554823726.418 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T15:28:46.638Z,1554823726.638 [CTD_Seabird](INFO): Powering down
2019-04-09T15:28:47.622Z,1554823727.622 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T15:28:47.622Z,1554823727.622 [CTD_Seabird] No Fault, FailCount= 1
2019-04-09T15:28:47.978Z,1554823727.978 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T15:28:47.980Z,1554823727.980 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T15:28:48.031Z,1554823728.031 [CBIT](FAULT): Main Battery Failure. Count: 991
2019-04-09T15:28:49.602Z,1554823729.602 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T15:28:49.602Z,1554823729.602 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-09T15:28:49.602Z,1554823729.602 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T15:28:49.622Z,1554823729.622 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:28:49.656Z,1554823729.656 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T15:28:49.656Z,1554823729.656 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-09T15:28:50.126Z,1554823730.126 [Aanderaa_O2](INFO): Powering down
2019-04-09T15:28:52.057Z,1554823732.057 [CBIT](FAULT): Main Battery Failure. Count: 1001
2019-04-09T15:28:52.446Z,1554823732.446 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:28:55.682Z,1554823735.682 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:28:56.115Z,1554823736.115 [CBIT](FAULT): Main Battery Failure. Count: 1011
2019-04-09T15:28:58.506Z,1554823738.506 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:29:00.141Z,1554823740.141 [CBIT](FAULT): Main Battery Failure. Count: 1021
2019-04-09T15:29:01.030Z,1554823741.030 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T15:29:01.030Z,1554823741.030 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-09T15:29:01.030Z,1554823741.030 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T15:29:01.342Z,1554823741.342 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:29:01.376Z,1554823741.376 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T15:29:01.434Z,1554823741.434 [CTD_Seabird](INFO): Powering down
2019-04-09T15:29:02.569Z,1554823742.569 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T15:29:02.569Z,1554823742.569 [CTD_Seabird] No Fault, FailCount= 2
2019-04-09T15:29:02.778Z,1554823742.778 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T15:29:02.780Z,1554823742.780 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T15:29:03.358Z,1554823743.358 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:29:04.206Z,1554823744.206 [CBIT](FAULT): Main Battery Failure. Count: 1031
2019-04-09T15:29:05.374Z,1554823745.374 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:29:08.218Z,1554823748.218 [CBIT](FAULT): Main Battery Failure. Count: 1041
2019-04-09T15:29:08.606Z,1554823748.606 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:29:11.434Z,1554823751.434 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:29:12.278Z,1554823752.278 [CBIT](FAULT): Main Battery Failure. Count: 1051
2019-04-09T15:29:14.666Z,1554823754.666 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:29:15.830Z,1554823755.830 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T15:29:15.830Z,1554823755.830 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-09T15:29:15.830Z,1554823755.830 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T15:29:15.921Z,1554823755.921 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T15:29:15.921Z,1554823755.921 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-09T15:29:16.234Z,1554823756.234 [CTD_Seabird](INFO): Powering down
2019-04-09T15:29:16.301Z,1554823756.301 [CBIT](FAULT): Main Battery Failure. Count: 1061
2019-04-09T15:29:17.494Z,1554823757.494 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:29:20.341Z,1554823760.341 [CBIT](FAULT): Main Battery Failure. Count: 1071
2019-04-09T15:29:20.726Z,1554823760.726 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:29:23.558Z,1554823763.558 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:29:24.397Z,1554823764.397 [CBIT](FAULT): Main Battery Failure. Count: 1081
2019-04-09T15:29:26.382Z,1554823766.382 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:29:28.402Z,1554823768.402 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:29:28.429Z,1554823768.429 [CBIT](FAULT): Main Battery Failure. Count: 1091
2019-04-09T15:29:31.638Z,1554823771.638 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:29:32.468Z,1554823772.468 [CBIT](FAULT): Main Battery Failure. Count: 1101
2019-04-09T15:29:34.466Z,1554823774.466 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:29:36.500Z,1554823776.500 [CBIT](FAULT): Main Battery Failure. Count: 1111
2019-04-09T15:29:37.698Z,1554823777.698 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:29:40.526Z,1554823780.526 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:29:40.558Z,1554823780.558 [CBIT](FAULT): Main Battery Failure. Count: 1121
2019-04-09T15:29:43.758Z,1554823783.758 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:29:44.588Z,1554823784.588 [CBIT](FAULT): Main Battery Failure. Count: 1131
2019-04-09T15:29:46.586Z,1554823786.586 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:29:48.629Z,1554823788.629 [CBIT](FAULT): Main Battery Failure. Count: 1141
2019-04-09T15:29:49.414Z,1554823789.414 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:29:51.434Z,1554823791.434 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:29:52.661Z,1554823792.661 [CBIT](FAULT): Main Battery Failure. Count: 1151
2019-04-09T15:29:53.454Z,1554823793.454 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:29:56.682Z,1554823796.682 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:29:56.709Z,1554823796.709 [CBIT](FAULT): Main Battery Failure. Count: 1161
2019-04-09T15:29:59.510Z,1554823799.510 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:30:00.734Z,1554823800.734 [CBIT](FAULT): Main Battery Failure. Count: 1171
2019-04-09T15:30:02.742Z,1554823802.742 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:30:04.781Z,1554823804.781 [CBIT](FAULT): Main Battery Failure. Count: 1181
2019-04-09T15:30:05.570Z,1554823805.570 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:30:08.802Z,1554823808.802 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:30:08.828Z,1554823808.828 [CBIT](FAULT): Main Battery Failure. Count: 1191
2019-04-09T15:30:10.837Z,1554823810.837 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T15:30:10.837Z,1554823810.837 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-04-09T15:30:10.837Z,1554823810.837 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T15:30:10.839Z,1554823810.839 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T15:30:10.839Z,1554823810.839 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2019-04-09T15:30:10.839Z,1554823810.839 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T15:30:10.840Z,1554823810.840 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T15:30:10.840Z,1554823810.840 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2019-04-09T15:30:10.840Z,1554823810.840 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T15:30:10.851Z,1554823810.851 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T15:30:10.851Z,1554823810.851 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T15:30:10.851Z,1554823810.851 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T15:30:11.257Z,1554823811.257 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T15:30:11.258Z,1554823811.258 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-04-09T15:30:11.258Z,1554823811.258 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T15:30:11.258Z,1554823811.258 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2019-04-09T15:30:11.259Z,1554823811.259 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T15:30:11.259Z,1554823811.259 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2019-04-09T15:30:11.630Z,1554823811.630 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:30:11.653Z,1554823811.653 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T15:30:12.854Z,1554823812.854 [CBIT](FAULT): Main Battery Failure. Count: 1201
2019-04-09T15:30:13.674Z,1554823813.674 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T15:30:13.674Z,1554823813.674 [BR_Ping1D] No Fault, FailCount= 6
2019-04-09T15:30:14.055Z,1554823814.055 [BR_Ping1D](INFO): Start
2019-04-09T15:30:14.462Z,1554823814.462 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:30:14.480Z,1554823814.480 [BR_Ping1D](INFO): Starting
2019-04-09T15:30:14.876Z,1554823814.876 [BR_Ping1D](INFO): Starting
2019-04-09T15:30:14.877Z,1554823814.877 [BR_Ping1D](INFO): readConfig
2019-04-09T15:30:14.877Z,1554823814.877 [BR_Ping1D](INFO): startup: 0
2019-04-09T15:30:14.877Z,1554823814.877 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T15:30:14.901Z,1554823814.901 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T15:30:14.901Z,1554823814.901 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T15:30:14.901Z,1554823814.901 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T15:30:14.901Z,1554823814.901 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T15:30:14.952Z,1554823814.952 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T15:30:14.953Z,1554823814.953 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 8
2019-04-09T15:30:15.250Z,1554823815.250 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T15:30:15.250Z,1554823815.250 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T15:30:15.296Z,1554823815.296 [BR_Ping1D](INFO): Starting
2019-04-09T15:30:15.678Z,1554823815.678 [BR_Ping1D](INFO): Starting
2019-04-09T15:30:15.678Z,1554823815.678 [BR_Ping1D](INFO): readConfig
2019-04-09T15:30:15.678Z,1554823815.678 [BR_Ping1D](INFO): startup: 1
2019-04-09T15:30:15.678Z,1554823815.678 [BR_Ping1D](INFO): UART requesting status
2019-04-09T15:30:16.075Z,1554823816.075 [BR_Ping1D](INFO): Starting
2019-04-09T15:30:16.075Z,1554823816.075 [BR_Ping1D](INFO): readConfig
2019-04-09T15:30:16.075Z,1554823816.075 [BR_Ping1D](INFO): startup: 2
2019-04-09T15:30:16.075Z,1554823816.075 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T15:30:16.075Z,1554823816.075 [BR_Ping1D](INFO): elapsed 1.1982
2019-04-09T15:30:16.075Z,1554823816.075 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T15:30:16.076Z,1554823816.076 [BR_Ping1D](INFO): received full packet, type: 1210
2019-04-09T15:30:16.076Z,1554823816.076 [BR_Ping1D](INFO): parsing data, res: 20
2019-04-09T15:30:16.076Z,1554823816.076 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-09T15:30:16.076Z,1554823816.076 [BR_Ping1D](INFO):  voltage: 5054
2019-04-09T15:30:16.077Z,1554823816.077 [BR_Ping1D](INFO):  interval: 7681
2019-04-09T15:30:16.077Z,1554823816.077 [BR_Ping1D](INFO):  gain: 1
2019-04-09T15:30:16.077Z,1554823816.077 [BR_Ping1D](INFO):  auto: 1
2019-04-09T15:30:16.077Z,1554823816.077 [BR_Ping1D](INFO): received full packet, type: 1215
2019-04-09T15:30:16.077Z,1554823816.077 [BR_Ping1D](INFO): CRC error in received UART packet
2019-04-09T15:30:16.078Z,1554823816.078 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T15:30:16.078Z,1554823816.078 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-04-09T15:30:16.078Z,1554823816.078 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-04-09T15:30:16.078Z,1554823816.078 [BR_Ping1D](INFO): (ping interval) 500 <-> 0
2019-04-09T15:30:16.079Z,1554823816.079 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-04-09T15:30:16.079Z,1554823816.079 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 65535
2019-04-09T15:30:16.079Z,1554823816.079 [BR_Ping1D](INFO): (ping enable) 1 <-> 0
2019-04-09T15:30:16.079Z,1554823816.079 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-04-09T15:30:16.079Z,1554823816.079 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-09T15:30:16.079Z,1554823816.079 [BR_Ping1D](ERROR): Data Fault
2019-04-09T15:30:16.094Z,1554823816.094 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T15:30:16.483Z,1554823816.483 [BR_Ping1D](INFO): Stop
2019-04-09T15:30:16.483Z,1554823816.483 [BR_Ping1D](INFO): uninitialize
2019-04-09T15:30:16.483Z,1554823816.483 [BR_Ping1D](INFO): Powering down
2019-04-09T15:30:16.891Z,1554823816.891 [BR_Ping1D](INFO): Stopping
2019-04-09T15:30:16.909Z,1554823816.909 [CBIT](FAULT): Main Battery Failure. Count: 1211
2019-04-09T15:30:17.298Z,1554823817.298 [BR_Ping1D](INFO): Stopping
2019-04-09T15:30:17.694Z,1554823817.694 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:30:17.712Z,1554823817.712 [BR_Ping1D](INFO): Stopped
2019-04-09T15:30:17.732Z,1554823817.732 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T15:30:17.732Z,1554823817.732 [BR_Ping1D] No Fault, FailCount= 1
2019-04-09T15:30:18.112Z,1554823818.112 [BR_Ping1D](INFO): Start
2019-04-09T15:30:18.512Z,1554823818.512 [BR_Ping1D](INFO): Starting
2019-04-09T15:30:18.906Z,1554823818.906 [BR_Ping1D](INFO): Starting
2019-04-09T15:30:18.906Z,1554823818.906 [BR_Ping1D](INFO): readConfig
2019-04-09T15:30:18.907Z,1554823818.907 [BR_Ping1D](INFO): startup: 0
2019-04-09T15:30:18.907Z,1554823818.907 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T15:30:18.918Z,1554823818.918 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T15:30:18.918Z,1554823818.918 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T15:30:18.918Z,1554823818.918 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T15:30:18.919Z,1554823818.919 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T15:30:19.310Z,1554823819.310 [BR_Ping1D](INFO): Starting
2019-04-09T15:30:19.715Z,1554823819.715 [BR_Ping1D](INFO): Starting
2019-04-09T15:30:19.715Z,1554823819.715 [BR_Ping1D](INFO): readConfig
2019-04-09T15:30:19.715Z,1554823819.715 [BR_Ping1D](INFO): startup: 1
2019-04-09T15:30:19.715Z,1554823819.715 [BR_Ping1D](INFO): UART requesting status
2019-04-09T15:30:20.119Z,1554823820.119 [BR_Ping1D](INFO): Starting
2019-04-09T15:30:20.120Z,1554823820.120 [BR_Ping1D](INFO): readConfig
2019-04-09T15:30:20.120Z,1554823820.120 [BR_Ping1D](INFO): startup: 2
2019-04-09T15:30:20.120Z,1554823820.120 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T15:30:20.120Z,1554823820.120 [BR_Ping1D](INFO): elapsed 1.2134
2019-04-09T15:30:20.120Z,1554823820.120 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T15:30:20.121Z,1554823820.121 [BR_Ping1D](INFO): received full packet, type: 1210
2019-04-09T15:30:20.121Z,1554823820.121 [BR_Ping1D](INFO): parsing data, res: 20
2019-04-09T15:30:20.121Z,1554823820.121 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-09T15:30:20.121Z,1554823820.121 [BR_Ping1D](INFO):  voltage: 5056
2019-04-09T15:30:20.122Z,1554823820.122 [BR_Ping1D](INFO):  interval: 7681
2019-04-09T15:30:20.122Z,1554823820.122 [BR_Ping1D](INFO):  gain: 1
2019-04-09T15:30:20.122Z,1554823820.122 [BR_Ping1D](INFO):  auto: 1
2019-04-09T15:30:20.122Z,1554823820.122 [BR_Ping1D](INFO): received full packet, type: 1215
2019-04-09T15:30:20.123Z,1554823820.123 [BR_Ping1D](INFO): CRC error in received UART packet
2019-04-09T15:30:20.123Z,1554823820.123 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T15:30:20.123Z,1554823820.123 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-04-09T15:30:20.123Z,1554823820.123 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-04-09T15:30:20.123Z,1554823820.123 [BR_Ping1D](INFO): (ping interval) 500 <-> 0
2019-04-09T15:30:20.123Z,1554823820.123 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-04-09T15:30:20.124Z,1554823820.124 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 65535
2019-04-09T15:30:20.124Z,1554823820.124 [BR_Ping1D](INFO): (ping enable) 1 <-> 0
2019-04-09T15:30:20.124Z,1554823820.124 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-04-09T15:30:20.124Z,1554823820.124 [BR_Ping1D] Data Fault, FailCount= 2
2019-04-09T15:30:20.124Z,1554823820.124 [BR_Ping1D](ERROR): Data Fault
2019-04-09T15:30:20.144Z,1554823820.144 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T15:30:20.518Z,1554823820.518 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:30:20.533Z,1554823820.533 [BR_Ping1D](INFO): Stop
2019-04-09T15:30:20.533Z,1554823820.533 [BR_Ping1D](INFO): uninitialize
2019-04-09T15:30:20.533Z,1554823820.533 [BR_Ping1D](INFO): Powering down
2019-04-09T15:30:20.943Z,1554823820.943 [BR_Ping1D](INFO): Stopping
2019-04-09T15:30:20.958Z,1554823820.958 [CBIT](FAULT): Main Battery Failure. Count: 1221
2019-04-09T15:30:21.341Z,1554823821.341 [BR_Ping1D](INFO): Stopping
2019-04-09T15:30:21.733Z,1554823821.733 [BR_Ping1D](INFO): Stopped
2019-04-09T15:30:21.752Z,1554823821.752 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T15:30:21.752Z,1554823821.752 [BR_Ping1D] No Fault, FailCount= 2
2019-04-09T15:30:22.138Z,1554823822.138 [BR_Ping1D](INFO): Start
2019-04-09T15:30:22.539Z,1554823822.539 [BR_Ping1D](INFO): Starting
2019-04-09T15:30:22.946Z,1554823822.946 [BR_Ping1D](INFO): Starting
2019-04-09T15:30:22.946Z,1554823822.946 [BR_Ping1D](INFO): readConfig
2019-04-09T15:30:22.947Z,1554823822.947 [BR_Ping1D](INFO): startup: 0
2019-04-09T15:30:22.947Z,1554823822.947 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T15:30:22.958Z,1554823822.958 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T15:30:22.959Z,1554823822.959 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T15:30:22.960Z,1554823822.960 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T15:30:22.962Z,1554823822.962 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T15:30:23.349Z,1554823823.349 [BR_Ping1D](INFO): Starting
2019-04-09T15:30:23.754Z,1554823823.754 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:30:23.769Z,1554823823.769 [BR_Ping1D](INFO): Starting
2019-04-09T15:30:23.769Z,1554823823.769 [BR_Ping1D](INFO): readConfig
2019-04-09T15:30:23.769Z,1554823823.769 [BR_Ping1D](INFO): startup: 1
2019-04-09T15:30:23.769Z,1554823823.769 [BR_Ping1D](INFO): UART requesting status
2019-04-09T15:30:24.176Z,1554823824.176 [BR_Ping1D](INFO): Starting
2019-04-09T15:30:24.176Z,1554823824.176 [BR_Ping1D](INFO): readConfig
2019-04-09T15:30:24.176Z,1554823824.176 [BR_Ping1D](INFO): startup: 2
2019-04-09T15:30:24.176Z,1554823824.176 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T15:30:24.177Z,1554823824.177 [BR_Ping1D](INFO): elapsed 1.2297
2019-04-09T15:30:24.177Z,1554823824.177 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T15:30:24.177Z,1554823824.177 [BR_Ping1D](INFO): received full packet, type: 1210
2019-04-09T15:30:24.178Z,1554823824.178 [BR_Ping1D](INFO): parsing data, res: 20
2019-04-09T15:30:24.178Z,1554823824.178 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-09T15:30:24.178Z,1554823824.178 [BR_Ping1D](INFO):  voltage: 5054
2019-04-09T15:30:24.178Z,1554823824.178 [BR_Ping1D](INFO):  interval: 7681
2019-04-09T15:30:24.178Z,1554823824.178 [BR_Ping1D](INFO):  gain: 1
2019-04-09T15:30:24.178Z,1554823824.178 [BR_Ping1D](INFO):  auto: 1
2019-04-09T15:30:24.179Z,1554823824.179 [BR_Ping1D](INFO): received full packet, type: 1215
2019-04-09T15:30:24.179Z,1554823824.179 [BR_Ping1D](INFO): CRC error in received UART packet
2019-04-09T15:30:24.179Z,1554823824.179 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T15:30:24.179Z,1554823824.179 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-04-09T15:30:24.179Z,1554823824.179 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-04-09T15:30:24.180Z,1554823824.180 [BR_Ping1D](INFO): (ping interval) 500 <-> 0
2019-04-09T15:30:24.180Z,1554823824.180 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-04-09T15:30:24.180Z,1554823824.180 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 65535
2019-04-09T15:30:24.181Z,1554823824.181 [BR_Ping1D](INFO): (ping enable) 1 <-> 0
2019-04-09T15:30:24.181Z,1554823824.181 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-04-09T15:30:24.181Z,1554823824.181 [BR_Ping1D] Data Fault, FailCount= 3
2019-04-09T15:30:24.181Z,1554823824.181 [BR_Ping1D](ERROR): Data Fault
2019-04-09T15:30:24.200Z,1554823824.200 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T15:30:24.577Z,1554823824.577 [BR_Ping1D](INFO): Stop
2019-04-09T15:30:24.577Z,1554823824.577 [BR_Ping1D](INFO): uninitialize
2019-04-09T15:30:24.577Z,1554823824.577 [BR_Ping1D](INFO): Powering down
2019-04-09T15:30:24.966Z,1554823824.966 [BR_Ping1D](INFO): Stopping
2019-04-09T15:30:24.978Z,1554823824.978 [CBIT](FAULT): Main Battery Failure. Count: 1231
2019-04-09T15:30:25.370Z,1554823825.370 [BR_Ping1D](INFO): Stopping
2019-04-09T15:30:25.778Z,1554823825.778 [BR_Ping1D](INFO): Stopped
2019-04-09T15:30:25.792Z,1554823825.792 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T15:30:25.792Z,1554823825.792 [BR_Ping1D] No Fault, FailCount= 3
2019-04-09T15:30:26.178Z,1554823826.178 [BR_Ping1D](INFO): Start
2019-04-09T15:30:26.578Z,1554823826.578 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:30:26.592Z,1554823826.592 [BR_Ping1D](INFO): Starting
2019-04-09T15:30:26.996Z,1554823826.996 [BR_Ping1D](INFO): Starting
2019-04-09T15:30:26.996Z,1554823826.996 [BR_Ping1D](INFO): readConfig
2019-04-09T15:30:26.997Z,1554823826.997 [BR_Ping1D](INFO): startup: 0
2019-04-09T15:30:26.997Z,1554823826.997 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T15:30:27.010Z,1554823827.010 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T15:30:27.011Z,1554823827.011 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T15:30:27.012Z,1554823827.012 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T15:30:27.014Z,1554823827.014 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T15:30:27.400Z,1554823827.400 [BR_Ping1D](INFO): Starting
2019-04-09T15:30:27.794Z,1554823827.794 [BR_Ping1D](INFO): Starting
2019-04-09T15:30:27.794Z,1554823827.794 [BR_Ping1D](INFO): readConfig
2019-04-09T15:30:27.794Z,1554823827.794 [BR_Ping1D](INFO): startup: 1
2019-04-09T15:30:27.794Z,1554823827.794 [BR_Ping1D](INFO): UART requesting status
2019-04-09T15:30:28.201Z,1554823828.201 [BR_Ping1D](INFO): Starting
2019-04-09T15:30:28.202Z,1554823828.202 [BR_Ping1D](INFO): readConfig
2019-04-09T15:30:28.202Z,1554823828.202 [BR_Ping1D](INFO): startup: 2
2019-04-09T15:30:28.202Z,1554823828.202 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T15:30:28.202Z,1554823828.202 [BR_Ping1D](INFO): elapsed 1.2052
2019-04-09T15:30:28.202Z,1554823828.202 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T15:30:28.203Z,1554823828.203 [BR_Ping1D](INFO): received full packet, type: 1210
2019-04-09T15:30:28.203Z,1554823828.203 [BR_Ping1D](INFO): parsing data, res: 20
2019-04-09T15:30:28.203Z,1554823828.203 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-09T15:30:28.203Z,1554823828.203 [BR_Ping1D](INFO):  voltage: 5056
2019-04-09T15:30:28.204Z,1554823828.204 [BR_Ping1D](INFO):  interval: 7681
2019-04-09T15:30:28.204Z,1554823828.204 [BR_Ping1D](INFO):  gain: 4
2019-04-09T15:30:28.204Z,1554823828.204 [BR_Ping1D](INFO):  auto: 1
2019-04-09T15:30:28.204Z,1554823828.204 [BR_Ping1D](INFO): received full packet, type: 1215
2019-04-09T15:30:28.204Z,1554823828.204 [BR_Ping1D](INFO): CRC error in received UART packet
2019-04-09T15:30:28.205Z,1554823828.205 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T15:30:28.205Z,1554823828.205 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-04-09T15:30:28.205Z,1554823828.205 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-04-09T15:30:28.205Z,1554823828.205 [BR_Ping1D](INFO): (ping interval) 500 <-> 0
2019-04-09T15:30:28.205Z,1554823828.205 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-04-09T15:30:28.206Z,1554823828.206 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 65535
2019-04-09T15:30:28.206Z,1554823828.206 [BR_Ping1D](INFO): (ping enable) 1 <-> 0
2019-04-09T15:30:28.206Z,1554823828.206 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-04-09T15:30:28.206Z,1554823828.206 [BR_Ping1D] Data Fault, FailCount= 4
2019-04-09T15:30:28.206Z,1554823828.206 [BR_Ping1D](ERROR): Data Fault
2019-04-09T15:30:28.264Z,1554823828.264 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T15:30:28.603Z,1554823828.603 [BR_Ping1D](INFO): Stop
2019-04-09T15:30:28.603Z,1554823828.603 [BR_Ping1D](INFO): uninitialize
2019-04-09T15:30:28.603Z,1554823828.603 [BR_Ping1D](INFO): Powering down
2019-04-09T15:30:29.010Z,1554823829.010 [BR_Ping1D](INFO): Stopping
2019-04-09T15:30:29.022Z,1554823829.022 [CBIT](FAULT): Main Battery Failure. Count: 1241
2019-04-09T15:30:29.407Z,1554823829.407 [BR_Ping1D](INFO): Stopping
2019-04-09T15:30:29.810Z,1554823829.810 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:30:29.824Z,1554823829.824 [BR_Ping1D](INFO): Stopped
2019-04-09T15:30:29.844Z,1554823829.844 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T15:30:29.844Z,1554823829.844 [BR_Ping1D] No Fault, FailCount= 4
2019-04-09T15:30:30.232Z,1554823830.232 [BR_Ping1D](INFO): Start
2019-04-09T15:30:30.635Z,1554823830.635 [BR_Ping1D](INFO): Starting
2019-04-09T15:30:31.023Z,1554823831.023 [BR_Ping1D](INFO): Starting
2019-04-09T15:30:31.023Z,1554823831.023 [BR_Ping1D](INFO): readConfig
2019-04-09T15:30:31.024Z,1554823831.024 [BR_Ping1D](INFO): startup: 0
2019-04-09T15:30:31.024Z,1554823831.024 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T15:30:31.046Z,1554823831.046 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T15:30:31.046Z,1554823831.046 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T15:30:31.047Z,1554823831.047 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T15:30:31.047Z,1554823831.047 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T15:30:31.429Z,1554823831.429 [BR_Ping1D](INFO): Starting
2019-04-09T15:30:31.839Z,1554823831.839 [BR_Ping1D](INFO): Starting
2019-04-09T15:30:31.839Z,1554823831.839 [BR_Ping1D](INFO): readConfig
2019-04-09T15:30:31.839Z,1554823831.839 [BR_Ping1D](INFO): startup: 1
2019-04-09T15:30:31.839Z,1554823831.839 [BR_Ping1D](INFO): UART requesting status
2019-04-09T15:30:32.238Z,1554823832.238 [BR_Ping1D](INFO): Starting
2019-04-09T15:30:32.238Z,1554823832.238 [BR_Ping1D](INFO): readConfig
2019-04-09T15:30:32.238Z,1554823832.238 [BR_Ping1D](INFO): startup: 2
2019-04-09T15:30:32.238Z,1554823832.238 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T15:30:32.239Z,1554823832.239 [BR_Ping1D](INFO): elapsed 1.2148
2019-04-09T15:30:32.239Z,1554823832.239 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T15:30:32.239Z,1554823832.239 [BR_Ping1D](INFO): received full packet, type: 1210
2019-04-09T15:30:32.240Z,1554823832.240 [BR_Ping1D](INFO): parsing data, res: 20
2019-04-09T15:30:32.240Z,1554823832.240 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-09T15:30:32.240Z,1554823832.240 [BR_Ping1D](INFO):  voltage: 5054
2019-04-09T15:30:32.240Z,1554823832.240 [BR_Ping1D](INFO):  interval: 7681
2019-04-09T15:30:32.240Z,1554823832.240 [BR_Ping1D](INFO):  gain: 4
2019-04-09T15:30:32.240Z,1554823832.240 [BR_Ping1D](INFO):  auto: 1
2019-04-09T15:30:32.241Z,1554823832.241 [BR_Ping1D](INFO): received full packet, type: 1215
2019-04-09T15:30:32.241Z,1554823832.241 [BR_Ping1D](INFO): CRC error in received UART packet
2019-04-09T15:30:32.241Z,1554823832.241 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T15:30:32.241Z,1554823832.241 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-04-09T15:30:32.241Z,1554823832.241 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-04-09T15:30:32.241Z,1554823832.241 [BR_Ping1D](INFO): (ping interval) 500 <-> 0
2019-04-09T15:30:32.242Z,1554823832.242 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-04-09T15:30:32.242Z,1554823832.242 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 65535
2019-04-09T15:30:32.243Z,1554823832.243 [BR_Ping1D](INFO): (ping enable) 1 <-> 0
2019-04-09T15:30:32.243Z,1554823832.243 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-04-09T15:30:32.243Z,1554823832.243 [BR_Ping1D] Data Fault, FailCount= 5
2019-04-09T15:30:32.243Z,1554823832.243 [BR_Ping1D](ERROR): Data Fault
2019-04-09T15:30:32.264Z,1554823832.264 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T15:30:32.264Z,1554823832.264 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-04-09T15:30:32.638Z,1554823832.638 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:30:32.653Z,1554823832.653 [BR_Ping1D](INFO): Stop
2019-04-09T15:30:32.653Z,1554823832.653 [BR_Ping1D](INFO): uninitialize
2019-04-09T15:30:32.653Z,1554823832.653 [BR_Ping1D](INFO): Powering down
2019-04-09T15:30:32.750Z,1554823832.750 [CBIT](INFO): Critical error at 20190409T153032
2019-04-09T15:30:33.057Z,1554823833.057 [BR_Ping1D](INFO): Stopping
2019-04-09T15:30:33.068Z,1554823833.068 [CBIT](FAULT): Main Battery Failure. Count: 1251
2019-04-09T15:30:33.461Z,1554823833.461 [BR_Ping1D](INFO): Stopping
2019-04-09T15:30:33.855Z,1554823833.855 [BR_Ping1D](INFO): Stopped
2019-04-09T15:30:35.450Z,1554823835.450 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T15:30:35.451Z,1554823835.451 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-04-09T15:30:35.451Z,1554823835.451 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T15:30:35.466Z,1554823835.466 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:30:35.495Z,1554823835.495 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T15:30:35.854Z,1554823835.854 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T15:30:36.701Z,1554823836.701 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T15:30:36.701Z,1554823836.701 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-04-09T15:30:37.096Z,1554823837.096 [CBIT](FAULT): Main Battery Failure. Count: 1261
2019-04-09T15:30:37.486Z,1554823837.486 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:30:39.086Z,1554823839.086 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T15:30:39.087Z,1554823839.087 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T15:30:39.506Z,1554823839.506 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:30:40.732Z,1554823840.732 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-04-09T15:25:40.6Z
2019-04-09T15:30:40.732Z,1554823840.732 [Default:CheckIn:Read_GPS] Stopped
2019-04-09T15:30:40.732Z,1554823840.732 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-04-09T15:30:41.139Z,1554823841.139 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-09T15:30:41.146Z,1554823841.146 [CBIT](FAULT): Main Battery Failure. Count: 1271
2019-04-09T15:30:41.641Z,1554823841.641 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:30:44.846Z,1554823844.846 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:30:45.281Z,1554823845.281 [CBIT](FAULT): Main Battery Failure. Count: 1281
2019-04-09T15:30:46.814Z,1554823846.814 [DataOverHttps](INFO): Sending 110 bytes from file Logs/20190409T152156/Courier0004.lzma
2019-04-09T15:30:47.619Z,1554823847.619 [DataOverHttps](INFO): Moved sent file to Logs/20190409T152156/Courier0004.lzma.bak
2019-04-09T15:30:47.620Z,1554823847.620 [DataOverHttps](INFO): SBD MOMSN=10512856
2019-04-09T15:30:47.680Z,1554823847.680 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:30:49.309Z,1554823849.309 [CBIT](FAULT): Main Battery Failure. Count: 1291
2019-04-09T15:30:50.502Z,1554823850.502 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:30:52.522Z,1554823852.522 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:30:53.359Z,1554823853.359 [CBIT](FAULT): Main Battery Failure. Count: 1301
2019-04-09T15:30:54.546Z,1554823854.546 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:30:57.389Z,1554823857.389 [CBIT](FAULT): Main Battery Failure. Count: 1311
2019-04-09T15:30:57.778Z,1554823857.778 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:30:59.375Z,1554823859.375 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T15:30:59.375Z,1554823859.375 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2019-04-09T15:30:59.375Z,1554823859.375 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T15:30:59.412Z,1554823859.412 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T15:30:59.778Z,1554823859.778 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T15:31:00.602Z,1554823860.602 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:31:00.644Z,1554823860.644 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T15:31:00.644Z,1554823860.644 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2019-04-09T15:31:01.445Z,1554823861.445 [CBIT](FAULT): Main Battery Failure. Count: 1321
2019-04-09T15:31:03.010Z,1554823863.010 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T15:31:03.011Z,1554823863.011 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T15:31:03.834Z,1554823863.834 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:31:05.561Z,1554823865.561 [CBIT](FAULT): Main Battery Failure. Count: 1331
2019-04-09T15:31:06.666Z,1554823866.666 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:31:09.503Z,1554823869.503 [CBIT](FAULT): Main Battery Failure. Count: 1341
2019-04-09T15:31:09.894Z,1554823869.894 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:31:12.722Z,1554823872.722 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:31:13.562Z,1554823873.562 [CBIT](FAULT): Main Battery Failure. Count: 1351
2019-04-09T15:31:15.550Z,1554823875.550 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:31:15.597Z,1554823875.597 [DataOverHttps](INFO): Sending 1355 bytes from file Logs/20190409T145246/Express0001.lzma
2019-04-09T15:31:16.399Z,1554823876.399 [DataOverHttps](INFO): Moved sent file to Logs/20190409T145246/Express0001.lzma.bak
2019-04-09T15:31:16.400Z,1554823876.400 [DataOverHttps](INFO): SBD MOMSN=10512861
2019-04-09T15:31:17.570Z,1554823877.570 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:31:17.601Z,1554823877.601 [CBIT](FAULT): Main Battery Failure. Count: 1361
2019-04-09T15:31:19.620Z,1554823879.620 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T15:31:19.620Z,1554823879.620 [Aanderaa_O2] No Fault, FailCount= 3
2019-04-09T15:31:20.802Z,1554823880.802 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:31:21.643Z,1554823881.643 [CBIT](FAULT): Main Battery Failure. Count: 1371
2019-04-09T15:31:23.211Z,1554823883.211 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T15:31:23.211Z,1554823883.211 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2019-04-09T15:31:23.211Z,1554823883.211 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T15:31:23.248Z,1554823883.248 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T15:31:23.614Z,1554823883.614 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T15:31:23.750Z,1554823883.750 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:31:24.471Z,1554823884.471 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T15:31:24.471Z,1554823884.471 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2019-04-09T15:31:25.674Z,1554823885.674 [CBIT](FAULT): Main Battery Failure. Count: 1381
2019-04-09T15:31:26.847Z,1554823886.847 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T15:31:26.847Z,1554823886.847 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T15:31:26.882Z,1554823886.882 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:31:29.690Z,1554823889.690 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:31:29.717Z,1554823889.717 [CBIT](FAULT): Main Battery Failure. Count: 1391
2019-04-09T15:31:30.078Z,1554823890.078 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T15:31:30.078Z,1554823890.078 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T15:31:30.078Z,1554823890.078 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T15:31:30.132Z,1554823890.132 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T15:31:30.602Z,1554823890.602 [Aanderaa_O2](INFO): Powering down
2019-04-09T15:31:31.327Z,1554823891.327 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T15:31:31.327Z,1554823891.327 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-09T15:31:32.922Z,1554823892.922 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:31:33.759Z,1554823893.759 [CBIT](FAULT): Main Battery Failure. Count: 1401
2019-04-09T15:31:35.754Z,1554823895.754 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:31:37.789Z,1554823897.789 [CBIT](FAULT): Main Battery Failure. Count: 1411
2019-04-09T15:31:38.582Z,1554823898.582 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:31:40.602Z,1554823900.602 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:31:41.794Z,1554823901.794 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T15:31:41.794Z,1554823901.794 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-09T15:31:41.794Z,1554823901.794 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T15:31:41.850Z,1554823901.850 [CBIT](FAULT): Main Battery Failure. Count: 1421
2019-04-09T15:31:41.856Z,1554823901.856 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T15:31:42.318Z,1554823902.318 [Aanderaa_O2](INFO): Powering down
2019-04-09T15:31:42.618Z,1554823902.618 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:31:43.078Z,1554823903.078 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T15:31:43.078Z,1554823903.078 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-09T15:31:44.273Z,1554823904.273 [DataOverHttps](INFO): Sending 1048 bytes from file Logs/20190409T145246/Express0005.lzma
2019-04-09T15:31:45.125Z,1554823905.125 [DataOverHttps](INFO): Moved sent file to Logs/20190409T145246/Express0005.lzma.bak
2019-04-09T15:31:45.125Z,1554823905.125 [DataOverHttps](INFO): SBD MOMSN=10512934
2019-04-09T15:31:45.850Z,1554823905.850 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:31:45.881Z,1554823905.881 [CBIT](FAULT): Main Battery Failure. Count: 1431
2019-04-09T15:31:45.884Z,1554823905.884 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T15:31:45.884Z,1554823905.884 [CTD_Seabird] No Fault, FailCount= 3
2019-04-09T15:31:45.906Z,1554823905.906 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T15:31:45.914Z,1554823905.914 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T15:31:47.047Z,1554823907.047 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T15:31:47.047Z,1554823907.047 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2019-04-09T15:31:47.047Z,1554823907.047 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T15:31:47.077Z,1554823907.077 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T15:31:47.451Z,1554823907.451 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T15:31:48.295Z,1554823908.295 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T15:31:48.295Z,1554823908.295 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2019-04-09T15:31:48.678Z,1554823908.678 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:31:49.903Z,1554823909.903 [CBIT](FAULT): Main Battery Failure. Count: 1441
2019-04-09T15:31:50.683Z,1554823910.683 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T15:31:50.683Z,1554823910.683 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T15:31:51.914Z,1554823911.914 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:31:53.510Z,1554823913.510 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T15:31:53.510Z,1554823913.510 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-09T15:31:53.510Z,1554823913.510 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T15:31:53.552Z,1554823913.552 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T15:31:53.552Z,1554823913.552 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-09T15:31:54.034Z,1554823914.034 [Aanderaa_O2](INFO): Powering down
2019-04-09T15:31:54.070Z,1554823914.070 [CBIT](FAULT): Main Battery Failure. Count: 1451
2019-04-09T15:31:54.742Z,1554823914.742 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:31:57.970Z,1554823917.970 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:31:58.002Z,1554823918.002 [CBIT](FAULT): Main Battery Failure. Count: 1461
2019-04-09T15:31:58.958Z,1554823918.958 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T15:31:58.958Z,1554823918.958 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-09T15:31:58.958Z,1554823918.958 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T15:31:59.208Z,1554823919.208 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T15:31:59.362Z,1554823919.362 [CTD_Seabird](INFO): Powering down
2019-04-09T15:32:00.409Z,1554823920.409 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T15:32:00.409Z,1554823920.409 [CTD_Seabird] No Fault, FailCount= 1
2019-04-09T15:32:00.706Z,1554823920.706 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T15:32:00.708Z,1554823920.708 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T15:32:00.798Z,1554823920.798 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:32:02.029Z,1554823922.029 [CBIT](FAULT): Main Battery Failure. Count: 1471
2019-04-09T15:32:03.630Z,1554823923.630 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:32:05.646Z,1554823925.646 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:32:06.081Z,1554823926.081 [CBIT](FAULT): Main Battery Failure. Count: 1481
2019-04-09T15:32:08.053Z,1554823928.053 [DataOverHttps](INFO): Sending 1358 bytes from file Logs/20190409T150011/Express0001.lzma
2019-04-09T15:32:08.855Z,1554823928.855 [DataOverHttps](INFO): Moved sent file to Logs/20190409T150011/Express0001.lzma.bak
2019-04-09T15:32:08.856Z,1554823928.856 [DataOverHttps](INFO): SBD MOMSN=10513044
2019-04-09T15:32:08.898Z,1554823928.898 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:32:10.109Z,1554823930.109 [CBIT](FAULT): Main Battery Failure. Count: 1491
2019-04-09T15:32:10.886Z,1554823930.886 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T15:32:10.887Z,1554823930.887 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2019-04-09T15:32:10.887Z,1554823930.887 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T15:32:10.924Z,1554823930.924 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T15:32:11.290Z,1554823931.290 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T15:32:11.710Z,1554823931.710 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:32:11.733Z,1554823931.733 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T15:32:11.733Z,1554823931.733 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-04-09T15:32:11.733Z,1554823931.733 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T15:32:11.735Z,1554823931.735 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T15:32:11.735Z,1554823931.735 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2019-04-09T15:32:11.735Z,1554823931.735 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T15:32:11.736Z,1554823931.736 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T15:32:11.736Z,1554823931.736 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5
2019-04-09T15:32:11.736Z,1554823931.736 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T15:32:11.747Z,1554823931.747 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T15:32:11.747Z,1554823931.747 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T15:32:11.747Z,1554823931.747 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T15:32:12.150Z,1554823932.150 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T15:32:12.150Z,1554823932.150 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-04-09T15:32:12.151Z,1554823932.151 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T15:32:12.151Z,1554823932.151 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2019-04-09T15:32:12.151Z,1554823932.151 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T15:32:12.151Z,1554823932.151 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5
2019-04-09T15:32:12.152Z,1554823932.152 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T15:32:12.152Z,1554823932.152 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2019-04-09T15:32:12.541Z,1554823932.541 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T15:32:13.758Z,1554823933.758 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T15:32:13.758Z,1554823933.758 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-09T15:32:13.758Z,1554823933.758 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T15:32:14.149Z,1554823934.149 [CBIT](FAULT): Main Battery Failure. Count: 1501
2019-04-09T15:32:14.152Z,1554823934.152 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T15:32:14.162Z,1554823934.162 [CTD_Seabird](INFO): Powering down
2019-04-09T15:32:14.523Z,1554823934.523 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T15:32:14.524Z,1554823934.524 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T15:32:14.942Z,1554823934.942 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:32:15.383Z,1554823935.383 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T15:32:15.383Z,1554823935.383 [CTD_Seabird] No Fault, FailCount= 2
2019-04-09T15:32:15.502Z,1554823935.502 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T15:32:15.504Z,1554823935.504 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T15:32:17.774Z,1554823937.774 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:32:18.207Z,1554823938.207 [CBIT](FAULT): Main Battery Failure. Count: 1511
2019-04-09T15:32:21.002Z,1554823941.002 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:32:22.238Z,1554823942.238 [CBIT](FAULT): Main Battery Failure. Count: 1521
2019-04-09T15:32:23.834Z,1554823943.834 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:32:26.274Z,1554823946.274 [CBIT](FAULT): Main Battery Failure. Count: 1531
2019-04-09T15:32:26.662Z,1554823946.662 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:32:28.566Z,1554823948.566 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T15:32:28.566Z,1554823948.566 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-09T15:32:28.566Z,1554823948.566 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T15:32:28.682Z,1554823948.682 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:32:28.711Z,1554823948.711 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T15:32:28.712Z,1554823948.712 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-09T15:32:28.970Z,1554823948.970 [CTD_Seabird](INFO): Powering down
2019-04-09T15:32:30.336Z,1554823950.336 [CBIT](FAULT): Main Battery Failure. Count: 1541
2019-04-09T15:32:30.698Z,1554823950.698 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:32:33.930Z,1554823953.930 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:32:34.375Z,1554823954.375 [CBIT](FAULT): Main Battery Failure. Count: 1551
2019-04-09T15:32:34.722Z,1554823954.722 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T15:32:34.722Z,1554823954.722 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2019-04-09T15:32:34.722Z,1554823954.722 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T15:32:34.768Z,1554823954.768 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T15:32:35.126Z,1554823955.126 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T15:32:35.980Z,1554823955.980 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T15:32:35.980Z,1554823955.980 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2019-04-09T15:32:36.707Z,1554823956.707 [DataOverHttps](INFO): Sending 461 bytes from file Logs/20190409T150011/Express0005.lzma
2019-04-09T15:32:36.759Z,1554823956.759 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:32:37.511Z,1554823957.511 [DataOverHttps](INFO): Moved sent file to Logs/20190409T150011/Express0005.lzma.bak
2019-04-09T15:32:37.512Z,1554823957.512 [DataOverHttps](INFO): SBD MOMSN=10513117
2019-04-09T15:32:38.359Z,1554823958.359 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T15:32:38.359Z,1554823958.359 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T15:32:38.406Z,1554823958.406 [CBIT](FAULT): Main Battery Failure. Count: 1561
2019-04-09T15:32:39.998Z,1554823959.998 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:32:42.437Z,1554823962.437 [CBIT](FAULT): Main Battery Failure. Count: 1571
2019-04-09T15:32:42.440Z,1554823962.440 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T15:32:42.440Z,1554823962.440 [MassServo] No Fault, FailCount= 5
2019-04-09T15:32:42.818Z,1554823962.818 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:32:44.350Z,1554823964.350 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T15:32:44.350Z,1554823964.350 [MassServo] Communications Fault, FailCount= 1
2019-04-09T15:32:44.350Z,1554823964.350 [MassServo](ERROR): Communications Fault
2019-04-09T15:32:44.370Z,1554823964.370 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T15:32:44.451Z,1554823964.451 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T15:32:44.452Z,1554823964.452 [MassServo](INFO): Powering down
2019-04-09T15:32:45.260Z,1554823965.260 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:32:45.379Z,1554823965.379 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T15:32:45.383Z,1554823965.383 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T15:32:45.383Z,1554823965.383 [MassServo] No Fault, FailCount= 1
2019-04-09T15:32:46.054Z,1554823966.054 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:32:46.522Z,1554823966.522 [CBIT](FAULT): Main Battery Failure. Count: 1581
2019-04-09T15:32:48.006Z,1554823968.006 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T15:32:48.006Z,1554823968.006 [MassServo] Communications Fault, FailCount= 2
2019-04-09T15:32:48.006Z,1554823968.006 [MassServo](ERROR): Communications Fault
2019-04-09T15:32:48.010Z,1554823968.010 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T15:32:48.088Z,1554823968.088 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T15:32:48.088Z,1554823968.088 [MassServo](INFO): Powering down
2019-04-09T15:32:48.864Z,1554823968.864 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-04-09T15:32:48.864Z,1554823968.864 [NAL9602] Data Fault, FailCount= 1
2019-04-09T15:32:48.864Z,1554823968.864 [NAL9602](ERROR): Data Fault
2019-04-09T15:32:48.889Z,1554823968.889 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:32:49.007Z,1554823969.007 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T15:32:49.012Z,1554823969.012 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T15:32:49.012Z,1554823969.012 [MassServo] No Fault, FailCount= 2
2019-04-09T15:32:49.012Z,1554823969.012 [CBIT](ERROR): Data Fault in component: NAL9602
2019-04-09T15:32:49.275Z,1554823969.275 [NAL9602](INFO): Powering down
2019-04-09T15:32:50.121Z,1554823970.121 [CBIT](INFO): Clearing failed state for component NAL9602
2019-04-09T15:32:50.121Z,1554823970.121 [NAL9602] No Fault, FailCount= 1
2019-04-09T15:32:50.508Z,1554823970.508 [CBIT](FAULT): Main Battery Failure. Count: 1591
2019-04-09T15:32:51.622Z,1554823971.622 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T15:32:51.622Z,1554823971.622 [MassServo] Communications Fault, FailCount= 3
2019-04-09T15:32:51.622Z,1554823971.622 [MassServo](ERROR): Communications Fault
2019-04-09T15:32:51.626Z,1554823971.626 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T15:32:51.716Z,1554823971.716 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T15:32:51.717Z,1554823971.717 [MassServo](INFO): Powering down
2019-04-09T15:32:52.525Z,1554823972.525 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:32:52.639Z,1554823972.639 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T15:32:52.643Z,1554823972.643 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T15:32:52.643Z,1554823972.643 [MassServo] No Fault, FailCount= 3
2019-04-09T15:32:54.575Z,1554823974.575 [CBIT](FAULT): Main Battery Failure. Count: 1601
2019-04-09T15:32:55.266Z,1554823975.266 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T15:32:55.266Z,1554823975.266 [MassServo] Communications Fault, FailCount= 4
2019-04-09T15:32:55.266Z,1554823975.266 [MassServo](ERROR): Communications Fault
2019-04-09T15:32:55.270Z,1554823975.270 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T15:32:55.364Z,1554823975.364 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T15:32:55.364Z,1554823975.364 [MassServo](INFO): Powering down
2019-04-09T15:32:56.172Z,1554823976.172 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:32:56.287Z,1554823976.287 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T15:32:56.291Z,1554823976.291 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T15:32:56.291Z,1554823976.291 [MassServo] No Fault, FailCount= 4
2019-04-09T15:32:57.825Z,1554823977.825 [DataOverHttps](INFO): Sending 1350 bytes from file Logs/20190409T150412/Express0001.lzma
2019-04-09T15:32:58.559Z,1554823978.559 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T15:32:58.559Z,1554823978.559 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7
2019-04-09T15:32:58.559Z,1554823978.559 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T15:32:58.628Z,1554823978.628 [DataOverHttps](INFO): Moved sent file to Logs/20190409T150412/Express0001.lzma.bak
2019-04-09T15:32:58.628Z,1554823978.628 [DataOverHttps](INFO): SBD MOMSN=10513153
2019-04-09T15:32:58.890Z,1554823978.890 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T15:32:58.890Z,1554823978.890 [MassServo] Communications Fault, FailCount= 5
2019-04-09T15:32:58.890Z,1554823978.890 [MassServo](ERROR): Communications Fault
2019-04-09T15:32:58.892Z,1554823978.892 [CBIT](FAULT): Main Battery Failure. Count: 1611
2019-04-09T15:32:58.894Z,1554823978.894 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T15:32:58.895Z,1554823978.895 [CBIT](CRITICAL): Communications Fault in component: MassServo
2019-04-09T15:32:58.895Z,1554823978.895 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T15:32:58.962Z,1554823978.962 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T15:32:59.125Z,1554823979.125 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T15:32:59.125Z,1554823979.125 [MassServo](INFO): Powering down
2019-04-09T15:32:59.127Z,1554823979.127 [CBIT](INFO): Critical error at 20190409T153258
2019-04-09T15:32:59.804Z,1554823979.804 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:32:59.919Z,1554823979.919 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T15:32:59.924Z,1554823979.924 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T15:32:59.924Z,1554823979.924 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7
2019-04-09T15:33:02.194Z,1554823982.194 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T15:33:02.194Z,1554823982.194 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T15:33:02.629Z,1554823982.629 [CBIT](FAULT): Main Battery Failure. Count: 1621
2019-04-09T15:33:06.672Z,1554823986.672 [CBIT](FAULT): Main Battery Failure. Count: 1631
2019-04-09T15:33:10.708Z,1554823990.708 [CBIT](FAULT): Main Battery Failure. Count: 1641
2019-04-09T15:33:14.751Z,1554823994.751 [CBIT](FAULT): Main Battery Failure. Count: 1651
2019-04-09T15:33:18.789Z,1554823998.789 [CBIT](FAULT): Main Battery Failure. Count: 1661
2019-04-09T15:33:19.385Z,1554823999.385 [DataOverHttps](INFO): Sending 1196 bytes from file Logs/20190409T150412/Express0005.lzma
2019-04-09T15:33:19.575Z,1554823999.575 [NAL9602](INFO): Powering up NAL9602
2019-04-09T15:33:20.188Z,1554824000.188 [DataOverHttps](INFO): Moved sent file to Logs/20190409T150412/Express0005.lzma.bak
2019-04-09T15:33:20.188Z,1554824000.188 [DataOverHttps](INFO): SBD MOMSN=10513226
2019-04-09T15:33:22.394Z,1554824002.394 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T15:33:22.395Z,1554824002.395 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 8
2019-04-09T15:33:22.395Z,1554824002.395 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T15:33:22.432Z,1554824002.432 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T15:33:22.432Z,1554824002.432 [CBIT](FAULT): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T15:33:22.798Z,1554824002.798 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T15:33:22.989Z,1554824002.989 [CBIT](FAULT): Main Battery Failure. Count: 1671
2019-04-09T15:33:26.865Z,1554824006.865 [CBIT](FAULT): Main Battery Failure. Count: 1681
2019-04-09T15:33:30.483Z,1554824010.483 [NAL9602](INFO): NAL9602 initialized
2019-04-09T15:33:30.913Z,1554824010.913 [CBIT](FAULT): Main Battery Failure. Count: 1691
2019-04-09T15:33:31.302Z,1554824011.302 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:33:34.534Z,1554824014.534 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:33:34.960Z,1554824014.960 [CBIT](FAULT): Main Battery Failure. Count: 1701
2019-04-09T15:33:37.358Z,1554824017.358 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:33:38.990Z,1554824018.990 [CBIT](FAULT): Main Battery Failure. Count: 1711
2019-04-09T15:33:39.961Z,1554824019.961 [DataOverHttps](INFO): Sending 1358 bytes from file Logs/20190409T151242/Express0001.lzma
2019-04-09T15:33:40.594Z,1554824020.594 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:33:40.764Z,1554824020.764 [DataOverHttps](INFO): Moved sent file to Logs/20190409T151242/Express0001.lzma.bak
2019-04-09T15:33:40.764Z,1554824020.764 [DataOverHttps](INFO): SBD MOMSN=10513355
2019-04-09T15:33:43.032Z,1554824023.032 [CBIT](FAULT): Main Battery Failure. Count: 1721
2019-04-09T15:33:43.418Z,1554824023.418 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:33:46.250Z,1554824026.250 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:33:47.080Z,1554824027.080 [CBIT](FAULT): Main Battery Failure. Count: 1731
2019-04-09T15:33:48.274Z,1554824028.274 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:33:50.290Z,1554824030.290 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:33:51.126Z,1554824031.126 [CBIT](FAULT): Main Battery Failure. Count: 1741
2019-04-09T15:33:52.306Z,1554824032.306 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:33:55.265Z,1554824035.265 [CBIT](FAULT): Main Battery Failure. Count: 1751
2019-04-09T15:33:55.542Z,1554824035.542 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:33:58.366Z,1554824038.366 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:33:59.204Z,1554824039.204 [CBIT](FAULT): Main Battery Failure. Count: 1761
2019-04-09T15:34:01.598Z,1554824041.598 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:34:03.310Z,1554824043.310 [CBIT](FAULT): Main Battery Failure. Count: 1771
2019-04-09T15:34:04.466Z,1554824044.466 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:34:04.824Z,1554824044.824 [DataOverHttps](INFO): Sending 590 bytes from file Logs/20190409T151242/Express0005.lzma
2019-04-09T15:34:05.628Z,1554824045.628 [DataOverHttps](INFO): Moved sent file to Logs/20190409T151242/Express0005.lzma.bak
2019-04-09T15:34:05.628Z,1554824045.628 [DataOverHttps](INFO): SBD MOMSN=10513428
2019-04-09T15:34:07.310Z,1554824047.310 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:34:07.337Z,1554824047.337 [CBIT](FAULT): Main Battery Failure. Count: 1781
2019-04-09T15:34:09.354Z,1554824049.354 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:34:11.361Z,1554824051.361 [CBIT](FAULT): Main Battery Failure. Count: 1791
2019-04-09T15:34:12.554Z,1554824052.554 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:34:12.573Z,1554824052.573 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T15:34:12.573Z,1554824052.573 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-04-09T15:34:12.573Z,1554824052.573 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T15:34:12.575Z,1554824052.575 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T15:34:12.575Z,1554824052.575 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2019-04-09T15:34:12.575Z,1554824052.575 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T15:34:12.576Z,1554824052.576 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T15:34:12.576Z,1554824052.576 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 6
2019-04-09T15:34:12.576Z,1554824052.576 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T15:34:12.599Z,1554824052.599 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T15:34:12.599Z,1554824052.599 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T15:34:12.600Z,1554824052.600 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T15:34:12.995Z,1554824052.995 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T15:34:12.995Z,1554824052.995 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-04-09T15:34:12.996Z,1554824052.996 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T15:34:12.996Z,1554824052.996 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2019-04-09T15:34:12.996Z,1554824052.996 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T15:34:12.996Z,1554824052.996 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 6
2019-04-09T15:34:13.393Z,1554824053.393 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T15:34:15.382Z,1554824055.382 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:34:15.413Z,1554824055.413 [CBIT](FAULT): Main Battery Failure. Count: 1801
2019-04-09T15:34:18.614Z,1554824058.614 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:34:19.449Z,1554824059.449 [CBIT](FAULT): Main Battery Failure. Count: 1811
2019-04-09T15:34:20.945Z,1554824060.945 [DataOverHttps](INFO): Sending 1340 bytes from file Logs/20190409T151705/Express0001.lzma
2019-04-09T15:34:21.442Z,1554824061.442 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:34:21.747Z,1554824061.747 [DataOverHttps](INFO): Moved sent file to Logs/20190409T151705/Express0001.lzma.bak
2019-04-09T15:34:21.748Z,1554824061.748 [DataOverHttps](INFO): SBD MOMSN=10513474
2019-04-09T15:34:23.488Z,1554824063.488 [CBIT](FAULT): Main Battery Failure. Count: 1821
2019-04-09T15:34:23.882Z,1554824063.882 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T15:34:23.882Z,1554824063.882 [Aanderaa_O2] No Fault, FailCount= 3
2019-04-09T15:34:24.690Z,1554824064.690 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:34:27.510Z,1554824067.510 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:34:27.570Z,1554824067.570 [CBIT](FAULT): Main Battery Failure. Count: 1831
2019-04-09T15:34:30.330Z,1554824070.330 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:34:31.557Z,1554824071.557 [CBIT](FAULT): Main Battery Failure. Count: 1841
2019-04-09T15:34:32.350Z,1554824072.350 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:34:34.354Z,1554824074.354 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T15:34:34.354Z,1554824074.354 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T15:34:34.354Z,1554824074.354 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T15:34:34.374Z,1554824074.374 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:34:34.402Z,1554824074.402 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T15:34:34.878Z,1554824074.878 [Aanderaa_O2](INFO): Powering down
2019-04-09T15:34:35.597Z,1554824075.597 [CBIT](FAULT): Main Battery Failure. Count: 1851
2019-04-09T15:34:35.599Z,1554824075.599 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T15:34:35.599Z,1554824075.599 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-09T15:34:37.602Z,1554824077.602 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:34:39.503Z,1554824079.503 [DataOverHttps](INFO): Sending 635 bytes from file Logs/20190409T151705/Express0005.lzma
2019-04-09T15:34:39.643Z,1554824079.643 [CBIT](FAULT): Main Battery Failure. Count: 1861
2019-04-09T15:34:40.307Z,1554824080.307 [DataOverHttps](INFO): Moved sent file to Logs/20190409T151705/Express0005.lzma.bak
2019-04-09T15:34:40.308Z,1554824080.308 [DataOverHttps](INFO): SBD MOMSN=10513546
2019-04-09T15:34:40.430Z,1554824080.430 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:34:43.662Z,1554824083.662 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:34:43.694Z,1554824083.694 [CBIT](FAULT): Main Battery Failure. Count: 1871
2019-04-09T15:34:46.070Z,1554824086.070 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T15:34:46.070Z,1554824086.070 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-09T15:34:46.070Z,1554824086.070 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T15:34:46.138Z,1554824086.138 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T15:34:46.594Z,1554824086.594 [Aanderaa_O2](INFO): Powering down
2019-04-09T15:34:46.610Z,1554824086.610 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:34:47.326Z,1554824087.326 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T15:34:47.326Z,1554824087.326 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-09T15:34:47.725Z,1554824087.725 [CBIT](FAULT): Main Battery Failure. Count: 1881
2019-04-09T15:34:49.722Z,1554824089.722 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:34:51.758Z,1554824091.758 [CBIT](FAULT): Main Battery Failure. Count: 1891
2019-04-09T15:34:52.550Z,1554824092.550 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:34:52.937Z,1554824092.937 [DataOverHttps](INFO): Sending 1353 bytes from file Logs/20190409T152156/Express0001.lzma
2019-04-09T15:34:53.739Z,1554824093.739 [DataOverHttps](INFO): Moved sent file to Logs/20190409T152156/Express0001.lzma.bak
2019-04-09T15:34:53.740Z,1554824093.740 [DataOverHttps](INFO): SBD MOMSN=10513600
2019-04-09T15:34:55.382Z,1554824095.382 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:34:55.821Z,1554824095.821 [CBIT](FAULT): Main Battery Failure. Count: 1901
2019-04-09T15:34:57.410Z,1554824097.410 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:34:57.790Z,1554824097.790 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T15:34:57.790Z,1554824097.790 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-09T15:34:57.790Z,1554824097.790 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T15:34:57.848Z,1554824097.848 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T15:34:57.848Z,1554824097.848 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-09T15:34:58.314Z,1554824098.314 [Aanderaa_O2](INFO): Powering down
2019-04-09T15:34:58.672Z,1554824098.672 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T15:34:58.673Z,1554824098.673 [CTD_Seabird] No Fault, FailCount= 3
2019-04-09T15:34:59.006Z,1554824099.006 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T15:34:59.010Z,1554824099.010 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T15:34:59.858Z,1554824099.858 [CBIT](FAULT): Main Battery Failure. Count: 1911
2019-04-09T15:35:00.379Z,1554824100.379 [CommandLine](IMPORTANT): got command restart application
2019-04-09T15:35:00.634Z,1554824100.634 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:35:01.382Z,1554824101.382 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T15:35:01.382Z,1554824101.382 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:35:01.474Z,1554824101.474 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-04-09T15:35:01.474Z,1554824101.474 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:35:01.475Z,1554824101.475 [CommandLine](INFO): Join timeout helper Thread ID is 2802
2019-04-09T15:35:01.477Z,1554824101.477 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-04-09T15:35:01.477Z,1554824101.477 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:35:01.477Z,1554824101.477 [NavChartDb](INFO): Join timeout helper Thread ID is 2803
2019-04-09T15:35:01.578Z,1554824101.578 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T15:35:01.578Z,1554824101.578 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:35:01.586Z,1554824101.586 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-04-09T15:35:01.586Z,1554824101.586 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:35:01.586Z,1554824101.586 [Radio_Surface](INFO): Join timeout helper Thread ID is 2804
2019-04-09T15:35:01.978Z,1554824101.978 [Radio_Surface](INFO): Powering down
2019-04-09T15:35:01.979Z,1554824101.979 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T15:35:01.980Z,1554824101.980 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:35:01.986Z,1554824101.986 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-04-09T15:35:01.986Z,1554824101.986 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:35:01.986Z,1554824101.986 [DataOverHttps](INFO): Join timeout helper Thread ID is 2805
2019-04-09T15:35:02.130Z,1554824102.130 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T15:35:02.130Z,1554824102.130 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:35:02.146Z,1554824102.146 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-04-09T15:35:02.146Z,1554824102.146 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:35:02.146Z,1554824102.146 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2806
2019-04-09T15:35:02.234Z,1554824102.234 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T15:35:02.235Z,1554824102.235 [WetLabsBB2FL](INFO): Powering down
2019-04-09T15:35:02.235Z,1554824102.235 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:35:02.247Z,1554824102.247 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-04-09T15:35:02.247Z,1554824102.247 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:35:02.247Z,1554824102.247 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2807
2019-04-09T15:35:03.022Z,1554824103.022 [CTD_Seabird](INFO): Powering down
2019-04-09T15:35:03.154Z,1554824103.154 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T15:35:03.154Z,1554824103.154 [CTD_Seabird](INFO): Powering down
2019-04-09T15:35:03.166Z,1554824103.166 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:35:03.187Z,1554824103.187 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-04-09T15:35:03.187Z,1554824103.187 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:35:03.187Z,1554824103.187 [logger](INFO): Join timeout helper Thread ID is 2808
2019-04-09T15:35:03.278Z,1554824103.278 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T15:35:03.278Z,1554824103.278 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:35:03.287Z,1554824103.287 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-04-09T15:35:03.287Z,1554824103.287 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:35:03.287Z,1554824103.287 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-04-09T15:35:03.287Z,1554824103.287 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:35:03.287Z,1554824103.287 [controlThread](INFO): Join timeout helper Thread ID is 2809
2019-04-09T15:35:03.442Z,1554824103.442 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T15:35:03.442Z,1554824103.442 [controlThread](DEBUG): Uninitializing ControlThread
2019-04-09T15:35:03.443Z,1554824103.443 [Aanderaa_O2](INFO): Powering down
2019-04-09T15:35:03.445Z,1554824103.445 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T15:35:03.445Z,1554824103.445 [NAL9602](INFO): Powering down
2019-04-09T15:35:03.447Z,1554824103.447 [BR_Ping1D](INFO): uninitialize
2019-04-09T15:35:03.447Z,1554824103.447 [BR_Ping1D](INFO): Powering down
2019-04-09T15:35:03.448Z,1554824103.448 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-04-09T15:35:03.448Z,1554824103.448 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-04-09T15:35:03.449Z,1554824103.449 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-04-09T15:35:03.449Z,1554824103.449 [MissionManager](INFO): Uninitializing Mission Default
2019-04-09T15:35:03.449Z,1554824103.449 [Default] Stopped
2019-04-09T15:35:03.450Z,1554824103.450 [Default](DEBUG): Aggregate::uninitialize Default
2019-04-09T15:35:03.450Z,1554824103.450 [Default:B.GoToSurface] Stopped
2019-04-09T15:35:03.450Z,1554824103.450 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-09T15:35:03.450Z,1554824103.450 [Default:CheckIn] Stopped
2019-04-09T15:35:03.450Z,1554824103.450 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-09T15:35:03.450Z,1554824103.450 [Default:CheckIn:Read_Iridium] Stopped
2019-04-09T15:35:03.453Z,1554824103.453 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-04-09T15:35:03.453Z,1554824103.453 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-04-09T15:35:03.453Z,1554824103.453 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-04-09T15:35:03.453Z,1554824103.453 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-04-09T15:35:03.454Z,1554824103.454 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-04-09T15:35:03.454Z,1554824103.454 [BuoyancyServo](INFO): Powering down
2019-04-09T15:35:03.466Z,1554824103.466 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-09T15:35:03.466Z,1554824103.466 [ElevatorServo](INFO): Powering down
2019-04-09T15:35:03.467Z,1554824103.467 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T15:35:03.467Z,1554824103.467 [MassServo](INFO): Powering down
2019-04-09T15:35:03.468Z,1554824103.468 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-09T15:35:03.468Z,1554824103.468 [RudderServo](INFO): Powering down
2019-04-09T15:35:03.468Z,1554824103.468 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-04-09T15:35:03.469Z,1554824103.469 [ThrusterServo](INFO): Powering down
2019-04-09T15:35:03.469Z,1554824103.469 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-04-09T15:35:03.469Z,1554824103.469 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-04-09T15:35:03.470Z,1554824103.470 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-04-09T15:35:03.470Z,1554824103.470 [CBIT](DEBUG): Powering off loads.
2019-04-09T15:35:03.482Z,1554824103.482 [CBIT](DEBUG): Disabling WDT.
2019-04-09T15:35:03.494Z,1554824103.494 [CBIT](DEBUG): Opening all GF detection circuits.
2019-04-09T15:35:03.494Z,1554824103.494 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:35:03.642Z,1554824103.642 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:35:03.647Z,1554824103.647 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:35:03.715Z,1554824103.715 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:35:03.720Z,1554824103.720 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:35:03.772Z,1554824103.772 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:35:03.832Z,1554824103.832 [logger ThreadHandler](INFO): Thread cancelled.