2019-04-09T16:04:44.117Z,1554825884.117 [Supervisor](DEBUG): Initializing supervisor.
2019-04-09T16:04:44.120Z,1554825884.120 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-09T16:04:44.121Z,1554825884.121 [SyncHandler](INFO): Protected caller Thread ID is 3891
2019-04-09T16:04:44.122Z,1554825884.122 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-04-09T16:04:44.123Z,1554825884.123 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-04-09T16:04:44.123Z,1554825884.123 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3892
2019-04-09T16:04:44.127Z,1554825884.127 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-04-09T16:04:44.139Z,1554825884.139 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-04-09T16:04:44.140Z,1554825884.140 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-04-09T16:04:44.141Z,1554825884.141 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3893
2019-04-09T16:04:44.142Z,1554825884.142 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-04-09T16:04:44.143Z,1554825884.143 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-04-09T16:04:44.143Z,1554825884.143 [logger ThreadHandler](INFO): Protected caller Thread ID is 3894
2019-04-09T16:04:44.145Z,1554825884.145 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-04-09T16:04:44.145Z,1554825884.145 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-04-09T16:04:44.147Z,1554825884.147 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-04-09T16:04:44.246Z,1554825884.246 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-04-09T16:04:44.247Z,1554825884.247 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-04-09T16:04:44.465Z,1554825884.465 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-04-09T16:04:44.466Z,1554825884.466 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-04-09T16:04:44.611Z,1554825884.611 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-04-09T16:04:44.612Z,1554825884.612 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-04-09T16:04:45.117Z,1554825885.117 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-04-09T16:04:45.118Z,1554825885.118 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-04-09T16:04:45.628Z,1554825885.628 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-04-09T16:04:45.628Z,1554825885.628 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-04-09T16:04:46.139Z,1554825886.139 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-04-09T16:04:46.139Z,1554825886.139 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-04-09T16:04:46.474Z,1554825886.474 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-04-09T16:04:46.475Z,1554825886.475 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-04-09T16:04:46.839Z,1554825886.839 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-04-09T16:04:46.840Z,1554825886.840 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-04-09T16:04:47.269Z,1554825887.269 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-04-09T16:04:47.270Z,1554825887.270 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-04-09T16:04:47.429Z,1554825887.429 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-04-09T16:04:47.429Z,1554825887.429 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-04-09T16:04:47.539Z,1554825887.539 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-04-09T16:04:47.540Z,1554825887.540 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-04-09T16:04:47.623Z,1554825887.623 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-04-09T16:04:47.728Z,1554825887.728 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-04-09T16:04:47.729Z,1554825887.729 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-04-09T16:04:47.938Z,1554825887.938 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-04-09T16:04:47.939Z,1554825887.939 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-04-09T16:04:48.161Z,1554825888.161 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-04-09T16:04:48.163Z,1554825888.163 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-04-09T16:04:48.164Z,1554825888.164 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-04-09T16:04:48.249Z,1554825888.249 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-04-09T16:04:48.495Z,1554825888.495 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-04-09T16:04:48.496Z,1554825888.496 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-04-09T16:04:48.595Z,1554825888.595 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-04-09T16:04:48.782Z,1554825888.782 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-04-09T16:04:49.015Z,1554825889.015 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-04-09T16:04:49.102Z,1554825889.102 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-04-09T16:04:49.204Z,1554825889.204 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-04-09T16:04:49.301Z,1554825889.301 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-04-09T16:04:49.464Z,1554825889.464 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-04-09T16:04:49.573Z,1554825889.573 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-04-09T16:04:49.676Z,1554825889.676 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2019-04-09T16:04:49.677Z,1554825889.677 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-04-09T16:04:49.681Z,1554825889.681 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-04-09T16:04:49.787Z,1554825889.787 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-04-09T16:04:49.787Z,1554825889.787 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-04-09T16:04:49.918Z,1554825889.918 [BuoyancyServo] Loaded
2019-04-09T16:04:49.918Z,1554825889.918 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-04-09T16:04:49.934Z,1554825889.934 [ElevatorServo] Loaded
2019-04-09T16:04:49.935Z,1554825889.935 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-04-09T16:04:49.951Z,1554825889.951 [MassServo] Loaded
2019-04-09T16:04:49.951Z,1554825889.951 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-04-09T16:04:49.967Z,1554825889.967 [RudderServo] Loaded
2019-04-09T16:04:49.968Z,1554825889.968 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-04-09T16:04:49.983Z,1554825889.983 [ThrusterServo] Loaded
2019-04-09T16:04:49.983Z,1554825889.983 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-04-09T16:04:49.984Z,1554825889.984 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-04-09T16:04:49.984Z,1554825889.984 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-04-09T16:04:50.130Z,1554825890.130 [SBIT](DEBUG): Construct Startup Built In Test.
2019-04-09T16:04:50.161Z,1554825890.161 [SBIT] Loaded
2019-04-09T16:04:50.161Z,1554825890.161 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-04-09T16:04:50.162Z,1554825890.162 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-04-09T16:04:50.192Z,1554825890.192 [IBIT] Loaded
2019-04-09T16:04:50.192Z,1554825890.192 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-04-09T16:04:50.196Z,1554825890.196 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-04-09T16:04:50.615Z,1554825890.615 [CBIT] Loaded
2019-04-09T16:04:50.615Z,1554825890.615 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-04-09T16:04:50.616Z,1554825890.616 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-04-09T16:04:50.617Z,1554825890.617 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-04-09T16:04:50.812Z,1554825890.812 [Aanderaa_O2] Loaded
2019-04-09T16:04:50.812Z,1554825890.812 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-04-09T16:04:50.822Z,1554825890.822 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-04-09T16:04:50.828Z,1554825890.828 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-04-09T16:04:50.829Z,1554825890.829 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-04-09T16:04:50.835Z,1554825890.835 [CTD_Seabird](INFO): created writer for : depth
2019-04-09T16:04:50.835Z,1554825890.835 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-04-09T16:04:50.841Z,1554825890.841 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-04-09T16:04:50.842Z,1554825890.842 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-04-09T16:04:50.847Z,1554825890.847 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-04-09T16:04:50.848Z,1554825890.848 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-04-09T16:04:50.854Z,1554825890.854 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-04-09T16:04:50.855Z,1554825890.855 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-04-09T16:04:50.860Z,1554825890.860 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-04-09T16:04:50.861Z,1554825890.861 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-04-09T16:04:50.866Z,1554825890.866 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-04-09T16:04:50.895Z,1554825890.895 [CTD_Seabird] Loaded
2019-04-09T16:04:50.896Z,1554825890.896 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-04-09T16:04:50.897Z,1554825890.897 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406654E0
2019-04-09T16:04:50.897Z,1554825890.897 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3973
2019-04-09T16:04:50.925Z,1554825890.925 [ESPComponent] Loaded
2019-04-09T16:04:50.925Z,1554825890.925 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-04-09T16:04:50.940Z,1554825890.940 [PAR_Licor] Loaded
2019-04-09T16:04:50.940Z,1554825890.940 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-04-09T16:04:50.993Z,1554825890.993 [WetLabsBB2FL] Loaded
2019-04-09T16:04:50.993Z,1554825890.993 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-04-09T16:04:50.994Z,1554825890.994 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406954E0
2019-04-09T16:04:50.994Z,1554825890.994 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3974
2019-04-09T16:04:50.995Z,1554825890.995 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-04-09T16:04:50.996Z,1554825890.996 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-04-09T16:04:51.143Z,1554825891.143 [DepthRateCalculator] Loaded
2019-04-09T16:04:51.143Z,1554825891.143 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-04-09T16:04:51.149Z,1554825891.149 [PitchRateCalculator] Loaded
2019-04-09T16:04:51.150Z,1554825891.150 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-04-09T16:04:51.167Z,1554825891.167 [SpeedCalculator] Loaded
2019-04-09T16:04:51.167Z,1554825891.167 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-04-09T16:04:51.283Z,1554825891.283 [TempGradientCalculator] Loaded
2019-04-09T16:04:51.283Z,1554825891.283 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-04-09T16:04:51.325Z,1554825891.325 [YawRateCalculator] Loaded
2019-04-09T16:04:51.326Z,1554825891.326 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-04-09T16:04:51.483Z,1554825891.483 [ElevatorOffsetCalculator] Loaded
2019-04-09T16:04:51.483Z,1554825891.483 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-04-09T16:04:51.484Z,1554825891.484 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-04-09T16:04:51.484Z,1554825891.484 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-04-09T16:04:51.611Z,1554825891.611 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-04-09T16:04:51.612Z,1554825891.612 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-04-09T16:04:51.843Z,1554825891.843 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-04-09T16:04:51.844Z,1554825891.844 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-04-09T16:04:51.927Z,1554825891.927 [DeadReckonUsingSpeedCalculator] Loaded
2019-04-09T16:04:51.928Z,1554825891.928 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-04-09T16:04:51.993Z,1554825891.993 [DeadReckonWithRespectToSeafloor] Loaded
2019-04-09T16:04:51.993Z,1554825891.993 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-04-09T16:04:52.016Z,1554825892.016 [NavChart] Loaded
2019-04-09T16:04:52.016Z,1554825892.016 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-04-09T16:04:52.020Z,1554825892.020 [UniversalFixResidualReporter] Loaded
2019-04-09T16:04:52.021Z,1554825892.021 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-04-09T16:04:52.021Z,1554825892.021 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-04-09T16:04:52.022Z,1554825892.022 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-04-09T16:04:52.352Z,1554825892.352 [AcousticModem_Benthos_ATM900] Loaded
2019-04-09T16:04:52.352Z,1554825892.352 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-04-09T16:04:52.427Z,1554825892.427 [DataOverHttps] Loaded
2019-04-09T16:04:52.427Z,1554825892.427 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-04-09T16:04:52.428Z,1554825892.428 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409714E0
2019-04-09T16:04:52.429Z,1554825892.429 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3975
2019-04-09T16:04:52.443Z,1554825892.443 [Depth_Keller] Loaded
2019-04-09T16:04:52.443Z,1554825892.443 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-04-09T16:04:52.543Z,1554825892.543 [NAL9602] Loaded
2019-04-09T16:04:52.543Z,1554825892.543 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-04-09T16:04:52.549Z,1554825892.549 [Onboard] Loaded
2019-04-09T16:04:52.549Z,1554825892.549 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-04-09T16:04:52.552Z,1554825892.552 [Radio_Surface] Loaded
2019-04-09T16:04:52.553Z,1554825892.553 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-04-09T16:04:52.554Z,1554825892.554 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A14E0
2019-04-09T16:04:52.554Z,1554825892.554 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3976
2019-04-09T16:04:53.948Z,1554825893.948 [BPC1] Loaded
2019-04-09T16:04:53.948Z,1554825893.948 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-04-09T16:04:53.981Z,1554825893.981 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minrange
2019-04-09T16:04:53.983Z,1554825893.983 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.maxrange
2019-04-09T16:04:53.985Z,1554825893.985 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.soundspeed
2019-04-09T16:04:53.987Z,1554825893.987 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.automode
2019-04-09T16:04:53.990Z,1554825893.990 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.gainindex
2019-04-09T16:04:53.992Z,1554825893.992 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minconfidence
2019-04-09T16:04:53.994Z,1554825893.994 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.pingenable
2019-04-09T16:04:53.996Z,1554825893.996 [BR_Ping1D] Loaded
2019-04-09T16:04:53.997Z,1554825893.997 [ComponentRegistry](DEBUG): SyncComponent "BR_Ping1D" handled in the control thread.
2019-04-09T16:04:53.997Z,1554825893.997 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-04-09T16:04:53.998Z,1554825893.998 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-04-09T16:04:54.064Z,1554825894.064 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-04-09T16:04:54.065Z,1554825894.065 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-04-09T16:04:54.136Z,1554825894.136 [VerticalControl](DEBUG): Construct VerticalControl.
2019-04-09T16:04:54.227Z,1554825894.227 [VerticalControl] Loaded
2019-04-09T16:04:54.227Z,1554825894.227 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-04-09T16:04:54.228Z,1554825894.228 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-04-09T16:04:54.290Z,1554825894.290 [HorizontalControl] Loaded
2019-04-09T16:04:54.290Z,1554825894.290 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-04-09T16:04:54.291Z,1554825894.291 [SpeedControl](DEBUG): Construct SpeedControl.
2019-04-09T16:04:54.293Z,1554825894.293 [SpeedControl] Loaded
2019-04-09T16:04:54.293Z,1554825894.293 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-04-09T16:04:54.294Z,1554825894.294 [LoopControl](DEBUG): Construct LoopControl.
2019-04-09T16:04:54.294Z,1554825894.294 [LoopControl] Loaded
2019-04-09T16:04:54.295Z,1554825894.295 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-04-09T16:04:54.295Z,1554825894.295 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-04-09T16:04:54.296Z,1554825894.296 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-04-09T16:04:54.309Z,1554825894.309 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-04-09T16:04:54.310Z,1554825894.310 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-04-09T16:04:54.608Z,1554825894.608 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-04-09T16:04:54.611Z,1554825894.611 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-04-09T16:04:54.612Z,1554825894.612 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-04-09T16:04:54.619Z,1554825894.619 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-04-09T16:04:54.620Z,1554825894.620 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACE4E0
2019-04-09T16:04:54.621Z,1554825894.621 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3977
2019-04-09T16:04:54.626Z,1554825894.626 [Supervisor](INFO): Main Thread ID is 4347
2019-04-09T16:04:54.626Z,1554825894.626 [Supervisor](DEBUG): Running supervisor.
2019-04-09T16:04:54.627Z,1554825894.627 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3978
2019-04-09T16:04:54.629Z,1554825894.629 [controlThread ThreadHandler](INFO): Handler Thread ID is 3979
2019-04-09T16:04:54.630Z,1554825894.630 [controlThread](DEBUG): Initializing ControlThread
2019-04-09T16:04:54.631Z,1554825894.631 [SBIT](INFO): Initialize SBIT Component.
2019-04-09T16:04:54.632Z,1554825894.632 [SBIT](IMPORTANT): git: 2019-03-18-31-ge98d5dc
2019-04-09T16:04:54.632Z,1554825894.632 [SBIT](INFO): git hash: e98d5dc50cbc39e402f19b9946ff4dc0776c8725
2019-04-09T16:04:54.632Z,1554825894.632 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-04-09T16:04:54.634Z,1554825894.634 [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:04:54.635Z,1554825894.635 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-04-09T16:04:54.636Z,1554825894.636 [IBIT](INFO): Initialize IBIT Component.
2019-04-09T16:04:54.636Z,1554825894.636 [CBIT](DEBUG): Initialize CBIT Component.
2019-04-09T16:04:54.637Z,1554825894.637 [logger ThreadHandler](INFO): Handler Thread ID is 3980
2019-04-09T16:04:54.650Z,1554825894.650 [CBIT](DEBUG): Initialized mux pins.
2019-04-09T16:04:54.650Z,1554825894.650 [CBIT](DEBUG): Initializing the watchdog timer.
2019-04-09T16:04:54.658Z,1554825894.658 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3981
2019-04-09T16:04:54.659Z,1554825894.659 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T16:04:54.663Z,1554825894.663 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T16:04:54.664Z,1554825894.664 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3983
2019-04-09T16:04:54.665Z,1554825894.665 [WetLabsBB2FL](INFO): Powering down
2019-04-09T16:04:54.674Z,1554825894.674 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-04-09T16:04:54.674Z,1554825894.674 [CBIT](DEBUG): Initializing heartbeat.
2019-04-09T16:04:54.694Z,1554825894.694 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3984
2019-04-09T16:04:54.695Z,1554825894.695 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-04-09T16:04:54.704Z,1554825894.704 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3985
2019-04-09T16:04:54.722Z,1554825894.722 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3986
2019-04-09T16:04:54.725Z,1554825894.725 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-09T16:04:54.726Z,1554825894.726 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-04-09T16:04:54.726Z,1554825894.726 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-09T16:04:54.726Z,1554825894.726 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-04-09T16:04:54.726Z,1554825894.726 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-09T16:04:54.727Z,1554825894.727 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-04-09T16:04:54.727Z,1554825894.727 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-09T16:04:54.727Z,1554825894.727 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-04-09T16:04:54.727Z,1554825894.727 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-09T16:04:54.727Z,1554825894.727 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-04-09T16:04:54.728Z,1554825894.728 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-09T16:04:54.728Z,1554825894.728 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-04-09T16:04:54.728Z,1554825894.728 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-09T16:04:54.728Z,1554825894.728 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-04-09T16:04:54.728Z,1554825894.728 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-09T16:04:54.728Z,1554825894.728 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-04-09T16:04:54.746Z,1554825894.746 [CBIT](DEBUG): Deactivating GF circuits.
2019-04-09T16:04:54.746Z,1554825894.746 [CBIT](DEBUG): Deactivating emergency mode.
2019-04-09T16:04:54.782Z,1554825894.782 [CBIT](DEBUG): Backplane powered.
2019-04-09T16:04:54.786Z,1554825894.786 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-04-09T16:04:54.787Z,1554825894.787 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-04-09T16:04:54.787Z,1554825894.787 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-04-09T16:04:54.787Z,1554825894.787 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-04-09T16:04:54.788Z,1554825894.788 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-04-09T16:04:54.788Z,1554825894.788 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-04-09T16:04:54.789Z,1554825894.789 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T16:04:54.790Z,1554825894.790 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-04-09T16:04:54.791Z,1554825894.791 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-04-09T16:04:54.795Z,1554825894.795 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-04-09T16:04:54.797Z,1554825894.797 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-04-09T16:04:54.798Z,1554825894.798 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-04-09T16:04:54.798Z,1554825894.798 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-04-09T16:04:54.799Z,1554825894.799 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-04-09T16:04:54.821Z,1554825894.821 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T16:04:54.857Z,1554825894.857 [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:04:54.858Z,1554825894.858 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-04-09T16:04:54.951Z,1554825894.951 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-04-09T16:04:54.953Z,1554825894.953 [Default:A.Wait](DEBUG): Construct Wait.
2019-04-09T16:04:54.954Z,1554825894.954 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T16:04:54.978Z,1554825894.978 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-04-09T16:04:54.996Z,1554825894.996 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-04-09T16:04:55.026Z,1554825895.026 [Default:E.Execute](DEBUG): Construct Execute.
2019-04-09T16:04:55.029Z,1554825895.029 [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:04:55.050Z,1554825895.050 [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:04:55.075Z,1554825895.075 [ESPComponent](INFO): powering down ESP
2019-04-09T16:04:55.106Z,1554825895.106 [Radio_Surface](INFO): Powering up
2019-04-09T16:04:55.503Z,1554825895.503 [PAR_Licor](INFO): A/D timeout, 8 tries over 125 ms

2019-04-09T16:04:55.503Z,1554825895.503 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T16:04:55.504Z,1554825895.504 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:04:55.504Z,1554825895.504 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:04:55.504Z,1554825895.504 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:04:55.724Z,1554825895.724 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:04:55.730Z,1554825895.730 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-04-09T16:04:55.732Z,1554825895.732 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:04:55.738Z,1554825895.738 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-09T16:04:55.739Z,1554825895.739 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:04:55.746Z,1554825895.746 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:04:55.747Z,1554825895.747 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:04:55.754Z,1554825895.754 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-09T16:04:55.755Z,1554825895.755 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:04:55.762Z,1554825895.762 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-04-09T16:04:55.765Z,1554825895.765 [CommandLine](FAULT): Scheduling is paused
2019-04-09T16:04:55.765Z,1554825895.765 [CBIT](INFO): Critical error at 20190409T160453
2019-04-09T16:04:55.765Z,1554825895.765 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-04-09T16:04:55.767Z,1554825895.767 [CBIT](FAULT): Main Battery Failure. Count: 1
2019-04-09T16:04:55.770Z,1554825895.770 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:04:55.770Z,1554825895.770 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T16:04:55.770Z,1554825895.770 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T16:04:56.318Z,1554825896.318 [PAR_Licor](INFO): A/D timeout, 9 tries over 139 ms

2019-04-09T16:04:56.319Z,1554825896.319 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T16:04:56.319Z,1554825896.319 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:04:56.358Z,1554825896.358 [BR_Ping1D](INFO): startup: 0
2019-04-09T16:04:56.358Z,1554825896.358 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T16:04:56.370Z,1554825896.370 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T16:04:56.370Z,1554825896.370 [BR_Ping1D](INFO): 66
2019-04-09T16:04:56.370Z,1554825896.370 [BR_Ping1D](INFO): 82
2019-04-09T16:04:56.370Z,1554825896.370 [BR_Ping1D](INFO): 2
2019-04-09T16:04:56.371Z,1554825896.371 [BR_Ping1D](INFO): 0
2019-04-09T16:04:56.371Z,1554825896.371 [BR_Ping1D](INFO): 236
2019-04-09T16:04:56.371Z,1554825896.371 [BR_Ping1D](INFO): 3
2019-04-09T16:04:56.371Z,1554825896.371 [BR_Ping1D](INFO): 0
2019-04-09T16:04:56.371Z,1554825896.371 [BR_Ping1D](INFO): 0
2019-04-09T16:04:56.371Z,1554825896.371 [BR_Ping1D](INFO): 1
2019-04-09T16:04:56.371Z,1554825896.371 [BR_Ping1D](INFO): 30
2019-04-09T16:04:56.371Z,1554825896.371 [BR_Ping1D](INFO): 164
2019-04-09T16:04:56.371Z,1554825896.371 [BR_Ping1D](INFO): 1
2019-04-09T16:04:56.372Z,1554825896.372 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T16:04:56.372Z,1554825896.372 [BR_Ping1D](INFO): 66
2019-04-09T16:04:56.372Z,1554825896.372 [BR_Ping1D](INFO): 82
2019-04-09T16:04:56.372Z,1554825896.372 [BR_Ping1D](INFO): 1
2019-04-09T16:04:56.372Z,1554825896.372 [BR_Ping1D](INFO): 0
2019-04-09T16:04:56.372Z,1554825896.372 [BR_Ping1D](INFO): 235
2019-04-09T16:04:56.372Z,1554825896.372 [BR_Ping1D](INFO): 3
2019-04-09T16:04:56.372Z,1554825896.372 [BR_Ping1D](INFO): 0
2019-04-09T16:04:56.373Z,1554825896.373 [BR_Ping1D](INFO): 0
2019-04-09T16:04:56.373Z,1554825896.373 [BR_Ping1D](INFO): 132
2019-04-09T16:04:56.373Z,1554825896.373 [BR_Ping1D](INFO): 1
2019-04-09T16:04:56.373Z,1554825896.373 [BR_Ping1D](INFO): 21
2019-04-09T16:04:56.373Z,1554825896.373 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T16:04:56.373Z,1554825896.373 [BR_Ping1D](INFO): 66
2019-04-09T16:04:56.373Z,1554825896.373 [BR_Ping1D](INFO): 82
2019-04-09T16:04:56.373Z,1554825896.373 [BR_Ping1D](INFO): 4
2019-04-09T16:04:56.374Z,1554825896.374 [BR_Ping1D](INFO): 0
2019-04-09T16:04:56.382Z,1554825896.382 [BR_Ping1D](INFO): 234
2019-04-09T16:04:56.382Z,1554825896.382 [BR_Ping1D](INFO): 3
2019-04-09T16:04:56.382Z,1554825896.382 [BR_Ping1D](INFO): 0
2019-04-09T16:04:56.382Z,1554825896.382 [BR_Ping1D](INFO): 0
2019-04-09T16:04:56.382Z,1554825896.382 [BR_Ping1D](INFO): 96
2019-04-09T16:04:56.382Z,1554825896.382 [BR_Ping1D](INFO): 227
2019-04-09T16:04:56.382Z,1554825896.382 [BR_Ping1D](INFO): 22
2019-04-09T16:04:56.382Z,1554825896.382 [BR_Ping1D](INFO): 0
2019-04-09T16:04:56.383Z,1554825896.383 [BR_Ping1D](INFO): 222
2019-04-09T16:04:56.383Z,1554825896.383 [BR_Ping1D](INFO): 2
2019-04-09T16:04:56.383Z,1554825896.383 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T16:04:56.383Z,1554825896.383 [BR_Ping1D](INFO): 66
2019-04-09T16:04:56.383Z,1554825896.383 [BR_Ping1D](INFO): 82
2019-04-09T16:04:56.383Z,1554825896.383 [BR_Ping1D](INFO): 1
2019-04-09T16:04:56.383Z,1554825896.383 [BR_Ping1D](INFO): 0
2019-04-09T16:04:56.383Z,1554825896.383 [BR_Ping1D](INFO): 238
2019-04-09T16:04:56.384Z,1554825896.384 [BR_Ping1D](INFO): 3
2019-04-09T16:04:56.384Z,1554825896.384 [BR_Ping1D](INFO): 0
2019-04-09T16:04:56.384Z,1554825896.384 [BR_Ping1D](INFO): 0
2019-04-09T16:04:56.384Z,1554825896.384 [BR_Ping1D](INFO): 135
2019-04-09T16:04:56.384Z,1554825896.384 [BR_Ping1D](INFO): 1
2019-04-09T16:04:56.384Z,1554825896.384 [BR_Ping1D](INFO): 21
2019-04-09T16:04:56.725Z,1554825896.725 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:04:56.726Z,1554825896.726 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T16:04:56.726Z,1554825896.726 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T16:04:56.757Z,1554825896.757 [BR_Ping1D](INFO): Starting
2019-04-09T16:04:57.157Z,1554825897.157 [BR_Ping1D](INFO): Starting
2019-04-09T16:04:57.157Z,1554825897.157 [BR_Ping1D](INFO): readConfig
2019-04-09T16:04:57.157Z,1554825897.157 [BR_Ping1D](INFO): startup: 1
2019-04-09T16:04:57.158Z,1554825897.158 [BR_Ping1D](INFO): UART requesting status
2019-04-09T16:04:57.158Z,1554825897.158 [BR_Ping1D](INFO): 66
2019-04-09T16:04:57.158Z,1554825897.158 [BR_Ping1D](INFO): 82
2019-04-09T16:04:57.158Z,1554825897.158 [BR_Ping1D](INFO): 0
2019-04-09T16:04:57.158Z,1554825897.158 [BR_Ping1D](INFO): 0
2019-04-09T16:04:57.158Z,1554825897.158 [BR_Ping1D](INFO): 186
2019-04-09T16:04:57.158Z,1554825897.158 [BR_Ping1D](INFO): 4
2019-04-09T16:04:57.158Z,1554825897.158 [BR_Ping1D](INFO): 0
2019-04-09T16:04:57.159Z,1554825897.159 [BR_Ping1D](INFO): 0
2019-04-09T16:04:57.159Z,1554825897.159 [BR_Ping1D](INFO): 82
2019-04-09T16:04:57.159Z,1554825897.159 [BR_Ping1D](INFO): 1
2019-04-09T16:04:57.166Z,1554825897.166 [BR_Ping1D](INFO): 66
2019-04-09T16:04:57.166Z,1554825897.166 [BR_Ping1D](INFO): 82
2019-04-09T16:04:57.166Z,1554825897.166 [BR_Ping1D](INFO): 0
2019-04-09T16:04:57.166Z,1554825897.166 [BR_Ping1D](INFO): 0
2019-04-09T16:04:57.166Z,1554825897.166 [BR_Ping1D](INFO): 191
2019-04-09T16:04:57.166Z,1554825897.166 [BR_Ping1D](INFO): 4
2019-04-09T16:04:57.166Z,1554825897.166 [BR_Ping1D](INFO): 0
2019-04-09T16:04:57.167Z,1554825897.167 [BR_Ping1D](INFO): 0
2019-04-09T16:04:57.167Z,1554825897.167 [BR_Ping1D](INFO): 87
2019-04-09T16:04:57.167Z,1554825897.167 [BR_Ping1D](INFO): 1
2019-04-09T16:04:57.174Z,1554825897.174 [BR_Ping1D](INFO): 66
2019-04-09T16:04:57.174Z,1554825897.174 [BR_Ping1D](INFO): 82
2019-04-09T16:04:57.174Z,1554825897.174 [BR_Ping1D](INFO): 0
2019-04-09T16:04:57.174Z,1554825897.174 [BR_Ping1D](INFO): 0
2019-04-09T16:04:57.174Z,1554825897.174 [BR_Ping1D](INFO): 179
2019-04-09T16:04:57.174Z,1554825897.174 [BR_Ping1D](INFO): 4
2019-04-09T16:04:57.174Z,1554825897.174 [BR_Ping1D](INFO): 0
2019-04-09T16:04:57.175Z,1554825897.175 [BR_Ping1D](INFO): 0
2019-04-09T16:04:57.175Z,1554825897.175 [BR_Ping1D](INFO): 75
2019-04-09T16:04:57.175Z,1554825897.175 [BR_Ping1D](INFO): 1
2019-04-09T16:04:57.678Z,1554825897.678 [PAR_Licor](INFO): A/D timeout, 9 tries over 138 ms

2019-04-09T16:04:57.678Z,1554825897.678 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T16:04:57.678Z,1554825897.678 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:04:57.699Z,1554825897.699 [BR_Ping1D](INFO): Starting
2019-04-09T16:04:57.700Z,1554825897.700 [BR_Ping1D](INFO): readConfig
2019-04-09T16:04:57.700Z,1554825897.700 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:04:57.700Z,1554825897.700 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:04:57.700Z,1554825897.700 [BR_Ping1D](INFO): elapsed 1.3424
2019-04-09T16:04:57.700Z,1554825897.700 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:04:57.701Z,1554825897.701 [BR_Ping1D](INFO): No UART data
2019-04-09T16:04:57.701Z,1554825897.701 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:04:57.701Z,1554825897.701 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:04:57.734Z,1554825897.734 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:04:57.734Z,1554825897.734 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T16:04:57.734Z,1554825897.734 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T16:04:57.965Z,1554825897.965 [BR_Ping1D](INFO): Starting
2019-04-09T16:04:57.965Z,1554825897.965 [BR_Ping1D](INFO): readConfig
2019-04-09T16:04:57.965Z,1554825897.965 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:04:57.966Z,1554825897.966 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:04:57.966Z,1554825897.966 [BR_Ping1D](INFO): elapsed 1.6079
2019-04-09T16:04:57.966Z,1554825897.966 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:04:57.966Z,1554825897.966 [BR_Ping1D](INFO): No UART data
2019-04-09T16:04:57.966Z,1554825897.966 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:04:57.967Z,1554825897.967 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:04:58.326Z,1554825898.326 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T16:04:58.326Z,1554825898.326 [MassServo] Communications Fault, FailCount= 1
2019-04-09T16:04:58.326Z,1554825898.326 [MassServo](ERROR): Communications Fault
2019-04-09T16:04:58.377Z,1554825898.377 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T16:04:58.428Z,1554825898.428 [BR_Ping1D](INFO): Starting
2019-04-09T16:04:58.428Z,1554825898.428 [BR_Ping1D](INFO): readConfig
2019-04-09T16:04:58.428Z,1554825898.428 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:04:58.428Z,1554825898.428 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:04:58.428Z,1554825898.428 [BR_Ping1D](INFO): elapsed 2.0705
2019-04-09T16:04:58.429Z,1554825898.429 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:04:58.429Z,1554825898.429 [BR_Ping1D](INFO): No UART data
2019-04-09T16:04:58.429Z,1554825898.429 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:04:58.429Z,1554825898.429 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:04:58.510Z,1554825898.510 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:04:58.510Z,1554825898.510 [MassServo](INFO): Powering down
2019-04-09T16:04:58.922Z,1554825898.922 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-09T16:04:58.922Z,1554825898.922 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T16:04:58.922Z,1554825898.922 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:04:58.976Z,1554825898.976 [BR_Ping1D](INFO): Starting
2019-04-09T16:04:58.976Z,1554825898.976 [BR_Ping1D](INFO): readConfig
2019-04-09T16:04:58.976Z,1554825898.976 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:04:58.976Z,1554825898.976 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:04:58.977Z,1554825898.977 [BR_Ping1D](INFO): elapsed 2.6187
2019-04-09T16:04:58.977Z,1554825898.977 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:04:58.977Z,1554825898.977 [BR_Ping1D](INFO): No UART data
2019-04-09T16:04:58.977Z,1554825898.977 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:04:58.977Z,1554825898.977 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:04:59.102Z,1554825899.102 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:04:59.102Z,1554825899.102 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T16:04:59.102Z,1554825899.102 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T16:04:59.215Z,1554825899.215 [BR_Ping1D](INFO): Starting
2019-04-09T16:04:59.215Z,1554825899.215 [BR_Ping1D](INFO): readConfig
2019-04-09T16:04:59.215Z,1554825899.215 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:04:59.215Z,1554825899.215 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:04:59.216Z,1554825899.216 [BR_Ping1D](INFO): elapsed 2.8579
2019-04-09T16:04:59.216Z,1554825899.216 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:04:59.216Z,1554825899.216 [BR_Ping1D](INFO): No UART data
2019-04-09T16:04:59.216Z,1554825899.216 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:04:59.216Z,1554825899.216 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:04:59.304Z,1554825899.304 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:04:59.423Z,1554825899.423 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:04:59.722Z,1554825899.722 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T16:04:59.722Z,1554825899.722 [MassServo] No Fault, FailCount= 1
2019-04-09T16:04:59.874Z,1554825899.874 [PAR_Licor](INFO): A/D timeout, 7 tries over 145 ms

2019-04-09T16:04:59.874Z,1554825899.874 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T16:04:59.874Z,1554825899.874 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:04:59.921Z,1554825899.921 [BR_Ping1D](INFO): Starting
2019-04-09T16:04:59.921Z,1554825899.921 [BR_Ping1D](INFO): readConfig
2019-04-09T16:04:59.921Z,1554825899.921 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:04:59.921Z,1554825899.921 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:04:59.922Z,1554825899.922 [BR_Ping1D](INFO): elapsed 3.5638
2019-04-09T16:04:59.922Z,1554825899.922 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:04:59.922Z,1554825899.922 [BR_Ping1D](INFO): No UART data
2019-04-09T16:04:59.922Z,1554825899.922 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:04:59.922Z,1554825899.922 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:04:59.976Z,1554825899.976 [CBIT](FAULT): Main Battery Failure. Count: 11
2019-04-09T16:04:59.979Z,1554825899.979 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:04:59.979Z,1554825899.979 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T16:04:59.979Z,1554825899.979 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T16:05:00.154Z,1554825900.154 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:00.154Z,1554825900.154 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:00.154Z,1554825900.154 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:00.154Z,1554825900.154 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:00.155Z,1554825900.155 [BR_Ping1D](INFO): elapsed 3.7969
2019-04-09T16:05:00.155Z,1554825900.155 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:00.155Z,1554825900.155 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:00.155Z,1554825900.155 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:00.155Z,1554825900.155 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:00.670Z,1554825900.670 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-09T16:05:00.670Z,1554825900.670 [PAR_Licor] Data Fault, FailCount= 3
2019-04-09T16:05:00.670Z,1554825900.670 [PAR_Licor](ERROR): Data Fault
2019-04-09T16:05:00.717Z,1554825900.717 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:00.717Z,1554825900.717 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:00.717Z,1554825900.717 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:00.717Z,1554825900.717 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:00.719Z,1554825900.719 [BR_Ping1D](INFO): elapsed 4.3598
2019-04-09T16:05:00.719Z,1554825900.719 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:00.719Z,1554825900.719 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:00.719Z,1554825900.719 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:00.719Z,1554825900.719 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:00.794Z,1554825900.794 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T16:05:00.795Z,1554825900.795 [CBIT](CRITICAL): Data Fault in component: PAR_Licor
2019-04-09T16:05:00.958Z,1554825900.958 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:00.958Z,1554825900.958 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:00.959Z,1554825900.959 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:00.959Z,1554825900.959 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:00.959Z,1554825900.959 [BR_Ping1D](INFO): elapsed 4.6012
2019-04-09T16:05:00.959Z,1554825900.959 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:00.959Z,1554825900.959 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:00.960Z,1554825900.960 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:00.960Z,1554825900.960 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:00.988Z,1554825900.988 [CBIT](INFO): Critical error at 20190409T160500
2019-04-09T16:05:01.361Z,1554825901.361 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:01.361Z,1554825901.361 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:01.362Z,1554825901.362 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:01.362Z,1554825901.362 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:01.362Z,1554825901.362 [BR_Ping1D](INFO): elapsed 5.0042
2019-04-09T16:05:01.362Z,1554825901.362 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:01.362Z,1554825901.362 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T16:05:01.362Z,1554825901.362 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-09T16:05:01.363Z,1554825901.363 [BR_Ping1D](ERROR): Data Fault
2019-04-09T16:05:01.395Z,1554825901.395 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T16:05:01.766Z,1554825901.766 [BR_Ping1D](INFO): Stop
2019-04-09T16:05:01.766Z,1554825901.766 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:05:01.766Z,1554825901.766 [BR_Ping1D](INFO): Powering down
2019-04-09T16:05:02.418Z,1554825902.418 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T16:05:02.418Z,1554825902.418 [MassServo] Communications Fault, FailCount= 2
2019-04-09T16:05:02.418Z,1554825902.418 [MassServo](ERROR): Communications Fault
2019-04-09T16:05:02.422Z,1554825902.422 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T16:05:02.446Z,1554825902.446 [BR_Ping1D](INFO): Stopping
2019-04-09T16:05:02.491Z,1554825902.491 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:05:02.491Z,1554825902.491 [MassServo](INFO): Powering down
2019-04-09T16:05:02.850Z,1554825902.850 [BR_Ping1D](INFO): Stopped
2019-04-09T16:05:02.887Z,1554825902.887 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T16:05:02.887Z,1554825902.887 [BR_Ping1D] No Fault, FailCount= 1
2019-04-09T16:05:03.266Z,1554825903.266 [BR_Ping1D](INFO): Start
2019-04-09T16:05:03.303Z,1554825903.303 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:05:03.423Z,1554825903.423 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:05:03.427Z,1554825903.427 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T16:05:03.427Z,1554825903.427 [MassServo] No Fault, FailCount= 2
2019-04-09T16:05:03.658Z,1554825903.658 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:04.061Z,1554825904.061 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:04.061Z,1554825904.061 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:04.062Z,1554825904.062 [BR_Ping1D](INFO): startup: 0
2019-04-09T16:05:04.062Z,1554825904.062 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T16:05:04.074Z,1554825904.074 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T16:05:04.074Z,1554825904.074 [BR_Ping1D](INFO): 66
2019-04-09T16:05:04.074Z,1554825904.074 [BR_Ping1D](INFO): 82
2019-04-09T16:05:04.074Z,1554825904.074 [BR_Ping1D](INFO): 2
2019-04-09T16:05:04.074Z,1554825904.074 [BR_Ping1D](INFO): 0
2019-04-09T16:05:04.074Z,1554825904.074 [BR_Ping1D](INFO): 236
2019-04-09T16:05:04.075Z,1554825904.075 [BR_Ping1D](INFO): 3
2019-04-09T16:05:04.075Z,1554825904.075 [BR_Ping1D](INFO): 0
2019-04-09T16:05:04.075Z,1554825904.075 [BR_Ping1D](INFO): 0
2019-04-09T16:05:04.075Z,1554825904.075 [BR_Ping1D](INFO): 1
2019-04-09T16:05:04.075Z,1554825904.075 [BR_Ping1D](INFO): 30
2019-04-09T16:05:04.075Z,1554825904.075 [BR_Ping1D](INFO): 164
2019-04-09T16:05:04.075Z,1554825904.075 [BR_Ping1D](INFO): 1
2019-04-09T16:05:04.075Z,1554825904.075 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T16:05:04.076Z,1554825904.076 [BR_Ping1D](INFO): 66
2019-04-09T16:05:04.076Z,1554825904.076 [BR_Ping1D](INFO): 82
2019-04-09T16:05:04.076Z,1554825904.076 [BR_Ping1D](INFO): 1
2019-04-09T16:05:04.076Z,1554825904.076 [BR_Ping1D](INFO): 0
2019-04-09T16:05:04.076Z,1554825904.076 [BR_Ping1D](INFO): 235
2019-04-09T16:05:04.076Z,1554825904.076 [BR_Ping1D](INFO): 3
2019-04-09T16:05:04.076Z,1554825904.076 [BR_Ping1D](INFO): 0
2019-04-09T16:05:04.076Z,1554825904.076 [BR_Ping1D](INFO): 0
2019-04-09T16:05:04.076Z,1554825904.076 [BR_Ping1D](INFO): 132
2019-04-09T16:05:04.076Z,1554825904.076 [BR_Ping1D](INFO): 1
2019-04-09T16:05:04.077Z,1554825904.077 [BR_Ping1D](INFO): 21
2019-04-09T16:05:04.077Z,1554825904.077 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T16:05:04.077Z,1554825904.077 [BR_Ping1D](INFO): 66
2019-04-09T16:05:04.077Z,1554825904.077 [BR_Ping1D](INFO): 82
2019-04-09T16:05:04.077Z,1554825904.077 [BR_Ping1D](INFO): 4
2019-04-09T16:05:04.077Z,1554825904.077 [BR_Ping1D](INFO): 0
2019-04-09T16:05:04.077Z,1554825904.077 [BR_Ping1D](INFO): 234
2019-04-09T16:05:04.077Z,1554825904.077 [BR_Ping1D](INFO): 3
2019-04-09T16:05:04.077Z,1554825904.077 [BR_Ping1D](INFO): 0
2019-04-09T16:05:04.078Z,1554825904.078 [BR_Ping1D](INFO): 0
2019-04-09T16:05:04.078Z,1554825904.078 [BR_Ping1D](INFO): 96
2019-04-09T16:05:04.079Z,1554825904.079 [BR_Ping1D](INFO): 227
2019-04-09T16:05:04.079Z,1554825904.079 [BR_Ping1D](INFO): 22
2019-04-09T16:05:04.080Z,1554825904.080 [BR_Ping1D](INFO): 0
2019-04-09T16:05:04.080Z,1554825904.080 [BR_Ping1D](INFO): 222
2019-04-09T16:05:04.081Z,1554825904.081 [BR_Ping1D](INFO): 2
2019-04-09T16:05:04.090Z,1554825904.090 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T16:05:04.090Z,1554825904.090 [BR_Ping1D](INFO): 66
2019-04-09T16:05:04.090Z,1554825904.090 [BR_Ping1D](INFO): 82
2019-04-09T16:05:04.090Z,1554825904.090 [BR_Ping1D](INFO): 1
2019-04-09T16:05:04.090Z,1554825904.090 [BR_Ping1D](INFO): 0
2019-04-09T16:05:04.090Z,1554825904.090 [BR_Ping1D](INFO): 238
2019-04-09T16:05:04.090Z,1554825904.090 [BR_Ping1D](INFO): 3
2019-04-09T16:05:04.091Z,1554825904.091 [BR_Ping1D](INFO): 0
2019-04-09T16:05:04.091Z,1554825904.091 [BR_Ping1D](INFO): 0
2019-04-09T16:05:04.091Z,1554825904.091 [BR_Ping1D](INFO): 135
2019-04-09T16:05:04.091Z,1554825904.091 [BR_Ping1D](INFO): 1
2019-04-09T16:05:04.091Z,1554825904.091 [BR_Ping1D](INFO): 21
2019-04-09T16:05:04.120Z,1554825904.120 [CBIT](FAULT): Main Battery Failure. Count: 21
2019-04-09T16:05:04.466Z,1554825904.466 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:04.870Z,1554825904.870 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:04.870Z,1554825904.870 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:04.870Z,1554825904.870 [BR_Ping1D](INFO): startup: 1
2019-04-09T16:05:04.870Z,1554825904.870 [BR_Ping1D](INFO): UART requesting status
2019-04-09T16:05:04.871Z,1554825904.871 [BR_Ping1D](INFO): 66
2019-04-09T16:05:04.871Z,1554825904.871 [BR_Ping1D](INFO): 82
2019-04-09T16:05:04.871Z,1554825904.871 [BR_Ping1D](INFO): 0
2019-04-09T16:05:04.871Z,1554825904.871 [BR_Ping1D](INFO): 0
2019-04-09T16:05:04.871Z,1554825904.871 [BR_Ping1D](INFO): 186
2019-04-09T16:05:04.871Z,1554825904.871 [BR_Ping1D](INFO): 4
2019-04-09T16:05:04.871Z,1554825904.871 [BR_Ping1D](INFO): 0
2019-04-09T16:05:04.871Z,1554825904.871 [BR_Ping1D](INFO): 0
2019-04-09T16:05:04.871Z,1554825904.871 [BR_Ping1D](INFO): 82
2019-04-09T16:05:04.872Z,1554825904.872 [BR_Ping1D](INFO): 1
2019-04-09T16:05:04.878Z,1554825904.878 [BR_Ping1D](INFO): 66
2019-04-09T16:05:04.878Z,1554825904.878 [BR_Ping1D](INFO): 82
2019-04-09T16:05:04.878Z,1554825904.878 [BR_Ping1D](INFO): 0
2019-04-09T16:05:04.878Z,1554825904.878 [BR_Ping1D](INFO): 0
2019-04-09T16:05:04.878Z,1554825904.878 [BR_Ping1D](INFO): 191
2019-04-09T16:05:04.878Z,1554825904.878 [BR_Ping1D](INFO): 4
2019-04-09T16:05:04.878Z,1554825904.878 [BR_Ping1D](INFO): 0
2019-04-09T16:05:04.879Z,1554825904.879 [BR_Ping1D](INFO): 0
2019-04-09T16:05:04.879Z,1554825904.879 [BR_Ping1D](INFO): 87
2019-04-09T16:05:04.879Z,1554825904.879 [BR_Ping1D](INFO): 1
2019-04-09T16:05:04.886Z,1554825904.886 [BR_Ping1D](INFO): 66
2019-04-09T16:05:04.886Z,1554825904.886 [BR_Ping1D](INFO): 82
2019-04-09T16:05:04.886Z,1554825904.886 [BR_Ping1D](INFO): 0
2019-04-09T16:05:04.886Z,1554825904.886 [BR_Ping1D](INFO): 0
2019-04-09T16:05:04.886Z,1554825904.886 [BR_Ping1D](INFO): 179
2019-04-09T16:05:04.886Z,1554825904.886 [BR_Ping1D](INFO): 4
2019-04-09T16:05:04.886Z,1554825904.886 [BR_Ping1D](INFO): 0
2019-04-09T16:05:04.887Z,1554825904.887 [BR_Ping1D](INFO): 0
2019-04-09T16:05:04.887Z,1554825904.887 [BR_Ping1D](INFO): 75
2019-04-09T16:05:04.887Z,1554825904.887 [BR_Ping1D](INFO): 1
2019-04-09T16:05:04.924Z,1554825904.924 [CBIT](CRITICAL): Environmental Failure. Press:14.873158 PSI. Humidity:35%. Temp:26 C. ABORTING MISSION
2019-04-09T16:05:05.255Z,1554825905.255 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T16:05:05.255Z,1554825905.255 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T16:05:05.255Z,1554825905.255 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T16:05:05.274Z,1554825905.274 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:05.274Z,1554825905.274 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:05.274Z,1554825905.274 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:05.274Z,1554825905.274 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:05.274Z,1554825905.274 [BR_Ping1D](INFO): elapsed 1.2123
2019-04-09T16:05:05.275Z,1554825905.275 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:05.275Z,1554825905.275 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:05.275Z,1554825905.275 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:05.275Z,1554825905.275 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:05.305Z,1554825905.305 [CBIT](INFO): Critical error at 20190409T160504
2019-04-09T16:05:05.307Z,1554825905.307 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T16:05:05.778Z,1554825905.778 [Aanderaa_O2](INFO): Powering down
2019-04-09T16:05:05.873Z,1554825905.873 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:05.873Z,1554825905.873 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:05.873Z,1554825905.873 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:05.873Z,1554825905.873 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:05.874Z,1554825905.874 [BR_Ping1D](INFO): elapsed 1.8116
2019-04-09T16:05:05.874Z,1554825905.874 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:05.874Z,1554825905.874 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:05.874Z,1554825905.874 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:05.874Z,1554825905.874 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:06.230Z,1554825906.230 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T16:05:06.230Z,1554825906.230 [MassServo] Communications Fault, FailCount= 3
2019-04-09T16:05:06.230Z,1554825906.230 [MassServo](ERROR): Communications Fault
2019-04-09T16:05:06.235Z,1554825906.234 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T16:05:06.263Z,1554825906.263 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:06.263Z,1554825906.263 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:06.264Z,1554825906.264 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:06.264Z,1554825906.264 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:06.264Z,1554825906.264 [BR_Ping1D](INFO): elapsed 2.2019
2019-04-09T16:05:06.264Z,1554825906.264 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:06.264Z,1554825906.264 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:06.265Z,1554825906.265 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:06.265Z,1554825906.265 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:06.275Z,1554825906.275 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:05:06.275Z,1554825906.275 [MassServo](INFO): Powering down
2019-04-09T16:05:06.661Z,1554825906.661 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:06.661Z,1554825906.661 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:06.662Z,1554825906.662 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:06.662Z,1554825906.662 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:06.662Z,1554825906.662 [BR_Ping1D](INFO): elapsed 2.5999
2019-04-09T16:05:06.662Z,1554825906.662 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:06.662Z,1554825906.662 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:06.663Z,1554825906.663 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:06.663Z,1554825906.663 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:06.695Z,1554825906.695 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T16:05:06.695Z,1554825906.695 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-09T16:05:07.066Z,1554825907.066 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:07.066Z,1554825907.066 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:07.066Z,1554825907.066 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:07.066Z,1554825907.066 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:07.067Z,1554825907.067 [BR_Ping1D](INFO): elapsed 3.0046
2019-04-09T16:05:07.067Z,1554825907.067 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:07.067Z,1554825907.067 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:07.067Z,1554825907.067 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:07.067Z,1554825907.067 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:07.079Z,1554825907.079 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:05:07.199Z,1554825907.199 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:05:07.203Z,1554825907.203 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T16:05:07.203Z,1554825907.203 [MassServo] No Fault, FailCount= 3
2019-04-09T16:05:07.481Z,1554825907.481 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:07.481Z,1554825907.481 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:07.482Z,1554825907.482 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:07.482Z,1554825907.482 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:07.482Z,1554825907.482 [BR_Ping1D](INFO): elapsed 3.4199
2019-04-09T16:05:07.482Z,1554825907.482 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:07.482Z,1554825907.482 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:07.483Z,1554825907.483 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:07.483Z,1554825907.483 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:07.710Z,1554825907.710 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T16:05:07.710Z,1554825907.710 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-09T16:05:07.710Z,1554825907.710 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T16:05:07.874Z,1554825907.874 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:07.874Z,1554825907.874 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:07.874Z,1554825907.874 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:07.874Z,1554825907.874 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:07.874Z,1554825907.874 [BR_Ping1D](INFO): elapsed 3.8123
2019-04-09T16:05:07.875Z,1554825907.875 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:07.875Z,1554825907.875 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:07.875Z,1554825907.875 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:07.875Z,1554825907.875 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:07.891Z,1554825907.891 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T16:05:08.114Z,1554825908.114 [CTD_Seabird](INFO): Powering down
2019-04-09T16:05:08.279Z,1554825908.279 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:08.279Z,1554825908.279 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:08.279Z,1554825908.279 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:08.279Z,1554825908.279 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:08.280Z,1554825908.280 [BR_Ping1D](INFO): elapsed 4.2175
2019-04-09T16:05:08.280Z,1554825908.280 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:08.280Z,1554825908.280 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:08.280Z,1554825908.280 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:08.280Z,1554825908.280 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:08.318Z,1554825908.318 [CBIT](FAULT): Main Battery Failure. Count: 31
2019-04-09T16:05:08.682Z,1554825908.682 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:08.682Z,1554825908.682 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:08.683Z,1554825908.683 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:08.683Z,1554825908.683 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:08.683Z,1554825908.683 [BR_Ping1D](INFO): elapsed 4.6208
2019-04-09T16:05:08.683Z,1554825908.683 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:08.683Z,1554825908.683 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:08.684Z,1554825908.684 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:08.684Z,1554825908.684 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:09.085Z,1554825909.085 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:09.085Z,1554825909.085 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:09.085Z,1554825909.085 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:09.086Z,1554825909.086 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:09.086Z,1554825909.086 [BR_Ping1D](INFO): elapsed 5.0243
2019-04-09T16:05:09.087Z,1554825909.087 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:09.087Z,1554825909.087 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T16:05:09.087Z,1554825909.087 [BR_Ping1D] Data Fault, FailCount= 2
2019-04-09T16:05:09.087Z,1554825909.087 [BR_Ping1D](ERROR): Data Fault
2019-04-09T16:05:09.103Z,1554825909.103 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T16:05:09.103Z,1554825909.103 [CTD_Seabird] No Fault, FailCount= 1
2019-04-09T16:05:09.103Z,1554825909.103 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T16:05:09.490Z,1554825909.490 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T16:05:09.492Z,1554825909.492 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T16:05:09.490Z,1554825909.490 [BR_Ping1D](INFO): Stop
2019-04-09T16:05:09.493Z,1554825909.493 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:05:09.493Z,1554825909.493 [BR_Ping1D](INFO): Powering down
2019-04-09T16:05:09.886Z,1554825909.886 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T16:05:09.886Z,1554825909.886 [MassServo] Communications Fault, FailCount= 4
2019-04-09T16:05:09.886Z,1554825909.886 [MassServo](ERROR): Communications Fault
2019-04-09T16:05:09.890Z,1554825909.890 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T16:05:09.925Z,1554825909.925 [BR_Ping1D](INFO): Stopping
2019-04-09T16:05:09.951Z,1554825909.951 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:05:09.951Z,1554825909.951 [MassServo](INFO): Powering down
2019-04-09T16:05:10.318Z,1554825910.318 [BR_Ping1D](INFO): Stopping
2019-04-09T16:05:10.721Z,1554825910.721 [BR_Ping1D](INFO): Stopped
2019-04-09T16:05:10.762Z,1554825910.762 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:05:10.883Z,1554825910.883 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:05:10.887Z,1554825910.887 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T16:05:10.888Z,1554825910.888 [MassServo] No Fault, FailCount= 4
2019-04-09T16:05:10.888Z,1554825910.888 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T16:05:10.888Z,1554825910.888 [BR_Ping1D] No Fault, FailCount= 2
2019-04-09T16:05:11.126Z,1554825911.126 [BR_Ping1D](INFO): Start
2019-04-09T16:05:11.529Z,1554825911.529 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:11.934Z,1554825911.934 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:11.934Z,1554825911.934 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:11.935Z,1554825911.935 [BR_Ping1D](INFO): startup: 0
2019-04-09T16:05:11.935Z,1554825911.935 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T16:05:11.946Z,1554825911.946 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T16:05:11.946Z,1554825911.946 [BR_Ping1D](INFO): 66
2019-04-09T16:05:11.946Z,1554825911.946 [BR_Ping1D](INFO): 82
2019-04-09T16:05:11.946Z,1554825911.946 [BR_Ping1D](INFO): 2
2019-04-09T16:05:11.946Z,1554825911.946 [BR_Ping1D](INFO): 0
2019-04-09T16:05:11.947Z,1554825911.947 [BR_Ping1D](INFO): 236
2019-04-09T16:05:11.947Z,1554825911.947 [BR_Ping1D](INFO): 3
2019-04-09T16:05:11.947Z,1554825911.947 [BR_Ping1D](INFO): 0
2019-04-09T16:05:11.947Z,1554825911.947 [BR_Ping1D](INFO): 0
2019-04-09T16:05:11.947Z,1554825911.947 [BR_Ping1D](INFO): 1
2019-04-09T16:05:11.947Z,1554825911.947 [BR_Ping1D](INFO): 30
2019-04-09T16:05:11.947Z,1554825911.947 [BR_Ping1D](INFO): 164
2019-04-09T16:05:11.947Z,1554825911.947 [BR_Ping1D](INFO): 1
2019-04-09T16:05:11.948Z,1554825911.948 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T16:05:11.948Z,1554825911.948 [BR_Ping1D](INFO): 66
2019-04-09T16:05:11.948Z,1554825911.948 [BR_Ping1D](INFO): 82
2019-04-09T16:05:11.948Z,1554825911.948 [BR_Ping1D](INFO): 1
2019-04-09T16:05:11.948Z,1554825911.948 [BR_Ping1D](INFO): 0
2019-04-09T16:05:11.948Z,1554825911.948 [BR_Ping1D](INFO): 235
2019-04-09T16:05:11.948Z,1554825911.948 [BR_Ping1D](INFO): 3
2019-04-09T16:05:11.948Z,1554825911.948 [BR_Ping1D](INFO): 0
2019-04-09T16:05:11.948Z,1554825911.948 [BR_Ping1D](INFO): 0
2019-04-09T16:05:11.948Z,1554825911.948 [BR_Ping1D](INFO): 132
2019-04-09T16:05:11.949Z,1554825911.949 [BR_Ping1D](INFO): 1
2019-04-09T16:05:11.949Z,1554825911.949 [BR_Ping1D](INFO): 21
2019-04-09T16:05:11.949Z,1554825911.949 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T16:05:11.949Z,1554825911.949 [BR_Ping1D](INFO): 66
2019-04-09T16:05:11.949Z,1554825911.949 [BR_Ping1D](INFO): 82
2019-04-09T16:05:11.949Z,1554825911.949 [BR_Ping1D](INFO): 4
2019-04-09T16:05:11.949Z,1554825911.949 [BR_Ping1D](INFO): 0
2019-04-09T16:05:11.950Z,1554825911.950 [BR_Ping1D](INFO): 234
2019-04-09T16:05:11.951Z,1554825911.951 [BR_Ping1D](INFO): 3
2019-04-09T16:05:11.952Z,1554825911.952 [BR_Ping1D](INFO): 0
2019-04-09T16:05:11.953Z,1554825911.953 [BR_Ping1D](INFO): 0
2019-04-09T16:05:11.953Z,1554825911.953 [BR_Ping1D](INFO): 96
2019-04-09T16:05:11.954Z,1554825911.954 [BR_Ping1D](INFO): 227
2019-04-09T16:05:11.954Z,1554825911.954 [BR_Ping1D](INFO): 22
2019-04-09T16:05:11.955Z,1554825911.955 [BR_Ping1D](INFO): 0
2019-04-09T16:05:11.955Z,1554825911.955 [BR_Ping1D](INFO): 222
2019-04-09T16:05:11.956Z,1554825911.956 [BR_Ping1D](INFO): 2
2019-04-09T16:05:11.957Z,1554825911.957 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T16:05:11.973Z,1554825911.973 [BR_Ping1D](INFO): 66
2019-04-09T16:05:11.973Z,1554825911.973 [BR_Ping1D](INFO): 82
2019-04-09T16:05:11.973Z,1554825911.973 [BR_Ping1D](INFO): 1
2019-04-09T16:05:11.973Z,1554825911.973 [BR_Ping1D](INFO): 0
2019-04-09T16:05:11.973Z,1554825911.973 [BR_Ping1D](INFO): 238
2019-04-09T16:05:11.973Z,1554825911.973 [BR_Ping1D](INFO): 3
2019-04-09T16:05:11.973Z,1554825911.973 [BR_Ping1D](INFO): 0
2019-04-09T16:05:11.973Z,1554825911.973 [BR_Ping1D](INFO): 0
2019-04-09T16:05:11.974Z,1554825911.974 [BR_Ping1D](INFO): 135
2019-04-09T16:05:11.974Z,1554825911.974 [BR_Ping1D](INFO): 1
2019-04-09T16:05:11.974Z,1554825911.974 [BR_Ping1D](INFO): 21
2019-04-09T16:05:12.338Z,1554825912.338 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:12.353Z,1554825912.353 [CBIT](FAULT): Main Battery Failure. Count: 41
2019-04-09T16:05:12.742Z,1554825912.742 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:12.742Z,1554825912.742 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:12.742Z,1554825912.742 [BR_Ping1D](INFO): startup: 1
2019-04-09T16:05:12.742Z,1554825912.742 [BR_Ping1D](INFO): UART requesting status
2019-04-09T16:05:12.743Z,1554825912.743 [BR_Ping1D](INFO): 66
2019-04-09T16:05:12.743Z,1554825912.743 [BR_Ping1D](INFO): 82
2019-04-09T16:05:12.743Z,1554825912.743 [BR_Ping1D](INFO): 0
2019-04-09T16:05:12.743Z,1554825912.743 [BR_Ping1D](INFO): 0
2019-04-09T16:05:12.743Z,1554825912.743 [BR_Ping1D](INFO): 186
2019-04-09T16:05:12.743Z,1554825912.743 [BR_Ping1D](INFO): 4
2019-04-09T16:05:12.743Z,1554825912.743 [BR_Ping1D](INFO): 0
2019-04-09T16:05:12.743Z,1554825912.743 [BR_Ping1D](INFO): 0
2019-04-09T16:05:12.743Z,1554825912.743 [BR_Ping1D](INFO): 82
2019-04-09T16:05:12.743Z,1554825912.743 [BR_Ping1D](INFO): 1
2019-04-09T16:05:12.750Z,1554825912.750 [BR_Ping1D](INFO): 66
2019-04-09T16:05:12.750Z,1554825912.750 [BR_Ping1D](INFO): 82
2019-04-09T16:05:12.750Z,1554825912.750 [BR_Ping1D](INFO): 0
2019-04-09T16:05:12.750Z,1554825912.750 [BR_Ping1D](INFO): 0
2019-04-09T16:05:12.750Z,1554825912.750 [BR_Ping1D](INFO): 191
2019-04-09T16:05:12.750Z,1554825912.750 [BR_Ping1D](INFO): 4
2019-04-09T16:05:12.750Z,1554825912.750 [BR_Ping1D](INFO): 0
2019-04-09T16:05:12.751Z,1554825912.751 [BR_Ping1D](INFO): 0
2019-04-09T16:05:12.751Z,1554825912.751 [BR_Ping1D](INFO): 87
2019-04-09T16:05:12.751Z,1554825912.751 [BR_Ping1D](INFO): 1
2019-04-09T16:05:12.758Z,1554825912.758 [BR_Ping1D](INFO): 66
2019-04-09T16:05:12.758Z,1554825912.758 [BR_Ping1D](INFO): 82
2019-04-09T16:05:12.758Z,1554825912.758 [BR_Ping1D](INFO): 0
2019-04-09T16:05:12.758Z,1554825912.758 [BR_Ping1D](INFO): 0
2019-04-09T16:05:12.758Z,1554825912.758 [BR_Ping1D](INFO): 179
2019-04-09T16:05:12.758Z,1554825912.758 [BR_Ping1D](INFO): 4
2019-04-09T16:05:12.758Z,1554825912.758 [BR_Ping1D](INFO): 0
2019-04-09T16:05:12.759Z,1554825912.759 [BR_Ping1D](INFO): 0
2019-04-09T16:05:12.759Z,1554825912.759 [BR_Ping1D](INFO): 75
2019-04-09T16:05:12.759Z,1554825912.759 [BR_Ping1D](INFO): 1
2019-04-09T16:05:13.150Z,1554825913.150 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:13.150Z,1554825913.150 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:13.150Z,1554825913.150 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:13.150Z,1554825913.150 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:13.150Z,1554825913.150 [BR_Ping1D](INFO): elapsed 1.2157
2019-04-09T16:05:13.151Z,1554825913.151 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:13.151Z,1554825913.151 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:13.151Z,1554825913.151 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:13.151Z,1554825913.151 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:13.478Z,1554825913.478 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T16:05:13.478Z,1554825913.478 [MassServo] Communications Fault, FailCount= 5
2019-04-09T16:05:13.478Z,1554825913.478 [MassServo](ERROR): Communications Fault
2019-04-09T16:05:13.482Z,1554825913.482 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T16:05:13.483Z,1554825913.483 [CBIT](CRITICAL): Communications Fault in component: MassServo
2019-04-09T16:05:13.551Z,1554825913.551 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:13.551Z,1554825913.551 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:13.551Z,1554825913.551 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:13.551Z,1554825913.551 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:13.551Z,1554825913.551 [BR_Ping1D](INFO): elapsed 1.6165
2019-04-09T16:05:13.552Z,1554825913.552 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:13.552Z,1554825913.552 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:13.552Z,1554825913.552 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:13.552Z,1554825913.552 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:13.564Z,1554825913.564 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:05:13.564Z,1554825913.564 [MassServo](INFO): Powering down
2019-04-09T16:05:13.566Z,1554825913.566 [CBIT](INFO): Critical error at 20190409T160513
2019-04-09T16:05:13.954Z,1554825913.954 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:13.954Z,1554825913.954 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:13.954Z,1554825913.954 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:13.954Z,1554825913.954 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:13.954Z,1554825913.954 [BR_Ping1D](INFO): elapsed 2.0197
2019-04-09T16:05:13.955Z,1554825913.955 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:13.955Z,1554825913.955 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:13.955Z,1554825913.955 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:13.955Z,1554825913.955 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:14.358Z,1554825914.358 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:14.358Z,1554825914.358 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:14.358Z,1554825914.358 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:14.358Z,1554825914.358 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:14.359Z,1554825914.359 [BR_Ping1D](INFO): elapsed 2.4237
2019-04-09T16:05:14.359Z,1554825914.359 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:14.359Z,1554825914.359 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:14.359Z,1554825914.359 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:14.359Z,1554825914.359 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:14.371Z,1554825914.371 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T16:05:14.492Z,1554825914.492 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T16:05:14.778Z,1554825914.778 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:14.778Z,1554825914.778 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:14.778Z,1554825914.778 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:14.778Z,1554825914.778 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:14.778Z,1554825914.778 [BR_Ping1D](INFO): elapsed 2.8436
2019-04-09T16:05:14.779Z,1554825914.779 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:14.779Z,1554825914.779 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:14.779Z,1554825914.779 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:14.779Z,1554825914.779 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:15.166Z,1554825915.166 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:15.166Z,1554825915.166 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:15.167Z,1554825915.167 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:15.167Z,1554825915.167 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:15.167Z,1554825915.167 [BR_Ping1D](INFO): elapsed 3.2323
2019-04-09T16:05:15.167Z,1554825915.167 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:15.167Z,1554825915.167 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:15.168Z,1554825915.168 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:15.168Z,1554825915.168 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:15.551Z,1554825915.551 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:05:15.551Z,1554825915.551 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-04-09T16:05:15.551Z,1554825915.551 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:05:15.569Z,1554825915.569 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:15.569Z,1554825915.569 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:15.570Z,1554825915.570 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:15.570Z,1554825915.570 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:15.570Z,1554825915.570 [BR_Ping1D](INFO): elapsed 3.6353
2019-04-09T16:05:15.570Z,1554825915.570 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:15.571Z,1554825915.571 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:15.571Z,1554825915.571 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:15.571Z,1554825915.571 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:15.587Z,1554825915.587 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:05:15.955Z,1554825915.955 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:05:16.115Z,1554825916.115 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:16.115Z,1554825916.115 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:16.115Z,1554825916.115 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:16.115Z,1554825916.115 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:16.115Z,1554825916.115 [BR_Ping1D](INFO): elapsed 4.1806
2019-04-09T16:05:16.116Z,1554825916.116 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:16.116Z,1554825916.116 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:16.116Z,1554825916.116 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:16.116Z,1554825916.116 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:16.378Z,1554825916.378 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:16.378Z,1554825916.378 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:16.378Z,1554825916.378 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:16.379Z,1554825916.379 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:16.379Z,1554825916.379 [BR_Ping1D](INFO): elapsed 4.4440
2019-04-09T16:05:16.379Z,1554825916.379 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:16.379Z,1554825916.379 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:16.379Z,1554825916.379 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:16.379Z,1554825916.379 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:16.392Z,1554825916.392 [CBIT](FAULT): Main Battery Failure. Count: 51
2019-04-09T16:05:16.781Z,1554825916.781 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:16.781Z,1554825916.781 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:16.782Z,1554825916.782 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:16.782Z,1554825916.782 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:16.782Z,1554825916.782 [BR_Ping1D](INFO): elapsed 4.8472
2019-04-09T16:05:16.782Z,1554825916.782 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:16.782Z,1554825916.782 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:16.783Z,1554825916.783 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:16.783Z,1554825916.783 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:16.811Z,1554825916.811 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:05:16.811Z,1554825916.811 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-04-09T16:05:17.166Z,1554825917.166 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T16:05:17.166Z,1554825917.166 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-09T16:05:17.167Z,1554825917.167 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T16:05:17.194Z,1554825917.194 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:17.194Z,1554825917.194 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:17.195Z,1554825917.195 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:17.195Z,1554825917.195 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:17.195Z,1554825917.195 [BR_Ping1D](INFO): elapsed 5.2603
2019-04-09T16:05:17.195Z,1554825917.195 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:17.195Z,1554825917.195 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T16:05:17.195Z,1554825917.195 [BR_Ping1D] Data Fault, FailCount= 3
2019-04-09T16:05:17.196Z,1554825917.196 [BR_Ping1D](ERROR): Data Fault
2019-04-09T16:05:17.227Z,1554825917.227 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T16:05:17.227Z,1554825917.227 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T16:05:17.690Z,1554825917.690 [Aanderaa_O2](INFO): Powering down
2019-04-09T16:05:17.710Z,1554825917.710 [BR_Ping1D](INFO): Stop
2019-04-09T16:05:17.710Z,1554825917.710 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:05:17.710Z,1554825917.710 [BR_Ping1D](INFO): Powering down
2019-04-09T16:05:17.994Z,1554825917.994 [BR_Ping1D](INFO): Stopping
2019-04-09T16:05:18.399Z,1554825918.399 [BR_Ping1D](INFO): Stopping
2019-04-09T16:05:18.432Z,1554825918.432 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T16:05:18.432Z,1554825918.432 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-09T16:05:18.807Z,1554825918.807 [BR_Ping1D](INFO): Stopped
2019-04-09T16:05:18.823Z,1554825918.823 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T16:05:18.823Z,1554825918.823 [BR_Ping1D] No Fault, FailCount= 3
2019-04-09T16:05:19.198Z,1554825919.198 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:05:19.199Z,1554825919.199 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:05:19.238Z,1554825919.238 [BR_Ping1D](INFO): Start
2019-04-09T16:05:19.291Z,1554825919.291 [SBIT](IMPORTANT): Beginning Startup BIT
2019-04-09T16:05:19.295Z,1554825919.295 [CBIT](IMPORTANT): Beginning ground fault scan
2019-04-09T16:05:19.609Z,1554825919.609 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:20.015Z,1554825920.015 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:20.015Z,1554825920.015 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:20.015Z,1554825920.015 [BR_Ping1D](INFO): startup: 0
2019-04-09T16:05:20.015Z,1554825920.015 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T16:05:20.026Z,1554825920.026 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T16:05:20.026Z,1554825920.026 [BR_Ping1D](INFO): 66
2019-04-09T16:05:20.026Z,1554825920.026 [BR_Ping1D](INFO): 82
2019-04-09T16:05:20.026Z,1554825920.026 [BR_Ping1D](INFO): 2
2019-04-09T16:05:20.026Z,1554825920.026 [BR_Ping1D](INFO): 0
2019-04-09T16:05:20.026Z,1554825920.026 [BR_Ping1D](INFO): 236
2019-04-09T16:05:20.027Z,1554825920.027 [BR_Ping1D](INFO): 3
2019-04-09T16:05:20.027Z,1554825920.027 [BR_Ping1D](INFO): 0
2019-04-09T16:05:20.027Z,1554825920.027 [BR_Ping1D](INFO): 0
2019-04-09T16:05:20.027Z,1554825920.027 [BR_Ping1D](INFO): 1
2019-04-09T16:05:20.027Z,1554825920.027 [BR_Ping1D](INFO): 30
2019-04-09T16:05:20.027Z,1554825920.027 [BR_Ping1D](INFO): 164
2019-04-09T16:05:20.027Z,1554825920.027 [BR_Ping1D](INFO): 1
2019-04-09T16:05:20.027Z,1554825920.027 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T16:05:20.028Z,1554825920.028 [BR_Ping1D](INFO): 66
2019-04-09T16:05:20.028Z,1554825920.028 [BR_Ping1D](INFO): 82
2019-04-09T16:05:20.028Z,1554825920.028 [BR_Ping1D](INFO): 1
2019-04-09T16:05:20.028Z,1554825920.028 [BR_Ping1D](INFO): 0
2019-04-09T16:05:20.028Z,1554825920.028 [BR_Ping1D](INFO): 235
2019-04-09T16:05:20.028Z,1554825920.028 [BR_Ping1D](INFO): 3
2019-04-09T16:05:20.028Z,1554825920.028 [BR_Ping1D](INFO): 0
2019-04-09T16:05:20.028Z,1554825920.028 [BR_Ping1D](INFO): 0
2019-04-09T16:05:20.028Z,1554825920.028 [BR_Ping1D](INFO): 132
2019-04-09T16:05:20.028Z,1554825920.028 [BR_Ping1D](INFO): 1
2019-04-09T16:05:20.029Z,1554825920.029 [BR_Ping1D](INFO): 21
2019-04-09T16:05:20.029Z,1554825920.029 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T16:05:20.029Z,1554825920.029 [BR_Ping1D](INFO): 66
2019-04-09T16:05:20.029Z,1554825920.029 [BR_Ping1D](INFO): 82
2019-04-09T16:05:20.029Z,1554825920.029 [BR_Ping1D](INFO): 4
2019-04-09T16:05:20.029Z,1554825920.029 [BR_Ping1D](INFO): 0
2019-04-09T16:05:20.029Z,1554825920.029 [BR_Ping1D](INFO): 234
2019-04-09T16:05:20.029Z,1554825920.029 [BR_Ping1D](INFO): 3
2019-04-09T16:05:20.030Z,1554825920.030 [BR_Ping1D](INFO): 0
2019-04-09T16:05:20.030Z,1554825920.030 [BR_Ping1D](INFO): 0
2019-04-09T16:05:20.031Z,1554825920.031 [BR_Ping1D](INFO): 96
2019-04-09T16:05:20.031Z,1554825920.031 [BR_Ping1D](INFO): 227
2019-04-09T16:05:20.032Z,1554825920.032 [BR_Ping1D](INFO): 22
2019-04-09T16:05:20.032Z,1554825920.032 [BR_Ping1D](INFO): 0
2019-04-09T16:05:20.033Z,1554825920.033 [BR_Ping1D](INFO): 222
2019-04-09T16:05:20.033Z,1554825920.033 [BR_Ping1D](INFO): 2
2019-04-09T16:05:20.034Z,1554825920.034 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T16:05:20.035Z,1554825920.035 [BR_Ping1D](INFO): 66
2019-04-09T16:05:20.036Z,1554825920.036 [BR_Ping1D](INFO): 82
2019-04-09T16:05:20.037Z,1554825920.037 [BR_Ping1D](INFO): 1
2019-04-09T16:05:20.037Z,1554825920.037 [BR_Ping1D](INFO): 0
2019-04-09T16:05:20.038Z,1554825920.038 [BR_Ping1D](INFO): 238
2019-04-09T16:05:20.038Z,1554825920.038 [BR_Ping1D](INFO): 3
2019-04-09T16:05:20.039Z,1554825920.039 [BR_Ping1D](INFO): 0
2019-04-09T16:05:20.039Z,1554825920.039 [BR_Ping1D](INFO): 0
2019-04-09T16:05:20.039Z,1554825920.039 [BR_Ping1D](INFO): 135
2019-04-09T16:05:20.039Z,1554825920.039 [BR_Ping1D](INFO): 1
2019-04-09T16:05:20.039Z,1554825920.039 [BR_Ping1D](INFO): 21
2019-04-09T16:05:20.418Z,1554825920.418 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:20.505Z,1554825920.505 [CBIT](FAULT): Main Battery Failure. Count: 61
2019-04-09T16:05:20.821Z,1554825920.821 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:20.822Z,1554825920.822 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:20.822Z,1554825920.822 [BR_Ping1D](INFO): startup: 1
2019-04-09T16:05:20.822Z,1554825920.822 [BR_Ping1D](INFO): UART requesting status
2019-04-09T16:05:20.822Z,1554825920.822 [BR_Ping1D](INFO): 66
2019-04-09T16:05:20.822Z,1554825920.822 [BR_Ping1D](INFO): 82
2019-04-09T16:05:20.822Z,1554825920.822 [BR_Ping1D](INFO): 0
2019-04-09T16:05:20.822Z,1554825920.822 [BR_Ping1D](INFO): 0
2019-04-09T16:05:20.823Z,1554825920.823 [BR_Ping1D](INFO): 186
2019-04-09T16:05:20.823Z,1554825920.823 [BR_Ping1D](INFO): 4
2019-04-09T16:05:20.823Z,1554825920.823 [BR_Ping1D](INFO): 0
2019-04-09T16:05:20.823Z,1554825920.823 [BR_Ping1D](INFO): 0
2019-04-09T16:05:20.823Z,1554825920.823 [BR_Ping1D](INFO): 82
2019-04-09T16:05:20.823Z,1554825920.823 [BR_Ping1D](INFO): 1
2019-04-09T16:05:20.830Z,1554825920.830 [BR_Ping1D](INFO): 66
2019-04-09T16:05:20.830Z,1554825920.830 [BR_Ping1D](INFO): 82
2019-04-09T16:05:20.830Z,1554825920.830 [BR_Ping1D](INFO): 0
2019-04-09T16:05:20.830Z,1554825920.830 [BR_Ping1D](INFO): 0
2019-04-09T16:05:20.830Z,1554825920.830 [BR_Ping1D](INFO): 191
2019-04-09T16:05:20.830Z,1554825920.830 [BR_Ping1D](INFO): 4
2019-04-09T16:05:20.830Z,1554825920.830 [BR_Ping1D](INFO): 0
2019-04-09T16:05:20.831Z,1554825920.831 [BR_Ping1D](INFO): 0
2019-04-09T16:05:20.831Z,1554825920.831 [BR_Ping1D](INFO): 87
2019-04-09T16:05:20.831Z,1554825920.831 [BR_Ping1D](INFO): 1
2019-04-09T16:05:20.838Z,1554825920.838 [BR_Ping1D](INFO): 66
2019-04-09T16:05:20.838Z,1554825920.838 [BR_Ping1D](INFO): 82
2019-04-09T16:05:20.838Z,1554825920.838 [BR_Ping1D](INFO): 0
2019-04-09T16:05:20.838Z,1554825920.838 [BR_Ping1D](INFO): 0
2019-04-09T16:05:20.838Z,1554825920.838 [BR_Ping1D](INFO): 179
2019-04-09T16:05:20.838Z,1554825920.838 [BR_Ping1D](INFO): 4
2019-04-09T16:05:20.838Z,1554825920.838 [BR_Ping1D](INFO): 0
2019-04-09T16:05:20.839Z,1554825920.839 [BR_Ping1D](INFO): 0
2019-04-09T16:05:20.839Z,1554825920.839 [BR_Ping1D](INFO): 75
2019-04-09T16:05:20.839Z,1554825920.839 [BR_Ping1D](INFO): 1
2019-04-09T16:05:21.225Z,1554825921.225 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:21.225Z,1554825921.225 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:21.225Z,1554825921.225 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:21.226Z,1554825921.226 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:21.226Z,1554825921.226 [BR_Ping1D](INFO): elapsed 1.2104
2019-04-09T16:05:21.226Z,1554825921.226 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:21.226Z,1554825921.226 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:21.226Z,1554825921.226 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:21.227Z,1554825921.227 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:21.630Z,1554825921.630 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:21.630Z,1554825921.630 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:21.630Z,1554825921.630 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:21.630Z,1554825921.630 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:21.630Z,1554825921.630 [BR_Ping1D](INFO): elapsed 1.6150
2019-04-09T16:05:21.631Z,1554825921.631 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:21.631Z,1554825921.631 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:21.631Z,1554825921.631 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:21.631Z,1554825921.631 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:22.035Z,1554825922.035 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:22.036Z,1554825922.036 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:22.036Z,1554825922.036 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:22.036Z,1554825922.036 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:22.036Z,1554825922.036 [BR_Ping1D](INFO): elapsed 2.0207
2019-04-09T16:05:22.036Z,1554825922.036 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:22.037Z,1554825922.037 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:22.037Z,1554825922.037 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:22.037Z,1554825922.037 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:22.438Z,1554825922.438 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:22.445Z,1554825922.445 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:22.446Z,1554825922.446 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:22.446Z,1554825922.446 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:22.446Z,1554825922.446 [BR_Ping1D](INFO): elapsed 2.4306
2019-04-09T16:05:22.446Z,1554825922.446 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:22.446Z,1554825922.446 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:22.447Z,1554825922.447 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:22.447Z,1554825922.447 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:22.542Z,1554825922.542 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T16:05:22.542Z,1554825922.542 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-09T16:05:22.542Z,1554825922.542 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T16:05:22.559Z,1554825922.559 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T16:05:22.827Z,1554825922.827 [NAL9602](INFO): Powering up NAL9602
2019-04-09T16:05:22.843Z,1554825922.843 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:22.843Z,1554825922.843 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:22.843Z,1554825922.843 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:22.843Z,1554825922.843 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:22.843Z,1554825922.843 [BR_Ping1D](INFO): elapsed 2.8279
2019-04-09T16:05:22.844Z,1554825922.844 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:22.844Z,1554825922.844 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:22.844Z,1554825922.844 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:22.844Z,1554825922.844 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:22.946Z,1554825922.946 [CTD_Seabird](INFO): Powering down
2019-04-09T16:05:23.245Z,1554825923.245 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:23.246Z,1554825923.246 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:23.246Z,1554825923.246 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:23.246Z,1554825923.246 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:23.246Z,1554825923.246 [BR_Ping1D](INFO): elapsed 3.2308
2019-04-09T16:05:23.246Z,1554825923.246 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:23.247Z,1554825923.247 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:23.247Z,1554825923.247 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:23.247Z,1554825923.247 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:23.649Z,1554825923.649 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:23.649Z,1554825923.649 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:23.649Z,1554825923.649 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:23.650Z,1554825923.650 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:23.650Z,1554825923.650 [BR_Ping1D](INFO): elapsed 3.6343
2019-04-09T16:05:23.650Z,1554825923.650 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:23.650Z,1554825923.650 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:23.650Z,1554825923.650 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:23.651Z,1554825923.651 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:24.054Z,1554825924.054 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:24.054Z,1554825924.054 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:24.054Z,1554825924.054 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:24.054Z,1554825924.054 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:24.054Z,1554825924.054 [BR_Ping1D](INFO): elapsed 4.0390
2019-04-09T16:05:24.055Z,1554825924.055 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:24.055Z,1554825924.055 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:24.055Z,1554825924.055 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:24.055Z,1554825924.055 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:24.187Z,1554825924.187 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T16:05:24.187Z,1554825924.187 [CTD_Seabird] No Fault, FailCount= 2
2019-04-09T16:05:24.290Z,1554825924.290 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T16:05:24.292Z,1554825924.292 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T16:05:24.458Z,1554825924.458 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:24.458Z,1554825924.458 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:24.458Z,1554825924.458 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:24.458Z,1554825924.458 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:24.459Z,1554825924.459 [BR_Ping1D](INFO): elapsed 4.4431
2019-04-09T16:05:24.459Z,1554825924.459 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:24.459Z,1554825924.459 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:24.459Z,1554825924.459 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:24.459Z,1554825924.459 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:24.481Z,1554825924.481 [CBIT](FAULT): Main Battery Failure. Count: 71
2019-04-09T16:05:24.861Z,1554825924.861 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:24.862Z,1554825924.862 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:24.862Z,1554825924.862 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:24.862Z,1554825924.862 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:24.862Z,1554825924.862 [BR_Ping1D](INFO): elapsed 4.8468
2019-04-09T16:05:24.862Z,1554825924.862 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:24.863Z,1554825924.863 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:24.863Z,1554825924.863 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:24.863Z,1554825924.863 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:25.266Z,1554825925.266 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:25.267Z,1554825925.267 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:25.267Z,1554825925.267 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:25.267Z,1554825925.267 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:25.267Z,1554825925.267 [BR_Ping1D](INFO): elapsed 5.2517
2019-04-09T16:05:25.267Z,1554825925.267 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:25.267Z,1554825925.267 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T16:05:25.268Z,1554825925.268 [BR_Ping1D] Data Fault, FailCount= 4
2019-04-09T16:05:25.268Z,1554825925.268 [BR_Ping1D](ERROR): Data Fault
2019-04-09T16:05:25.296Z,1554825925.296 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T16:05:25.673Z,1554825925.673 [BR_Ping1D](INFO): Stop
2019-04-09T16:05:25.673Z,1554825925.673 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:05:25.673Z,1554825925.673 [BR_Ping1D](INFO): Powering down
2019-04-09T16:05:26.073Z,1554825926.073 [BR_Ping1D](INFO): Stopping
2019-04-09T16:05:26.478Z,1554825926.478 [BR_Ping1D](INFO): Stopping
2019-04-09T16:05:26.881Z,1554825926.881 [BR_Ping1D](INFO): Stopped
2019-04-09T16:05:26.896Z,1554825926.896 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T16:05:26.896Z,1554825926.896 [BR_Ping1D] No Fault, FailCount= 4
2019-04-09T16:05:27.285Z,1554825927.285 [BR_Ping1D](INFO): Start
2019-04-09T16:05:27.689Z,1554825927.689 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:28.094Z,1554825928.094 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:28.094Z,1554825928.094 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:28.094Z,1554825928.094 [BR_Ping1D](INFO): startup: 0
2019-04-09T16:05:28.094Z,1554825928.094 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T16:05:28.106Z,1554825928.106 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T16:05:28.106Z,1554825928.106 [BR_Ping1D](INFO): 66
2019-04-09T16:05:28.106Z,1554825928.106 [BR_Ping1D](INFO): 82
2019-04-09T16:05:28.106Z,1554825928.106 [BR_Ping1D](INFO): 2
2019-04-09T16:05:28.106Z,1554825928.106 [BR_Ping1D](INFO): 0
2019-04-09T16:05:28.106Z,1554825928.106 [BR_Ping1D](INFO): 236
2019-04-09T16:05:28.107Z,1554825928.107 [BR_Ping1D](INFO): 3
2019-04-09T16:05:28.107Z,1554825928.107 [BR_Ping1D](INFO): 0
2019-04-09T16:05:28.107Z,1554825928.107 [BR_Ping1D](INFO): 0
2019-04-09T16:05:28.107Z,1554825928.107 [BR_Ping1D](INFO): 1
2019-04-09T16:05:28.107Z,1554825928.107 [BR_Ping1D](INFO): 30
2019-04-09T16:05:28.107Z,1554825928.107 [BR_Ping1D](INFO): 164
2019-04-09T16:05:28.107Z,1554825928.107 [BR_Ping1D](INFO): 1
2019-04-09T16:05:28.107Z,1554825928.107 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T16:05:28.108Z,1554825928.108 [BR_Ping1D](INFO): 66
2019-04-09T16:05:28.108Z,1554825928.108 [BR_Ping1D](INFO): 82
2019-04-09T16:05:28.108Z,1554825928.108 [BR_Ping1D](INFO): 1
2019-04-09T16:05:28.108Z,1554825928.108 [BR_Ping1D](INFO): 0
2019-04-09T16:05:28.108Z,1554825928.108 [BR_Ping1D](INFO): 235
2019-04-09T16:05:28.108Z,1554825928.108 [BR_Ping1D](INFO): 3
2019-04-09T16:05:28.108Z,1554825928.108 [BR_Ping1D](INFO): 0
2019-04-09T16:05:28.108Z,1554825928.108 [BR_Ping1D](INFO): 0
2019-04-09T16:05:28.108Z,1554825928.108 [BR_Ping1D](INFO): 132
2019-04-09T16:05:28.108Z,1554825928.108 [BR_Ping1D](INFO): 1
2019-04-09T16:05:28.109Z,1554825928.109 [BR_Ping1D](INFO): 21
2019-04-09T16:05:28.109Z,1554825928.109 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T16:05:28.109Z,1554825928.109 [BR_Ping1D](INFO): 66
2019-04-09T16:05:28.109Z,1554825928.109 [BR_Ping1D](INFO): 82
2019-04-09T16:05:28.109Z,1554825928.109 [BR_Ping1D](INFO): 4
2019-04-09T16:05:28.109Z,1554825928.109 [BR_Ping1D](INFO): 0
2019-04-09T16:05:28.109Z,1554825928.109 [BR_Ping1D](INFO): 234
2019-04-09T16:05:28.109Z,1554825928.109 [BR_Ping1D](INFO): 3
2019-04-09T16:05:28.109Z,1554825928.109 [BR_Ping1D](INFO): 0
2019-04-09T16:05:28.110Z,1554825928.110 [BR_Ping1D](INFO): 0
2019-04-09T16:05:28.110Z,1554825928.110 [BR_Ping1D](INFO): 96
2019-04-09T16:05:28.111Z,1554825928.111 [BR_Ping1D](INFO): 227
2019-04-09T16:05:28.111Z,1554825928.111 [BR_Ping1D](INFO): 22
2019-04-09T16:05:28.112Z,1554825928.112 [BR_Ping1D](INFO): 0
2019-04-09T16:05:28.112Z,1554825928.112 [BR_Ping1D](INFO): 222
2019-04-09T16:05:28.113Z,1554825928.113 [BR_Ping1D](INFO): 2
2019-04-09T16:05:28.114Z,1554825928.114 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T16:05:28.115Z,1554825928.115 [BR_Ping1D](INFO): 66
2019-04-09T16:05:28.115Z,1554825928.115 [BR_Ping1D](INFO): 82
2019-04-09T16:05:28.116Z,1554825928.116 [BR_Ping1D](INFO): 1
2019-04-09T16:05:28.116Z,1554825928.116 [BR_Ping1D](INFO): 0
2019-04-09T16:05:28.117Z,1554825928.117 [BR_Ping1D](INFO): 238
2019-04-09T16:05:28.117Z,1554825928.117 [BR_Ping1D](INFO): 3
2019-04-09T16:05:28.118Z,1554825928.118 [BR_Ping1D](INFO): 0
2019-04-09T16:05:28.118Z,1554825928.118 [BR_Ping1D](INFO): 0
2019-04-09T16:05:28.118Z,1554825928.118 [BR_Ping1D](INFO): 135
2019-04-09T16:05:28.118Z,1554825928.118 [BR_Ping1D](INFO): 1
2019-04-09T16:05:28.118Z,1554825928.118 [BR_Ping1D](INFO): 21
2019-04-09T16:05:28.498Z,1554825928.498 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:28.525Z,1554825928.525 [CBIT](FAULT): Main Battery Failure. Count: 81
2019-04-09T16:05:28.882Z,1554825928.882 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T16:05:28.882Z,1554825928.882 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-09T16:05:28.882Z,1554825928.882 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T16:05:28.915Z,1554825928.915 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:28.915Z,1554825928.915 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:28.916Z,1554825928.916 [BR_Ping1D](INFO): startup: 1
2019-04-09T16:05:28.916Z,1554825928.916 [BR_Ping1D](INFO): UART requesting status
2019-04-09T16:05:28.916Z,1554825928.916 [BR_Ping1D](INFO): 66
2019-04-09T16:05:28.916Z,1554825928.916 [BR_Ping1D](INFO): 82
2019-04-09T16:05:28.916Z,1554825928.916 [BR_Ping1D](INFO): 0
2019-04-09T16:05:28.916Z,1554825928.916 [BR_Ping1D](INFO): 0
2019-04-09T16:05:28.916Z,1554825928.916 [BR_Ping1D](INFO): 186
2019-04-09T16:05:28.916Z,1554825928.916 [BR_Ping1D](INFO): 4
2019-04-09T16:05:28.917Z,1554825928.917 [BR_Ping1D](INFO): 0
2019-04-09T16:05:28.917Z,1554825928.917 [BR_Ping1D](INFO): 0
2019-04-09T16:05:28.917Z,1554825928.917 [BR_Ping1D](INFO): 82
2019-04-09T16:05:28.917Z,1554825928.917 [BR_Ping1D](INFO): 1
2019-04-09T16:05:28.926Z,1554825928.926 [BR_Ping1D](INFO): 66
2019-04-09T16:05:28.926Z,1554825928.926 [BR_Ping1D](INFO): 82
2019-04-09T16:05:28.926Z,1554825928.926 [BR_Ping1D](INFO): 0
2019-04-09T16:05:28.926Z,1554825928.926 [BR_Ping1D](INFO): 0
2019-04-09T16:05:28.926Z,1554825928.926 [BR_Ping1D](INFO): 191
2019-04-09T16:05:28.926Z,1554825928.926 [BR_Ping1D](INFO): 4
2019-04-09T16:05:28.927Z,1554825928.927 [BR_Ping1D](INFO): 0
2019-04-09T16:05:28.927Z,1554825928.927 [BR_Ping1D](INFO): 0
2019-04-09T16:05:28.927Z,1554825928.927 [BR_Ping1D](INFO): 87
2019-04-09T16:05:28.927Z,1554825928.927 [BR_Ping1D](INFO): 1
2019-04-09T16:05:28.934Z,1554825928.934 [BR_Ping1D](INFO): 66
2019-04-09T16:05:28.934Z,1554825928.934 [BR_Ping1D](INFO): 82
2019-04-09T16:05:28.934Z,1554825928.934 [BR_Ping1D](INFO): 0
2019-04-09T16:05:28.934Z,1554825928.934 [BR_Ping1D](INFO): 0
2019-04-09T16:05:28.934Z,1554825928.934 [BR_Ping1D](INFO): 179
2019-04-09T16:05:28.934Z,1554825928.934 [BR_Ping1D](INFO): 4
2019-04-09T16:05:28.934Z,1554825928.934 [BR_Ping1D](INFO): 0
2019-04-09T16:05:28.935Z,1554825928.935 [BR_Ping1D](INFO): 0
2019-04-09T16:05:28.935Z,1554825928.935 [BR_Ping1D](INFO): 75
2019-04-09T16:05:28.935Z,1554825928.935 [BR_Ping1D](INFO): 1
2019-04-09T16:05:28.965Z,1554825928.965 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T16:05:28.965Z,1554825928.965 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-09T16:05:29.406Z,1554825929.406 [Aanderaa_O2](INFO): Powering down
2019-04-09T16:05:29.433Z,1554825929.433 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:29.433Z,1554825929.433 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:29.433Z,1554825929.433 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:29.433Z,1554825929.433 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:29.433Z,1554825929.433 [BR_Ping1D](INFO): elapsed 1.3391
2019-04-09T16:05:29.434Z,1554825929.434 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:29.434Z,1554825929.434 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:29.434Z,1554825929.434 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:29.434Z,1554825929.434 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:29.709Z,1554825929.709 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:29.710Z,1554825929.710 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:29.710Z,1554825929.710 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:29.710Z,1554825929.710 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:29.710Z,1554825929.710 [BR_Ping1D](INFO): elapsed 1.6159
2019-04-09T16:05:29.710Z,1554825929.710 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:29.711Z,1554825929.711 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:29.711Z,1554825929.711 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:29.711Z,1554825929.711 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:30.113Z,1554825930.113 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:30.113Z,1554825930.113 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:30.113Z,1554825930.113 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:30.114Z,1554825930.114 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:30.114Z,1554825930.114 [BR_Ping1D](INFO): elapsed 2.0195
2019-04-09T16:05:30.114Z,1554825930.114 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:30.114Z,1554825930.114 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:30.114Z,1554825930.114 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:30.115Z,1554825930.115 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:30.172Z,1554825930.172 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.006239
CHAN A1 (24V): -0.127305
CHAN A2 (12V): -0.004776
CHAN A3 (5V): -0.002296
CHAN B0 (3.3V): -0.001260
CHAN B1 (3.15aV): -0.001259
CHAN B2 (3.15bV): -0.001047
CHAN B3 (GND): -0.000815
OPEN: -0.000732
Full Scale Calc: 4.765 mA, -1.589 mA
2019-04-09T16:05:30.517Z,1554825930.517 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:30.518Z,1554825930.518 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:30.518Z,1554825930.518 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:30.518Z,1554825930.518 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:30.518Z,1554825930.518 [BR_Ping1D](INFO): elapsed 2.4239
2019-04-09T16:05:30.518Z,1554825930.518 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:30.519Z,1554825930.519 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:30.519Z,1554825930.519 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:30.519Z,1554825930.519 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:30.921Z,1554825930.921 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:30.921Z,1554825930.921 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:30.921Z,1554825930.921 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:30.922Z,1554825930.922 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:30.922Z,1554825930.922 [BR_Ping1D](INFO): elapsed 2.8274
2019-04-09T16:05:30.922Z,1554825930.922 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:30.922Z,1554825930.922 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:30.922Z,1554825930.922 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:30.923Z,1554825930.923 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:31.326Z,1554825931.326 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:31.326Z,1554825931.326 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:31.326Z,1554825931.326 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:31.327Z,1554825931.327 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:31.327Z,1554825931.327 [BR_Ping1D](INFO): elapsed 3.2324
2019-04-09T16:05:31.327Z,1554825931.327 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:31.327Z,1554825931.327 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:31.327Z,1554825931.327 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:31.327Z,1554825931.327 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:31.729Z,1554825931.729 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:31.729Z,1554825931.729 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:31.729Z,1554825931.729 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:31.729Z,1554825931.729 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:31.730Z,1554825931.730 [BR_Ping1D](INFO): elapsed 3.6352
2019-04-09T16:05:31.730Z,1554825931.730 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:31.730Z,1554825931.730 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:31.730Z,1554825931.730 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:31.731Z,1554825931.731 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:32.135Z,1554825932.135 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:32.135Z,1554825932.135 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:32.135Z,1554825932.135 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:32.135Z,1554825932.135 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:32.136Z,1554825932.136 [BR_Ping1D](INFO): elapsed 4.0412
2019-04-09T16:05:32.136Z,1554825932.136 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:32.136Z,1554825932.136 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:32.136Z,1554825932.136 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:32.136Z,1554825932.136 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:32.540Z,1554825932.540 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:32.540Z,1554825932.540 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:32.540Z,1554825932.540 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:32.540Z,1554825932.540 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:32.540Z,1554825932.540 [BR_Ping1D](INFO): elapsed 4.4459
2019-04-09T16:05:32.540Z,1554825932.540 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:32.541Z,1554825932.541 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:32.541Z,1554825932.541 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:32.541Z,1554825932.541 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:32.552Z,1554825932.552 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T16:05:32.553Z,1554825932.553 [CBIT](FAULT): Main Battery Failure. Count: 91
2019-04-09T16:05:32.939Z,1554825932.939 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:32.939Z,1554825932.939 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:32.939Z,1554825932.939 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:32.939Z,1554825932.939 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:32.939Z,1554825932.939 [BR_Ping1D](INFO): elapsed 4.8450
2019-04-09T16:05:32.940Z,1554825932.940 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:32.940Z,1554825932.940 [BR_Ping1D](INFO): No UART data
2019-04-09T16:05:32.940Z,1554825932.940 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T16:05:32.940Z,1554825932.940 [BR_Ping1D](INFO): not all values verified yet
2019-04-09T16:05:33.357Z,1554825933.357 [BR_Ping1D](INFO): Starting
2019-04-09T16:05:33.357Z,1554825933.357 [BR_Ping1D](INFO): readConfig
2019-04-09T16:05:33.357Z,1554825933.357 [BR_Ping1D](INFO): startup: 2
2019-04-09T16:05:33.357Z,1554825933.357 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T16:05:33.357Z,1554825933.357 [BR_Ping1D](INFO): elapsed 5.2629
2019-04-09T16:05:33.357Z,1554825933.357 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T16:05:33.358Z,1554825933.358 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-09T16:05:33.358Z,1554825933.358 [BR_Ping1D] Data Fault, FailCount= 5
2019-04-09T16:05:33.358Z,1554825933.358 [BR_Ping1D](ERROR): Data Fault
2019-04-09T16:05:33.432Z,1554825933.432 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T16:05:33.432Z,1554825933.432 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-04-09T16:05:33.743Z,1554825933.743 [NAL9602](INFO): NAL9602 initialized
2019-04-09T16:05:33.758Z,1554825933.758 [BR_Ping1D](INFO): Stop
2019-04-09T16:05:33.758Z,1554825933.758 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:05:33.758Z,1554825933.758 [BR_Ping1D](INFO): Powering down
2019-04-09T16:05:33.872Z,1554825933.872 [CBIT](INFO): Critical error at 20190409T160533
2019-04-09T16:05:34.159Z,1554825934.159 [BR_Ping1D](INFO): Stopping
2019-04-09T16:05:34.554Z,1554825934.554 [NAL9602](DEBUG): Fix Requested
2019-04-09T16:05:34.569Z,1554825934.569 [BR_Ping1D](INFO): Stopping
2019-04-09T16:05:34.976Z,1554825934.976 [BR_Ping1D](INFO): Stopped
2019-04-09T16:05:36.661Z,1554825936.661 [CBIT](FAULT): Main Battery Failure. Count: 101
2019-04-09T16:05:37.342Z,1554825937.342 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T16:05:37.342Z,1554825937.342 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-09T16:05:37.342Z,1554825937.342 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T16:05:37.467Z,1554825937.467 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T16:05:37.467Z,1554825937.467 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-09T16:05:37.746Z,1554825937.746 [CTD_Seabird](INFO): Powering down
2019-04-09T16:05:39.386Z,1554825939.386 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:05:39.386Z,1554825939.386 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2019-04-09T16:05:39.386Z,1554825939.386 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:05:39.487Z,1554825939.487 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:05:39.790Z,1554825939.790 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:05:40.629Z,1554825940.629 [CBIT](FAULT): Main Battery Failure. Count: 111
2019-04-09T16:05:40.632Z,1554825940.632 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:05:40.632Z,1554825940.632 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2019-04-09T16:05:43.022Z,1554825943.022 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:05:43.022Z,1554825943.022 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:05:44.685Z,1554825944.685 [CBIT](FAULT): Main Battery Failure. Count: 121
2019-04-09T16:05:48.721Z,1554825948.721 [CBIT](FAULT): Main Battery Failure. Count: 131
2019-04-09T16:05:52.763Z,1554825952.763 [CBIT](FAULT): Main Battery Failure. Count: 141
2019-04-09T16:05:56.796Z,1554825956.796 [CBIT](FAULT): Main Battery Failure. Count: 151
2019-04-09T16:05:59.227Z,1554825959.227 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T16:05:59.227Z,1554825959.227 [SBIT](FAULT): Control surface position failure.
2019-04-09T16:06:00.827Z,1554825960.827 [CBIT](FAULT): Main Battery Failure. Count: 161
2019-04-09T16:06:03.224Z,1554825963.224 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:06:03.224Z,1554825963.224 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2019-04-09T16:06:03.224Z,1554825963.224 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:06:03.339Z,1554825963.339 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:06:03.626Z,1554825963.626 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:06:04.731Z,1554825964.731 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:06:04.731Z,1554825964.731 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2019-04-09T16:06:04.873Z,1554825964.873 [CBIT](FAULT): Main Battery Failure. Count: 171
2019-04-09T16:06:06.858Z,1554825966.858 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:06:06.859Z,1554825966.859 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:06:08.929Z,1554825968.929 [CBIT](FAULT): Main Battery Failure. Count: 181
2019-04-09T16:06:12.952Z,1554825972.952 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T16:06:12.952Z,1554825972.952 [SBIT](FAULT): Control surface position failure.
2019-04-09T16:06:12.953Z,1554825972.953 [CBIT](FAULT): Main Battery Failure. Count: 191
2019-04-09T16:06:13.350Z,1554825973.350 [SBIT](CRITICAL): SBIT FAILED
2019-04-09T16:06:13.365Z,1554825973.365 [CommandLine](IMPORTANT): got command configSet list
2019-04-09T16:06:13.365Z,1554825973.365 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-04-09T16:06:13.366Z,1554825973.366 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-04-09T16:06:13.366Z,1554825973.366 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2019-04-09T16:06:13.368Z,1554825973.368 [CBIT](INFO): Critical error at 20190409T160613
2019-04-09T16:06:13.771Z,1554825973.771 [MissionManager](IMPORTANT): Started mission Startup
2019-04-09T16:06:13.772Z,1554825973.772 [Startup] Running Loop=1
2019-04-09T16:06:13.772Z,1554825973.772 [Startup](DEBUG): Aggregate::initialize Startup
2019-04-09T16:06:13.772Z,1554825973.772 [Startup:A.GoToSurface] Running Loop=1
2019-04-09T16:06:13.772Z,1554825973.772 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-09T16:06:13.773Z,1554825973.773 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-09T16:06:13.773Z,1554825973.773 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-09T16:06:13.773Z,1554825973.773 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-09T16:06:13.774Z,1554825973.774 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-09T16:06:13.774Z,1554825973.774 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-09T16:06:13.776Z,1554825973.776 [Startup:StartupSatComms] Running Loop=1
2019-04-09T16:06:13.776Z,1554825973.776 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-04-09T16:06:13.776Z,1554825973.776 [Startup:StartupSatComms:A] Running Loop=1
2019-04-09T16:06:14.172Z,1554825974.172 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-04-09T16:06:16.988Z,1554825976.988 [CBIT](FAULT): Main Battery Failure. Count: 201
2019-04-09T16:06:21.036Z,1554825981.036 [CBIT](FAULT): Main Battery Failure. Count: 211
2019-04-09T16:06:25.089Z,1554825985.089 [CBIT](FAULT): Main Battery Failure. Count: 221
2019-04-09T16:06:27.058Z,1554825987.058 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:06:27.059Z,1554825987.059 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2019-04-09T16:06:27.059Z,1554825987.059 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:06:27.097Z,1554825987.097 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:06:27.463Z,1554825987.463 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:06:28.311Z,1554825988.311 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:06:28.311Z,1554825988.311 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2019-04-09T16:06:29.107Z,1554825989.107 [CBIT](FAULT): Main Battery Failure. Count: 231
2019-04-09T16:06:30.694Z,1554825990.694 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:06:30.695Z,1554825990.695 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:06:33.153Z,1554825993.153 [CBIT](FAULT): Main Battery Failure. Count: 241
2019-04-09T16:06:37.186Z,1554825997.186 [CBIT](FAULT): Main Battery Failure. Count: 251
2019-04-09T16:06:41.245Z,1554826001.245 [CBIT](FAULT): Main Battery Failure. Count: 261
2019-04-09T16:06:45.283Z,1554826005.283 [CBIT](FAULT): Main Battery Failure. Count: 271
2019-04-09T16:06:49.306Z,1554826009.306 [CBIT](FAULT): Main Battery Failure. Count: 281
2019-04-09T16:06:50.894Z,1554826010.894 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:06:50.894Z,1554826010.894 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2019-04-09T16:06:50.895Z,1554826010.895 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:06:50.944Z,1554826010.944 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:06:51.298Z,1554826011.298 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:06:52.147Z,1554826012.147 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:06:52.147Z,1554826012.147 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2019-04-09T16:06:53.353Z,1554826013.353 [CBIT](FAULT): Main Battery Failure. Count: 291
2019-04-09T16:06:54.530Z,1554826014.530 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:06:54.531Z,1554826014.531 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:06:54.961Z,1554826014.961 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T16:06:54.961Z,1554826014.961 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-04-09T16:06:54.961Z,1554826014.961 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T16:06:54.963Z,1554826014.963 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T16:06:54.963Z,1554826014.963 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-04-09T16:06:54.963Z,1554826014.963 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T16:06:54.964Z,1554826014.964 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T16:06:54.964Z,1554826014.964 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-04-09T16:06:54.964Z,1554826014.964 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T16:06:54.975Z,1554826014.975 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T16:06:54.975Z,1554826014.975 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T16:06:54.975Z,1554826014.975 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T16:06:55.373Z,1554826015.373 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T16:06:55.373Z,1554826015.373 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-04-09T16:06:55.373Z,1554826015.373 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T16:06:55.374Z,1554826015.374 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-04-09T16:06:55.374Z,1554826015.374 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T16:06:55.374Z,1554826015.374 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-04-09T16:06:55.785Z,1554826015.785 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T16:06:57.393Z,1554826017.393 [CBIT](FAULT): Main Battery Failure. Count: 301
2019-04-09T16:07:01.445Z,1554826021.445 [CBIT](FAULT): Main Battery Failure. Count: 311
2019-04-09T16:07:05.473Z,1554826025.473 [CBIT](FAULT): Main Battery Failure. Count: 321
2019-04-09T16:07:09.517Z,1554826029.517 [CBIT](FAULT): Main Battery Failure. Count: 331
2019-04-09T16:07:13.562Z,1554826033.562 [CBIT](FAULT): Main Battery Failure. Count: 341
2019-04-09T16:07:13.951Z,1554826033.951 [Startup:StartupSatComms:A](INFO): Timed out from 2019-04-09T16:06:13.8Z
2019-04-09T16:07:13.951Z,1554826033.951 [Startup:StartupSatComms:A] Stopped
2019-04-09T16:07:13.952Z,1554826033.952 [Startup:StartupSatComms:B] Running Loop=1
2019-04-09T16:07:14.352Z,1554826034.352 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-09T16:07:14.731Z,1554826034.731 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:07:14.731Z,1554826034.731 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2019-04-09T16:07:14.731Z,1554826034.731 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:07:14.768Z,1554826034.768 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:07:15.134Z,1554826035.134 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:07:15.979Z,1554826035.979 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:07:15.980Z,1554826035.980 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2019-04-09T16:07:17.603Z,1554826037.603 [CBIT](FAULT): Main Battery Failure. Count: 351
2019-04-09T16:07:18.367Z,1554826038.367 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:07:18.367Z,1554826038.367 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:07:21.634Z,1554826041.634 [CBIT](FAULT): Main Battery Failure. Count: 361
2019-04-09T16:07:25.673Z,1554826045.673 [CBIT](FAULT): Main Battery Failure. Count: 371
2019-04-09T16:07:29.708Z,1554826049.708 [CBIT](FAULT): Main Battery Failure. Count: 381
2019-04-09T16:07:33.260Z,1554826053.260 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004853
2019-04-09T16:07:33.770Z,1554826053.770 [CBIT](FAULT): Main Battery Failure. Count: 391
2019-04-09T16:07:37.805Z,1554826057.805 [CBIT](FAULT): Main Battery Failure. Count: 401
2019-04-09T16:07:37.937Z,1554826057.937 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190409T155653/Courier0004.lzma
2019-04-09T16:07:38.566Z,1554826058.566 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:07:38.567Z,1554826058.567 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7
2019-04-09T16:07:38.567Z,1554826058.567 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:07:38.620Z,1554826058.620 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:07:38.744Z,1554826058.744 [DataOverHttps](INFO): Moved sent file to Logs/20190409T155653/Courier0004.lzma.bak
2019-04-09T16:07:38.744Z,1554826058.744 [DataOverHttps](INFO): SBD MOMSN=10514082
2019-04-09T16:07:38.970Z,1554826058.970 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:07:39.820Z,1554826059.820 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T16:07:39.820Z,1554826059.820 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7
2019-04-09T16:07:41.834Z,1554826061.834 [CBIT](FAULT): Main Battery Failure. Count: 411
2019-04-09T16:07:42.202Z,1554826062.202 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T16:07:42.203Z,1554826062.203 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T16:07:45.905Z,1554826065.905 [CBIT](FAULT): Main Battery Failure. Count: 421
2019-04-09T16:07:49.910Z,1554826069.910 [CBIT](FAULT): Main Battery Failure. Count: 431
2019-04-09T16:07:53.970Z,1554826073.970 [CBIT](FAULT): Main Battery Failure. Count: 441
2019-04-09T16:07:57.993Z,1554826077.993 [CBIT](FAULT): Main Battery Failure. Count: 451
2019-04-09T16:07:59.219Z,1554826079.219 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T16:07:59.219Z,1554826079.219 [Aanderaa_O2] No Fault, FailCount= 3
2019-04-09T16:08:02.027Z,1554826082.027 [CBIT](FAULT): Main Battery Failure. Count: 461
2019-04-09T16:08:02.403Z,1554826082.403 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T16:08:02.403Z,1554826082.403 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 8
2019-04-09T16:08:02.403Z,1554826082.403 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T16:08:02.440Z,1554826082.440 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:08:02.440Z,1554826082.440 [CBIT](FAULT): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T16:08:02.807Z,1554826082.807 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:08:06.073Z,1554826086.073 [CBIT](FAULT): Main Battery Failure. Count: 471
2019-04-09T16:08:07.698Z,1554826087.698 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T16:08:07.698Z,1554826087.698 [CTD_Seabird] No Fault, FailCount= 3
2019-04-09T16:08:07.802Z,1554826087.802 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T16:08:07.804Z,1554826087.804 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T16:08:09.674Z,1554826089.674 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T16:08:09.675Z,1554826089.675 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T16:08:09.675Z,1554826089.675 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T16:08:09.722Z,1554826089.722 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T16:08:10.198Z,1554826090.198 [Aanderaa_O2](INFO): Powering down
2019-04-09T16:08:10.245Z,1554826090.245 [CBIT](FAULT): Main Battery Failure. Count: 481
2019-04-09T16:08:10.926Z,1554826090.926 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T16:08:10.926Z,1554826090.926 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-09T16:08:14.151Z,1554826094.151 [Startup:StartupSatComms:B](INFO): Timed out from 2019-04-09T16:07:13.0Z
2019-04-09T16:08:14.152Z,1554826094.152 [Startup:StartupSatComms:B] Stopped
2019-04-09T16:08:14.152Z,1554826094.152 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-04-09T16:08:14.152Z,1554826094.152 [Startup:StartupSatComms] Stopped
2019-04-09T16:08:14.152Z,1554826094.152 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-04-09T16:08:14.153Z,1554826094.153 [Startup](INFO): Completed Startup
2019-04-09T16:08:14.153Z,1554826094.153 [MissionManager](INFO): Startup is completed.
2019-04-09T16:08:14.153Z,1554826094.153 [MissionManager](INFO): Uninitializing Mission Startup
2019-04-09T16:08:14.153Z,1554826094.153 [Startup] Stopped
2019-04-09T16:08:14.153Z,1554826094.153 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-04-09T16:08:14.153Z,1554826094.153 [Startup:A.GoToSurface] Stopped
2019-04-09T16:08:14.153Z,1554826094.153 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-09T16:08:14.169Z,1554826094.169 [CBIT](FAULT): Main Battery Failure. Count: 491
2019-04-09T16:08:14.571Z,1554826094.571 [MissionManager](IMPORTANT): Started mission Default
2019-04-09T16:08:14.571Z,1554826094.571 [Default] Running Loop=1
2019-04-09T16:08:14.571Z,1554826094.571 [Default](DEBUG): Aggregate::initialize Default
2019-04-09T16:08:14.571Z,1554826094.571 [Default:B.GoToSurface] Running Loop=1
2019-04-09T16:08:14.571Z,1554826094.571 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-09T16:08:14.571Z,1554826094.571 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-09T16:08:14.572Z,1554826094.572 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-09T16:08:14.572Z,1554826094.572 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-09T16:08:14.572Z,1554826094.572 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-09T16:08:14.573Z,1554826094.573 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-09T16:08:14.573Z,1554826094.573 [Default:A.Wait] Running Loop=1
2019-04-09T16:08:14.573Z,1554826094.573 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-04-09T16:08:18.203Z,1554826098.203 [CBIT](FAULT): Main Battery Failure. Count: 501
2019-04-09T16:08:20.854Z,1554826100.854 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T16:08:20.854Z,1554826100.854 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-09T16:08:20.854Z,1554826100.854 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T16:08:21.036Z,1554826101.036 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T16:08:21.258Z,1554826101.258 [CTD_Seabird](INFO): Powering down
2019-04-09T16:08:21.390Z,1554826101.390 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T16:08:21.391Z,1554826101.391 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-09T16:08:21.391Z,1554826101.391 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T16:08:21.440Z,1554826101.440 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T16:08:21.914Z,1554826101.914 [Aanderaa_O2](INFO): Powering down
2019-04-09T16:08:22.234Z,1554826102.234 [CBIT](FAULT): Main Battery Failure. Count: 511
2019-04-09T16:08:22.236Z,1554826102.236 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T16:08:22.236Z,1554826102.236 [CTD_Seabird] No Fault, FailCount= 1
2019-04-09T16:08:22.602Z,1554826102.602 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T16:08:22.604Z,1554826102.604 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T16:08:22.643Z,1554826102.643 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T16:08:22.643Z,1554826102.643 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-09T16:08:26.289Z,1554826106.289 [CBIT](FAULT): Main Battery Failure. Count: 521
2019-04-09T16:08:27.895Z,1554826107.895 [Default:A.Wait](INFO): Done Waiting.
2019-04-09T16:08:27.896Z,1554826107.896 [Default:A.Wait] Stopped
2019-04-09T16:08:27.896Z,1554826107.896 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-04-09T16:08:28.296Z,1554826108.296 [Default:CheckIn] Running Loop=1
2019-04-09T16:08:28.296Z,1554826108.296 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-09T16:08:28.296Z,1554826108.296 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-09T16:08:28.687Z,1554826108.687 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-04-09T16:08:29.331Z,1554826109.331 [DataOverHttps](INFO): Sending 358 bytes from file Logs/20190409T160144/Courier0000.lzma
2019-04-09T16:08:30.135Z,1554826110.135 [DataOverHttps](INFO): Moved sent file to Logs/20190409T160144/Courier0000.lzma.bak
2019-04-09T16:08:30.135Z,1554826110.135 [DataOverHttps](INFO): SBD MOMSN=10514100
2019-04-09T16:08:30.319Z,1554826110.319 [CBIT](FAULT): Main Battery Failure. Count: 531
2019-04-09T16:08:33.106Z,1554826113.106 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T16:08:33.106Z,1554826113.106 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-09T16:08:33.106Z,1554826113.106 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T16:08:33.148Z,1554826113.148 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T16:08:33.148Z,1554826113.148 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-09T16:08:33.630Z,1554826113.630 [Aanderaa_O2](INFO): Powering down
2019-04-09T16:08:34.357Z,1554826114.357 [CBIT](FAULT): Main Battery Failure. Count: 541
2019-04-09T16:08:35.654Z,1554826115.654 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T16:08:35.654Z,1554826115.654 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-09T16:08:35.654Z,1554826115.654 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T16:08:35.980Z,1554826115.980 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T16:08:36.058Z,1554826116.058 [CTD_Seabird](INFO): Powering down
2019-04-09T16:08:37.180Z,1554826117.180 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T16:08:37.180Z,1554826117.180 [CTD_Seabird] No Fault, FailCount= 2
2019-04-09T16:08:37.402Z,1554826117.402 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T16:08:37.404Z,1554826117.404 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T16:08:38.393Z,1554826118.393 [CBIT](FAULT): Main Battery Failure. Count: 551
2019-04-09T16:08:42.445Z,1554826122.445 [CBIT](FAULT): Main Battery Failure. Count: 561
2019-04-09T16:08:46.485Z,1554826126.485 [CBIT](FAULT): Main Battery Failure. Count: 571
2019-04-09T16:08:50.454Z,1554826130.454 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T16:08:50.454Z,1554826130.454 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-09T16:08:50.454Z,1554826130.454 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T16:08:50.513Z,1554826130.513 [CBIT](FAULT): Main Battery Failure. Count: 581
2019-04-09T16:08:50.516Z,1554826130.516 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T16:08:50.516Z,1554826130.516 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-09T16:08:50.858Z,1554826130.858 [CTD_Seabird](INFO): Powering down
2019-04-09T16:08:52.216Z,1554826132.216 [CommandLine](IMPORTANT): got command restart application
2019-04-09T16:08:53.222Z,1554826133.222 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:08:53.222Z,1554826133.222 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:08:53.362Z,1554826133.362 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-04-09T16:08:53.362Z,1554826133.362 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:08:53.363Z,1554826133.363 [CommandLine](INFO): Join timeout helper Thread ID is 4037
2019-04-09T16:08:53.378Z,1554826133.378 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-04-09T16:08:53.378Z,1554826133.378 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:08:53.379Z,1554826133.379 [NavChartDb](INFO): Join timeout helper Thread ID is 4038
2019-04-09T16:08:53.538Z,1554826133.538 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:08:53.538Z,1554826133.538 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:08:53.550Z,1554826133.550 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-04-09T16:08:53.550Z,1554826133.550 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:08:53.551Z,1554826133.551 [Radio_Surface](INFO): Join timeout helper Thread ID is 4039
2019-04-09T16:08:53.886Z,1554826133.886 [Radio_Surface](INFO): Powering down
2019-04-09T16:08:53.887Z,1554826133.887 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:08:53.887Z,1554826133.887 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:08:53.898Z,1554826133.898 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-04-09T16:08:53.898Z,1554826133.898 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:08:53.898Z,1554826133.898 [DataOverHttps](INFO): Join timeout helper Thread ID is 4040
2019-04-09T16:08:54.254Z,1554826134.254 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:08:54.257Z,1554826134.257 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:08:54.266Z,1554826134.266 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-04-09T16:08:54.266Z,1554826134.266 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:08:54.267Z,1554826134.267 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4041
2019-04-09T16:08:54.557Z,1554826134.557 [CBIT](FAULT): Main Battery Failure. Count: 591
2019-04-09T16:08:54.642Z,1554826134.642 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:08:54.643Z,1554826134.643 [WetLabsBB2FL](INFO): Powering down
2019-04-09T16:08:54.643Z,1554826134.643 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:08:54.647Z,1554826134.647 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-04-09T16:08:54.647Z,1554826134.647 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:08:54.647Z,1554826134.647 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4042
2019-04-09T16:08:55.038Z,1554826135.038 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:08:55.038Z,1554826135.038 [CTD_Seabird](INFO): Powering down
2019-04-09T16:08:55.050Z,1554826135.050 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:08:55.071Z,1554826135.071 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-04-09T16:08:55.071Z,1554826135.071 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:08:55.071Z,1554826135.071 [logger](INFO): Join timeout helper Thread ID is 4043
2019-04-09T16:08:55.134Z,1554826135.134 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:08:55.134Z,1554826135.134 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:08:55.151Z,1554826135.151 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-04-09T16:08:55.151Z,1554826135.151 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:08:55.151Z,1554826135.151 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-04-09T16:08:55.151Z,1554826135.151 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:08:55.151Z,1554826135.151 [controlThread](INFO): Join timeout helper Thread ID is 4044
2019-04-09T16:08:55.322Z,1554826135.322 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T16:08:55.322Z,1554826135.322 [controlThread](DEBUG): Uninitializing ControlThread
2019-04-09T16:08:55.323Z,1554826135.323 [Aanderaa_O2](INFO): Powering down
2019-04-09T16:08:55.325Z,1554826135.325 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T16:08:55.325Z,1554826135.325 [NAL9602](INFO): Powering down
2019-04-09T16:08:55.327Z,1554826135.327 [BR_Ping1D](INFO): uninitialize
2019-04-09T16:08:55.328Z,1554826135.328 [BR_Ping1D](INFO): Powering down
2019-04-09T16:08:55.329Z,1554826135.329 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-04-09T16:08:55.329Z,1554826135.329 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-04-09T16:08:55.330Z,1554826135.330 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-04-09T16:08:55.330Z,1554826135.330 [MissionManager](INFO): Uninitializing Mission Default
2019-04-09T16:08:55.330Z,1554826135.330 [Default] Stopped
2019-04-09T16:08:55.330Z,1554826135.330 [Default](DEBUG): Aggregate::uninitialize Default
2019-04-09T16:08:55.330Z,1554826135.330 [Default:B.GoToSurface] Stopped
2019-04-09T16:08:55.331Z,1554826135.331 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-09T16:08:55.331Z,1554826135.331 [Default:CheckIn] Stopped
2019-04-09T16:08:55.331Z,1554826135.331 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-09T16:08:55.331Z,1554826135.331 [Default:CheckIn:Read_GPS] Stopped
2019-04-09T16:08:55.333Z,1554826135.333 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-04-09T16:08:55.333Z,1554826135.333 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-04-09T16:08:55.334Z,1554826135.334 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-04-09T16:08:55.334Z,1554826135.334 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-04-09T16:08:55.334Z,1554826135.334 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-04-09T16:08:55.335Z,1554826135.335 [BuoyancyServo](INFO): Powering down
2019-04-09T16:08:55.346Z,1554826135.346 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-09T16:08:55.346Z,1554826135.346 [ElevatorServo](INFO): Powering down
2019-04-09T16:08:55.347Z,1554826135.347 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T16:08:55.347Z,1554826135.347 [MassServo](INFO): Powering down
2019-04-09T16:08:55.348Z,1554826135.348 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-09T16:08:55.348Z,1554826135.348 [RudderServo](INFO): Powering down
2019-04-09T16:08:55.349Z,1554826135.349 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-04-09T16:08:55.349Z,1554826135.349 [ThrusterServo](INFO): Powering down
2019-04-09T16:08:55.350Z,1554826135.350 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-04-09T16:08:55.350Z,1554826135.350 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-04-09T16:08:55.350Z,1554826135.350 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-04-09T16:08:55.350Z,1554826135.350 [CBIT](DEBUG): Powering off loads.
2019-04-09T16:08:55.362Z,1554826135.362 [CBIT](DEBUG): Disabling WDT.
2019-04-09T16:08:55.374Z,1554826135.374 [CBIT](DEBUG): Opening all GF detection circuits.
2019-04-09T16:08:55.375Z,1554826135.375 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:08:55.538Z,1554826135.538 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:08:55.543Z,1554826135.543 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:08:55.614Z,1554826135.614 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:08:55.619Z,1554826135.619 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:08:55.671Z,1554826135.671 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T16:08:55.733Z,1554826135.733 [logger ThreadHandler](INFO): Thread cancelled.