2019-04-09T01:28:02.739Z,1554773282.739 [Supervisor](DEBUG): Initializing supervisor.
2019-04-09T01:28:02.742Z,1554773282.742 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-09T01:28:02.742Z,1554773282.742 [SyncHandler](INFO): Protected caller Thread ID is 5810
2019-04-09T01:28:02.743Z,1554773282.743 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-04-09T01:28:02.744Z,1554773282.744 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-04-09T01:28:02.744Z,1554773282.744 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5811
2019-04-09T01:28:02.747Z,1554773282.747 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-04-09T01:28:02.760Z,1554773282.760 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-04-09T01:28:02.761Z,1554773282.761 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-04-09T01:28:02.761Z,1554773282.761 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5812
2019-04-09T01:28:02.762Z,1554773282.762 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-04-09T01:28:02.763Z,1554773282.763 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-04-09T01:28:02.763Z,1554773282.763 [logger ThreadHandler](INFO): Protected caller Thread ID is 5813
2019-04-09T01:28:02.765Z,1554773282.765 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-04-09T01:28:02.766Z,1554773282.766 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-04-09T01:28:02.767Z,1554773282.767 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-04-09T01:28:02.861Z,1554773282.861 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-04-09T01:28:02.862Z,1554773282.862 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-04-09T01:28:03.061Z,1554773283.061 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-04-09T01:28:03.062Z,1554773283.062 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-04-09T01:28:03.197Z,1554773283.197 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-04-09T01:28:03.198Z,1554773283.198 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-04-09T01:28:03.653Z,1554773283.653 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-04-09T01:28:03.653Z,1554773283.653 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-04-09T01:28:04.091Z,1554773284.091 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-04-09T01:28:04.092Z,1554773284.092 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-04-09T01:28:04.556Z,1554773284.556 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-04-09T01:28:04.556Z,1554773284.556 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-04-09T01:28:04.861Z,1554773284.861 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-04-09T01:28:04.861Z,1554773284.861 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-04-09T01:28:05.190Z,1554773285.190 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-04-09T01:28:05.191Z,1554773285.191 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-04-09T01:28:05.583Z,1554773285.583 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-04-09T01:28:05.584Z,1554773285.584 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-04-09T01:28:05.732Z,1554773285.732 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-04-09T01:28:05.732Z,1554773285.732 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-04-09T01:28:05.836Z,1554773285.836 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-04-09T01:28:05.837Z,1554773285.837 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-04-09T01:28:05.917Z,1554773285.917 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-04-09T01:28:06.017Z,1554773286.017 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-04-09T01:28:06.017Z,1554773286.017 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-04-09T01:28:06.210Z,1554773286.210 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-04-09T01:28:06.211Z,1554773286.211 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-04-09T01:28:06.417Z,1554773286.417 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-04-09T01:28:06.419Z,1554773286.419 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-04-09T01:28:06.420Z,1554773286.420 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-04-09T01:28:06.502Z,1554773286.502 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-04-09T01:28:06.733Z,1554773286.733 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-04-09T01:28:06.734Z,1554773286.734 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-04-09T01:28:06.828Z,1554773286.828 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-04-09T01:28:07.017Z,1554773287.017 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-04-09T01:28:07.381Z,1554773287.381 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-04-09T01:28:07.628Z,1554773287.628 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-04-09T01:28:07.796Z,1554773287.796 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-04-09T01:28:07.924Z,1554773287.924 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-04-09T01:28:08.119Z,1554773288.119 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-04-09T01:28:08.223Z,1554773288.223 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-04-09T01:28:08.320Z,1554773288.320 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2019-04-09T01:28:08.321Z,1554773288.321 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-04-09T01:28:08.325Z,1554773288.325 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-04-09T01:28:08.422Z,1554773288.422 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-04-09T01:28:08.422Z,1554773288.422 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-04-09T01:28:08.544Z,1554773288.544 [BuoyancyServo] Loaded
2019-04-09T01:28:08.545Z,1554773288.545 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-04-09T01:28:08.560Z,1554773288.560 [ElevatorServo] Loaded
2019-04-09T01:28:08.560Z,1554773288.560 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-04-09T01:28:08.575Z,1554773288.575 [MassServo] Loaded
2019-04-09T01:28:08.575Z,1554773288.575 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-04-09T01:28:08.590Z,1554773288.590 [RudderServo] Loaded
2019-04-09T01:28:08.591Z,1554773288.591 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-04-09T01:28:08.605Z,1554773288.605 [ThrusterServo] Loaded
2019-04-09T01:28:08.605Z,1554773288.605 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-04-09T01:28:08.605Z,1554773288.605 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-04-09T01:28:08.606Z,1554773288.606 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-04-09T01:28:08.739Z,1554773288.739 [SBIT](DEBUG): Construct Startup Built In Test.
2019-04-09T01:28:08.767Z,1554773288.767 [SBIT] Loaded
2019-04-09T01:28:08.768Z,1554773288.768 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-04-09T01:28:08.768Z,1554773288.768 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-04-09T01:28:08.796Z,1554773288.796 [IBIT] Loaded
2019-04-09T01:28:08.796Z,1554773288.796 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-04-09T01:28:08.799Z,1554773288.799 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-04-09T01:28:09.182Z,1554773289.182 [CBIT] Loaded
2019-04-09T01:28:09.182Z,1554773289.182 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-04-09T01:28:09.183Z,1554773289.183 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-04-09T01:28:09.184Z,1554773289.184 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-04-09T01:28:09.364Z,1554773289.364 [Aanderaa_O2] Loaded
2019-04-09T01:28:09.364Z,1554773289.364 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-04-09T01:28:09.373Z,1554773289.373 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-04-09T01:28:09.379Z,1554773289.379 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-04-09T01:28:09.380Z,1554773289.380 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-04-09T01:28:09.385Z,1554773289.385 [CTD_Seabird](INFO): created writer for : depth
2019-04-09T01:28:09.386Z,1554773289.386 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-04-09T01:28:09.391Z,1554773289.391 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-04-09T01:28:09.392Z,1554773289.392 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-04-09T01:28:09.397Z,1554773289.397 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-04-09T01:28:09.397Z,1554773289.397 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-04-09T01:28:09.403Z,1554773289.403 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-04-09T01:28:09.403Z,1554773289.403 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-04-09T01:28:09.409Z,1554773289.409 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-04-09T01:28:09.409Z,1554773289.409 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-04-09T01:28:09.414Z,1554773289.414 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-04-09T01:28:09.440Z,1554773289.440 [CTD_Seabird] Loaded
2019-04-09T01:28:09.441Z,1554773289.441 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-04-09T01:28:09.442Z,1554773289.442 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406654E0
2019-04-09T01:28:09.442Z,1554773289.442 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5892
2019-04-09T01:28:09.468Z,1554773289.468 [ESPComponent] Loaded
2019-04-09T01:28:09.468Z,1554773289.468 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-04-09T01:28:09.482Z,1554773289.482 [PAR_Licor] Loaded
2019-04-09T01:28:09.482Z,1554773289.482 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-04-09T01:28:09.530Z,1554773289.530 [WetLabsBB2FL] Loaded
2019-04-09T01:28:09.530Z,1554773289.530 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-04-09T01:28:09.531Z,1554773289.531 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406954E0
2019-04-09T01:28:09.532Z,1554773289.532 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5893
2019-04-09T01:28:09.532Z,1554773289.532 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-04-09T01:28:09.533Z,1554773289.533 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-04-09T01:28:09.573Z,1554773289.573 [DepthRateCalculator] Loaded
2019-04-09T01:28:09.573Z,1554773289.573 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-04-09T01:28:09.579Z,1554773289.579 [PitchRateCalculator] Loaded
2019-04-09T01:28:09.579Z,1554773289.579 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-04-09T01:28:09.595Z,1554773289.595 [SpeedCalculator] Loaded
2019-04-09T01:28:09.595Z,1554773289.595 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-04-09T01:28:09.616Z,1554773289.616 [TempGradientCalculator] Loaded
2019-04-09T01:28:09.616Z,1554773289.616 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-04-09T01:28:09.622Z,1554773289.622 [YawRateCalculator] Loaded
2019-04-09T01:28:09.622Z,1554773289.622 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-04-09T01:28:09.655Z,1554773289.655 [ElevatorOffsetCalculator] Loaded
2019-04-09T01:28:09.655Z,1554773289.655 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-04-09T01:28:09.656Z,1554773289.656 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-04-09T01:28:09.656Z,1554773289.656 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-04-09T01:28:09.683Z,1554773289.683 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-04-09T01:28:09.684Z,1554773289.684 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-04-09T01:28:09.785Z,1554773289.785 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-04-09T01:28:09.785Z,1554773289.785 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-04-09T01:28:09.844Z,1554773289.844 [DeadReckonUsingSpeedCalculator] Loaded
2019-04-09T01:28:09.845Z,1554773289.845 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-04-09T01:28:09.904Z,1554773289.904 [DeadReckonWithRespectToSeafloor] Loaded
2019-04-09T01:28:09.904Z,1554773289.904 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-04-09T01:28:09.925Z,1554773289.925 [NavChart] Loaded
2019-04-09T01:28:09.925Z,1554773289.925 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-04-09T01:28:09.929Z,1554773289.929 [UniversalFixResidualReporter] Loaded
2019-04-09T01:28:09.929Z,1554773289.929 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-04-09T01:28:09.930Z,1554773289.930 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-04-09T01:28:09.930Z,1554773289.930 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-04-09T01:28:10.237Z,1554773290.237 [AcousticModem_Benthos_ATM900] Loaded
2019-04-09T01:28:10.237Z,1554773290.237 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-04-09T01:28:10.314Z,1554773290.314 [DataOverHttps] Loaded
2019-04-09T01:28:10.314Z,1554773290.314 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-04-09T01:28:10.315Z,1554773290.315 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409704E0
2019-04-09T01:28:10.315Z,1554773290.315 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5894
2019-04-09T01:28:10.328Z,1554773290.328 [Depth_Keller] Loaded
2019-04-09T01:28:10.329Z,1554773290.329 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-04-09T01:28:10.419Z,1554773290.419 [NAL9602] Loaded
2019-04-09T01:28:10.419Z,1554773290.419 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-04-09T01:28:10.425Z,1554773290.425 [Onboard] Loaded
2019-04-09T01:28:10.425Z,1554773290.425 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-04-09T01:28:10.428Z,1554773290.428 [Radio_Surface] Loaded
2019-04-09T01:28:10.429Z,1554773290.429 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-04-09T01:28:10.429Z,1554773290.429 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A04E0
2019-04-09T01:28:10.430Z,1554773290.430 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5895
2019-04-09T01:28:11.708Z,1554773291.708 [BPC1] Loaded
2019-04-09T01:28:11.709Z,1554773291.709 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-04-09T01:28:11.711Z,1554773291.711 [BR_Ping1D](INFO): constructor
2019-04-09T01:28:11.740Z,1554773291.740 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minrange
2019-04-09T01:28:11.742Z,1554773291.742 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.maxrange
2019-04-09T01:28:11.744Z,1554773291.744 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.soundspeed
2019-04-09T01:28:11.745Z,1554773291.745 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.automode
2019-04-09T01:28:11.748Z,1554773291.748 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.gainindex
2019-04-09T01:28:11.750Z,1554773291.750 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minconfidence
2019-04-09T01:28:11.752Z,1554773291.752 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.pingenable
2019-04-09T01:28:11.754Z,1554773291.754 [BR_Ping1D] Loaded
2019-04-09T01:28:11.754Z,1554773291.754 [ComponentRegistry](DEBUG): SyncComponent "BR_Ping1D" handled in the control thread.
2019-04-09T01:28:11.755Z,1554773291.755 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-04-09T01:28:11.755Z,1554773291.755 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-04-09T01:28:11.818Z,1554773291.818 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-04-09T01:28:11.819Z,1554773291.819 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-04-09T01:28:11.884Z,1554773291.884 [VerticalControl](DEBUG): Construct VerticalControl.
2019-04-09T01:28:11.967Z,1554773291.967 [VerticalControl] Loaded
2019-04-09T01:28:11.967Z,1554773291.967 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-04-09T01:28:11.968Z,1554773291.968 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-04-09T01:28:12.025Z,1554773292.025 [HorizontalControl] Loaded
2019-04-09T01:28:12.025Z,1554773292.025 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-04-09T01:28:12.026Z,1554773292.026 [SpeedControl](DEBUG): Construct SpeedControl.
2019-04-09T01:28:12.028Z,1554773292.028 [SpeedControl] Loaded
2019-04-09T01:28:12.028Z,1554773292.028 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-04-09T01:28:12.029Z,1554773292.029 [LoopControl](DEBUG): Construct LoopControl.
2019-04-09T01:28:12.029Z,1554773292.029 [LoopControl] Loaded
2019-04-09T01:28:12.030Z,1554773292.030 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-04-09T01:28:12.030Z,1554773292.030 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-04-09T01:28:12.031Z,1554773292.031 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-04-09T01:28:12.090Z,1554773292.090 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-04-09T01:28:12.090Z,1554773292.090 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-04-09T01:28:12.401Z,1554773292.401 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-04-09T01:28:12.405Z,1554773292.405 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-04-09T01:28:12.406Z,1554773292.406 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-04-09T01:28:12.413Z,1554773292.413 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-04-09T01:28:12.414Z,1554773292.414 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACD4E0
2019-04-09T01:28:12.414Z,1554773292.414 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5896
2019-04-09T01:28:12.419Z,1554773292.419 [Supervisor](INFO): Main Thread ID is 4347
2019-04-09T01:28:12.419Z,1554773292.419 [Supervisor](DEBUG): Running supervisor.
2019-04-09T01:28:12.420Z,1554773292.420 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5897
2019-04-09T01:28:12.422Z,1554773292.422 [controlThread ThreadHandler](INFO): Handler Thread ID is 5898
2019-04-09T01:28:12.422Z,1554773292.422 [controlThread](DEBUG): Initializing ControlThread
2019-04-09T01:28:12.424Z,1554773292.424 [SBIT](INFO): Initialize SBIT Component.
2019-04-09T01:28:12.425Z,1554773292.425 [SBIT](IMPORTANT): git: 2019-03-18-31-ge98d5dc
2019-04-09T01:28:12.425Z,1554773292.425 [SBIT](INFO): git hash: e98d5dc50cbc39e402f19b9946ff4dc0776c8725
2019-04-09T01:28:12.425Z,1554773292.425 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-04-09T01:28:12.427Z,1554773292.427 [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-09T01:28:12.428Z,1554773292.428 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-04-09T01:28:12.428Z,1554773292.428 [IBIT](INFO): Initialize IBIT Component.
2019-04-09T01:28:12.429Z,1554773292.429 [CBIT](DEBUG): Initialize CBIT Component.
2019-04-09T01:28:12.430Z,1554773292.430 [logger ThreadHandler](INFO): Handler Thread ID is 5899
2019-04-09T01:28:12.442Z,1554773292.442 [CBIT](DEBUG): Initialized mux pins.
2019-04-09T01:28:12.442Z,1554773292.442 [CBIT](DEBUG): Initializing the watchdog timer.
2019-04-09T01:28:12.450Z,1554773292.450 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5900
2019-04-09T01:28:12.451Z,1554773292.451 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T01:28:12.455Z,1554773292.455 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T01:28:12.456Z,1554773292.456 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5902
2019-04-09T01:28:12.457Z,1554773292.457 [WetLabsBB2FL](INFO): Powering down
2019-04-09T01:28:12.466Z,1554773292.466 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-04-09T01:28:12.467Z,1554773292.467 [CBIT](DEBUG): Initializing heartbeat.
2019-04-09T01:28:12.486Z,1554773292.486 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5903
2019-04-09T01:28:12.487Z,1554773292.487 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-04-09T01:28:12.496Z,1554773292.496 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5904
2019-04-09T01:28:12.514Z,1554773292.514 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5905
2019-04-09T01:28:12.517Z,1554773292.517 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-09T01:28:12.517Z,1554773292.517 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-04-09T01:28:12.518Z,1554773292.518 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-09T01:28:12.518Z,1554773292.518 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-04-09T01:28:12.518Z,1554773292.518 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-09T01:28:12.518Z,1554773292.518 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-04-09T01:28:12.519Z,1554773292.519 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-09T01:28:12.519Z,1554773292.519 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-04-09T01:28:12.519Z,1554773292.519 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-09T01:28:12.519Z,1554773292.519 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-04-09T01:28:12.519Z,1554773292.519 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-09T01:28:12.520Z,1554773292.520 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-04-09T01:28:12.520Z,1554773292.520 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-09T01:28:12.520Z,1554773292.520 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-04-09T01:28:12.520Z,1554773292.520 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-09T01:28:12.520Z,1554773292.520 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-04-09T01:28:12.538Z,1554773292.538 [CBIT](DEBUG): Deactivating GF circuits.
2019-04-09T01:28:12.538Z,1554773292.538 [CBIT](DEBUG): Deactivating emergency mode.
2019-04-09T01:28:12.574Z,1554773292.574 [CBIT](DEBUG): Backplane powered.
2019-04-09T01:28:12.578Z,1554773292.578 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-04-09T01:28:12.579Z,1554773292.579 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-04-09T01:28:12.579Z,1554773292.579 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-04-09T01:28:12.579Z,1554773292.579 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-04-09T01:28:12.580Z,1554773292.580 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-04-09T01:28:12.580Z,1554773292.580 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-04-09T01:28:12.581Z,1554773292.581 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T01:28:12.582Z,1554773292.582 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-04-09T01:28:12.583Z,1554773292.583 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-04-09T01:28:12.587Z,1554773292.587 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-04-09T01:28:12.589Z,1554773292.589 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-04-09T01:28:12.590Z,1554773292.590 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-04-09T01:28:12.591Z,1554773292.591 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-04-09T01:28:12.591Z,1554773292.591 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-04-09T01:28:12.619Z,1554773292.619 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T01:28:12.651Z,1554773292.651 [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-09T01:28:12.651Z,1554773292.651 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-04-09T01:28:12.741Z,1554773292.741 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-04-09T01:28:12.742Z,1554773292.742 [Default:A.Wait](DEBUG): Construct Wait.
2019-04-09T01:28:12.744Z,1554773292.744 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T01:28:12.802Z,1554773292.802 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-04-09T01:28:12.805Z,1554773292.805 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-04-09T01:28:12.830Z,1554773292.830 [Default:E.Execute](DEBUG): Construct Execute.
2019-04-09T01:28:12.834Z,1554773292.834 [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-09T01:28:12.866Z,1554773292.866 [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-09T01:28:12.883Z,1554773292.883 [ESPComponent](INFO): powering down ESP
2019-04-09T01:28:12.898Z,1554773292.898 [Radio_Surface](INFO): Powering up
2019-04-09T01:28:13.286Z,1554773293.286 [PAR_Licor](INFO): A/D timeout, 8 tries over 133 ms

2019-04-09T01:28:13.286Z,1554773293.286 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T01:28:13.287Z,1554773293.287 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:28:13.287Z,1554773293.287 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:28:13.287Z,1554773293.287 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:28:13.311Z,1554773293.311 [Depth_Keller](ERROR): Pressure reading out of range: 1895.684326 decibar
2019-04-09T01:28:13.357Z,1554773293.357 [BR_Ping1D](INFO): Start
2019-04-09T01:28:13.363Z,1554773293.363 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-04-09T01:28:13.417Z,1554773293.417 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:28:13.426Z,1554773293.426 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-04-09T01:28:13.428Z,1554773293.428 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:28:13.434Z,1554773293.434 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-09T01:28:13.435Z,1554773293.435 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:28:13.442Z,1554773293.442 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:28:13.443Z,1554773293.443 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:28:13.450Z,1554773293.450 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-09T01:28:13.451Z,1554773293.451 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:28:13.458Z,1554773293.458 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-04-09T01:28:13.461Z,1554773293.461 [CommandLine](FAULT): Scheduling is paused
2019-04-09T01:28:13.461Z,1554773293.461 [CBIT](INFO): Critical error at 20190409T012811
2019-04-09T01:28:13.461Z,1554773293.461 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-04-09T01:28:13.463Z,1554773293.463 [CBIT](FAULT): Main Battery Failure. Count: 1
2019-04-09T01:28:13.467Z,1554773293.467 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:28:13.467Z,1554773293.467 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:28:13.467Z,1554773293.467 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T01:28:13.580Z,1554773293.580 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:14.014Z,1554773294.014 [PAR_Licor](INFO): A/D timeout, 8 tries over 138 ms

2019-04-09T01:28:14.014Z,1554773294.014 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T01:28:14.014Z,1554773294.014 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:28:14.050Z,1554773294.050 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:14.051Z,1554773294.051 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:14.051Z,1554773294.051 [BR_Ping1D](INFO): XXXX automode1: 1
2019-04-09T01:28:14.051Z,1554773294.051 [BR_Ping1D](INFO): XXXX automode2: 1
2019-04-09T01:28:14.051Z,1554773294.051 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T01:28:14.062Z,1554773294.062 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T01:28:14.062Z,1554773294.062 [BR_Ping1D](INFO): packet size:10
2019-04-09T01:28:14.062Z,1554773294.062 [BR_Ping1D](INFO): 3636
2019-04-09T01:28:14.062Z,1554773294.062 [BR_Ping1D](INFO): 3832
2019-04-09T01:28:14.406Z,1554773294.406 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-04-09T01:28:14.406Z,1554773294.406 [RudderServo](FAULT): Rudder failed to initialize
2019-04-09T01:28:14.406Z,1554773294.406 [RudderServo] Communications Fault, FailCount= 1
2019-04-09T01:28:14.406Z,1554773294.406 [RudderServo](ERROR): Communications Fault
2019-04-09T01:28:14.517Z,1554773294.517 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-04-09T01:28:14.518Z,1554773294.518 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:28:14.518Z,1554773294.518 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:28:14.518Z,1554773294.518 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T01:28:14.552Z,1554773294.552 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:14.552Z,1554773294.552 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:14.552Z,1554773294.552 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:14.552Z,1554773294.552 [BR_Ping1D](INFO): XXXX automode2: 1
2019-04-09T01:28:14.553Z,1554773294.553 [BR_Ping1D](INFO): UART requesting status
2019-04-09T01:28:14.553Z,1554773294.553 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:28:14.553Z,1554773294.553 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:28:14.553Z,1554773294.553 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:28:14.703Z,1554773294.703 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-09T01:28:14.703Z,1554773294.703 [RudderServo](INFO): Powering down
2019-04-09T01:28:14.949Z,1554773294.949 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:14.949Z,1554773294.949 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:14.949Z,1554773294.949 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:14.950Z,1554773294.950 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:14.950Z,1554773294.950 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:14.950Z,1554773294.950 [BR_Ping1D](INFO): elapsed0.8986
2019-04-09T01:28:14.950Z,1554773294.950 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:14.951Z,1554773294.951 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:15.470Z,1554773295.470 [PAR_Licor](INFO): A/D timeout, 9 tries over 138 ms

2019-04-09T01:28:15.470Z,1554773295.470 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T01:28:15.470Z,1554773295.470 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:28:15.511Z,1554773295.511 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:15.511Z,1554773295.511 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:15.511Z,1554773295.511 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:15.511Z,1554773295.511 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:15.511Z,1554773295.511 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:15.512Z,1554773295.511 [BR_Ping1D](INFO): elapsed1.4600
2019-04-09T01:28:15.512Z,1554773295.512 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:15.512Z,1554773295.512 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:15.543Z,1554773295.543 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:28:15.663Z,1554773295.663 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-09T01:28:15.667Z,1554773295.667 [CBIT](INFO): Clearing failed state for component RudderServo
2019-04-09T01:28:15.667Z,1554773295.667 [RudderServo] No Fault, FailCount= 1
2019-04-09T01:28:15.667Z,1554773295.667 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:28:15.668Z,1554773295.668 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:28:15.668Z,1554773295.668 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T01:28:15.757Z,1554773295.757 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:15.758Z,1554773295.758 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:15.758Z,1554773295.758 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:15.758Z,1554773295.758 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:15.758Z,1554773295.758 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:15.758Z,1554773295.758 [BR_Ping1D](INFO): elapsed1.7070
2019-04-09T01:28:15.759Z,1554773295.759 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:15.759Z,1554773295.759 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:16.118Z,1554773296.118 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:28:16.118Z,1554773296.118 [MassServo] Communications Fault, FailCount= 1
2019-04-09T01:28:16.118Z,1554773296.118 [MassServo](ERROR): Communications Fault
2019-04-09T01:28:16.146Z,1554773296.146 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:28:16.193Z,1554773296.193 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:16.193Z,1554773296.193 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:16.193Z,1554773296.193 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:16.193Z,1554773296.193 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:16.193Z,1554773296.193 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:16.194Z,1554773296.194 [BR_Ping1D](INFO): elapsed2.1422
2019-04-09T01:28:16.194Z,1554773296.194 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:16.194Z,1554773296.194 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:16.270Z,1554773296.270 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:28:16.270Z,1554773296.270 [MassServo](INFO): Powering down
2019-04-09T01:28:16.577Z,1554773296.577 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:16.577Z,1554773296.577 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:16.578Z,1554773296.578 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:16.578Z,1554773296.578 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:16.578Z,1554773296.578 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:16.578Z,1554773296.578 [BR_Ping1D](INFO): elapsed2.5267
2019-04-09T01:28:16.578Z,1554773296.578 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:16.579Z,1554773296.579 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:17.102Z,1554773297.102 [PAR_Licor](INFO): A/D timeout, 9 tries over 142 ms

2019-04-09T01:28:17.102Z,1554773297.102 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T01:28:17.102Z,1554773297.102 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:28:17.138Z,1554773297.138 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:17.138Z,1554773297.138 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:17.139Z,1554773297.139 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:17.139Z,1554773297.139 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:17.139Z,1554773297.139 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:17.139Z,1554773297.139 [BR_Ping1D](INFO): elapsed3.0876
2019-04-09T01:28:17.139Z,1554773297.139 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:17.139Z,1554773297.139 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:17.252Z,1554773297.252 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:28:17.371Z,1554773297.371 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:28:17.526Z,1554773297.526 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T01:28:17.526Z,1554773297.526 [MassServo] No Fault, FailCount= 1
2019-04-09T01:28:17.527Z,1554773297.527 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:28:17.527Z,1554773297.527 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:28:17.527Z,1554773297.527 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T01:28:17.596Z,1554773297.596 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:17.596Z,1554773297.596 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:17.596Z,1554773297.596 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:17.596Z,1554773297.596 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:17.596Z,1554773297.596 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:17.597Z,1554773297.597 [BR_Ping1D](INFO): elapsed3.5452
2019-04-09T01:28:17.597Z,1554773297.597 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:17.597Z,1554773297.597 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:17.688Z,1554773297.688 [CBIT](FAULT): Main Battery Failure. Count: 11
2019-04-09T01:28:17.957Z,1554773297.957 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:17.958Z,1554773297.958 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:17.958Z,1554773297.958 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:17.958Z,1554773297.958 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:17.958Z,1554773297.958 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:17.958Z,1554773297.958 [BR_Ping1D](INFO): elapsed3.9070
2019-04-09T01:28:17.959Z,1554773297.959 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:17.959Z,1554773297.959 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:18.475Z,1554773298.475 [PAR_Licor](INFO): A/D timeout, 8 tries over 135 ms

2019-04-09T01:28:18.475Z,1554773298.475 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T01:28:18.475Z,1554773298.475 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:28:18.508Z,1554773298.508 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:18.509Z,1554773298.509 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:18.509Z,1554773298.509 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:18.509Z,1554773298.509 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:18.509Z,1554773298.509 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:18.509Z,1554773298.509 [BR_Ping1D](INFO): elapsed4.4579
2019-04-09T01:28:18.510Z,1554773298.510 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:18.510Z,1554773298.510 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:18.622Z,1554773298.622 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:28:18.623Z,1554773298.623 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:28:18.623Z,1554773298.623 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T01:28:18.766Z,1554773298.766 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:18.766Z,1554773298.766 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:18.766Z,1554773298.766 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:18.767Z,1554773298.767 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:18.767Z,1554773298.767 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:18.767Z,1554773298.767 [BR_Ping1D](INFO): elapsed4.7155
2019-04-09T01:28:18.767Z,1554773298.767 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:18.767Z,1554773298.767 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:19.290Z,1554773299.290 [PAR_Licor](INFO): A/D timeout, 9 tries over 143 ms

2019-04-09T01:28:19.290Z,1554773299.290 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T01:28:19.290Z,1554773299.290 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:28:19.325Z,1554773299.325 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:19.325Z,1554773299.325 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:19.325Z,1554773299.325 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:19.326Z,1554773299.326 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:19.326Z,1554773299.326 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:19.326Z,1554773299.326 [BR_Ping1D](INFO): elapsed5.2745
2019-04-09T01:28:19.326Z,1554773299.326 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:19.326Z,1554773299.326 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-09T01:28:19.326Z,1554773299.326 [BR_Ping1D](ERROR): Data Fault
2019-04-09T01:28:19.878Z,1554773299.878 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:28:19.878Z,1554773299.878 [MassServo] Communications Fault, FailCount= 2
2019-04-09T01:28:19.878Z,1554773299.878 [MassServo](ERROR): Communications Fault
2019-04-09T01:28:19.883Z,1554773299.883 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:28:19.883Z,1554773299.883 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:28:19.883Z,1554773299.883 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:28:19.883Z,1554773299.883 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T01:28:19.883Z,1554773299.883 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T01:28:19.913Z,1554773299.913 [BR_Ping1D](INFO): Stop
2019-04-09T01:28:19.914Z,1554773299.914 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:28:20.091Z,1554773300.091 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:28:20.091Z,1554773300.091 [MassServo](INFO): Powering down
2019-04-09T01:28:20.313Z,1554773300.313 [BR_Ping1D](INFO): Stopping
2019-04-09T01:28:20.830Z,1554773300.830 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-09T01:28:20.830Z,1554773300.830 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T01:28:20.830Z,1554773300.830 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:28:20.890Z,1554773300.890 [BR_Ping1D](INFO): Stopping
2019-04-09T01:28:20.931Z,1554773300.931 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:28:21.051Z,1554773301.051 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:28:21.055Z,1554773301.055 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T01:28:21.055Z,1554773301.055 [MassServo] No Fault, FailCount= 2
2019-04-09T01:28:21.056Z,1554773301.056 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:28:21.056Z,1554773301.056 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:28:21.056Z,1554773301.056 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T01:28:21.125Z,1554773301.125 [BR_Ping1D](INFO): Stopped
2019-04-09T01:28:21.168Z,1554773301.168 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T01:28:21.168Z,1554773301.168 [BR_Ping1D] No Fault, FailCount= 1
2019-04-09T01:28:21.642Z,1554773301.642 [PAR_Licor](INFO): A/D timeout, 9 tries over 139 ms

2019-04-09T01:28:21.642Z,1554773301.642 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T01:28:21.642Z,1554773301.642 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:28:21.683Z,1554773301.683 [BR_Ping1D](INFO): Start
2019-04-09T01:28:21.731Z,1554773301.731 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:28:21.731Z,1554773301.731 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:28:21.732Z,1554773301.732 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T01:28:21.930Z,1554773301.930 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:22.002Z,1554773302.002 [CBIT](FAULT): Main Battery Failure. Count: 21
2019-04-09T01:28:22.442Z,1554773302.442 [PAR_Licor](INFO): A/D timeout, 9 tries over 130 ms

2019-04-09T01:28:22.442Z,1554773302.442 [PAR_Licor] Data Fault, FailCount= 3
2019-04-09T01:28:22.442Z,1554773302.442 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:28:22.465Z,1554773302.465 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:22.465Z,1554773302.465 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:22.466Z,1554773302.466 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:22.466Z,1554773302.466 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:22.466Z,1554773302.466 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T01:28:22.478Z,1554773302.478 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T01:28:22.478Z,1554773302.478 [BR_Ping1D](INFO): packet size:10
2019-04-09T01:28:22.478Z,1554773302.478 [BR_Ping1D](INFO): 3636
2019-04-09T01:28:22.478Z,1554773302.478 [BR_Ping1D](INFO): 3832
2019-04-09T01:28:22.519Z,1554773302.519 [CBIT](CRITICAL): Environmental Failure. Press:14.864676 PSI. Humidity:44%. Temp:26 C. ABORTING MISSION
2019-04-09T01:28:22.520Z,1554773302.520 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:28:22.520Z,1554773302.520 [CBIT](CRITICAL): Data Fault in component: PAR_Licor
2019-04-09T01:28:22.733Z,1554773302.733 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:22.745Z,1554773302.745 [CBIT](INFO): Critical error at 20190409T012822
2019-04-09T01:28:23.118Z,1554773303.118 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T01:28:23.118Z,1554773303.118 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T01:28:23.119Z,1554773303.119 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T01:28:23.142Z,1554773303.142 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:23.142Z,1554773303.142 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:23.142Z,1554773303.142 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:23.143Z,1554773303.143 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:23.143Z,1554773303.143 [BR_Ping1D](INFO): UART requesting status
2019-04-09T01:28:23.143Z,1554773303.143 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:28:23.143Z,1554773303.143 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:28:23.143Z,1554773303.143 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:28:23.490Z,1554773303.490 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:28:23.490Z,1554773303.490 [MassServo] Communications Fault, FailCount= 3
2019-04-09T01:28:23.490Z,1554773303.490 [MassServo](ERROR): Communications Fault
2019-04-09T01:28:23.495Z,1554773303.495 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:28:23.495Z,1554773303.495 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T01:28:23.642Z,1554773303.642 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:28:23.716Z,1554773303.716 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:23.716Z,1554773303.716 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:23.716Z,1554773303.716 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:23.717Z,1554773303.717 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:23.717Z,1554773303.717 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:23.717Z,1554773303.717 [BR_Ping1D](INFO): elapsed1.2509
2019-04-09T01:28:23.717Z,1554773303.717 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:23.717Z,1554773303.717 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:23.743Z,1554773303.743 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:28:23.743Z,1554773303.743 [MassServo](INFO): Powering down
2019-04-09T01:28:23.946Z,1554773303.946 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:23.946Z,1554773303.946 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:23.946Z,1554773303.946 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:23.946Z,1554773303.946 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:23.946Z,1554773303.946 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:23.947Z,1554773303.947 [BR_Ping1D](INFO): elapsed1.4806
2019-04-09T01:28:23.947Z,1554773303.947 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:23.947Z,1554773303.947 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:24.349Z,1554773304.349 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:24.349Z,1554773304.349 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:24.349Z,1554773304.349 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:24.350Z,1554773304.350 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:24.350Z,1554773304.350 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:24.350Z,1554773304.350 [BR_Ping1D](INFO): elapsed1.8842
2019-04-09T01:28:24.350Z,1554773304.350 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:24.351Z,1554773304.351 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:24.363Z,1554773304.363 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:28:24.483Z,1554773304.483 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:28:24.487Z,1554773304.487 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T01:28:24.488Z,1554773304.488 [MassServo] No Fault, FailCount= 3
2019-04-09T01:28:24.488Z,1554773304.488 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T01:28:24.488Z,1554773304.488 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-09T01:28:24.754Z,1554773304.754 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:24.754Z,1554773304.754 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:24.754Z,1554773304.754 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:24.755Z,1554773304.755 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:24.755Z,1554773304.755 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:24.755Z,1554773304.755 [BR_Ping1D](INFO): elapsed2.2890
2019-04-09T01:28:24.755Z,1554773304.755 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:24.755Z,1554773304.755 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:25.169Z,1554773305.169 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:25.170Z,1554773305.170 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:25.170Z,1554773305.170 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:25.170Z,1554773305.170 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:25.170Z,1554773305.170 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:25.170Z,1554773305.170 [BR_Ping1D](INFO): elapsed2.7044
2019-04-09T01:28:25.171Z,1554773305.171 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:25.171Z,1554773305.171 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:25.502Z,1554773305.502 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T01:28:25.502Z,1554773305.502 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-09T01:28:25.502Z,1554773305.502 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T01:28:25.562Z,1554773305.562 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:25.563Z,1554773305.563 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:25.563Z,1554773305.563 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:25.563Z,1554773305.563 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:25.563Z,1554773305.563 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:25.563Z,1554773305.563 [BR_Ping1D](INFO): elapsed3.0973
2019-04-09T01:28:25.563Z,1554773305.563 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:25.564Z,1554773305.564 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:25.607Z,1554773305.607 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T01:28:25.906Z,1554773305.906 [CTD_Seabird](INFO): Powering down
2019-04-09T01:28:25.970Z,1554773305.970 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:25.970Z,1554773305.970 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:25.970Z,1554773305.970 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:25.970Z,1554773305.970 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:25.971Z,1554773305.971 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:25.971Z,1554773305.971 [BR_Ping1D](INFO): elapsed3.5048
2019-04-09T01:28:25.971Z,1554773305.971 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:25.971Z,1554773305.971 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:25.994Z,1554773305.994 [CBIT](FAULT): Main Battery Failure. Count: 31
2019-04-09T01:28:26.370Z,1554773306.370 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:26.370Z,1554773306.370 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:26.370Z,1554773306.370 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:26.370Z,1554773306.370 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:26.371Z,1554773306.371 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:26.371Z,1554773306.371 [BR_Ping1D](INFO): elapsed3.9047
2019-04-09T01:28:26.371Z,1554773306.371 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:26.371Z,1554773306.371 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:26.773Z,1554773306.773 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:26.773Z,1554773306.773 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:26.774Z,1554773306.774 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:26.774Z,1554773306.774 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:26.774Z,1554773306.774 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:26.774Z,1554773306.774 [BR_Ping1D](INFO): elapsed4.3083
2019-04-09T01:28:26.774Z,1554773306.774 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:26.775Z,1554773306.775 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:27.098Z,1554773307.098 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:28:27.098Z,1554773307.098 [MassServo] Communications Fault, FailCount= 4
2019-04-09T01:28:27.098Z,1554773307.098 [MassServo](ERROR): Communications Fault
2019-04-09T01:28:27.102Z,1554773307.102 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:28:27.103Z,1554773307.103 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T01:28:27.103Z,1554773307.103 [CTD_Seabird] No Fault, FailCount= 1
2019-04-09T01:28:27.182Z,1554773307.182 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:27.182Z,1554773307.182 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:27.183Z,1554773307.183 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:27.183Z,1554773307.183 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:27.183Z,1554773307.183 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:27.183Z,1554773307.183 [BR_Ping1D](INFO): elapsed4.7172
2019-04-09T01:28:27.183Z,1554773307.183 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:27.184Z,1554773307.184 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:27.203Z,1554773307.203 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:28:27.203Z,1554773307.203 [MassServo](INFO): Powering down
2019-04-09T01:28:27.326Z,1554773307.326 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T01:28:27.328Z,1554773307.328 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T01:28:27.586Z,1554773307.586 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:27.586Z,1554773307.586 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:27.586Z,1554773307.586 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:27.587Z,1554773307.587 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:27.587Z,1554773307.587 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:27.587Z,1554773307.587 [BR_Ping1D](INFO): elapsed5.1209
2019-04-09T01:28:27.587Z,1554773307.587 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:27.587Z,1554773307.587 [BR_Ping1D] Data Fault, FailCount= 2
2019-04-09T01:28:27.587Z,1554773307.587 [BR_Ping1D](ERROR): Data Fault
2019-04-09T01:28:27.650Z,1554773307.650 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T01:28:27.986Z,1554773307.986 [BR_Ping1D](INFO): Stop
2019-04-09T01:28:27.986Z,1554773307.986 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:28:28.080Z,1554773308.080 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:28:28.199Z,1554773308.199 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:28:28.203Z,1554773308.203 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T01:28:28.203Z,1554773308.203 [MassServo] No Fault, FailCount= 4
2019-04-09T01:28:28.390Z,1554773308.390 [BR_Ping1D](INFO): Stopping
2019-04-09T01:28:28.794Z,1554773308.794 [BR_Ping1D](INFO): Stopping
2019-04-09T01:28:29.197Z,1554773309.197 [BR_Ping1D](INFO): Stopped
2019-04-09T01:28:29.251Z,1554773309.251 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T01:28:29.251Z,1554773309.251 [BR_Ping1D] No Fault, FailCount= 2
2019-04-09T01:28:29.603Z,1554773309.603 [BR_Ping1D](INFO): Start
2019-04-09T01:28:30.006Z,1554773310.006 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:30.108Z,1554773310.108 [CBIT](FAULT): Main Battery Failure. Count: 41
2019-04-09T01:28:30.410Z,1554773310.410 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:30.410Z,1554773310.410 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:30.411Z,1554773310.411 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:30.411Z,1554773310.411 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:30.411Z,1554773310.411 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T01:28:30.422Z,1554773310.422 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T01:28:30.422Z,1554773310.422 [BR_Ping1D](INFO): packet size:10
2019-04-09T01:28:30.422Z,1554773310.422 [BR_Ping1D](INFO): 3636
2019-04-09T01:28:30.422Z,1554773310.422 [BR_Ping1D](INFO): 3832
2019-04-09T01:28:30.790Z,1554773310.790 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:28:30.790Z,1554773310.790 [MassServo] Communications Fault, FailCount= 5
2019-04-09T01:28:30.790Z,1554773310.790 [MassServo](ERROR): Communications Fault
2019-04-09T01:28:30.795Z,1554773310.795 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:28:30.795Z,1554773310.795 [CBIT](CRITICAL): Communications Fault in component: MassServo
2019-04-09T01:28:30.823Z,1554773310.823 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:30.891Z,1554773310.891 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:28:30.891Z,1554773310.891 [MassServo](INFO): Powering down
2019-04-09T01:28:30.914Z,1554773310.914 [CBIT](INFO): Critical error at 20190409T012830
2019-04-09T01:28:31.222Z,1554773311.222 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:31.222Z,1554773311.222 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:31.222Z,1554773311.222 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:31.222Z,1554773311.222 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:31.222Z,1554773311.222 [BR_Ping1D](INFO): UART requesting status
2019-04-09T01:28:31.222Z,1554773311.223 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:28:31.223Z,1554773311.223 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:28:31.223Z,1554773311.223 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:28:31.625Z,1554773311.625 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:31.625Z,1554773311.625 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:31.626Z,1554773311.626 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:31.626Z,1554773311.626 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:31.626Z,1554773311.626 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:31.626Z,1554773311.626 [BR_Ping1D](INFO): elapsed1.2151
2019-04-09T01:28:31.626Z,1554773311.626 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:31.627Z,1554773311.627 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:31.651Z,1554773311.651 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:28:31.771Z,1554773311.771 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:28:32.030Z,1554773312.030 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:32.030Z,1554773312.030 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:32.031Z,1554773312.031 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:32.031Z,1554773312.031 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:32.031Z,1554773312.031 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:32.031Z,1554773312.031 [BR_Ping1D](INFO): elapsed1.6200
2019-04-09T01:28:32.031Z,1554773312.031 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:32.032Z,1554773312.032 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:32.434Z,1554773312.434 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:32.434Z,1554773312.434 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:32.435Z,1554773312.435 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:32.435Z,1554773312.435 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:32.435Z,1554773312.435 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:32.435Z,1554773312.435 [BR_Ping1D](INFO): elapsed2.0240
2019-04-09T01:28:32.435Z,1554773312.435 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:32.436Z,1554773312.436 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:32.837Z,1554773312.837 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:32.838Z,1554773312.838 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:32.838Z,1554773312.838 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:32.838Z,1554773312.838 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:32.838Z,1554773312.838 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:32.838Z,1554773312.838 [BR_Ping1D](INFO): elapsed2.4273
2019-04-09T01:28:32.839Z,1554773312.839 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:32.839Z,1554773312.839 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:33.242Z,1554773313.242 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:33.242Z,1554773313.242 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:33.242Z,1554773313.242 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:33.242Z,1554773313.242 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:33.242Z,1554773313.242 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:33.243Z,1554773313.243 [BR_Ping1D](INFO): elapsed2.8315
2019-04-09T01:28:33.243Z,1554773313.243 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:33.243Z,1554773313.243 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:33.628Z,1554773313.628 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:28:33.628Z,1554773313.628 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-04-09T01:28:33.628Z,1554773313.628 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:28:33.645Z,1554773313.645 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:33.645Z,1554773313.645 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:33.646Z,1554773313.646 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:33.646Z,1554773313.646 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:33.646Z,1554773313.646 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:33.646Z,1554773313.646 [BR_Ping1D](INFO): elapsed3.2349
2019-04-09T01:28:33.646Z,1554773313.646 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:33.647Z,1554773313.647 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:33.675Z,1554773313.675 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:28:34.031Z,1554773314.031 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:28:34.190Z,1554773314.190 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:34.190Z,1554773314.190 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:34.190Z,1554773314.190 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:34.190Z,1554773314.190 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:34.190Z,1554773314.190 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:34.191Z,1554773314.191 [BR_Ping1D](INFO): elapsed3.7794
2019-04-09T01:28:34.191Z,1554773314.191 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:34.191Z,1554773314.191 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:34.220Z,1554773314.220 [CBIT](FAULT): Main Battery Failure. Count: 51
2019-04-09T01:28:34.467Z,1554773314.467 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:34.467Z,1554773314.467 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:34.467Z,1554773314.467 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:34.468Z,1554773314.468 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:34.468Z,1554773314.468 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:34.468Z,1554773314.468 [BR_Ping1D](INFO): elapsed4.0567
2019-04-09T01:28:34.468Z,1554773314.468 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:34.468Z,1554773314.468 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:34.838Z,1554773314.838 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T01:28:34.838Z,1554773314.838 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-09T01:28:34.838Z,1554773314.838 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T01:28:34.858Z,1554773314.858 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:34.858Z,1554773314.858 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:34.859Z,1554773314.859 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:34.859Z,1554773314.859 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:34.859Z,1554773314.859 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:34.859Z,1554773314.859 [BR_Ping1D](INFO): elapsed4.4478
2019-04-09T01:28:34.859Z,1554773314.859 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:34.859Z,1554773314.859 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:34.875Z,1554773314.875 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T01:28:34.875Z,1554773314.875 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:28:34.875Z,1554773314.875 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-04-09T01:28:35.362Z,1554773315.362 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:28:35.379Z,1554773315.379 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:35.380Z,1554773315.380 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:35.380Z,1554773315.380 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:35.380Z,1554773315.380 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:35.380Z,1554773315.380 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:35.380Z,1554773315.380 [BR_Ping1D](INFO): elapsed4.9691
2019-04-09T01:28:35.380Z,1554773315.380 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:35.381Z,1554773315.381 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:35.665Z,1554773315.665 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:35.665Z,1554773315.665 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:35.666Z,1554773315.666 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:35.666Z,1554773315.666 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:35.666Z,1554773315.666 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:35.667Z,1554773315.667 [BR_Ping1D](INFO): elapsed5.2554
2019-04-09T01:28:35.667Z,1554773315.667 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:35.667Z,1554773315.667 [BR_Ping1D] Data Fault, FailCount= 3
2019-04-09T01:28:35.667Z,1554773315.667 [BR_Ping1D](ERROR): Data Fault
2019-04-09T01:28:35.691Z,1554773315.691 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T01:28:36.070Z,1554773316.070 [BR_Ping1D](INFO): Stop
2019-04-09T01:28:36.070Z,1554773316.070 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:28:36.169Z,1554773316.169 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T01:28:36.170Z,1554773316.170 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-09T01:28:36.473Z,1554773316.473 [BR_Ping1D](INFO): Stopping
2019-04-09T01:28:36.891Z,1554773316.891 [BR_Ping1D](INFO): Stopping
2019-04-09T01:28:36.904Z,1554773316.904 [SBIT](IMPORTANT): Beginning Startup BIT
2019-04-09T01:28:36.913Z,1554773316.913 [CBIT](IMPORTANT): Beginning ground fault scan
2019-04-09T01:28:37.262Z,1554773317.262 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:28:37.263Z,1554773317.263 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:28:37.309Z,1554773317.309 [BR_Ping1D](INFO): Stopped
2019-04-09T01:28:37.411Z,1554773317.411 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T01:28:37.411Z,1554773317.411 [BR_Ping1D] No Fault, FailCount= 3
2019-04-09T01:28:37.685Z,1554773317.685 [BR_Ping1D](INFO): Start
2019-04-09T01:28:38.089Z,1554773318.089 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:38.174Z,1554773318.174 [CBIT](FAULT): Main Battery Failure. Count: 61
2019-04-09T01:28:38.494Z,1554773318.494 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:38.494Z,1554773318.494 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:38.494Z,1554773318.494 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:38.494Z,1554773318.494 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:38.494Z,1554773318.494 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T01:28:38.506Z,1554773318.506 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T01:28:38.506Z,1554773318.506 [BR_Ping1D](INFO): packet size:10
2019-04-09T01:28:38.506Z,1554773318.506 [BR_Ping1D](INFO): 3636
2019-04-09T01:28:38.506Z,1554773318.506 [BR_Ping1D](INFO): 3832
2019-04-09T01:28:38.897Z,1554773318.897 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:39.302Z,1554773319.302 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:39.302Z,1554773319.302 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:39.302Z,1554773319.302 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:39.302Z,1554773319.302 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:39.302Z,1554773319.302 [BR_Ping1D](INFO): UART requesting status
2019-04-09T01:28:39.303Z,1554773319.303 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:28:39.304Z,1554773319.304 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:28:39.305Z,1554773319.305 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:28:39.707Z,1554773319.707 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:39.707Z,1554773319.707 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:39.708Z,1554773319.708 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:39.708Z,1554773319.708 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:39.708Z,1554773319.708 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:39.708Z,1554773319.708 [BR_Ping1D](INFO): elapsed1.2136
2019-04-09T01:28:39.708Z,1554773319.708 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:39.708Z,1554773319.708 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:40.113Z,1554773320.113 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:40.113Z,1554773320.113 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:40.113Z,1554773320.113 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:40.113Z,1554773320.113 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:40.114Z,1554773320.114 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:40.114Z,1554773320.114 [BR_Ping1D](INFO): elapsed1.6193
2019-04-09T01:28:40.114Z,1554773320.114 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:40.114Z,1554773320.114 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:40.378Z,1554773320.378 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T01:28:40.378Z,1554773320.378 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-09T01:28:40.378Z,1554773320.378 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T01:28:40.499Z,1554773320.499 [NAL9602](INFO): Powering up NAL9602
2019-04-09T01:28:40.515Z,1554773320.515 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:40.515Z,1554773320.515 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:40.515Z,1554773320.515 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:40.515Z,1554773320.515 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:40.515Z,1554773320.515 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:40.516Z,1554773320.516 [BR_Ping1D](INFO): elapsed2.0211
2019-04-09T01:28:40.516Z,1554773320.516 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:40.516Z,1554773320.516 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:40.715Z,1554773320.715 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T01:28:40.782Z,1554773320.782 [CTD_Seabird](INFO): Powering down
2019-04-09T01:28:40.919Z,1554773320.919 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:40.919Z,1554773320.919 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:40.919Z,1554773320.919 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:40.919Z,1554773320.919 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:40.919Z,1554773320.919 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:40.919Z,1554773320.919 [BR_Ping1D](INFO): elapsed2.4249
2019-04-09T01:28:40.920Z,1554773320.920 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:40.920Z,1554773320.920 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:41.322Z,1554773321.322 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:41.322Z,1554773321.322 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:41.322Z,1554773321.322 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:41.322Z,1554773321.322 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:41.322Z,1554773321.322 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:41.322Z,1554773321.322 [BR_Ping1D](INFO): elapsed2.8280
2019-04-09T01:28:41.323Z,1554773321.323 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:41.323Z,1554773321.323 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:41.727Z,1554773321.727 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:41.727Z,1554773321.727 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:41.727Z,1554773321.727 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:41.727Z,1554773321.727 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:41.727Z,1554773321.727 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:41.728Z,1554773321.728 [BR_Ping1D](INFO): elapsed3.2332
2019-04-09T01:28:41.728Z,1554773321.728 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:41.728Z,1554773321.728 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:41.859Z,1554773321.859 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T01:28:41.859Z,1554773321.859 [CTD_Seabird] No Fault, FailCount= 2
2019-04-09T01:28:42.126Z,1554773322.126 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T01:28:42.128Z,1554773322.128 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T01:28:42.132Z,1554773322.132 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:42.132Z,1554773322.132 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:42.132Z,1554773322.132 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:42.132Z,1554773322.132 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:42.132Z,1554773322.132 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:42.132Z,1554773322.132 [BR_Ping1D](INFO): elapsed3.6379
2019-04-09T01:28:42.133Z,1554773322.133 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:42.133Z,1554773322.133 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:42.146Z,1554773322.146 [CBIT](FAULT): Main Battery Failure. Count: 71
2019-04-09T01:28:42.533Z,1554773322.533 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:42.534Z,1554773322.534 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:42.534Z,1554773322.534 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:42.534Z,1554773322.534 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:42.534Z,1554773322.534 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:42.534Z,1554773322.534 [BR_Ping1D](INFO): elapsed4.0399
2019-04-09T01:28:42.535Z,1554773322.535 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:42.535Z,1554773322.535 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:42.937Z,1554773322.937 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:42.937Z,1554773322.937 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:42.938Z,1554773322.938 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:42.938Z,1554773322.938 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:42.938Z,1554773322.938 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:42.938Z,1554773322.938 [BR_Ping1D](INFO): elapsed4.4438
2019-04-09T01:28:42.938Z,1554773322.938 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:42.939Z,1554773322.939 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:43.342Z,1554773323.342 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:43.342Z,1554773323.342 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:43.342Z,1554773323.342 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:43.342Z,1554773323.342 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:43.343Z,1554773323.343 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:43.343Z,1554773323.343 [BR_Ping1D](INFO): elapsed4.8483
2019-04-09T01:28:43.343Z,1554773323.343 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:43.343Z,1554773323.343 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:43.747Z,1554773323.747 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:43.748Z,1554773323.748 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:43.748Z,1554773323.748 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:43.748Z,1554773323.748 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:43.748Z,1554773323.748 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:43.748Z,1554773323.748 [BR_Ping1D](INFO): elapsed5.2539
2019-04-09T01:28:43.749Z,1554773323.749 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:43.749Z,1554773323.749 [BR_Ping1D] Data Fault, FailCount= 4
2019-04-09T01:28:43.749Z,1554773323.749 [BR_Ping1D](ERROR): Data Fault
2019-04-09T01:28:43.764Z,1554773323.764 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T01:28:44.162Z,1554773324.162 [BR_Ping1D](INFO): Stop
2019-04-09T01:28:44.162Z,1554773324.162 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:28:44.553Z,1554773324.553 [BR_Ping1D](INFO): Stopping
2019-04-09T01:28:44.957Z,1554773324.957 [BR_Ping1D](INFO): Stopping
2019-04-09T01:28:45.361Z,1554773325.361 [BR_Ping1D](INFO): Stopped
2019-04-09T01:28:45.388Z,1554773325.388 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T01:28:45.388Z,1554773325.388 [BR_Ping1D] No Fault, FailCount= 4
2019-04-09T01:28:45.765Z,1554773325.765 [BR_Ping1D](INFO): Start
2019-04-09T01:28:46.169Z,1554773326.169 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:46.181Z,1554773326.181 [CBIT](FAULT): Main Battery Failure. Count: 81
2019-04-09T01:28:46.554Z,1554773326.554 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T01:28:46.555Z,1554773326.555 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-09T01:28:46.555Z,1554773326.555 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T01:28:46.575Z,1554773326.575 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:46.575Z,1554773326.575 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:46.575Z,1554773326.575 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:46.575Z,1554773326.575 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:46.575Z,1554773326.575 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T01:28:46.586Z,1554773326.586 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T01:28:46.586Z,1554773326.586 [BR_Ping1D](INFO): packet size:10
2019-04-09T01:28:46.586Z,1554773326.586 [BR_Ping1D](INFO): 3636
2019-04-09T01:28:46.586Z,1554773326.586 [BR_Ping1D](INFO): 3832
2019-04-09T01:28:46.620Z,1554773326.620 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T01:28:46.620Z,1554773326.620 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-09T01:28:47.078Z,1554773327.078 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:28:47.102Z,1554773327.102 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:47.103Z,1554773327.103 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:47.103Z,1554773327.103 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:47.103Z,1554773327.103 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:47.103Z,1554773327.103 [BR_Ping1D](INFO): UART requesting status
2019-04-09T01:28:47.103Z,1554773327.103 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:28:47.104Z,1554773327.104 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:28:47.104Z,1554773327.104 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:28:47.382Z,1554773327.382 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:47.382Z,1554773327.382 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:47.382Z,1554773327.382 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:47.382Z,1554773327.382 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:47.382Z,1554773327.382 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:47.383Z,1554773327.383 [BR_Ping1D](INFO): elapsed0.8072
2019-04-09T01:28:47.383Z,1554773327.383 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:47.383Z,1554773327.383 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:47.786Z,1554773327.786 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:47.786Z,1554773327.786 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:47.786Z,1554773327.786 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:47.786Z,1554773327.786 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:47.786Z,1554773327.786 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:47.786Z,1554773327.786 [BR_Ping1D](INFO): elapsed1.2111
2019-04-09T01:28:47.787Z,1554773327.787 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:47.787Z,1554773327.787 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:47.844Z,1554773327.844 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.006884
CHAN A1 (24V): -0.127499
CHAN A2 (12V): -0.005328
CHAN A3 (5V): -0.002807
CHAN B0 (3.3V): -0.001202
CHAN B1 (3.15aV): -0.001052
CHAN B2 (3.15bV): -0.000814
CHAN B3 (GND): -0.000235
OPEN: -0.000947
Full Scale Calc: 4.765 mA, -1.589 mA
2019-04-09T01:28:48.190Z,1554773328.190 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:48.190Z,1554773328.190 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:48.190Z,1554773328.190 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:48.190Z,1554773328.190 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:48.190Z,1554773328.190 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:48.191Z,1554773328.191 [BR_Ping1D](INFO): elapsed1.6153
2019-04-09T01:28:48.191Z,1554773328.191 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:48.191Z,1554773328.191 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:48.593Z,1554773328.593 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:48.593Z,1554773328.593 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:48.594Z,1554773328.594 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:48.594Z,1554773328.594 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:48.594Z,1554773328.594 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:48.594Z,1554773328.594 [BR_Ping1D](INFO): elapsed2.0189
2019-04-09T01:28:48.594Z,1554773328.594 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:48.595Z,1554773328.595 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:49.006Z,1554773329.006 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:49.006Z,1554773329.006 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:49.006Z,1554773329.006 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:49.006Z,1554773329.006 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:49.007Z,1554773329.007 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:49.007Z,1554773329.007 [BR_Ping1D](INFO): elapsed2.4315
2019-04-09T01:28:49.007Z,1554773329.007 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:49.007Z,1554773329.007 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:49.401Z,1554773329.401 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:49.401Z,1554773329.401 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:49.402Z,1554773329.402 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:49.402Z,1554773329.402 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:49.402Z,1554773329.402 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:49.402Z,1554773329.402 [BR_Ping1D](INFO): elapsed2.8269
2019-04-09T01:28:49.402Z,1554773329.402 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:49.403Z,1554773329.403 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:49.804Z,1554773329.804 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:49.804Z,1554773329.804 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:49.804Z,1554773329.804 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:49.804Z,1554773329.804 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:49.804Z,1554773329.804 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:49.805Z,1554773329.805 [BR_Ping1D](INFO): elapsed3.2293
2019-04-09T01:28:49.805Z,1554773329.805 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:49.805Z,1554773329.805 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:50.212Z,1554773330.212 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:50.212Z,1554773330.212 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:50.212Z,1554773330.212 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:50.212Z,1554773330.212 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:50.213Z,1554773330.213 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:50.213Z,1554773330.213 [BR_Ping1D](INFO): elapsed3.6374
2019-04-09T01:28:50.213Z,1554773330.213 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:50.213Z,1554773330.213 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:50.229Z,1554773330.229 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T01:28:50.234Z,1554773330.234 [CBIT](FAULT): Main Battery Failure. Count: 91
2019-04-09T01:28:50.613Z,1554773330.613 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:50.621Z,1554773330.621 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:50.622Z,1554773330.622 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:50.622Z,1554773330.622 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:50.622Z,1554773330.622 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:50.622Z,1554773330.622 [BR_Ping1D](INFO): elapsed4.0468
2019-04-09T01:28:50.622Z,1554773330.622 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:50.623Z,1554773330.623 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:51.029Z,1554773331.029 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:51.029Z,1554773331.029 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:51.029Z,1554773331.029 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:51.029Z,1554773331.029 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:51.029Z,1554773331.029 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:51.029Z,1554773331.029 [BR_Ping1D](INFO): elapsed4.4541
2019-04-09T01:28:51.047Z,1554773331.047 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:51.048Z,1554773331.048 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:51.407Z,1554773331.407 [NAL9602](INFO): NAL9602 initialized
2019-04-09T01:28:51.422Z,1554773331.422 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:51.422Z,1554773331.422 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:51.423Z,1554773331.423 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:51.423Z,1554773331.423 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:51.423Z,1554773331.423 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:51.423Z,1554773331.423 [BR_Ping1D](INFO): elapsed4.8477
2019-04-09T01:28:51.423Z,1554773331.423 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:51.423Z,1554773331.423 [BR_Ping1D](INFO): No UART data
2019-04-09T01:28:51.834Z,1554773331.834 [BR_Ping1D](INFO): Starting
2019-04-09T01:28:51.835Z,1554773331.835 [BR_Ping1D](INFO): readConfig
2019-04-09T01:28:51.835Z,1554773331.835 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:28:51.835Z,1554773331.835 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:28:51.835Z,1554773331.835 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:28:51.835Z,1554773331.835 [BR_Ping1D](INFO): elapsed5.2599
2019-04-09T01:28:51.835Z,1554773331.835 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:28:51.836Z,1554773331.836 [BR_Ping1D] Data Fault, FailCount= 5
2019-04-09T01:28:51.836Z,1554773331.836 [BR_Ping1D](ERROR): Data Fault
2019-04-09T01:28:51.852Z,1554773331.852 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T01:28:51.852Z,1554773331.852 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-04-09T01:28:52.226Z,1554773332.226 [NAL9602](DEBUG): Fix Requested
2019-04-09T01:28:52.241Z,1554773332.241 [BR_Ping1D](INFO): Stop
2019-04-09T01:28:52.241Z,1554773332.241 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:28:52.456Z,1554773332.456 [CBIT](INFO): Critical error at 20190409T012851
2019-04-09T01:28:52.649Z,1554773332.649 [BR_Ping1D](INFO): Stopping
2019-04-09T01:28:53.061Z,1554773333.061 [BR_Ping1D](INFO): Stopping
2019-04-09T01:28:53.441Z,1554773333.441 [BR_Ping1D](INFO): Stopped
2019-04-09T01:28:54.329Z,1554773334.329 [CBIT](FAULT): Main Battery Failure. Count: 101
2019-04-09T01:28:55.178Z,1554773335.178 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T01:28:55.178Z,1554773335.178 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-09T01:28:55.178Z,1554773335.178 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T01:28:55.543Z,1554773335.543 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T01:28:55.543Z,1554773335.543 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-09T01:28:55.582Z,1554773335.582 [CTD_Seabird](INFO): Powering down
2019-04-09T01:28:57.463Z,1554773337.463 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:28:57.463Z,1554773337.463 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2019-04-09T01:28:57.463Z,1554773337.463 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:28:57.643Z,1554773337.643 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:28:57.867Z,1554773337.867 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:28:58.338Z,1554773338.338 [CBIT](FAULT): Main Battery Failure. Count: 111
2019-04-09T01:28:58.724Z,1554773338.724 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:28:58.724Z,1554773338.724 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2019-04-09T01:29:01.107Z,1554773341.107 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:29:01.107Z,1554773341.107 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:29:02.349Z,1554773342.349 [CBIT](FAULT): Main Battery Failure. Count: 121
2019-04-09T01:29:06.404Z,1554773346.404 [CBIT](FAULT): Main Battery Failure. Count: 131
2019-04-09T01:29:10.444Z,1554773350.444 [CBIT](FAULT): Main Battery Failure. Count: 141
2019-04-09T01:29:14.475Z,1554773354.475 [CBIT](FAULT): Main Battery Failure. Count: 151
2019-04-09T01:29:16.912Z,1554773356.912 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T01:29:16.912Z,1554773356.912 [SBIT](FAULT): Control surface position failure.
2019-04-09T01:29:18.517Z,1554773358.517 [CBIT](FAULT): Main Battery Failure. Count: 161
2019-04-09T01:29:21.306Z,1554773361.306 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:29:21.307Z,1554773361.307 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2019-04-09T01:29:21.307Z,1554773361.307 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:29:21.423Z,1554773361.423 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:29:21.710Z,1554773361.710 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:29:22.559Z,1554773362.559 [CBIT](FAULT): Main Battery Failure. Count: 171
2019-04-09T01:29:22.562Z,1554773362.562 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:29:22.562Z,1554773362.562 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2019-04-09T01:29:24.944Z,1554773364.944 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:29:24.945Z,1554773364.945 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:29:26.619Z,1554773366.619 [CBIT](FAULT): Main Battery Failure. Count: 181
2019-04-09T01:29:30.640Z,1554773370.640 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T01:29:30.640Z,1554773370.640 [SBIT](FAULT): Control surface position failure.
2019-04-09T01:29:30.641Z,1554773370.641 [CBIT](FAULT): Main Battery Failure. Count: 191
2019-04-09T01:29:31.039Z,1554773371.039 [SBIT](CRITICAL): SBIT FAILED
2019-04-09T01:29:31.054Z,1554773371.054 [CBIT](INFO): Critical error at 20190409T012931
2019-04-09T01:29:31.107Z,1554773371.107 [CommandLine](IMPORTANT): got command configSet list
2019-04-09T01:29:31.107Z,1554773371.107 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-04-09T01:29:31.108Z,1554773371.108 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-04-09T01:29:31.108Z,1554773371.108 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2019-04-09T01:29:31.451Z,1554773371.451 [MissionManager](IMPORTANT): Started mission Startup
2019-04-09T01:29:31.451Z,1554773371.451 [Startup] Running Loop=1
2019-04-09T01:29:31.452Z,1554773371.452 [Startup](DEBUG): Aggregate::initialize Startup
2019-04-09T01:29:31.452Z,1554773371.452 [Startup:A.GoToSurface] Running Loop=1
2019-04-09T01:29:31.452Z,1554773371.452 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-09T01:29:31.452Z,1554773371.452 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-09T01:29:31.453Z,1554773371.453 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-09T01:29:31.453Z,1554773371.453 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-09T01:29:31.453Z,1554773371.453 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-09T01:29:31.454Z,1554773371.454 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-09T01:29:31.455Z,1554773371.455 [Startup:StartupSatComms] Running Loop=1
2019-04-09T01:29:31.456Z,1554773371.456 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-04-09T01:29:31.456Z,1554773371.456 [Startup:StartupSatComms:A] Running Loop=1
2019-04-09T01:29:31.856Z,1554773371.856 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-04-09T01:29:34.669Z,1554773374.669 [CBIT](FAULT): Main Battery Failure. Count: 201
2019-04-09T01:29:38.722Z,1554773378.722 [CBIT](FAULT): Main Battery Failure. Count: 211
2019-04-09T01:29:42.773Z,1554773382.773 [CBIT](FAULT): Main Battery Failure. Count: 221
2019-04-09T01:29:45.142Z,1554773385.142 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:29:45.143Z,1554773385.143 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2019-04-09T01:29:45.143Z,1554773385.143 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:29:45.177Z,1554773385.177 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:29:45.546Z,1554773385.546 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:29:46.399Z,1554773386.399 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:29:46.399Z,1554773386.399 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2019-04-09T01:29:46.794Z,1554773386.794 [CBIT](FAULT): Main Battery Failure. Count: 231
2019-04-09T01:29:48.779Z,1554773388.779 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:29:48.780Z,1554773388.780 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:29:50.834Z,1554773390.834 [CBIT](FAULT): Main Battery Failure. Count: 241
2019-04-09T01:29:54.873Z,1554773394.873 [CBIT](FAULT): Main Battery Failure. Count: 251
2019-04-09T01:29:58.924Z,1554773398.924 [CBIT](FAULT): Main Battery Failure. Count: 261
2019-04-09T01:30:02.969Z,1554773402.969 [CBIT](FAULT): Main Battery Failure. Count: 271
2019-04-09T01:30:06.999Z,1554773406.999 [CBIT](FAULT): Main Battery Failure. Count: 281
2019-04-09T01:30:08.979Z,1554773408.979 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:30:08.979Z,1554773408.979 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2019-04-09T01:30:08.979Z,1554773408.979 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:30:09.027Z,1554773409.027 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:30:09.383Z,1554773409.383 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:30:10.231Z,1554773410.231 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:30:10.231Z,1554773410.231 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2019-04-09T01:30:11.041Z,1554773411.041 [CBIT](FAULT): Main Battery Failure. Count: 291
2019-04-09T01:30:12.614Z,1554773412.614 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:30:12.615Z,1554773412.615 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:30:12.657Z,1554773412.657 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T01:30:12.657Z,1554773412.657 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-04-09T01:30:12.657Z,1554773412.657 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T01:30:12.659Z,1554773412.659 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T01:30:12.659Z,1554773412.659 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-04-09T01:30:12.659Z,1554773412.659 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T01:30:12.660Z,1554773412.660 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T01:30:12.661Z,1554773412.661 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-04-09T01:30:12.661Z,1554773412.661 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T01:30:12.672Z,1554773412.672 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T01:30:12.672Z,1554773412.672 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T01:30:12.672Z,1554773412.672 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T01:30:13.053Z,1554773413.053 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T01:30:13.053Z,1554773413.053 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-04-09T01:30:13.053Z,1554773413.053 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T01:30:13.053Z,1554773413.053 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-04-09T01:30:13.054Z,1554773413.054 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T01:30:13.054Z,1554773413.054 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-04-09T01:30:13.458Z,1554773413.458 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T01:30:15.074Z,1554773415.074 [CBIT](FAULT): Main Battery Failure. Count: 301
2019-04-09T01:30:19.124Z,1554773419.124 [CBIT](FAULT): Main Battery Failure. Count: 311
2019-04-09T01:30:23.149Z,1554773423.149 [CBIT](FAULT): Main Battery Failure. Count: 321
2019-04-09T01:30:27.197Z,1554773427.197 [CBIT](FAULT): Main Battery Failure. Count: 331
2019-04-09T01:30:31.245Z,1554773431.245 [CBIT](FAULT): Main Battery Failure. Count: 341
2019-04-09T01:30:31.632Z,1554773431.632 [Startup:StartupSatComms:A](INFO): Timed out from 2019-04-09T01:29:31.5Z
2019-04-09T01:30:31.633Z,1554773431.633 [Startup:StartupSatComms:A] Stopped
2019-04-09T01:30:31.633Z,1554773431.633 [Startup:StartupSatComms:B] Running Loop=1
2019-04-09T01:30:32.039Z,1554773432.039 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-09T01:30:32.814Z,1554773432.814 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:30:32.815Z,1554773432.815 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2019-04-09T01:30:32.815Z,1554773432.815 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:30:32.885Z,1554773432.885 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:30:33.219Z,1554773433.219 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:30:34.069Z,1554773434.069 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:30:34.069Z,1554773434.069 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2019-04-09T01:30:35.304Z,1554773435.304 [CBIT](FAULT): Main Battery Failure. Count: 351
2019-04-09T01:30:36.450Z,1554773436.450 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:30:36.451Z,1554773436.451 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:30:39.322Z,1554773439.322 [CBIT](FAULT): Main Battery Failure. Count: 361
2019-04-09T01:30:41.984Z,1554773441.984 [CommandLine](IMPORTANT): got command restart application
2019-04-09T01:30:42.990Z,1554773442.990 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:30:42.990Z,1554773442.990 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:30:43.178Z,1554773443.178 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-04-09T01:30:43.178Z,1554773443.178 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:30:43.179Z,1554773443.179 [CommandLine](INFO): Join timeout helper Thread ID is 5949
2019-04-09T01:30:43.180Z,1554773443.180 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-04-09T01:30:43.180Z,1554773443.180 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:30:43.180Z,1554773443.180 [NavChartDb](INFO): Join timeout helper Thread ID is 5950
2019-04-09T01:30:43.335Z,1554773443.335 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:30:43.335Z,1554773443.335 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:30:43.338Z,1554773443.338 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-04-09T01:30:43.339Z,1554773443.339 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:30:43.339Z,1554773443.339 [Radio_Surface](INFO): Join timeout helper Thread ID is 5951
2019-04-09T01:30:43.354Z,1554773443.354 [CBIT](FAULT): Main Battery Failure. Count: 371
2019-04-09T01:30:43.618Z,1554773443.618 [Radio_Surface](INFO): Powering down
2019-04-09T01:30:43.619Z,1554773443.619 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:30:43.619Z,1554773443.619 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:30:43.622Z,1554773443.622 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-04-09T01:30:43.622Z,1554773443.622 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:30:43.622Z,1554773443.622 [DataOverHttps](INFO): Join timeout helper Thread ID is 5952
2019-04-09T01:30:43.986Z,1554773443.986 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:30:43.987Z,1554773443.987 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:30:44.006Z,1554773444.006 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-04-09T01:30:44.006Z,1554773444.006 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:30:44.006Z,1554773444.006 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5953
2019-04-09T01:30:44.170Z,1554773444.170 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:30:44.171Z,1554773444.171 [WetLabsBB2FL](INFO): Powering down
2019-04-09T01:30:44.172Z,1554773444.172 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:30:44.191Z,1554773444.191 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-04-09T01:30:44.191Z,1554773444.191 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:30:44.191Z,1554773444.191 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5954
2019-04-09T01:30:44.390Z,1554773444.390 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:30:44.390Z,1554773444.390 [CTD_Seabird](INFO): Powering down
2019-04-09T01:30:44.402Z,1554773444.402 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:30:44.403Z,1554773444.403 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-04-09T01:30:44.403Z,1554773444.403 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:30:44.404Z,1554773444.404 [logger](INFO): Join timeout helper Thread ID is 5955
2019-04-09T01:30:44.454Z,1554773444.454 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:30:44.454Z,1554773444.454 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:30:44.463Z,1554773444.463 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-04-09T01:30:44.463Z,1554773444.463 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:30:44.463Z,1554773444.463 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-04-09T01:30:44.463Z,1554773444.463 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:30:44.463Z,1554773444.463 [controlThread](INFO): Join timeout helper Thread ID is 5956
2019-04-09T01:30:44.526Z,1554773444.526 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:30:44.526Z,1554773444.526 [controlThread](DEBUG): Uninitializing ControlThread
2019-04-09T01:30:44.527Z,1554773444.527 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:30:44.529Z,1554773444.529 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:30:44.646Z,1554773444.646 [NAL9602](INFO): Powering down
2019-04-09T01:30:44.648Z,1554773444.648 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:30:44.650Z,1554773444.650 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-04-09T01:30:44.651Z,1554773444.651 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-04-09T01:30:44.651Z,1554773444.651 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-04-09T01:30:44.652Z,1554773444.652 [MissionManager](INFO): Uninitializing Mission Default
2019-04-09T01:30:44.654Z,1554773444.654 [MissionManager](INFO): Uninitializing Mission Startup
2019-04-09T01:30:44.654Z,1554773444.654 [Startup] Stopped
2019-04-09T01:30:44.654Z,1554773444.654 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-04-09T01:30:44.655Z,1554773444.655 [Startup:A.GoToSurface] Stopped
2019-04-09T01:30:44.655Z,1554773444.655 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-09T01:30:44.655Z,1554773444.655 [Startup:StartupSatComms] Stopped
2019-04-09T01:30:44.655Z,1554773444.655 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-04-09T01:30:44.655Z,1554773444.655 [Startup:StartupSatComms:B] Stopped
2019-04-09T01:30:44.656Z,1554773444.656 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-04-09T01:30:44.656Z,1554773444.656 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-04-09T01:30:44.657Z,1554773444.657 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-04-09T01:30:44.657Z,1554773444.657 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-04-09T01:30:44.657Z,1554773444.657 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-04-09T01:30:44.657Z,1554773444.657 [BuoyancyServo](INFO): Powering down
2019-04-09T01:30:44.670Z,1554773444.670 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-09T01:30:44.670Z,1554773444.670 [ElevatorServo](INFO): Powering down
2019-04-09T01:30:44.671Z,1554773444.671 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:30:44.671Z,1554773444.671 [MassServo](INFO): Powering down
2019-04-09T01:30:44.672Z,1554773444.672 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-09T01:30:44.672Z,1554773444.672 [RudderServo](INFO): Powering down
2019-04-09T01:30:44.673Z,1554773444.673 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-04-09T01:30:44.673Z,1554773444.673 [ThrusterServo](INFO): Powering down
2019-04-09T01:30:44.674Z,1554773444.674 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-04-09T01:30:44.674Z,1554773444.674 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-04-09T01:30:44.674Z,1554773444.674 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-04-09T01:30:44.674Z,1554773444.674 [CBIT](DEBUG): Powering off loads.
2019-04-09T01:30:44.686Z,1554773444.686 [CBIT](DEBUG): Disabling WDT.
2019-04-09T01:30:44.698Z,1554773444.698 [CBIT](DEBUG): Opening all GF detection circuits.
2019-04-09T01:30:44.699Z,1554773444.699 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:30:44.845Z,1554773444.845 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:30:44.850Z,1554773444.850 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:30:44.917Z,1554773444.917 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:30:44.922Z,1554773444.922 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:30:44.972Z,1554773444.972 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:30:45.029Z,1554773445.029 [logger ThreadHandler](INFO): Thread cancelled.