2019-04-09T16:11:53.948Z,1554826313.948 [Supervisor](DEBUG): Initializing supervisor.
2019-04-09T16:11:53.951Z,1554826313.951 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-09T16:11:53.951Z,1554826313.951 [SyncHandler](INFO): Protected caller Thread ID is 4190
2019-04-09T16:11:53.952Z,1554826313.952 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-04-09T16:11:53.953Z,1554826313.953 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-04-09T16:11:53.953Z,1554826313.953 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4191
2019-04-09T16:11:53.957Z,1554826313.957 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-04-09T16:11:53.969Z,1554826313.969 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-04-09T16:11:53.970Z,1554826313.970 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-04-09T16:11:53.970Z,1554826313.970 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4192
2019-04-09T16:11:53.971Z,1554826313.971 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-04-09T16:11:53.972Z,1554826313.972 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-04-09T16:11:53.972Z,1554826313.972 [logger ThreadHandler](INFO): Protected caller Thread ID is 4193
2019-04-09T16:11:53.974Z,1554826313.974 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-04-09T16:11:53.975Z,1554826313.975 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-04-09T16:11:53.976Z,1554826313.976 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-04-09T16:11:54.072Z,1554826314.072 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-04-09T16:11:54.073Z,1554826314.073 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-04-09T16:11:54.274Z,1554826314.274 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-04-09T16:11:54.274Z,1554826314.274 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-04-09T16:11:54.411Z,1554826314.411 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-04-09T16:11:54.412Z,1554826314.412 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-04-09T16:11:54.869Z,1554826314.869 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-04-09T16:11:54.869Z,1554826314.869 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-04-09T16:11:55.311Z,1554826315.311 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-04-09T16:11:55.311Z,1554826315.311 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-04-09T16:11:55.782Z,1554826315.782 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-04-09T16:11:55.783Z,1554826315.783 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-04-09T16:11:56.094Z,1554826316.094 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-04-09T16:11:56.094Z,1554826316.094 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-04-09T16:11:56.425Z,1554826316.425 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-04-09T16:11:56.426Z,1554826316.426 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-04-09T16:11:56.821Z,1554826316.821 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-04-09T16:11:56.822Z,1554826316.822 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-04-09T16:11:56.972Z,1554826316.972 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-04-09T16:11:56.972Z,1554826316.972 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-04-09T16:11:57.079Z,1554826317.079 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-04-09T16:11:57.079Z,1554826317.079 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-04-09T16:11:57.161Z,1554826317.161 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-04-09T16:11:57.263Z,1554826317.263 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-04-09T16:11:57.263Z,1554826317.263 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-04-09T16:11:57.458Z,1554826317.458 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-04-09T16:11:57.459Z,1554826317.459 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-04-09T16:11:57.667Z,1554826317.667 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-04-09T16:11:57.669Z,1554826317.669 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-04-09T16:11:57.670Z,1554826317.670 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-04-09T16:11:57.753Z,1554826317.753 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-04-09T16:11:57.989Z,1554826317.989 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-04-09T16:11:57.989Z,1554826317.989 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-04-09T16:11:58.116Z,1554826318.116 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-04-09T16:11:58.524Z,1554826318.524 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-04-09T16:11:58.881Z,1554826318.881 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-04-09T16:11:58.967Z,1554826318.967 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-04-09T16:11:59.065Z,1554826319.065 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-04-09T16:11:59.159Z,1554826319.159 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-04-09T16:11:59.311Z,1554826319.311 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-04-09T16:11:59.416Z,1554826319.416 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-04-09T16:11:59.514Z,1554826319.514 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-04-09T16:11:59.518Z,1554826319.518 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-04-09T16:11:59.617Z,1554826319.617 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-04-09T16:11:59.618Z,1554826319.618 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-04-09T16:11:59.745Z,1554826319.745 [BuoyancyServo] Loaded
2019-04-09T16:11:59.745Z,1554826319.745 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-04-09T16:11:59.760Z,1554826319.760 [ElevatorServo] Loaded
2019-04-09T16:11:59.760Z,1554826319.760 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-04-09T16:11:59.776Z,1554826319.776 [MassServo] Loaded
2019-04-09T16:11:59.776Z,1554826319.776 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-04-09T16:11:59.791Z,1554826319.791 [RudderServo] Loaded
2019-04-09T16:11:59.791Z,1554826319.791 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-04-09T16:11:59.805Z,1554826319.805 [ThrusterServo] Loaded
2019-04-09T16:11:59.805Z,1554826319.805 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-04-09T16:11:59.806Z,1554826319.806 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-04-09T16:11:59.807Z,1554826319.807 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-04-09T16:11:59.942Z,1554826319.942 [SBIT](DEBUG): Construct Startup Built In Test.
2019-04-09T16:11:59.970Z,1554826319.970 [SBIT] Loaded
2019-04-09T16:11:59.971Z,1554826319.971 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-04-09T16:11:59.971Z,1554826319.971 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-04-09T16:11:59.999Z,1554826319.999 [IBIT] Loaded
2019-04-09T16:11:59.000Z,1554826320.000 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-04-09T16:12:00.003Z,1554826320.003 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-04-09T16:12:00.390Z,1554826320.390 [CBIT] Loaded
2019-04-09T16:12:00.390Z,1554826320.390 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-04-09T16:12:00.390Z,1554826320.390 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-04-09T16:12:00.391Z,1554826320.391 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-04-09T16:12:00.575Z,1554826320.575 [Aanderaa_O2] Loaded
2019-04-09T16:12:00.575Z,1554826320.575 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-04-09T16:12:00.585Z,1554826320.585 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-04-09T16:12:00.590Z,1554826320.590 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-04-09T16:12:00.592Z,1554826320.592 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-04-09T16:12:00.596Z,1554826320.596 [CTD_Seabird](INFO): created writer for : depth
2019-04-09T16:12:00.597Z,1554826320.597 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-04-09T16:12:00.602Z,1554826320.602 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-04-09T16:12:00.603Z,1554826320.603 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-04-09T16:12:00.608Z,1554826320.608 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-04-09T16:12:00.609Z,1554826320.609 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-04-09T16:12:00.614Z,1554826320.614 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-04-09T16:12:00.615Z,1554826320.615 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-04-09T16:12:00.620Z,1554826320.620 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-04-09T16:12:00.621Z,1554826320.621 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-04-09T16:12:00.626Z,1554826320.626 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-04-09T16:12:00.652Z,1554826320.652 [CTD_Seabird] Loaded
2019-04-09T16:12:00.653Z,1554826320.653 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-04-09T16:12:00.654Z,1554826320.654 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406654E0
2019-04-09T16:12:00.654Z,1554826320.654 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4272
2019-04-09T16:12:00.680Z,1554826320.680 [ESPComponent] Loaded
2019-04-09T16:12:00.680Z,1554826320.680 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-04-09T16:12:00.694Z,1554826320.694 [PAR_Licor] Loaded
2019-04-09T16:12:00.694Z,1554826320.694 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-04-09T16:12:00.742Z,1554826320.742 [WetLabsBB2FL] Loaded
2019-04-09T16:12:00.742Z,1554826320.742 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-04-09T16:12:00.743Z,1554826320.743 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406954E0
2019-04-09T16:12:00.744Z,1554826320.744 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4273
2019-04-09T16:12:00.744Z,1554826320.744 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-04-09T16:12:00.745Z,1554826320.745 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-04-09T16:12:00.785Z,1554826320.785 [DepthRateCalculator] Loaded
2019-04-09T16:12:00.786Z,1554826320.786 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-04-09T16:12:00.791Z,1554826320.791 [PitchRateCalculator] Loaded
2019-04-09T16:12:00.792Z,1554826320.792 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-04-09T16:12:00.807Z,1554826320.807 [SpeedCalculator] Loaded
2019-04-09T16:12:00.808Z,1554826320.808 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-04-09T16:12:00.829Z,1554826320.829 [TempGradientCalculator] Loaded
2019-04-09T16:12:00.829Z,1554826320.829 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-04-09T16:12:00.835Z,1554826320.835 [YawRateCalculator] Loaded
2019-04-09T16:12:00.836Z,1554826320.836 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-04-09T16:12:00.868Z,1554826320.868 [ElevatorOffsetCalculator] Loaded
2019-04-09T16:12:00.869Z,1554826320.869 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-04-09T16:12:00.869Z,1554826320.869 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-04-09T16:12:00.870Z,1554826320.870 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-04-09T16:12:00.896Z,1554826320.896 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-04-09T16:12:00.897Z,1554826320.897 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-04-09T16:12:00.999Z,1554826320.999 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-04-09T16:12:00.000Z,1554826321.000 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-04-09T16:12:01.059Z,1554826321.059 [DeadReckonUsingSpeedCalculator] Loaded
2019-04-09T16:12:01.060Z,1554826321.060 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-04-09T16:12:01.119Z,1554826321.119 [DeadReckonWithRespectToSeafloor] Loaded
2019-04-09T16:12:01.120Z,1554826321.120 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-04-09T16:12:01.140Z,1554826321.140 [NavChart] Loaded
2019-04-09T16:12:01.141Z,1554826321.141 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-04-09T16:12:01.145Z,1554826321.145 [UniversalFixResidualReporter] Loaded
2019-04-09T16:12:01.145Z,1554826321.145 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-04-09T16:12:01.145Z,1554826321.145 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-04-09T16:12:01.146Z,1554826321.146 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-04-09T16:12:01.456Z,1554826321.456 [AcousticModem_Benthos_ATM900] Loaded
2019-04-09T16:12:01.457Z,1554826321.457 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-04-09T16:12:01.529Z,1554826321.529 [DataOverHttps] Loaded
2019-04-09T16:12:01.529Z,1554826321.529 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-04-09T16:12:01.530Z,1554826321.530 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409714E0
2019-04-09T16:12:01.531Z,1554826321.531 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4274
2019-04-09T16:12:01.544Z,1554826321.544 [Depth_Keller] Loaded
2019-04-09T16:12:01.544Z,1554826321.544 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-04-09T16:12:01.636Z,1554826321.636 [NAL9602] Loaded
2019-04-09T16:12:01.636Z,1554826321.636 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-04-09T16:12:01.642Z,1554826321.642 [Onboard] Loaded
2019-04-09T16:12:01.642Z,1554826321.642 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-04-09T16:12:01.645Z,1554826321.645 [Radio_Surface] Loaded
2019-04-09T16:12:01.645Z,1554826321.645 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-04-09T16:12:01.647Z,1554826321.647 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A14E0
2019-04-09T16:12:01.647Z,1554826321.647 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4275
2019-04-09T16:12:02.938Z,1554826322.938 [BPC1] Loaded
2019-04-09T16:12:02.938Z,1554826322.938 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-04-09T16:12:02.968Z,1554826322.968 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minrange
2019-04-09T16:12:02.970Z,1554826322.970 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.maxrange
2019-04-09T16:12:02.972Z,1554826322.972 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.soundspeed
2019-04-09T16:12:02.974Z,1554826322.974 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.automode
2019-04-09T16:12:02.977Z,1554826322.977 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.gainindex
2019-04-09T16:12:02.979Z,1554826322.979 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minconfidence
2019-04-09T16:12:02.981Z,1554826322.981 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.pingenable
2019-04-09T16:12:02.983Z,1554826322.983 [BR_Ping1D] Loaded
2019-04-09T16:12:02.984Z,1554826322.984 [ComponentRegistry](DEBUG): SyncComponent "BR_Ping1D" handled in the control thread.
2019-04-09T16:12:02.984Z,1554826322.984 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-04-09T16:12:02.985Z,1554826322.985 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-04-09T16:12:03.048Z,1554826323.048 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-04-09T16:12:03.049Z,1554826323.049 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-04-09T16:12:03.115Z,1554826323.115 [VerticalControl](DEBUG): Construct VerticalControl.
2019-04-09T16:12:03.199Z,1554826323.199 [VerticalControl] Loaded
2019-04-09T16:12:03.200Z,1554826323.200 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-04-09T16:12:03.200Z,1554826323.200 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-04-09T16:12:03.258Z,1554826323.258 [HorizontalControl] Loaded
2019-04-09T16:12:03.258Z,1554826323.258 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-04-09T16:12:03.259Z,1554826323.259 [SpeedControl](DEBUG): Construct SpeedControl.
2019-04-09T16:12:03.260Z,1554826323.260 [SpeedControl] Loaded
2019-04-09T16:12:03.261Z,1554826323.261 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-04-09T16:12:03.261Z,1554826323.261 [LoopControl](DEBUG): Construct LoopControl.
2019-04-09T16:12:03.262Z,1554826323.262 [LoopControl] Loaded
2019-04-09T16:12:03.262Z,1554826323.262 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-04-09T16:12:03.263Z,1554826323.263 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-04-09T16:12:03.263Z,1554826323.263 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-04-09T16:12:03.321Z,1554826323.321 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-04-09T16:12:03.321Z,1554826323.321 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-04-09T16:12:03.820Z,1554826323.820 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-04-09T16:12:03.824Z,1554826323.824 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-04-09T16:12:03.825Z,1554826323.825 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-04-09T16:12:03.831Z,1554826323.831 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-04-09T16:12:03.832Z,1554826323.832 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACE4E0
2019-04-09T16:12:03.833Z,1554826323.833 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4276
2019-04-09T16:12:03.838Z,1554826323.838 [Supervisor](INFO): Main Thread ID is 4347
2019-04-09T16:12:03.838Z,1554826323.838 [Supervisor](DEBUG): Running supervisor.
2019-04-09T16:12:03.838Z,1554826323.838 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4277
2019-04-09T16:12:03.841Z,1554826323.841 [controlThread ThreadHandler](INFO): Handler Thread ID is 4278
2019-04-09T16:12:03.841Z,1554826323.841 [controlThread](DEBUG): Initializing ControlThread
2019-04-09T16:12:03.843Z,1554826323.843 [SBIT](INFO): Initialize SBIT Component.
2019-04-09T16:12:03.843Z,1554826323.843 [SBIT](IMPORTANT): git: 2019-03-18-31-ge98d5dc
2019-04-09T16:12:03.844Z,1554826323.844 [SBIT](INFO): git hash: e98d5dc50cbc39e402f19b9946ff4dc0776c8725
2019-04-09T16:12:03.844Z,1554826323.844 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-04-09T16:12:03.845Z,1554826323.845 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-04-09T16:12:03.847Z,1554826323.847 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-04-09T16:12:03.847Z,1554826323.847 [IBIT](INFO): Initialize IBIT Component.
2019-04-09T16:12:03.848Z,1554826323.848 [CBIT](DEBUG): Initialize CBIT Component.
2019-04-09T16:12:03.849Z,1554826323.849 [logger ThreadHandler](INFO): Handler Thread ID is 4279
2019-04-09T16:12:03.862Z,1554826323.862 [CBIT](DEBUG): Initialized mux pins.
2019-04-09T16:12:03.862Z,1554826323.862 [CBIT](DEBUG): Initializing the watchdog timer.
2019-04-09T16:12:03.874Z,1554826323.874 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4280
2019-04-09T16:12:03.875Z,1554826323.875 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T16:12:03.878Z,1554826323.878 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T16:12:03.880Z,1554826323.880 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4282
2019-04-09T16:12:03.881Z,1554826323.881 [WetLabsBB2FL](INFO): Powering down
2019-04-09T16:12:03.886Z,1554826323.886 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-04-09T16:12:03.886Z,1554826323.886 [CBIT](DEBUG): Initializing heartbeat.
2019-04-09T16:12:03.910Z,1554826323.910 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4283
2019-04-09T16:12:03.911Z,1554826323.911 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-04-09T16:12:03.919Z,1554826323.919 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4284
2019-04-09T16:12:03.934Z,1554826323.934 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4285
2019-04-09T16:12:03.937Z,1554826323.937 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-09T16:12:03.938Z,1554826323.938 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-04-09T16:12:03.938Z,1554826323.938 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-09T16:12:03.938Z,1554826323.938 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-04-09T16:12:03.938Z,1554826323.938 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-09T16:12:03.939Z,1554826323.939 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-04-09T16:12:03.939Z,1554826323.939 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-09T16:12:03.939Z,1554826323.939 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-04-09T16:12:03.939Z,1554826323.939 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-09T16:12:03.939Z,1554826323.939 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-04-09T16:12:03.940Z,1554826323.940 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-09T16:12:03.940Z,1554826323.940 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-04-09T16:12:03.940Z,1554826323.940 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-09T16:12:03.940Z,1554826323.940 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-04-09T16:12:03.940Z,1554826323.940 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-09T16:12:03.940Z,1554826323.940 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-04-09T16:12:03.958Z,1554826323.958 [CBIT](DEBUG): Deactivating GF circuits.
2019-04-09T16:12:03.958Z,1554826323.958 [CBIT](DEBUG): Deactivating emergency mode.
2019-04-09T16:12:03.994Z,1554826323.994 [CBIT](DEBUG): Backplane powered.
2019-04-09T16:12:03.998Z,1554826323.998 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-04-09T16:12:03.999Z,1554826323.999 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-04-09T16:12:03.999Z,1554826323.999 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-04-09T16:12:03.999Z,1554826323.999 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-04-09T16:12:03.000Z,1554826324.000 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-04-09T16:12:04.000Z,1554826324.000 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-04-09T16:12:04.001Z,1554826324.001 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T16:12:04.002Z,1554826324.002 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-04-09T16:12:04.003Z,1554826324.003 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-04-09T16:12:04.007Z,1554826324.007 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-04-09T16:12:04.009Z,1554826324.009 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-04-09T16:12:04.010Z,1554826324.010 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-04-09T16:12:04.010Z,1554826324.010 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-04-09T16:12:04.011Z,1554826324.011 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-04-09T16:12:04.033Z,1554826324.033 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T16:12:04.070Z,1554826324.070 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2019-04-09T16:12:04.078Z,1554826324.078 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-04-09T16:12:04.142Z,1554826324.142 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-04-09T16:12:04.143Z,1554826324.143 [Default:A.Wait](DEBUG): Construct Wait.
2019-04-09T16:12:04.145Z,1554826324.145 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T16:12:04.195Z,1554826324.195 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-04-09T16:12:04.221Z,1554826324.221 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-04-09T16:12:04.245Z,1554826324.245 [Default:E.Execute](DEBUG): Construct Execute.
2019-04-09T16:12:04.248Z,1554826324.248 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2019-04-09T16:12:04.270Z,1554826324.270 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,Depth_Keller,NAL9602,Onboard,BPC1,BR_Ping1D,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-04-09T16:12:04.283Z,1554826324.283 [ESPComponent](INFO): powering down ESP
2019-04-09T16:12:04.322Z,1554826324.322 [Radio_Surface](INFO): Powering up
2019-04-09T16:12:04.704Z,1554826324.704 [PAR_Licor](INFO): A/D timeout, 8 tries over 141 ms

2019-04-09T16:12:04.705Z,1554826324.705 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T16:12:04.705Z,1554826324.705 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:12:04.705Z,1554826324.705 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:12:04.706Z,1554826324.706 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:12:04.914Z,1554826324.914 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:12:04.922Z,1554826324.922 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-04-09T16:12:04.924Z,1554826324.924 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:12:04.930Z,1554826324.930 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-09T16:12:04.931Z,1554826324.931 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:12:04.938Z,1554826324.938 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:12:04.939Z,1554826324.939 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:12:04.946Z,1554826324.946 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-09T16:12:04.947Z,1554826324.947 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:12:04.954Z,1554826324.954 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-04-09T16:12:04.957Z,1554826324.957 [CommandLine](FAULT): Scheduling is paused
2019-04-09T16:12:04.957Z,1554826324.957 [CBIT](INFO): Critical error at 20190409T161202
2019-04-09T16:12:04.957Z,1554826324.957 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-04-09T16:12:04.958Z,1554826324.958 [CBIT](FAULT): Main Battery Failure. Count: 1
2019-04-09T16:12:04.962Z,1554826324.962 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:12:04.962Z,1554826324.962 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T16:12:04.962Z,1554826324.962 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T16:12:05.510Z,1554826325.510 [PAR_Licor](INFO): A/D timeout, 9 tries over 138 ms

2019-04-09T16:12:05.510Z,1554826325.510 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T16:12:05.510Z,1554826325.510 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:12:05.547Z,1554826325.547 [BR_Ping1D](INFO): startup: 0
2019-04-09T16:12:05.547Z,1554826325.547 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T16:12:05.558Z,1554826325.558 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T16:12:05.558Z,1554826325.558 [BR_Ping1D](INFO): 66
2019-04-09T16:12:05.558Z,1554826325.558 [BR_Ping1D](INFO): 82
2019-04-09T16:12:05.558Z,1554826325.558 [BR_Ping1D](INFO): 2
2019-04-09T16:12:05.558Z,1554826325.558 [BR_Ping1D](INFO): 0
2019-04-09T16:12:05.559Z,1554826325.559 [BR_Ping1D](INFO): 236
2019-04-09T16:12:05.559Z,1554826325.559 [BR_Ping1D](INFO): 3
2019-04-09T16:12:05.559Z,1554826325.559 [BR_Ping1D](INFO): 0
2019-04-09T16:12:05.559Z,1554826325.559 [BR_Ping1D](INFO): 0
2019-04-09T16:12:05.559Z,1554826325.559 [BR_Ping1D](INFO): 1
2019-04-09T16:12:05.559Z,1554826325.559 [BR_Ping1D](INFO): 30
2019-04-09T16:12:05.559Z,1554826325.559 [BR_Ping1D](INFO): 164
2019-04-09T16:12:05.559Z,1554826325.559 [BR_Ping1D](INFO): 1
2019-04-09T16:12:05.560Z,1554826325.560 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T16:12:05.560Z,1554826325.560 [BR_Ping1D](INFO): 66
2019-04-09T16:12:05.560Z,1554826325.560 [BR_Ping1D](INFO): 82
2019-04-09T16:12:05.560Z,1554826325.560 [BR_Ping1D](INFO): 1
2019-04-09T16:12:05.560Z,1554826325.560 [BR_Ping1D](INFO): 0
2019-04-09T16:12:05.560Z,1554826325.560 [BR_Ping1D](INFO): 235
2019-04-09T16:12:05.560Z,1554826325.560 [BR_Ping1D](INFO): 3
2019-04-09T16:12:05.560Z,1554826325.560 [BR_Ping1D](INFO): 0
2019-04-09T16:12:05.560Z,1554826325.560 [BR_Ping1D](INFO): 0
2019-04-09T16:12:05.561Z,1554826325.561 [BR_Ping1D](INFO): 132
2019-04-09T16:12:05.561Z,1554826325.561 [BR_Ping1D](INFO): 1
2019-04-09T16:12:05.561Z,1554826325.561 [BR_Ping1D](INFO): 21
2019-04-09T16:12:05.561Z,1554826325.561 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T16:12:05.561Z,1554826325.561 [BR_Ping1D](INFO): 66
2019-04-09T16:12:05.561Z,1554826325.561 [BR_Ping1D](INFO): 82
2019-04-09T16:12:05.561Z,1554826325.561 [BR_Ping1D](INFO): 4
2019-04-09T16:12:05.561Z,1554826325.561 [BR_Ping1D](INFO): 0
2019-04-09T16:12:05.562Z,1554826325.562 [BR_Ping1D](INFO): 234
2019-04-09T16:12:05.562Z,1554826325.562 [BR_Ping1D](INFO): 3
2019-04-09T16:12:05.563Z,1554826325.563 [BR_Ping1D](INFO): 0
2019-04-09T16:12:05.563Z,1554826325.563 [BR_Ping1D](INFO): 0
2019-04-09T16:12:05.564Z,1554826325.564 [BR_Ping1D](INFO): 96
2019-04-09T16:12:05.564Z,1554826325.564 [BR_Ping1D](INFO): 227
2019-04-09T16:12:05.565Z,1554826325.565 [BR_Ping1D](INFO): 22
2019-04-09T16:12:05.565Z,1554826325.565 [BR_Ping1D](INFO): 0
2019-04-09T16:12:05.566Z,1554826325.566 [BR_Ping1D](INFO): 222
2019-04-09T16:12:05.566Z,1554826325.566 [BR_Ping1D](INFO): 2
2019-04-09T16:12:05.568Z,1554826325.568 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T16:12:05.568Z,1554826325.568 [BR_Ping1D](INFO): 66
2019-04-09T16:12:05.569Z,1554826325.569 [BR_Ping1D](INFO): 82
2019-04-09T16:12:05.569Z,1554826325.569 [BR_Ping1D](INFO): 1
2019-04-09T16:12:05.570Z,1554826325.570 [BR_Ping1D](INFO): 0
2019-04-09T16:12:05.570Z,1554826325.570 [BR_Ping1D](INFO): 238
2019-04-09T16:12:05.571Z,1554826325.571 [BR_Ping1D](INFO): 3
2019-04-09T16:12:05.571Z,1554826325.571 [BR_Ping1D](INFO): 0
2019-04-09T16:12:05.572Z,1554826325.572 [BR_Ping1D](INFO): 0
2019-04-09T16:12:05.572Z,1554826325.572 [BR_Ping1D](INFO): 135
2019-04-09T16:12:05.573Z,1554826325.573 [BR_Ping1D](INFO): 1
2019-04-09T16:12:05.573Z,1554826325.573 [BR_Ping1D](INFO): 21
2019-04-09T16:12:05.917Z,1554826325.917 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:12:05.918Z,1554826325.918 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T16:12:05.918Z,1554826325.918 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T16:12:05.961Z,1554826325.961 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:06.350Z,1554826326.350 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:06.350Z,1554826326.350 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:06.351Z,1554826326.351 [BR_Ping1D](INFO): startup: 1
2019-04-09T16:12:06.351Z,1554826326.351 [BR_Ping1D](INFO): UART requesting status
2019-04-09T16:12:06.351Z,1554826326.351 [BR_Ping1D](INFO): 66
2019-04-09T16:12:06.351Z,1554826326.351 [BR_Ping1D](INFO): 82
2019-04-09T16:12:06.351Z,1554826326.351 [BR_Ping1D](INFO): 0
2019-04-09T16:12:06.351Z,1554826326.351 [BR_Ping1D](INFO): 0
2019-04-09T16:12:06.351Z,1554826326.351 [BR_Ping1D](INFO): 186
2019-04-09T16:12:06.351Z,1554826326.351 [BR_Ping1D](INFO): 4
2019-04-09T16:12:06.351Z,1554826326.351 [BR_Ping1D](INFO): 0
2019-04-09T16:12:06.352Z,1554826326.352 [BR_Ping1D](INFO): 0
2019-04-09T16:12:06.352Z,1554826326.352 [BR_Ping1D](INFO): 82
2019-04-09T16:12:06.352Z,1554826326.352 [BR_Ping1D](INFO): 1
2019-04-09T16:12:06.358Z,1554826326.358 [BR_Ping1D](INFO): 66
2019-04-09T16:12:06.358Z,1554826326.358 [BR_Ping1D](INFO): 82
2019-04-09T16:12:06.358Z,1554826326.358 [BR_Ping1D](INFO): 0
2019-04-09T16:12:06.358Z,1554826326.358 [BR_Ping1D](INFO): 0
2019-04-09T16:12:06.358Z,1554826326.358 [BR_Ping1D](INFO): 191
2019-04-09T16:12:06.358Z,1554826326.358 [BR_Ping1D](INFO): 4
2019-04-09T16:12:06.358Z,1554826326.358 [BR_Ping1D](INFO): 0
2019-04-09T16:12:06.359Z,1554826326.359 [BR_Ping1D](INFO): 0
2019-04-09T16:12:06.359Z,1554826326.359 [BR_Ping1D](INFO): 87
2019-04-09T16:12:06.359Z,1554826326.359 [BR_Ping1D](INFO): 1
2019-04-09T16:12:06.366Z,1554826326.366 [BR_Ping1D](INFO): 66
2019-04-09T16:12:06.366Z,1554826326.366 [BR_Ping1D](INFO): 82
2019-04-09T16:12:06.366Z,1554826326.366 [BR_Ping1D](INFO): 0
2019-04-09T16:12:06.366Z,1554826326.366 [BR_Ping1D](INFO): 0
2019-04-09T16:12:06.366Z,1554826326.366 [BR_Ping1D](INFO): 179
2019-04-09T16:12:06.366Z,1554826326.366 [BR_Ping1D](INFO): 4
2019-04-09T16:12:06.366Z,1554826326.366 [BR_Ping1D](INFO): 0
2019-04-09T16:12:06.367Z,1554826326.367 [BR_Ping1D](INFO): 0
2019-04-09T16:12:06.367Z,1554826326.367 [BR_Ping1D](INFO): 75
2019-04-09T16:12:06.367Z,1554826326.367 [BR_Ping1D](INFO): 1
2019-04-09T16:12:06.870Z,1554826326.870 [PAR_Licor](INFO): A/D timeout, 9 tries over 138 ms

2019-04-09T16:12:06.870Z,1554826326.870 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T16:12:06.870Z,1554826326.870 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:12:06.910Z,1554826326.910 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:06.910Z,1554826326.910 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:06.910Z,1554826326.910 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:06.910Z,1554826326.910 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:06.911Z,1554826326.911 [BR_Ping1D](INFO): elapsed 1.3631
2019-04-09T16:12:06.911Z,1554826326.911 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:06.911Z,1554826326.911 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:06.911Z,1554826326.911 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:06.911Z,1554826326.911 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:06.946Z,1554826326.946 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:12:06.946Z,1554826326.946 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T16:12:06.946Z,1554826326.946 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T16:12:07.158Z,1554826327.158 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:07.158Z,1554826327.158 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:07.159Z,1554826327.159 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:07.159Z,1554826327.159 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:07.159Z,1554826327.159 [BR_Ping1D](INFO): elapsed 1.6115
2019-04-09T16:12:07.159Z,1554826327.159 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:07.159Z,1554826327.159 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:07.160Z,1554826327.160 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:07.160Z,1554826327.160 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:07.502Z,1554826327.502 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T16:12:07.502Z,1554826327.502 [MassServo] Communications Fault, FailCount= 1
2019-04-09T16:12:07.502Z,1554826327.502 [MassServo](ERROR): Communications Fault
2019-04-09T16:12:07.553Z,1554826327.553 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T16:12:07.609Z,1554826327.609 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:07.609Z,1554826327.609 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:07.609Z,1554826327.609 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:07.609Z,1554826327.609 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:07.610Z,1554826327.610 [BR_Ping1D](INFO): elapsed 2.0621
2019-04-09T16:12:07.610Z,1554826327.610 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:07.610Z,1554826327.610 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:07.610Z,1554826327.610 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:07.610Z,1554826327.610 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:07.710Z,1554826327.710 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:12:07.710Z,1554826327.710 [MassServo](INFO): Powering down
2019-04-09T16:12:07.985Z,1554826327.985 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:07.985Z,1554826327.985 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:07.986Z,1554826327.986 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:07.986Z,1554826327.986 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:07.986Z,1554826327.986 [BR_Ping1D](INFO): elapsed 2.4384
2019-04-09T16:12:07.986Z,1554826327.986 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:07.986Z,1554826327.986 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:07.987Z,1554826327.987 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:07.987Z,1554826327.987 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:08.398Z,1554826328.398 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:08.398Z,1554826328.398 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:08.398Z,1554826328.398 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:08.398Z,1554826328.398 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:08.398Z,1554826328.398 [BR_Ping1D](INFO): elapsed 2.8509
2019-04-09T16:12:08.399Z,1554826328.399 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:08.399Z,1554826328.399 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:08.399Z,1554826328.399 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:08.399Z,1554826328.399 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:08.512Z,1554826328.512 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:12:08.631Z,1554826328.631 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:12:08.942Z,1554826328.942 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T16:12:08.942Z,1554826328.942 [MassServo] No Fault, FailCount= 1
2019-04-09T16:12:09.074Z,1554826329.074 [PAR_Licor](INFO): A/D timeout, 7 tries over 125 ms

2019-04-09T16:12:09.074Z,1554826329.074 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T16:12:09.074Z,1554826329.074 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:12:09.104Z,1554826329.104 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:09.104Z,1554826329.104 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:09.104Z,1554826329.104 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:09.104Z,1554826329.104 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:09.104Z,1554826329.104 [BR_Ping1D](INFO): elapsed 3.5568
2019-04-09T16:12:09.105Z,1554826329.105 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:09.105Z,1554826329.105 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:09.105Z,1554826329.105 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:09.105Z,1554826329.105 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:09.157Z,1554826329.157 [CBIT](FAULT): Main Battery Failure. Count: 11
2019-04-09T16:12:09.160Z,1554826329.160 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:12:09.160Z,1554826329.160 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T16:12:09.160Z,1554826329.160 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T16:12:09.374Z,1554826329.374 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:09.374Z,1554826329.374 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:09.374Z,1554826329.374 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:09.375Z,1554826329.375 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:09.375Z,1554826329.375 [BR_Ping1D](INFO): elapsed 3.8273
2019-04-09T16:12:09.375Z,1554826329.375 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:09.375Z,1554826329.375 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:09.375Z,1554826329.375 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:09.376Z,1554826329.376 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:09.894Z,1554826329.894 [PAR_Licor](INFO): A/D timeout, 9 tries over 139 ms

2019-04-09T16:12:09.894Z,1554826329.894 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T16:12:09.894Z,1554826329.894 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:12:09.934Z,1554826329.934 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:09.934Z,1554826329.934 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:09.934Z,1554826329.934 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:09.934Z,1554826329.934 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:09.935Z,1554826329.935 [BR_Ping1D](INFO): elapsed 4.3871
2019-04-09T16:12:09.935Z,1554826329.935 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:09.935Z,1554826329.935 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:09.935Z,1554826329.935 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:09.935Z,1554826329.935 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:09.999Z,1554826329.999 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:12:09.999Z,1554826329.999 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T16:12:09.999Z,1554826329.999 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T16:12:10.182Z,1554826330.182 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:10.182Z,1554826330.182 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:10.182Z,1554826330.182 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:10.183Z,1554826330.183 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:10.183Z,1554826330.183 [BR_Ping1D](INFO): elapsed 4.6353
2019-04-09T16:12:10.183Z,1554826330.183 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:10.183Z,1554826330.183 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:10.183Z,1554826330.183 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:10.184Z,1554826330.184 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:10.698Z,1554826330.698 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-09T16:12:10.698Z,1554826330.698 [PAR_Licor] Data Fault, FailCount= 3
2019-04-09T16:12:10.698Z,1554826330.698 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:12:10.752Z,1554826330.752 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:10.752Z,1554826330.752 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:10.752Z,1554826330.752 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:10.752Z,1554826330.752 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:10.753Z,1554826330.753 [BR_Ping1D](INFO): elapsed 5.2050
2019-04-09T16:12:10.753Z,1554826330.753 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:10.753Z,1554826330.753 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T16:12:10.753Z,1554826330.753 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-09T16:12:10.753Z,1554826330.753 [BR_Ping1D](ERROR): Data Fault
2019-04-09T16:12:11.302Z,1554826331.302 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T16:12:11.302Z,1554826331.302 [MassServo] Communications Fault, FailCount= 2
2019-04-09T16:12:11.302Z,1554826331.302 [MassServo](ERROR): Communications Fault
2019-04-09T16:12:11.306Z,1554826331.306 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T16:12:11.307Z,1554826331.307 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:12:11.307Z,1554826331.307 [CBIT](CRITICAL): Data Fault in component: PAR_Licor
2019-04-09T16:12:11.308Z,1554826331.308 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T16:12:11.337Z,1554826331.337 [BR_Ping1D](INFO): Stop
2019-04-09T16:12:11.337Z,1554826331.337 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:12:11.337Z,1554826331.337 [BR_Ping1D](INFO): Powering down
2019-04-09T16:12:11.475Z,1554826331.475 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:12:11.475Z,1554826331.475 [MassServo](INFO): Powering down
2019-04-09T16:12:11.490Z,1554826331.490 [CBIT](INFO): Critical error at 20190409T161211
2019-04-09T16:12:11.733Z,1554826331.733 [BR_Ping1D](INFO): Stopping
2019-04-09T16:12:12.138Z,1554826332.138 [BR_Ping1D](INFO): Stopping
2019-04-09T16:12:12.151Z,1554826332.151 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:12:12.271Z,1554826332.271 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:12:12.275Z,1554826332.275 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T16:12:12.275Z,1554826332.275 [MassServo] No Fault, FailCount= 2
2019-04-09T16:12:12.542Z,1554826332.542 [BR_Ping1D](INFO): Stopped
2019-04-09T16:12:12.575Z,1554826332.575 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T16:12:12.575Z,1554826332.575 [BR_Ping1D] No Fault, FailCount= 1
2019-04-09T16:12:12.946Z,1554826332.946 [BR_Ping1D](INFO): Start
2019-04-09T16:12:13.349Z,1554826333.349 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:13.388Z,1554826333.388 [CBIT](FAULT): Main Battery Failure. Count: 21
2019-04-09T16:12:13.754Z,1554826333.754 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:13.754Z,1554826333.754 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:13.755Z,1554826333.755 [BR_Ping1D](INFO): startup: 0
2019-04-09T16:12:13.755Z,1554826333.755 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T16:12:13.766Z,1554826333.766 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T16:12:13.766Z,1554826333.766 [BR_Ping1D](INFO): 66
2019-04-09T16:12:13.766Z,1554826333.766 [BR_Ping1D](INFO): 82
2019-04-09T16:12:13.766Z,1554826333.766 [BR_Ping1D](INFO): 2
2019-04-09T16:12:13.766Z,1554826333.766 [BR_Ping1D](INFO): 0
2019-04-09T16:12:13.767Z,1554826333.767 [BR_Ping1D](INFO): 236
2019-04-09T16:12:13.767Z,1554826333.767 [BR_Ping1D](INFO): 3
2019-04-09T16:12:13.767Z,1554826333.767 [BR_Ping1D](INFO): 0
2019-04-09T16:12:13.767Z,1554826333.767 [BR_Ping1D](INFO): 0
2019-04-09T16:12:13.767Z,1554826333.767 [BR_Ping1D](INFO): 1
2019-04-09T16:12:13.767Z,1554826333.767 [BR_Ping1D](INFO): 30
2019-04-09T16:12:13.767Z,1554826333.767 [BR_Ping1D](INFO): 164
2019-04-09T16:12:13.767Z,1554826333.767 [BR_Ping1D](INFO): 1
2019-04-09T16:12:13.767Z,1554826333.767 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T16:12:13.768Z,1554826333.768 [BR_Ping1D](INFO): 66
2019-04-09T16:12:13.768Z,1554826333.768 [BR_Ping1D](INFO): 82
2019-04-09T16:12:13.768Z,1554826333.768 [BR_Ping1D](INFO): 1
2019-04-09T16:12:13.768Z,1554826333.768 [BR_Ping1D](INFO): 0
2019-04-09T16:12:13.768Z,1554826333.768 [BR_Ping1D](INFO): 235
2019-04-09T16:12:13.768Z,1554826333.768 [BR_Ping1D](INFO): 3
2019-04-09T16:12:13.768Z,1554826333.768 [BR_Ping1D](INFO): 0
2019-04-09T16:12:13.768Z,1554826333.768 [BR_Ping1D](INFO): 0
2019-04-09T16:12:13.768Z,1554826333.768 [BR_Ping1D](INFO): 132
2019-04-09T16:12:13.769Z,1554826333.769 [BR_Ping1D](INFO): 1
2019-04-09T16:12:13.769Z,1554826333.769 [BR_Ping1D](INFO): 21
2019-04-09T16:12:13.769Z,1554826333.769 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T16:12:13.769Z,1554826333.769 [BR_Ping1D](INFO): 66
2019-04-09T16:12:13.769Z,1554826333.769 [BR_Ping1D](INFO): 82
2019-04-09T16:12:13.769Z,1554826333.769 [BR_Ping1D](INFO): 4
2019-04-09T16:12:13.769Z,1554826333.769 [BR_Ping1D](INFO): 0
2019-04-09T16:12:13.769Z,1554826333.769 [BR_Ping1D](INFO): 234
2019-04-09T16:12:13.769Z,1554826333.769 [BR_Ping1D](INFO): 3
2019-04-09T16:12:13.770Z,1554826333.770 [BR_Ping1D](INFO): 0
2019-04-09T16:12:13.770Z,1554826333.770 [BR_Ping1D](INFO): 0
2019-04-09T16:12:13.771Z,1554826333.771 [BR_Ping1D](INFO): 96
2019-04-09T16:12:13.771Z,1554826333.771 [BR_Ping1D](INFO): 227
2019-04-09T16:12:13.772Z,1554826333.772 [BR_Ping1D](INFO): 22
2019-04-09T16:12:13.772Z,1554826333.772 [BR_Ping1D](INFO): 0
2019-04-09T16:12:13.773Z,1554826333.773 [BR_Ping1D](INFO): 222
2019-04-09T16:12:13.773Z,1554826333.773 [BR_Ping1D](INFO): 2
2019-04-09T16:12:13.774Z,1554826333.774 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T16:12:13.775Z,1554826333.775 [BR_Ping1D](INFO): 66
2019-04-09T16:12:13.776Z,1554826333.776 [BR_Ping1D](INFO): 82
2019-04-09T16:12:13.776Z,1554826333.776 [BR_Ping1D](INFO): 1
2019-04-09T16:12:13.776Z,1554826333.776 [BR_Ping1D](INFO): 0
2019-04-09T16:12:13.777Z,1554826333.777 [BR_Ping1D](INFO): 238
2019-04-09T16:12:13.777Z,1554826333.777 [BR_Ping1D](INFO): 3
2019-04-09T16:12:13.782Z,1554826333.782 [BR_Ping1D](INFO): 0
2019-04-09T16:12:13.782Z,1554826333.782 [BR_Ping1D](INFO): 0
2019-04-09T16:12:13.782Z,1554826333.782 [BR_Ping1D](INFO): 135
2019-04-09T16:12:13.782Z,1554826333.782 [BR_Ping1D](INFO): 1
2019-04-09T16:12:13.782Z,1554826333.782 [BR_Ping1D](INFO): 21
2019-04-09T16:12:14.157Z,1554826334.157 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:14.175Z,1554826334.175 [CBIT](CRITICAL): Environmental Failure. Press:14.822944 PSI. Humidity:35%. Temp:26 C. ABORTING MISSION
2019-04-09T16:12:14.542Z,1554826334.542 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T16:12:14.542Z,1554826334.542 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T16:12:14.543Z,1554826334.543 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T16:12:14.562Z,1554826334.562 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:14.562Z,1554826334.562 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:14.562Z,1554826334.562 [BR_Ping1D](INFO): startup: 1
2019-04-09T16:12:14.562Z,1554826334.562 [BR_Ping1D](INFO): UART requesting status
2019-04-09T16:12:14.563Z,1554826334.563 [BR_Ping1D](INFO): 66
2019-04-09T16:12:14.563Z,1554826334.563 [BR_Ping1D](INFO): 82
2019-04-09T16:12:14.563Z,1554826334.563 [BR_Ping1D](INFO): 0
2019-04-09T16:12:14.563Z,1554826334.563 [BR_Ping1D](INFO): 0
2019-04-09T16:12:14.563Z,1554826334.563 [BR_Ping1D](INFO): 186
2019-04-09T16:12:14.563Z,1554826334.563 [BR_Ping1D](INFO): 4
2019-04-09T16:12:14.563Z,1554826334.563 [BR_Ping1D](INFO): 0
2019-04-09T16:12:14.563Z,1554826334.563 [BR_Ping1D](INFO): 0
2019-04-09T16:12:14.563Z,1554826334.563 [BR_Ping1D](INFO): 82
2019-04-09T16:12:14.563Z,1554826334.563 [BR_Ping1D](INFO): 1
2019-04-09T16:12:14.570Z,1554826334.570 [BR_Ping1D](INFO): 66
2019-04-09T16:12:14.570Z,1554826334.570 [BR_Ping1D](INFO): 82
2019-04-09T16:12:14.570Z,1554826334.570 [BR_Ping1D](INFO): 0
2019-04-09T16:12:14.570Z,1554826334.570 [BR_Ping1D](INFO): 0
2019-04-09T16:12:14.570Z,1554826334.570 [BR_Ping1D](INFO): 191
2019-04-09T16:12:14.570Z,1554826334.570 [BR_Ping1D](INFO): 4
2019-04-09T16:12:14.570Z,1554826334.570 [BR_Ping1D](INFO): 0
2019-04-09T16:12:14.571Z,1554826334.571 [BR_Ping1D](INFO): 0
2019-04-09T16:12:14.571Z,1554826334.571 [BR_Ping1D](INFO): 87
2019-04-09T16:12:14.571Z,1554826334.571 [BR_Ping1D](INFO): 1
2019-04-09T16:12:14.578Z,1554826334.578 [BR_Ping1D](INFO): 66
2019-04-09T16:12:14.578Z,1554826334.578 [BR_Ping1D](INFO): 82
2019-04-09T16:12:14.578Z,1554826334.578 [BR_Ping1D](INFO): 0
2019-04-09T16:12:14.578Z,1554826334.578 [BR_Ping1D](INFO): 0
2019-04-09T16:12:14.578Z,1554826334.578 [BR_Ping1D](INFO): 179
2019-04-09T16:12:14.578Z,1554826334.578 [BR_Ping1D](INFO): 4
2019-04-09T16:12:14.578Z,1554826334.578 [BR_Ping1D](INFO): 0
2019-04-09T16:12:14.579Z,1554826334.579 [BR_Ping1D](INFO): 0
2019-04-09T16:12:14.579Z,1554826334.579 [BR_Ping1D](INFO): 75
2019-04-09T16:12:14.579Z,1554826334.579 [BR_Ping1D](INFO): 1
2019-04-09T16:12:14.902Z,1554826334.902 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T16:12:14.902Z,1554826334.902 [MassServo] Communications Fault, FailCount= 3
2019-04-09T16:12:14.902Z,1554826334.902 [MassServo](ERROR): Communications Fault
2019-04-09T16:12:14.904Z,1554826334.904 [CBIT](INFO): Critical error at 20190409T161214
2019-04-09T16:12:14.907Z,1554826334.907 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T16:12:14.907Z,1554826334.907 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T16:12:15.066Z,1554826335.066 [Aanderaa_O2](INFO): Powering down
2019-04-09T16:12:15.149Z,1554826335.149 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:15.149Z,1554826335.149 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:15.149Z,1554826335.149 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:15.150Z,1554826335.150 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:15.150Z,1554826335.150 [BR_Ping1D](INFO): elapsed 1.3949
2019-04-09T16:12:15.150Z,1554826335.150 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:15.150Z,1554826335.150 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:15.150Z,1554826335.150 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:15.151Z,1554826335.151 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:15.179Z,1554826335.179 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:12:15.179Z,1554826335.179 [MassServo](INFO): Powering down
2019-04-09T16:12:15.370Z,1554826335.370 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:15.370Z,1554826335.370 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:15.370Z,1554826335.370 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:15.370Z,1554826335.370 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:15.371Z,1554826335.371 [BR_Ping1D](INFO): elapsed 1.6156
2019-04-09T16:12:15.371Z,1554826335.371 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:15.371Z,1554826335.371 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:15.371Z,1554826335.371 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:15.371Z,1554826335.371 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:15.773Z,1554826335.773 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:15.773Z,1554826335.773 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:15.774Z,1554826335.774 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:15.774Z,1554826335.774 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:15.774Z,1554826335.774 [BR_Ping1D](INFO): elapsed 2.0192
2019-04-09T16:12:15.774Z,1554826335.774 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:15.774Z,1554826335.774 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:15.775Z,1554826335.775 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:15.775Z,1554826335.775 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:15.787Z,1554826335.787 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:12:15.907Z,1554826335.907 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:12:15.911Z,1554826335.911 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T16:12:15.911Z,1554826335.911 [MassServo] No Fault, FailCount= 3
2019-04-09T16:12:15.912Z,1554826335.912 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T16:12:15.912Z,1554826335.912 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-09T16:12:16.178Z,1554826336.178 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:16.178Z,1554826336.178 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:16.178Z,1554826336.178 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:16.179Z,1554826336.179 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:16.179Z,1554826336.179 [BR_Ping1D](INFO): elapsed 2.4238
2019-04-09T16:12:16.179Z,1554826336.179 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:16.179Z,1554826336.179 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:16.179Z,1554826336.179 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:16.179Z,1554826336.179 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:16.593Z,1554826336.593 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:16.593Z,1554826336.593 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:16.593Z,1554826336.593 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:16.594Z,1554826336.594 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:16.594Z,1554826336.594 [BR_Ping1D](INFO): elapsed 2.8390
2019-04-09T16:12:16.594Z,1554826336.594 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:16.594Z,1554826336.594 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:16.594Z,1554826336.594 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:16.595Z,1554826336.595 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:16.926Z,1554826336.926 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T16:12:16.926Z,1554826336.926 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-09T16:12:16.926Z,1554826336.926 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T16:12:16.987Z,1554826336.987 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:16.987Z,1554826336.987 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:16.987Z,1554826336.987 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:16.987Z,1554826336.987 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:16.988Z,1554826336.988 [BR_Ping1D](INFO): elapsed 3.2326
2019-04-09T16:12:16.988Z,1554826336.988 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:16.988Z,1554826336.988 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:16.988Z,1554826336.988 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:16.988Z,1554826336.988 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:17.029Z,1554826337.029 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T16:12:17.330Z,1554826337.330 [CTD_Seabird](INFO): Powering down
2019-04-09T16:12:17.389Z,1554826337.389 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:17.390Z,1554826337.390 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:17.390Z,1554826337.390 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:17.390Z,1554826337.390 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:17.390Z,1554826337.390 [BR_Ping1D](INFO): elapsed 3.6354
2019-04-09T16:12:17.391Z,1554826337.391 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:17.391Z,1554826337.391 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:17.391Z,1554826337.391 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:17.391Z,1554826337.391 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:17.413Z,1554826337.413 [CBIT](FAULT): Main Battery Failure. Count: 31
2019-04-09T16:12:17.793Z,1554826337.793 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:17.794Z,1554826337.794 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:17.794Z,1554826337.794 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:17.794Z,1554826337.794 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:17.794Z,1554826337.794 [BR_Ping1D](INFO): elapsed 4.0394
2019-04-09T16:12:17.795Z,1554826337.795 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:17.795Z,1554826337.795 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:17.795Z,1554826337.795 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:17.795Z,1554826337.795 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:18.197Z,1554826338.197 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:18.197Z,1554826338.197 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:18.198Z,1554826338.198 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:18.198Z,1554826338.198 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:18.198Z,1554826338.198 [BR_Ping1D](INFO): elapsed 4.4431
2019-04-09T16:12:18.198Z,1554826338.198 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:18.198Z,1554826338.198 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:18.199Z,1554826338.199 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:18.199Z,1554826338.199 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:18.522Z,1554826338.522 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T16:12:18.522Z,1554826338.522 [MassServo] Communications Fault, FailCount= 4
2019-04-09T16:12:18.522Z,1554826338.522 [MassServo](ERROR): Communications Fault
2019-04-09T16:12:18.526Z,1554826338.526 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T16:12:18.527Z,1554826338.527 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T16:12:18.527Z,1554826338.527 [CTD_Seabird] No Fault, FailCount= 1
2019-04-09T16:12:18.606Z,1554826338.606 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:18.606Z,1554826338.606 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:18.607Z,1554826338.607 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:18.607Z,1554826338.607 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:18.607Z,1554826338.607 [BR_Ping1D](INFO): elapsed 4.8520
2019-04-09T16:12:18.607Z,1554826338.607 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:18.607Z,1554826338.607 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:18.608Z,1554826338.608 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:18.608Z,1554826338.608 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:18.627Z,1554826338.627 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:12:18.627Z,1554826338.627 [MassServo](INFO): Powering down
2019-04-09T16:12:18.706Z,1554826338.706 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T16:12:18.708Z,1554826338.708 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T16:12:19.006Z,1554826339.006 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:19.006Z,1554826339.006 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:19.006Z,1554826339.006 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:19.007Z,1554826339.007 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:19.007Z,1554826339.007 [BR_Ping1D](INFO): elapsed 5.2519
2019-04-09T16:12:19.007Z,1554826339.007 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:19.007Z,1554826339.007 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T16:12:19.007Z,1554826339.007 [BR_Ping1D] Data Fault, FailCount= 2
2019-04-09T16:12:19.007Z,1554826339.007 [BR_Ping1D](ERROR): Data Fault
2019-04-09T16:12:19.039Z,1554826339.039 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T16:12:19.410Z,1554826339.410 [BR_Ping1D](INFO): Stop
2019-04-09T16:12:19.410Z,1554826339.410 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:12:19.410Z,1554826339.410 [BR_Ping1D](INFO): Powering down
2019-04-09T16:12:19.496Z,1554826339.496 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:12:19.615Z,1554826339.615 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:12:19.619Z,1554826339.619 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T16:12:19.619Z,1554826339.619 [MassServo] No Fault, FailCount= 4
2019-04-09T16:12:19.814Z,1554826339.814 [BR_Ping1D](INFO): Stopping
2019-04-09T16:12:20.218Z,1554826340.218 [BR_Ping1D](INFO): Stopping
2019-04-09T16:12:20.621Z,1554826340.621 [BR_Ping1D](INFO): Stopped
2019-04-09T16:12:20.694Z,1554826340.694 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T16:12:20.694Z,1554826340.694 [BR_Ping1D] No Fault, FailCount= 2
2019-04-09T16:12:21.027Z,1554826341.027 [BR_Ping1D](INFO): Start
2019-04-09T16:12:21.429Z,1554826341.429 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:21.440Z,1554826341.440 [CBIT](FAULT): Main Battery Failure. Count: 41
2019-04-09T16:12:21.834Z,1554826341.834 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:21.834Z,1554826341.834 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:21.834Z,1554826341.834 [BR_Ping1D](INFO): startup: 0
2019-04-09T16:12:21.834Z,1554826341.834 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T16:12:21.846Z,1554826341.846 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T16:12:21.846Z,1554826341.846 [BR_Ping1D](INFO): 66
2019-04-09T16:12:21.846Z,1554826341.846 [BR_Ping1D](INFO): 82
2019-04-09T16:12:21.846Z,1554826341.846 [BR_Ping1D](INFO): 2
2019-04-09T16:12:21.846Z,1554826341.846 [BR_Ping1D](INFO): 0
2019-04-09T16:12:21.847Z,1554826341.847 [BR_Ping1D](INFO): 236
2019-04-09T16:12:21.847Z,1554826341.847 [BR_Ping1D](INFO): 3
2019-04-09T16:12:21.847Z,1554826341.847 [BR_Ping1D](INFO): 0
2019-04-09T16:12:21.847Z,1554826341.847 [BR_Ping1D](INFO): 0
2019-04-09T16:12:21.847Z,1554826341.847 [BR_Ping1D](INFO): 1
2019-04-09T16:12:21.847Z,1554826341.847 [BR_Ping1D](INFO): 30
2019-04-09T16:12:21.847Z,1554826341.847 [BR_Ping1D](INFO): 164
2019-04-09T16:12:21.847Z,1554826341.847 [BR_Ping1D](INFO): 1
2019-04-09T16:12:21.848Z,1554826341.848 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T16:12:21.848Z,1554826341.848 [BR_Ping1D](INFO): 66
2019-04-09T16:12:21.848Z,1554826341.848 [BR_Ping1D](INFO): 82
2019-04-09T16:12:21.848Z,1554826341.848 [BR_Ping1D](INFO): 1
2019-04-09T16:12:21.848Z,1554826341.848 [BR_Ping1D](INFO): 0
2019-04-09T16:12:21.848Z,1554826341.848 [BR_Ping1D](INFO): 235
2019-04-09T16:12:21.848Z,1554826341.848 [BR_Ping1D](INFO): 3
2019-04-09T16:12:21.848Z,1554826341.848 [BR_Ping1D](INFO): 0
2019-04-09T16:12:21.848Z,1554826341.848 [BR_Ping1D](INFO): 0
2019-04-09T16:12:21.849Z,1554826341.849 [BR_Ping1D](INFO): 132
2019-04-09T16:12:21.849Z,1554826341.849 [BR_Ping1D](INFO): 1
2019-04-09T16:12:21.849Z,1554826341.849 [BR_Ping1D](INFO): 21
2019-04-09T16:12:21.849Z,1554826341.849 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T16:12:21.849Z,1554826341.849 [BR_Ping1D](INFO): 66
2019-04-09T16:12:21.849Z,1554826341.849 [BR_Ping1D](INFO): 82
2019-04-09T16:12:21.849Z,1554826341.849 [BR_Ping1D](INFO): 4
2019-04-09T16:12:21.849Z,1554826341.849 [BR_Ping1D](INFO): 0
2019-04-09T16:12:21.849Z,1554826341.849 [BR_Ping1D](INFO): 234
2019-04-09T16:12:21.850Z,1554826341.850 [BR_Ping1D](INFO): 3
2019-04-09T16:12:21.850Z,1554826341.850 [BR_Ping1D](INFO): 0
2019-04-09T16:12:21.851Z,1554826341.851 [BR_Ping1D](INFO): 0
2019-04-09T16:12:21.852Z,1554826341.852 [BR_Ping1D](INFO): 96
2019-04-09T16:12:21.852Z,1554826341.852 [BR_Ping1D](INFO): 227
2019-04-09T16:12:21.853Z,1554826341.853 [BR_Ping1D](INFO): 22
2019-04-09T16:12:21.853Z,1554826341.853 [BR_Ping1D](INFO): 0
2019-04-09T16:12:21.854Z,1554826341.854 [BR_Ping1D](INFO): 222
2019-04-09T16:12:21.854Z,1554826341.854 [BR_Ping1D](INFO): 2
2019-04-09T16:12:21.855Z,1554826341.855 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T16:12:21.856Z,1554826341.856 [BR_Ping1D](INFO): 66
2019-04-09T16:12:21.856Z,1554826341.856 [BR_Ping1D](INFO): 82
2019-04-09T16:12:21.857Z,1554826341.857 [BR_Ping1D](INFO): 1
2019-04-09T16:12:21.857Z,1554826341.857 [BR_Ping1D](INFO): 0
2019-04-09T16:12:21.858Z,1554826341.858 [BR_Ping1D](INFO): 238
2019-04-09T16:12:21.859Z,1554826341.859 [BR_Ping1D](INFO): 3
2019-04-09T16:12:21.859Z,1554826341.859 [BR_Ping1D](INFO): 0
2019-04-09T16:12:21.859Z,1554826341.859 [BR_Ping1D](INFO): 0
2019-04-09T16:12:21.859Z,1554826341.859 [BR_Ping1D](INFO): 135
2019-04-09T16:12:21.859Z,1554826341.859 [BR_Ping1D](INFO): 1
2019-04-09T16:12:21.859Z,1554826341.859 [BR_Ping1D](INFO): 21
2019-04-09T16:12:22.218Z,1554826342.218 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T16:12:22.218Z,1554826342.218 [MassServo] Communications Fault, FailCount= 5
2019-04-09T16:12:22.218Z,1554826342.218 [MassServo](ERROR): Communications Fault
2019-04-09T16:12:22.223Z,1554826342.223 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T16:12:22.223Z,1554826342.223 [CBIT](CRITICAL): Communications Fault in component: MassServo
2019-04-09T16:12:22.257Z,1554826342.257 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:22.307Z,1554826342.307 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:12:22.307Z,1554826342.307 [MassServo](INFO): Powering down
2019-04-09T16:12:22.318Z,1554826342.318 [CBIT](INFO): Critical error at 20190409T161222
2019-04-09T16:12:22.650Z,1554826342.650 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:22.650Z,1554826342.650 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:22.650Z,1554826342.650 [BR_Ping1D](INFO): startup: 1
2019-04-09T16:12:22.650Z,1554826342.650 [BR_Ping1D](INFO): UART requesting status
2019-04-09T16:12:22.650Z,1554826342.650 [BR_Ping1D](INFO): 66
2019-04-09T16:12:22.651Z,1554826342.651 [BR_Ping1D](INFO): 82
2019-04-09T16:12:22.651Z,1554826342.651 [BR_Ping1D](INFO): 0
2019-04-09T16:12:22.651Z,1554826342.651 [BR_Ping1D](INFO): 0
2019-04-09T16:12:22.651Z,1554826342.651 [BR_Ping1D](INFO): 186
2019-04-09T16:12:22.651Z,1554826342.651 [BR_Ping1D](INFO): 4
2019-04-09T16:12:22.651Z,1554826342.651 [BR_Ping1D](INFO): 0
2019-04-09T16:12:22.651Z,1554826342.651 [BR_Ping1D](INFO): 0
2019-04-09T16:12:22.651Z,1554826342.651 [BR_Ping1D](INFO): 82
2019-04-09T16:12:22.651Z,1554826342.651 [BR_Ping1D](INFO): 1
2019-04-09T16:12:22.658Z,1554826342.658 [BR_Ping1D](INFO): 66
2019-04-09T16:12:22.658Z,1554826342.658 [BR_Ping1D](INFO): 82
2019-04-09T16:12:22.658Z,1554826342.658 [BR_Ping1D](INFO): 0
2019-04-09T16:12:22.658Z,1554826342.658 [BR_Ping1D](INFO): 0
2019-04-09T16:12:22.658Z,1554826342.658 [BR_Ping1D](INFO): 191
2019-04-09T16:12:22.658Z,1554826342.658 [BR_Ping1D](INFO): 4
2019-04-09T16:12:22.658Z,1554826342.658 [BR_Ping1D](INFO): 0
2019-04-09T16:12:22.659Z,1554826342.659 [BR_Ping1D](INFO): 0
2019-04-09T16:12:22.659Z,1554826342.659 [BR_Ping1D](INFO): 87
2019-04-09T16:12:22.659Z,1554826342.659 [BR_Ping1D](INFO): 1
2019-04-09T16:12:22.666Z,1554826342.666 [BR_Ping1D](INFO): 66
2019-04-09T16:12:22.666Z,1554826342.666 [BR_Ping1D](INFO): 82
2019-04-09T16:12:22.666Z,1554826342.666 [BR_Ping1D](INFO): 0
2019-04-09T16:12:22.666Z,1554826342.666 [BR_Ping1D](INFO): 0
2019-04-09T16:12:22.666Z,1554826342.666 [BR_Ping1D](INFO): 179
2019-04-09T16:12:22.666Z,1554826342.666 [BR_Ping1D](INFO): 4
2019-04-09T16:12:22.667Z,1554826342.667 [BR_Ping1D](INFO): 0
2019-04-09T16:12:22.667Z,1554826342.667 [BR_Ping1D](INFO): 0
2019-04-09T16:12:22.667Z,1554826342.667 [BR_Ping1D](INFO): 75
2019-04-09T16:12:22.667Z,1554826342.667 [BR_Ping1D](INFO): 1
2019-04-09T16:12:23.054Z,1554826343.054 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:23.054Z,1554826343.054 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:23.054Z,1554826343.054 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:23.054Z,1554826343.054 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:23.055Z,1554826343.055 [BR_Ping1D](INFO): elapsed 1.2203
2019-04-09T16:12:23.055Z,1554826343.055 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:23.055Z,1554826343.055 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:23.055Z,1554826343.055 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:23.055Z,1554826343.055 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:23.088Z,1554826343.088 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:12:23.207Z,1554826343.207 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:12:23.458Z,1554826343.458 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:23.458Z,1554826343.458 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:23.458Z,1554826343.458 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:23.459Z,1554826343.459 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:23.459Z,1554826343.459 [BR_Ping1D](INFO): elapsed 1.6244
2019-04-09T16:12:23.459Z,1554826343.459 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:23.459Z,1554826343.459 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:23.459Z,1554826343.459 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:23.459Z,1554826343.459 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:23.862Z,1554826343.862 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:23.862Z,1554826343.862 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:23.862Z,1554826343.862 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:23.863Z,1554826343.863 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:23.863Z,1554826343.863 [BR_Ping1D](INFO): elapsed 2.0284
2019-04-09T16:12:23.863Z,1554826343.863 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:23.863Z,1554826343.863 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:23.863Z,1554826343.863 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:23.863Z,1554826343.863 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:24.266Z,1554826344.266 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:24.266Z,1554826344.266 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:24.267Z,1554826344.267 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:24.267Z,1554826344.267 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:24.267Z,1554826344.267 [BR_Ping1D](INFO): elapsed 2.4326
2019-04-09T16:12:24.267Z,1554826344.267 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:24.267Z,1554826344.267 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:24.268Z,1554826344.268 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:24.268Z,1554826344.268 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:24.670Z,1554826344.670 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:24.670Z,1554826344.670 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:24.670Z,1554826344.670 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:24.670Z,1554826344.670 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:24.670Z,1554826344.670 [BR_Ping1D](INFO): elapsed 2.8361
2019-04-09T16:12:24.671Z,1554826344.671 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:24.671Z,1554826344.671 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:24.671Z,1554826344.671 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:24.671Z,1554826344.671 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:25.055Z,1554826345.055 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:12:25.055Z,1554826345.055 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-04-09T16:12:25.055Z,1554826345.055 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:12:25.074Z,1554826345.074 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:25.074Z,1554826345.074 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:25.074Z,1554826345.074 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:25.074Z,1554826345.074 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:25.074Z,1554826345.074 [BR_Ping1D](INFO): elapsed 3.2401
2019-04-09T16:12:25.075Z,1554826345.075 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:25.075Z,1554826345.075 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:25.075Z,1554826345.075 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:25.075Z,1554826345.075 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:25.166Z,1554826345.166 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:12:25.459Z,1554826345.459 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:12:25.621Z,1554826345.621 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:25.622Z,1554826345.622 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:25.622Z,1554826345.622 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:25.622Z,1554826345.622 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:25.622Z,1554826345.622 [BR_Ping1D](INFO): elapsed 3.7880
2019-04-09T16:12:25.623Z,1554826345.623 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:25.623Z,1554826345.623 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:25.623Z,1554826345.623 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:25.623Z,1554826345.623 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:25.652Z,1554826345.652 [CBIT](FAULT): Main Battery Failure. Count: 51
2019-04-09T16:12:25.884Z,1554826345.884 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:25.884Z,1554826345.884 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:25.884Z,1554826345.884 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:25.884Z,1554826345.884 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:25.884Z,1554826345.884 [BR_Ping1D](INFO): elapsed 4.0500
2019-04-09T16:12:25.885Z,1554826345.885 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:25.885Z,1554826345.885 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:25.885Z,1554826345.885 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:25.885Z,1554826345.885 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:26.266Z,1554826346.266 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T16:12:26.266Z,1554826346.266 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-09T16:12:26.267Z,1554826346.267 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T16:12:26.286Z,1554826346.286 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:26.286Z,1554826346.286 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:26.286Z,1554826346.286 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:26.287Z,1554826346.287 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:26.287Z,1554826346.287 [BR_Ping1D](INFO): elapsed 4.4524
2019-04-09T16:12:26.287Z,1554826346.287 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:26.287Z,1554826346.287 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:26.287Z,1554826346.287 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:26.287Z,1554826346.287 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:26.323Z,1554826346.323 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T16:12:26.323Z,1554826346.323 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:12:26.323Z,1554826346.323 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-04-09T16:12:26.790Z,1554826346.790 [Aanderaa_O2](INFO): Powering down
2019-04-09T16:12:26.810Z,1554826346.810 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:26.810Z,1554826346.810 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:26.811Z,1554826346.811 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:26.811Z,1554826346.811 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:26.811Z,1554826346.811 [BR_Ping1D](INFO): elapsed 4.9765
2019-04-09T16:12:26.811Z,1554826346.811 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:26.811Z,1554826346.811 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:26.811Z,1554826346.811 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:26.812Z,1554826346.812 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:27.094Z,1554826347.094 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:27.094Z,1554826347.094 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:27.094Z,1554826347.094 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:27.094Z,1554826347.094 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:27.095Z,1554826347.095 [BR_Ping1D](INFO): elapsed 5.2602
2019-04-09T16:12:27.095Z,1554826347.095 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:27.095Z,1554826347.095 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T16:12:27.095Z,1554826347.095 [BR_Ping1D] Data Fault, FailCount= 3
2019-04-09T16:12:27.095Z,1554826347.095 [BR_Ping1D](ERROR): Data Fault
2019-04-09T16:12:27.127Z,1554826347.127 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T16:12:27.498Z,1554826347.498 [BR_Ping1D](INFO): Stop
2019-04-09T16:12:27.498Z,1554826347.498 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:12:27.498Z,1554826347.498 [BR_Ping1D](INFO): Powering down
2019-04-09T16:12:27.587Z,1554826347.587 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T16:12:27.587Z,1554826347.587 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-09T16:12:27.912Z,1554826347.912 [BR_Ping1D](INFO): Stopping
2019-04-09T16:12:28.318Z,1554826348.318 [BR_Ping1D](INFO): Stopping
2019-04-09T16:12:28.352Z,1554826348.352 [SBIT](IMPORTANT): Beginning Startup BIT
2019-04-09T16:12:28.360Z,1554826348.360 [CBIT](IMPORTANT): Beginning ground fault scan
2019-04-09T16:12:28.690Z,1554826348.690 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:12:28.691Z,1554826348.691 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:12:28.729Z,1554826348.729 [BR_Ping1D](INFO): Stopped
2019-04-09T16:12:28.831Z,1554826348.831 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T16:12:28.832Z,1554826348.832 [BR_Ping1D] No Fault, FailCount= 3
2019-04-09T16:12:29.114Z,1554826349.114 [BR_Ping1D](INFO): Start
2019-04-09T16:12:29.516Z,1554826349.516 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:29.592Z,1554826349.592 [CBIT](FAULT): Main Battery Failure. Count: 61
2019-04-09T16:12:29.922Z,1554826349.922 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:29.922Z,1554826349.922 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:29.922Z,1554826349.922 [BR_Ping1D](INFO): startup: 0
2019-04-09T16:12:29.922Z,1554826349.922 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T16:12:29.934Z,1554826349.934 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T16:12:29.934Z,1554826349.934 [BR_Ping1D](INFO): 66
2019-04-09T16:12:29.934Z,1554826349.934 [BR_Ping1D](INFO): 82
2019-04-09T16:12:29.934Z,1554826349.934 [BR_Ping1D](INFO): 2
2019-04-09T16:12:29.934Z,1554826349.934 [BR_Ping1D](INFO): 0
2019-04-09T16:12:29.934Z,1554826349.934 [BR_Ping1D](INFO): 236
2019-04-09T16:12:29.935Z,1554826349.935 [BR_Ping1D](INFO): 3
2019-04-09T16:12:29.935Z,1554826349.935 [BR_Ping1D](INFO): 0
2019-04-09T16:12:29.935Z,1554826349.935 [BR_Ping1D](INFO): 0
2019-04-09T16:12:29.935Z,1554826349.935 [BR_Ping1D](INFO): 1
2019-04-09T16:12:29.935Z,1554826349.935 [BR_Ping1D](INFO): 30
2019-04-09T16:12:29.935Z,1554826349.935 [BR_Ping1D](INFO): 164
2019-04-09T16:12:29.935Z,1554826349.935 [BR_Ping1D](INFO): 1
2019-04-09T16:12:29.935Z,1554826349.935 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T16:12:29.935Z,1554826349.935 [BR_Ping1D](INFO): 66
2019-04-09T16:12:29.936Z,1554826349.936 [BR_Ping1D](INFO): 82
2019-04-09T16:12:29.936Z,1554826349.936 [BR_Ping1D](INFO): 1
2019-04-09T16:12:29.936Z,1554826349.936 [BR_Ping1D](INFO): 0
2019-04-09T16:12:29.936Z,1554826349.936 [BR_Ping1D](INFO): 235
2019-04-09T16:12:29.936Z,1554826349.936 [BR_Ping1D](INFO): 3
2019-04-09T16:12:29.936Z,1554826349.936 [BR_Ping1D](INFO): 0
2019-04-09T16:12:29.936Z,1554826349.936 [BR_Ping1D](INFO): 0
2019-04-09T16:12:29.936Z,1554826349.936 [BR_Ping1D](INFO): 132
2019-04-09T16:12:29.936Z,1554826349.936 [BR_Ping1D](INFO): 1
2019-04-09T16:12:29.936Z,1554826349.936 [BR_Ping1D](INFO): 21
2019-04-09T16:12:29.937Z,1554826349.937 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T16:12:29.937Z,1554826349.937 [BR_Ping1D](INFO): 66
2019-04-09T16:12:29.937Z,1554826349.937 [BR_Ping1D](INFO): 82
2019-04-09T16:12:29.937Z,1554826349.937 [BR_Ping1D](INFO): 4
2019-04-09T16:12:29.937Z,1554826349.937 [BR_Ping1D](INFO): 0
2019-04-09T16:12:29.937Z,1554826349.937 [BR_Ping1D](INFO): 234
2019-04-09T16:12:29.937Z,1554826349.937 [BR_Ping1D](INFO): 3
2019-04-09T16:12:29.937Z,1554826349.937 [BR_Ping1D](INFO): 0
2019-04-09T16:12:29.938Z,1554826349.938 [BR_Ping1D](INFO): 0
2019-04-09T16:12:29.938Z,1554826349.938 [BR_Ping1D](INFO): 96
2019-04-09T16:12:29.939Z,1554826349.939 [BR_Ping1D](INFO): 227
2019-04-09T16:12:29.939Z,1554826349.939 [BR_Ping1D](INFO): 22
2019-04-09T16:12:29.940Z,1554826349.940 [BR_Ping1D](INFO): 0
2019-04-09T16:12:29.940Z,1554826349.940 [BR_Ping1D](INFO): 222
2019-04-09T16:12:29.941Z,1554826349.941 [BR_Ping1D](INFO): 2
2019-04-09T16:12:29.942Z,1554826349.942 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T16:12:29.943Z,1554826349.943 [BR_Ping1D](INFO): 66
2019-04-09T16:12:29.943Z,1554826349.943 [BR_Ping1D](INFO): 82
2019-04-09T16:12:29.944Z,1554826349.944 [BR_Ping1D](INFO): 1
2019-04-09T16:12:29.944Z,1554826349.944 [BR_Ping1D](INFO): 0
2019-04-09T16:12:29.945Z,1554826349.945 [BR_Ping1D](INFO): 238
2019-04-09T16:12:29.945Z,1554826349.945 [BR_Ping1D](INFO): 3
2019-04-09T16:12:29.946Z,1554826349.946 [BR_Ping1D](INFO): 0
2019-04-09T16:12:29.946Z,1554826349.946 [BR_Ping1D](INFO): 0
2019-04-09T16:12:29.947Z,1554826349.947 [BR_Ping1D](INFO): 135
2019-04-09T16:12:29.947Z,1554826349.947 [BR_Ping1D](INFO): 1
2019-04-09T16:12:29.948Z,1554826349.948 [BR_Ping1D](INFO): 21
2019-04-09T16:12:30.325Z,1554826350.325 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:30.730Z,1554826350.730 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:30.730Z,1554826350.730 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:30.731Z,1554826350.731 [BR_Ping1D](INFO): startup: 1
2019-04-09T16:12:30.731Z,1554826350.731 [BR_Ping1D](INFO): UART requesting status
2019-04-09T16:12:30.731Z,1554826350.731 [BR_Ping1D](INFO): 66
2019-04-09T16:12:30.731Z,1554826350.731 [BR_Ping1D](INFO): 82
2019-04-09T16:12:30.731Z,1554826350.731 [BR_Ping1D](INFO): 0
2019-04-09T16:12:30.731Z,1554826350.731 [BR_Ping1D](INFO): 0
2019-04-09T16:12:30.731Z,1554826350.731 [BR_Ping1D](INFO): 186
2019-04-09T16:12:30.731Z,1554826350.731 [BR_Ping1D](INFO): 4
2019-04-09T16:12:30.732Z,1554826350.732 [BR_Ping1D](INFO): 0
2019-04-09T16:12:30.732Z,1554826350.732 [BR_Ping1D](INFO): 0
2019-04-09T16:12:30.732Z,1554826350.732 [BR_Ping1D](INFO): 82
2019-04-09T16:12:30.732Z,1554826350.732 [BR_Ping1D](INFO): 1
2019-04-09T16:12:30.738Z,1554826350.738 [BR_Ping1D](INFO): 66
2019-04-09T16:12:30.738Z,1554826350.738 [BR_Ping1D](INFO): 82
2019-04-09T16:12:30.738Z,1554826350.738 [BR_Ping1D](INFO): 0
2019-04-09T16:12:30.738Z,1554826350.738 [BR_Ping1D](INFO): 0
2019-04-09T16:12:30.738Z,1554826350.738 [BR_Ping1D](INFO): 191
2019-04-09T16:12:30.738Z,1554826350.738 [BR_Ping1D](INFO): 4
2019-04-09T16:12:30.739Z,1554826350.739 [BR_Ping1D](INFO): 0
2019-04-09T16:12:30.739Z,1554826350.739 [BR_Ping1D](INFO): 0
2019-04-09T16:12:30.739Z,1554826350.739 [BR_Ping1D](INFO): 87
2019-04-09T16:12:30.739Z,1554826350.739 [BR_Ping1D](INFO): 1
2019-04-09T16:12:30.746Z,1554826350.746 [BR_Ping1D](INFO): 66
2019-04-09T16:12:30.746Z,1554826350.746 [BR_Ping1D](INFO): 82
2019-04-09T16:12:30.746Z,1554826350.746 [BR_Ping1D](INFO): 0
2019-04-09T16:12:30.746Z,1554826350.746 [BR_Ping1D](INFO): 0
2019-04-09T16:12:30.746Z,1554826350.746 [BR_Ping1D](INFO): 179
2019-04-09T16:12:30.746Z,1554826350.746 [BR_Ping1D](INFO): 4
2019-04-09T16:12:30.746Z,1554826350.746 [BR_Ping1D](INFO): 0
2019-04-09T16:12:30.747Z,1554826350.747 [BR_Ping1D](INFO): 0
2019-04-09T16:12:30.747Z,1554826350.747 [BR_Ping1D](INFO): 75
2019-04-09T16:12:30.747Z,1554826350.747 [BR_Ping1D](INFO): 1
2019-04-09T16:12:31.133Z,1554826351.133 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:31.134Z,1554826351.134 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:31.134Z,1554826351.134 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:31.134Z,1554826351.134 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:31.134Z,1554826351.134 [BR_Ping1D](INFO): elapsed 1.2118
2019-04-09T16:12:31.135Z,1554826351.135 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:31.135Z,1554826351.135 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:31.135Z,1554826351.135 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:31.135Z,1554826351.135 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:31.545Z,1554826351.545 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:31.545Z,1554826351.545 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:31.546Z,1554826351.546 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:31.546Z,1554826351.546 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:31.546Z,1554826351.546 [BR_Ping1D](INFO): elapsed 1.6234
2019-04-09T16:12:31.546Z,1554826351.546 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:31.546Z,1554826351.546 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:31.547Z,1554826351.547 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:31.547Z,1554826351.547 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:31.758Z,1554826351.758 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T16:12:31.758Z,1554826351.758 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-09T16:12:31.758Z,1554826351.758 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T16:12:31.927Z,1554826351.927 [NAL9602](INFO): Powering up NAL9602
2019-04-09T16:12:31.943Z,1554826351.943 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:31.943Z,1554826351.943 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:31.943Z,1554826351.943 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:31.943Z,1554826351.943 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:31.944Z,1554826351.944 [BR_Ping1D](INFO): elapsed 2.0211
2019-04-09T16:12:31.944Z,1554826351.944 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:31.944Z,1554826351.944 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:31.944Z,1554826351.944 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:31.944Z,1554826351.944 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:32.115Z,1554826352.115 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T16:12:32.162Z,1554826352.162 [CTD_Seabird](INFO): Powering down
2019-04-09T16:12:32.346Z,1554826352.346 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:32.346Z,1554826352.346 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:32.346Z,1554826352.346 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:32.346Z,1554826352.346 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:32.347Z,1554826352.347 [BR_Ping1D](INFO): elapsed 2.4240
2019-04-09T16:12:32.347Z,1554826352.347 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:32.347Z,1554826352.347 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:32.347Z,1554826352.347 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:32.347Z,1554826352.347 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:32.749Z,1554826352.749 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:32.749Z,1554826352.749 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:32.750Z,1554826352.750 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:32.750Z,1554826352.750 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:32.750Z,1554826352.750 [BR_Ping1D](INFO): elapsed 2.8276
2019-04-09T16:12:32.750Z,1554826352.750 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:32.751Z,1554826352.751 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:32.751Z,1554826352.751 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:32.751Z,1554826352.751 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:33.162Z,1554826353.162 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:33.162Z,1554826353.162 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:33.163Z,1554826353.163 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:33.163Z,1554826353.163 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:33.163Z,1554826353.163 [BR_Ping1D](INFO): elapsed 3.2403
2019-04-09T16:12:33.163Z,1554826353.163 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:33.163Z,1554826353.163 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:33.163Z,1554826353.163 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:33.164Z,1554826353.164 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:33.303Z,1554826353.303 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T16:12:33.303Z,1554826353.303 [CTD_Seabird] No Fault, FailCount= 2
2019-04-09T16:12:33.506Z,1554826353.506 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T16:12:33.516Z,1554826353.516 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T16:12:33.557Z,1554826353.557 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:33.557Z,1554826353.557 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:33.557Z,1554826353.557 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:33.557Z,1554826353.557 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:33.558Z,1554826353.558 [BR_Ping1D](INFO): elapsed 3.6351
2019-04-09T16:12:33.558Z,1554826353.558 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:33.558Z,1554826353.558 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:33.558Z,1554826353.558 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:33.558Z,1554826353.558 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:33.585Z,1554826353.585 [CBIT](FAULT): Main Battery Failure. Count: 71
2019-04-09T16:12:33.961Z,1554826353.961 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:33.962Z,1554826353.962 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:33.962Z,1554826353.962 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:33.962Z,1554826353.962 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:33.962Z,1554826353.962 [BR_Ping1D](INFO): elapsed 4.0397
2019-04-09T16:12:33.962Z,1554826353.962 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:33.963Z,1554826353.963 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:33.963Z,1554826353.963 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:33.963Z,1554826353.963 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:34.365Z,1554826354.365 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:34.365Z,1554826354.365 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:34.366Z,1554826354.366 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:34.366Z,1554826354.366 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:34.366Z,1554826354.366 [BR_Ping1D](INFO): elapsed 4.4437
2019-04-09T16:12:34.366Z,1554826354.366 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:34.367Z,1554826354.367 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:34.367Z,1554826354.367 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:34.367Z,1554826354.367 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:34.773Z,1554826354.773 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:34.773Z,1554826354.773 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:34.773Z,1554826354.773 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:34.773Z,1554826354.773 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:34.773Z,1554826354.773 [BR_Ping1D](INFO): elapsed 4.8509
2019-04-09T16:12:34.774Z,1554826354.774 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:34.774Z,1554826354.774 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:34.774Z,1554826354.774 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:34.774Z,1554826354.774 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:35.173Z,1554826355.173 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:35.173Z,1554826355.173 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:35.173Z,1554826355.173 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:35.173Z,1554826355.173 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:35.174Z,1554826355.174 [BR_Ping1D](INFO): elapsed 5.2511
2019-04-09T16:12:35.174Z,1554826355.174 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:35.174Z,1554826355.174 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T16:12:35.174Z,1554826355.174 [BR_Ping1D] Data Fault, FailCount= 4
2019-04-09T16:12:35.174Z,1554826355.174 [BR_Ping1D](ERROR): Data Fault
2019-04-09T16:12:35.235Z,1554826355.235 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T16:12:35.578Z,1554826355.578 [BR_Ping1D](INFO): Stop
2019-04-09T16:12:35.578Z,1554826355.578 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:12:35.578Z,1554826355.578 [BR_Ping1D](INFO): Powering down
2019-04-09T16:12:35.981Z,1554826355.981 [BR_Ping1D](INFO): Stopping
2019-04-09T16:12:36.385Z,1554826356.385 [BR_Ping1D](INFO): Stopping
2019-04-09T16:12:36.789Z,1554826356.789 [BR_Ping1D](INFO): Stopped
2019-04-09T16:12:36.816Z,1554826356.816 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T16:12:36.816Z,1554826356.816 [BR_Ping1D] No Fault, FailCount= 4
2019-04-09T16:12:37.193Z,1554826357.193 [BR_Ping1D](INFO): Start
2019-04-09T16:12:37.597Z,1554826357.597 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:37.609Z,1554826357.609 [CBIT](FAULT): Main Battery Failure. Count: 81
2019-04-09T16:12:37.983Z,1554826357.983 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T16:12:37.983Z,1554826357.983 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-09T16:12:37.983Z,1554826357.983 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T16:12:38.022Z,1554826358.022 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:38.022Z,1554826358.022 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:38.023Z,1554826358.023 [BR_Ping1D](INFO): startup: 0
2019-04-09T16:12:38.023Z,1554826358.023 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T16:12:38.034Z,1554826358.034 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T16:12:38.034Z,1554826358.034 [BR_Ping1D](INFO): 66
2019-04-09T16:12:38.034Z,1554826358.034 [BR_Ping1D](INFO): 82
2019-04-09T16:12:38.034Z,1554826358.034 [BR_Ping1D](INFO): 2
2019-04-09T16:12:38.034Z,1554826358.034 [BR_Ping1D](INFO): 0
2019-04-09T16:12:38.035Z,1554826358.035 [BR_Ping1D](INFO): 236
2019-04-09T16:12:38.035Z,1554826358.035 [BR_Ping1D](INFO): 3
2019-04-09T16:12:38.035Z,1554826358.035 [BR_Ping1D](INFO): 0
2019-04-09T16:12:38.035Z,1554826358.035 [BR_Ping1D](INFO): 0
2019-04-09T16:12:38.035Z,1554826358.035 [BR_Ping1D](INFO): 1
2019-04-09T16:12:38.035Z,1554826358.035 [BR_Ping1D](INFO): 30
2019-04-09T16:12:38.035Z,1554826358.035 [BR_Ping1D](INFO): 164
2019-04-09T16:12:38.035Z,1554826358.035 [BR_Ping1D](INFO): 1
2019-04-09T16:12:38.035Z,1554826358.035 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T16:12:38.036Z,1554826358.036 [BR_Ping1D](INFO): 66
2019-04-09T16:12:38.036Z,1554826358.036 [BR_Ping1D](INFO): 82
2019-04-09T16:12:38.036Z,1554826358.036 [BR_Ping1D](INFO): 1
2019-04-09T16:12:38.036Z,1554826358.036 [BR_Ping1D](INFO): 0
2019-04-09T16:12:38.036Z,1554826358.036 [BR_Ping1D](INFO): 235
2019-04-09T16:12:38.036Z,1554826358.036 [BR_Ping1D](INFO): 3
2019-04-09T16:12:38.036Z,1554826358.036 [BR_Ping1D](INFO): 0
2019-04-09T16:12:38.036Z,1554826358.036 [BR_Ping1D](INFO): 0
2019-04-09T16:12:38.036Z,1554826358.036 [BR_Ping1D](INFO): 132
2019-04-09T16:12:38.037Z,1554826358.037 [BR_Ping1D](INFO): 1
2019-04-09T16:12:38.037Z,1554826358.037 [BR_Ping1D](INFO): 21
2019-04-09T16:12:38.037Z,1554826358.037 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T16:12:38.037Z,1554826358.037 [BR_Ping1D](INFO): 66
2019-04-09T16:12:38.037Z,1554826358.037 [BR_Ping1D](INFO): 82
2019-04-09T16:12:38.037Z,1554826358.037 [BR_Ping1D](INFO): 4
2019-04-09T16:12:38.037Z,1554826358.037 [BR_Ping1D](INFO): 0
2019-04-09T16:12:38.037Z,1554826358.037 [BR_Ping1D](INFO): 234
2019-04-09T16:12:38.038Z,1554826358.038 [BR_Ping1D](INFO): 3
2019-04-09T16:12:38.038Z,1554826358.038 [BR_Ping1D](INFO): 0
2019-04-09T16:12:38.039Z,1554826358.039 [BR_Ping1D](INFO): 0
2019-04-09T16:12:38.039Z,1554826358.039 [BR_Ping1D](INFO): 96
2019-04-09T16:12:38.040Z,1554826358.040 [BR_Ping1D](INFO): 227
2019-04-09T16:12:38.040Z,1554826358.040 [BR_Ping1D](INFO): 22
2019-04-09T16:12:38.041Z,1554826358.041 [BR_Ping1D](INFO): 0
2019-04-09T16:12:38.041Z,1554826358.041 [BR_Ping1D](INFO): 222
2019-04-09T16:12:38.041Z,1554826358.041 [BR_Ping1D](INFO): 2
2019-04-09T16:12:38.043Z,1554826358.043 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T16:12:38.043Z,1554826358.043 [BR_Ping1D](INFO): 66
2019-04-09T16:12:38.044Z,1554826358.044 [BR_Ping1D](INFO): 82
2019-04-09T16:12:38.044Z,1554826358.044 [BR_Ping1D](INFO): 1
2019-04-09T16:12:38.045Z,1554826358.045 [BR_Ping1D](INFO): 0
2019-04-09T16:12:38.045Z,1554826358.045 [BR_Ping1D](INFO): 238
2019-04-09T16:12:38.045Z,1554826358.045 [BR_Ping1D](INFO): 3
2019-04-09T16:12:38.045Z,1554826358.045 [BR_Ping1D](INFO): 0
2019-04-09T16:12:38.046Z,1554826358.046 [BR_Ping1D](INFO): 0
2019-04-09T16:12:38.058Z,1554826358.058 [BR_Ping1D](INFO): 135
2019-04-09T16:12:38.058Z,1554826358.058 [BR_Ping1D](INFO): 1
2019-04-09T16:12:38.058Z,1554826358.058 [BR_Ping1D](INFO): 21
2019-04-09T16:12:38.116Z,1554826358.116 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T16:12:38.116Z,1554826358.116 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-09T16:12:38.506Z,1554826358.506 [Aanderaa_O2](INFO): Powering down
2019-04-09T16:12:38.525Z,1554826358.525 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:38.526Z,1554826358.526 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:38.526Z,1554826358.526 [BR_Ping1D](INFO): startup: 1
2019-04-09T16:12:38.527Z,1554826358.527 [BR_Ping1D](INFO): UART requesting status
2019-04-09T16:12:38.527Z,1554826358.527 [BR_Ping1D](INFO): 66
2019-04-09T16:12:38.527Z,1554826358.527 [BR_Ping1D](INFO): 82
2019-04-09T16:12:38.527Z,1554826358.527 [BR_Ping1D](INFO): 0
2019-04-09T16:12:38.527Z,1554826358.527 [BR_Ping1D](INFO): 0
2019-04-09T16:12:38.527Z,1554826358.527 [BR_Ping1D](INFO): 186
2019-04-09T16:12:38.527Z,1554826358.527 [BR_Ping1D](INFO): 4
2019-04-09T16:12:38.527Z,1554826358.527 [BR_Ping1D](INFO): 0
2019-04-09T16:12:38.527Z,1554826358.527 [BR_Ping1D](INFO): 0
2019-04-09T16:12:38.527Z,1554826358.527 [BR_Ping1D](INFO): 82
2019-04-09T16:12:38.528Z,1554826358.528 [BR_Ping1D](INFO): 1
2019-04-09T16:12:38.534Z,1554826358.534 [BR_Ping1D](INFO): 66
2019-04-09T16:12:38.534Z,1554826358.534 [BR_Ping1D](INFO): 82
2019-04-09T16:12:38.534Z,1554826358.534 [BR_Ping1D](INFO): 0
2019-04-09T16:12:38.534Z,1554826358.534 [BR_Ping1D](INFO): 0
2019-04-09T16:12:38.534Z,1554826358.534 [BR_Ping1D](INFO): 191
2019-04-09T16:12:38.534Z,1554826358.534 [BR_Ping1D](INFO): 4
2019-04-09T16:12:38.534Z,1554826358.534 [BR_Ping1D](INFO): 0
2019-04-09T16:12:38.535Z,1554826358.535 [BR_Ping1D](INFO): 0
2019-04-09T16:12:38.535Z,1554826358.535 [BR_Ping1D](INFO): 87
2019-04-09T16:12:38.535Z,1554826358.535 [BR_Ping1D](INFO): 1
2019-04-09T16:12:38.542Z,1554826358.542 [BR_Ping1D](INFO): 66
2019-04-09T16:12:38.542Z,1554826358.542 [BR_Ping1D](INFO): 82
2019-04-09T16:12:38.542Z,1554826358.542 [BR_Ping1D](INFO): 0
2019-04-09T16:12:38.542Z,1554826358.542 [BR_Ping1D](INFO): 0
2019-04-09T16:12:38.542Z,1554826358.542 [BR_Ping1D](INFO): 179
2019-04-09T16:12:38.542Z,1554826358.542 [BR_Ping1D](INFO): 4
2019-04-09T16:12:38.542Z,1554826358.542 [BR_Ping1D](INFO): 0
2019-04-09T16:12:38.543Z,1554826358.543 [BR_Ping1D](INFO): 0
2019-04-09T16:12:38.543Z,1554826358.543 [BR_Ping1D](INFO): 75
2019-04-09T16:12:38.543Z,1554826358.543 [BR_Ping1D](INFO): 1
2019-04-09T16:12:38.809Z,1554826358.809 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:38.809Z,1554826358.809 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:38.810Z,1554826358.810 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:38.810Z,1554826358.810 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:38.810Z,1554826358.810 [BR_Ping1D](INFO): elapsed 0.7871
2019-04-09T16:12:38.810Z,1554826358.810 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:38.810Z,1554826358.810 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:38.811Z,1554826358.811 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:38.811Z,1554826358.811 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:39.213Z,1554826359.213 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:39.213Z,1554826359.213 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:39.214Z,1554826359.214 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:39.214Z,1554826359.214 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:39.214Z,1554826359.214 [BR_Ping1D](INFO): elapsed 1.1914
2019-04-09T16:12:39.214Z,1554826359.214 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:39.215Z,1554826359.215 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:39.215Z,1554826359.215 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:39.215Z,1554826359.215 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:39.264Z,1554826359.264 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.006193
CHAN A1 (24V): -0.127255
CHAN A2 (12V): -0.005252
CHAN A3 (5V): -0.002926
CHAN B0 (3.3V): -0.000904
CHAN B1 (3.15aV): -0.000622
CHAN B2 (3.15bV): -0.001185
CHAN B3 (GND): -0.000481
OPEN: -0.000756
Full Scale Calc: 4.765 mA, -1.589 mA
2019-04-09T16:12:39.617Z,1554826359.617 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:39.618Z,1554826359.618 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:39.618Z,1554826359.618 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:39.618Z,1554826359.618 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:39.618Z,1554826359.618 [BR_Ping1D](INFO): elapsed 1.5955
2019-04-09T16:12:39.618Z,1554826359.618 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:39.619Z,1554826359.619 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:39.619Z,1554826359.619 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:39.619Z,1554826359.619 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:40.021Z,1554826360.021 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:40.021Z,1554826360.021 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:40.021Z,1554826360.021 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:40.022Z,1554826360.022 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:40.022Z,1554826360.022 [BR_Ping1D](INFO): elapsed 1.9991
2019-04-09T16:12:40.022Z,1554826360.022 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:40.022Z,1554826360.022 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:40.022Z,1554826360.022 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:40.023Z,1554826360.023 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:40.426Z,1554826360.426 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:40.426Z,1554826360.426 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:40.426Z,1554826360.426 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:40.426Z,1554826360.426 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:40.427Z,1554826360.427 [BR_Ping1D](INFO): elapsed 2.4038
2019-04-09T16:12:40.427Z,1554826360.427 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:40.427Z,1554826360.427 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:40.427Z,1554826360.427 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:40.427Z,1554826360.427 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:40.829Z,1554826360.829 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:40.829Z,1554826360.829 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:40.830Z,1554826360.830 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:40.830Z,1554826360.830 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:40.830Z,1554826360.830 [BR_Ping1D](INFO): elapsed 2.8072
2019-04-09T16:12:40.830Z,1554826360.830 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:40.830Z,1554826360.830 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:40.831Z,1554826360.831 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:40.831Z,1554826360.831 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:41.231Z,1554826361.231 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:41.232Z,1554826361.232 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:41.232Z,1554826361.232 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:41.232Z,1554826361.232 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:41.232Z,1554826361.232 [BR_Ping1D](INFO): elapsed 3.2094
2019-04-09T16:12:41.232Z,1554826361.232 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:41.233Z,1554826361.233 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:41.233Z,1554826361.233 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:41.233Z,1554826361.233 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:41.640Z,1554826361.640 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:41.640Z,1554826361.640 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:41.640Z,1554826361.640 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:41.640Z,1554826361.640 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:41.641Z,1554826361.641 [BR_Ping1D](INFO): elapsed 3.6179
2019-04-09T16:12:41.641Z,1554826361.641 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:41.641Z,1554826361.641 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:41.641Z,1554826361.641 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:41.641Z,1554826361.641 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:41.657Z,1554826361.657 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T16:12:41.662Z,1554826361.662 [CBIT](FAULT): Main Battery Failure. Count: 91
2019-04-09T16:12:42.042Z,1554826362.042 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:42.042Z,1554826362.042 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:42.042Z,1554826362.042 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:42.042Z,1554826362.042 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:42.042Z,1554826362.042 [BR_Ping1D](INFO): elapsed 4.0196
2019-04-09T16:12:42.043Z,1554826362.043 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:42.043Z,1554826362.043 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:42.043Z,1554826362.043 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:42.043Z,1554826362.043 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:42.457Z,1554826362.457 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:42.457Z,1554826362.457 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:42.457Z,1554826362.457 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:42.457Z,1554826362.457 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:42.457Z,1554826362.457 [BR_Ping1D](INFO): elapsed 4.4345
2019-04-09T16:12:42.457Z,1554826362.457 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:42.458Z,1554826362.458 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:42.458Z,1554826362.458 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:42.458Z,1554826362.458 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:42.835Z,1554826362.835 [NAL9602](INFO): NAL9602 initialized
2019-04-09T16:12:42.850Z,1554826362.850 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:42.850Z,1554826362.850 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:42.851Z,1554826362.851 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:42.851Z,1554826362.851 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:42.851Z,1554826362.851 [BR_Ping1D](INFO): elapsed 4.8282
2019-04-09T16:12:42.851Z,1554826362.851 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:42.851Z,1554826362.851 [BR_Ping1D](INFO): No UART data
2019-04-09T16:12:42.852Z,1554826362.852 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:12:42.852Z,1554826362.852 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:12:43.263Z,1554826363.263 [BR_Ping1D](INFO): Starting
2019-04-09T16:12:43.263Z,1554826363.263 [BR_Ping1D](INFO): readConfig
2019-04-09T16:12:43.263Z,1554826363.263 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:12:43.263Z,1554826363.263 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:12:43.263Z,1554826363.263 [BR_Ping1D](INFO): elapsed 5.2405
2019-04-09T16:12:43.263Z,1554826363.263 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:12:43.264Z,1554826363.264 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T16:12:43.264Z,1554826363.264 [BR_Ping1D] Data Fault, FailCount= 5
2019-04-09T16:12:43.264Z,1554826363.264 [BR_Ping1D](ERROR): Data Fault
2019-04-09T16:12:43.280Z,1554826363.280 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T16:12:43.280Z,1554826363.280 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-04-09T16:12:43.654Z,1554826363.654 [NAL9602](DEBUG): Fix Requested
2019-04-09T16:12:43.669Z,1554826363.669 [BR_Ping1D](INFO): Stop
2019-04-09T16:12:43.669Z,1554826363.669 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:12:43.669Z,1554826363.669 [BR_Ping1D](INFO): Powering down
2019-04-09T16:12:43.888Z,1554826363.888 [CBIT](INFO): Critical error at 20190409T161243
2019-04-09T16:12:44.077Z,1554826364.077 [BR_Ping1D](INFO): Stopping
2019-04-09T16:12:44.489Z,1554826364.489 [BR_Ping1D](INFO): Stopping
2019-04-09T16:12:44.871Z,1554826364.871 [BR_Ping1D](INFO): Stopped
2019-04-09T16:12:45.769Z,1554826365.769 [CBIT](FAULT): Main Battery Failure. Count: 101
2019-04-09T16:12:46.558Z,1554826366.558 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T16:12:46.558Z,1554826366.558 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-09T16:12:46.559Z,1554826366.559 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T16:12:46.567Z,1554826366.567 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T16:12:46.568Z,1554826366.568 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-09T16:12:46.962Z,1554826366.962 [CTD_Seabird](INFO): Powering down
2019-04-09T16:12:48.891Z,1554826368.891 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:12:48.891Z,1554826368.891 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2019-04-09T16:12:48.891Z,1554826368.891 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:12:49.063Z,1554826369.063 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:12:49.295Z,1554826369.295 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:12:49.773Z,1554826369.773 [CBIT](FAULT): Main Battery Failure. Count: 111
2019-04-09T16:12:50.156Z,1554826370.156 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:12:50.156Z,1554826370.156 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2019-04-09T16:12:52.539Z,1554826372.539 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:12:52.539Z,1554826372.539 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:12:53.794Z,1554826373.794 [CBIT](FAULT): Main Battery Failure. Count: 121
2019-04-09T16:12:57.832Z,1554826377.832 [CBIT](FAULT): Main Battery Failure. Count: 131
2019-04-09T16:13:01.881Z,1554826381.881 [CBIT](FAULT): Main Battery Failure. Count: 141
2019-04-09T16:13:05.913Z,1554826385.913 [CBIT](FAULT): Main Battery Failure. Count: 151
2019-04-09T16:13:08.344Z,1554826388.344 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T16:13:08.344Z,1554826388.344 [SBIT](FAULT): Control surface position failure.
2019-04-09T16:13:09.948Z,1554826389.948 [CBIT](FAULT): Main Battery Failure. Count: 161
2019-04-09T16:13:12.740Z,1554826392.740 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:13:12.740Z,1554826392.740 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2019-04-09T16:13:12.740Z,1554826392.740 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:13:12.875Z,1554826392.875 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:13:13.142Z,1554826393.142 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:13:13.985Z,1554826393.985 [CBIT](FAULT): Main Battery Failure. Count: 171
2019-04-09T16:13:14.008Z,1554826394.008 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:13:14.009Z,1554826394.009 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2019-04-09T16:13:16.374Z,1554826396.374 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:13:16.375Z,1554826396.375 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:13:18.041Z,1554826398.041 [CBIT](FAULT): Main Battery Failure. Count: 181
2019-04-09T16:13:22.065Z,1554826402.065 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T16:13:22.065Z,1554826402.065 [SBIT](FAULT): Control surface position failure.
2019-04-09T16:13:22.066Z,1554826402.066 [CBIT](FAULT): Main Battery Failure. Count: 191
2019-04-09T16:13:22.471Z,1554826402.471 [SBIT](CRITICAL): SBIT FAILED
2019-04-09T16:13:22.487Z,1554826402.487 [CBIT](INFO): Critical error at 20190409T161322
2019-04-09T16:13:22.538Z,1554826402.538 [CommandLine](IMPORTANT): got command configSet list
2019-04-09T16:13:22.539Z,1554826402.539 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-04-09T16:13:22.540Z,1554826402.540 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-04-09T16:13:22.540Z,1554826402.540 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2019-04-09T16:13:22.883Z,1554826402.883 [MissionManager](IMPORTANT): Started mission Startup
2019-04-09T16:13:22.883Z,1554826402.883 [Startup] Running Loop=1
2019-04-09T16:13:22.884Z,1554826402.884 [Startup](DEBUG): Aggregate::initialize Startup
2019-04-09T16:13:22.884Z,1554826402.884 [Startup:A.GoToSurface] Running Loop=1
2019-04-09T16:13:22.884Z,1554826402.884 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-09T16:13:22.884Z,1554826402.884 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-09T16:13:22.885Z,1554826402.885 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-09T16:13:22.885Z,1554826402.885 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-09T16:13:22.886Z,1554826402.886 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-09T16:13:22.886Z,1554826402.886 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-09T16:13:22.888Z,1554826402.888 [Startup:StartupSatComms] Running Loop=1
2019-04-09T16:13:22.888Z,1554826402.888 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-04-09T16:13:22.888Z,1554826402.888 [Startup:StartupSatComms:A] Running Loop=1
2019-04-09T16:13:23.283Z,1554826403.283 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-04-09T16:13:26.105Z,1554826406.105 [CBIT](FAULT): Main Battery Failure. Count: 201
2019-04-09T16:13:30.146Z,1554826410.146 [CBIT](FAULT): Main Battery Failure. Count: 211
2019-04-09T16:13:34.205Z,1554826414.205 [CBIT](FAULT): Main Battery Failure. Count: 221
2019-04-09T16:13:36.574Z,1554826416.574 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:13:36.575Z,1554826416.575 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2019-04-09T16:13:36.575Z,1554826416.575 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:13:36.612Z,1554826416.612 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:13:36.978Z,1554826416.978 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:13:37.828Z,1554826417.828 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:13:37.828Z,1554826417.828 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2019-04-09T16:13:38.218Z,1554826418.218 [CBIT](FAULT): Main Battery Failure. Count: 231
2019-04-09T16:13:40.210Z,1554826420.210 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:13:40.211Z,1554826420.211 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:13:42.265Z,1554826422.265 [CBIT](FAULT): Main Battery Failure. Count: 241
2019-04-09T16:13:46.305Z,1554826426.305 [CBIT](FAULT): Main Battery Failure. Count: 251
2019-04-09T16:13:50.352Z,1554826430.352 [CBIT](FAULT): Main Battery Failure. Count: 261
2019-04-09T16:13:54.401Z,1554826434.401 [CBIT](FAULT): Main Battery Failure. Count: 271
2019-04-09T16:13:58.427Z,1554826438.427 [CBIT](FAULT): Main Battery Failure. Count: 281
2019-04-09T16:14:00.411Z,1554826440.411 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:14:00.411Z,1554826440.411 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2019-04-09T16:14:00.411Z,1554826440.411 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:14:00.464Z,1554826440.464 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:14:00.814Z,1554826440.814 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:14:01.664Z,1554826441.664 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:14:01.664Z,1554826441.664 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2019-04-09T16:14:02.459Z,1554826442.459 [CBIT](FAULT): Main Battery Failure. Count: 291
2019-04-09T16:14:04.047Z,1554826444.047 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:14:04.047Z,1554826444.047 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:14:04.089Z,1554826444.089 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T16:14:04.089Z,1554826444.089 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-04-09T16:14:04.089Z,1554826444.089 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T16:14:04.091Z,1554826444.091 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T16:14:04.091Z,1554826444.091 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-04-09T16:14:04.091Z,1554826444.091 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T16:14:04.092Z,1554826444.092 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T16:14:04.093Z,1554826444.093 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-04-09T16:14:04.093Z,1554826444.093 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T16:14:04.104Z,1554826444.104 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T16:14:04.104Z,1554826444.104 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T16:14:04.104Z,1554826444.104 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T16:14:04.483Z,1554826444.483 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T16:14:04.483Z,1554826444.483 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-04-09T16:14:04.483Z,1554826444.483 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T16:14:04.484Z,1554826444.484 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-04-09T16:14:04.484Z,1554826444.484 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T16:14:04.484Z,1554826444.484 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-04-09T16:14:04.897Z,1554826444.897 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T16:14:06.505Z,1554826446.505 [CBIT](FAULT): Main Battery Failure. Count: 301
2019-04-09T16:14:10.556Z,1554826450.556 [CBIT](FAULT): Main Battery Failure. Count: 311
2019-04-09T16:14:14.586Z,1554826454.586 [CBIT](FAULT): Main Battery Failure. Count: 321
2019-04-09T16:14:18.627Z,1554826458.627 [CBIT](FAULT): Main Battery Failure. Count: 331
2019-04-09T16:14:22.670Z,1554826462.670 [CBIT](FAULT): Main Battery Failure. Count: 341
2019-04-09T16:14:23.058Z,1554826463.058 [Startup:StartupSatComms:A](INFO): Timed out from 2019-04-09T16:13:22.9Z
2019-04-09T16:14:23.058Z,1554826463.058 [Startup:StartupSatComms:A] Stopped
2019-04-09T16:14:23.058Z,1554826463.058 [Startup:StartupSatComms:B] Running Loop=1
2019-04-09T16:14:23.475Z,1554826463.475 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-09T16:14:24.247Z,1554826464.247 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:14:24.247Z,1554826464.247 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2019-04-09T16:14:24.247Z,1554826464.247 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:14:24.278Z,1554826464.278 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:14:24.650Z,1554826464.650 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:14:25.498Z,1554826465.498 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:14:25.498Z,1554826465.498 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2019-04-09T16:14:26.715Z,1554826466.715 [CBIT](FAULT): Main Battery Failure. Count: 351
2019-04-09T16:14:27.882Z,1554826467.882 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:14:27.883Z,1554826467.883 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:14:30.746Z,1554826470.746 [CBIT](FAULT): Main Battery Failure. Count: 361
2019-04-09T16:14:34.786Z,1554826474.786 [CBIT](FAULT): Main Battery Failure. Count: 371
2019-04-09T16:14:38.824Z,1554826478.824 [CBIT](FAULT): Main Battery Failure. Count: 381
2019-04-09T16:14:42.877Z,1554826482.877 [CBIT](FAULT): Main Battery Failure. Count: 391
2019-04-09T16:14:46.558Z,1554826486.558 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.006280
2019-04-09T16:14:46.940Z,1554826486.940 [CBIT](FAULT): Main Battery Failure. Count: 401
2019-04-09T16:14:47.648Z,1554826487.648 [CommandLine](IMPORTANT): got command restart application
2019-04-09T16:14:48.083Z,1554826488.083 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:14:48.083Z,1554826488.083 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7
2019-04-09T16:14:48.083Z,1554826488.083 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:14:48.151Z,1554826488.151 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:14:48.487Z,1554826488.487 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:14:48.655Z,1554826488.655 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:14:48.655Z,1554826488.655 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:14:48.846Z,1554826488.846 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-04-09T16:14:48.846Z,1554826488.846 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:14:48.847Z,1554826488.847 [CommandLine](INFO): Join timeout helper Thread ID is 4331
2019-04-09T16:14:48.875Z,1554826488.875 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-04-09T16:14:48.875Z,1554826488.875 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:14:48.876Z,1554826488.876 [NavChartDb](INFO): Join timeout helper Thread ID is 4332
2019-04-09T16:14:49.178Z,1554826489.178 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:14:49.178Z,1554826489.178 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:14:49.198Z,1554826489.198 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-04-09T16:14:49.198Z,1554826489.198 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:14:49.199Z,1554826489.199 [Radio_Surface](INFO): Join timeout helper Thread ID is 4333
2019-04-09T16:14:49.347Z,1554826489.347 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:14:49.347Z,1554826489.347 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7
2019-04-09T16:14:49.570Z,1554826489.570 [Radio_Surface](INFO): Powering down
2019-04-09T16:14:49.571Z,1554826489.571 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:14:49.572Z,1554826489.572 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:14:49.590Z,1554826489.590 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-04-09T16:14:49.590Z,1554826489.590 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:14:49.591Z,1554826489.591 [DataOverHttps](INFO): Join timeout helper Thread ID is 4334
2019-04-09T16:14:50.940Z,1554826490.940 [CBIT](FAULT): Main Battery Failure. Count: 411
2019-04-09T16:14:51.718Z,1554826491.718 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:14:51.719Z,1554826491.719 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:14:52.908Z,1554826492.908 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:14:52.912Z,1554826492.912 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:14:52.914Z,1554826492.914 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-04-09T16:14:52.914Z,1554826492.914 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:14:52.915Z,1554826492.915 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4336
2019-04-09T16:14:53.274Z,1554826493.274 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:14:53.275Z,1554826493.275 [WetLabsBB2FL](INFO): Powering down
2019-04-09T16:14:53.275Z,1554826493.275 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:14:53.279Z,1554826493.279 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-04-09T16:14:53.279Z,1554826493.279 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:14:53.279Z,1554826493.279 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4337
2019-04-09T16:14:53.562Z,1554826493.562 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:14:53.562Z,1554826493.562 [CTD_Seabird](INFO): Powering down
2019-04-09T16:14:53.574Z,1554826493.574 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:14:53.583Z,1554826493.583 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-04-09T16:14:53.583Z,1554826493.583 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:14:53.583Z,1554826493.583 [logger](INFO): Join timeout helper Thread ID is 4338
2019-04-09T16:14:53.584Z,1554826493.584 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:14:53.584Z,1554826493.584 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:14:53.585Z,1554826493.585 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-04-09T16:14:53.585Z,1554826493.585 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:14:53.585Z,1554826493.585 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-04-09T16:14:53.585Z,1554826493.585 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:14:53.586Z,1554826493.586 [controlThread](INFO): Join timeout helper Thread ID is 4339
2019-04-09T16:14:53.734Z,1554826493.734 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:14:53.734Z,1554826493.734 [controlThread](DEBUG): Uninitializing ControlThread
2019-04-09T16:14:53.735Z,1554826493.735 [Aanderaa_O2](INFO): Powering down
2019-04-09T16:14:53.737Z,1554826493.737 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:14:53.854Z,1554826493.854 [NAL9602](INFO): Powering down
2019-04-09T16:14:53.856Z,1554826493.856 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:14:53.856Z,1554826493.856 [BR_Ping1D](INFO): Powering down
2019-04-09T16:14:53.858Z,1554826493.858 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-04-09T16:14:53.859Z,1554826493.859 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-04-09T16:14:53.859Z,1554826493.859 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-04-09T16:14:53.860Z,1554826493.860 [MissionManager](INFO): Uninitializing Mission Default
2019-04-09T16:14:53.862Z,1554826493.862 [MissionManager](INFO): Uninitializing Mission Startup
2019-04-09T16:14:53.862Z,1554826493.862 [Startup] Stopped
2019-04-09T16:14:53.862Z,1554826493.862 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-04-09T16:14:53.863Z,1554826493.863 [Startup:A.GoToSurface] Stopped
2019-04-09T16:14:53.863Z,1554826493.863 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-09T16:14:53.863Z,1554826493.863 [Startup:StartupSatComms] Stopped
2019-04-09T16:14:53.863Z,1554826493.863 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-04-09T16:14:53.863Z,1554826493.863 [Startup:StartupSatComms:B] Stopped
2019-04-09T16:14:53.864Z,1554826493.864 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-04-09T16:14:53.864Z,1554826493.864 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-04-09T16:14:53.865Z,1554826493.865 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-04-09T16:14:53.865Z,1554826493.865 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-04-09T16:14:53.865Z,1554826493.865 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-04-09T16:14:53.865Z,1554826493.865 [BuoyancyServo](INFO): Powering down
2019-04-09T16:14:53.878Z,1554826493.878 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-09T16:14:53.878Z,1554826493.878 [ElevatorServo](INFO): Powering down
2019-04-09T16:14:53.879Z,1554826493.879 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:14:53.879Z,1554826493.879 [MassServo](INFO): Powering down
2019-04-09T16:14:53.880Z,1554826493.880 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-09T16:14:53.880Z,1554826493.880 [RudderServo](INFO): Powering down
2019-04-09T16:14:53.881Z,1554826493.881 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-04-09T16:14:53.881Z,1554826493.881 [ThrusterServo](INFO): Powering down
2019-04-09T16:14:53.882Z,1554826493.882 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-04-09T16:14:53.882Z,1554826493.882 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-04-09T16:14:53.882Z,1554826493.882 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-04-09T16:14:53.882Z,1554826493.882 [CBIT](DEBUG): Powering off loads.
2019-04-09T16:14:53.894Z,1554826493.894 [CBIT](DEBUG): Disabling WDT.
2019-04-09T16:14:53.906Z,1554826493.906 [CBIT](DEBUG): Opening all GF detection circuits.
2019-04-09T16:14:53.907Z,1554826493.907 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:14:54.069Z,1554826494.069 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:14:54.074Z,1554826494.074 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:14:54.141Z,1554826494.141 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:14:54.146Z,1554826494.146 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:14:54.197Z,1554826494.197 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:14:54.255Z,1554826494.255 [logger ThreadHandler](INFO): Thread cancelled.