2019-04-11T20:41:53.272Z,1555015313.272 [Supervisor](DEBUG): Initializing supervisor.
2019-04-11T20:41:53.276Z,1555015313.276 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-11T20:41:53.276Z,1555015313.276 [SyncHandler](INFO): Protected caller Thread ID is 1979
2019-04-11T20:41:53.277Z,1555015313.277 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-04-11T20:41:53.278Z,1555015313.278 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-04-11T20:41:53.278Z,1555015313.278 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1980
2019-04-11T20:41:53.282Z,1555015313.282 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-04-11T20:41:53.295Z,1555015313.295 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-04-11T20:41:53.296Z,1555015313.296 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-04-11T20:41:53.296Z,1555015313.296 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1981
2019-04-11T20:41:53.297Z,1555015313.297 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-04-11T20:41:53.298Z,1555015313.298 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-04-11T20:41:53.299Z,1555015313.299 [logger ThreadHandler](INFO): Protected caller Thread ID is 1982
2019-04-11T20:41:53.301Z,1555015313.301 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-04-11T20:41:53.301Z,1555015313.301 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-04-11T20:41:53.303Z,1555015313.303 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-04-11T20:41:53.403Z,1555015313.403 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-04-11T20:41:53.404Z,1555015313.404 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-04-11T20:41:53.627Z,1555015313.627 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-04-11T20:41:53.628Z,1555015313.628 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-04-11T20:41:53.776Z,1555015313.776 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-04-11T20:41:53.776Z,1555015313.776 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-04-11T20:41:54.413Z,1555015314.413 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-04-11T20:41:54.414Z,1555015314.414 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-04-11T20:41:54.927Z,1555015314.927 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-04-11T20:41:54.928Z,1555015314.928 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-04-11T20:41:55.755Z,1555015315.755 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-04-11T20:41:55.755Z,1555015315.755 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-04-11T20:41:56.099Z,1555015316.099 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-04-11T20:41:56.100Z,1555015316.100 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-04-11T20:41:56.479Z,1555015316.479 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-04-11T20:41:56.479Z,1555015316.479 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-04-11T20:41:56.916Z,1555015316.916 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-04-11T20:41:56.917Z,1555015316.917 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-04-11T20:41:57.079Z,1555015317.079 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-04-11T20:41:57.080Z,1555015317.080 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-04-11T20:41:57.191Z,1555015317.191 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-04-11T20:41:57.191Z,1555015317.191 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-04-11T20:41:57.276Z,1555015317.276 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-04-11T20:41:57.382Z,1555015317.382 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-04-11T20:41:57.383Z,1555015317.383 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-04-11T20:41:57.607Z,1555015317.607 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-04-11T20:41:57.607Z,1555015317.607 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-04-11T20:41:57.836Z,1555015317.836 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-04-11T20:41:57.838Z,1555015317.838 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-04-11T20:41:57.839Z,1555015317.839 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-04-11T20:41:57.924Z,1555015317.924 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-04-11T20:41:58.169Z,1555015318.169 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-04-11T20:41:58.170Z,1555015318.170 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-04-11T20:41:58.271Z,1555015318.271 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-04-11T20:41:58.465Z,1555015318.465 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-04-11T20:41:58.707Z,1555015318.707 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-04-11T20:41:58.796Z,1555015318.796 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-04-11T20:41:58.900Z,1555015318.900 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-04-11T20:41:58.998Z,1555015318.998 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-04-11T20:41:59.166Z,1555015319.166 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-04-11T20:41:59.278Z,1555015319.278 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-04-11T20:41:59.383Z,1555015319.383 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2019-04-11T20:41:59.383Z,1555015319.383 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-04-11T20:41:59.387Z,1555015319.387 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-04-11T20:41:59.489Z,1555015319.489 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-04-11T20:41:59.489Z,1555015319.489 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-04-11T20:41:59.617Z,1555015319.617 [BuoyancyServo] Loaded
2019-04-11T20:41:59.617Z,1555015319.617 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-04-11T20:41:59.633Z,1555015319.633 [ElevatorServo] Loaded
2019-04-11T20:41:59.633Z,1555015319.633 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-04-11T20:41:59.649Z,1555015319.649 [MassServo] Loaded
2019-04-11T20:41:59.649Z,1555015319.649 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-04-11T20:41:59.665Z,1555015319.665 [RudderServo] Loaded
2019-04-11T20:41:59.666Z,1555015319.666 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-04-11T20:41:59.681Z,1555015319.681 [ThrusterServo] Loaded
2019-04-11T20:41:59.681Z,1555015319.681 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-04-11T20:41:59.681Z,1555015319.681 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-04-11T20:41:59.682Z,1555015319.682 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-04-11T20:41:59.821Z,1555015319.821 [SBIT](DEBUG): Construct Startup Built In Test.
2019-04-11T20:41:59.851Z,1555015319.851 [SBIT] Loaded
2019-04-11T20:41:59.852Z,1555015319.852 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-04-11T20:41:59.852Z,1555015319.852 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-04-11T20:41:59.882Z,1555015319.882 [IBIT] Loaded
2019-04-11T20:41:59.882Z,1555015319.882 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-04-11T20:41:59.886Z,1555015319.886 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-04-11T20:42:00.756Z,1555015320.756 [CBIT] Loaded
2019-04-11T20:42:00.756Z,1555015320.756 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-04-11T20:42:00.767Z,1555015320.767 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-04-11T20:42:00.769Z,1555015320.769 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-04-11T20:42:01.030Z,1555015321.030 [Aanderaa_O2] Loaded
2019-04-11T20:42:01.030Z,1555015321.030 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-04-11T20:42:01.041Z,1555015321.041 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-04-11T20:42:01.046Z,1555015321.046 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-04-11T20:42:01.048Z,1555015321.048 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-04-11T20:42:01.053Z,1555015321.053 [CTD_Seabird](INFO): created writer for : depth
2019-04-11T20:42:01.053Z,1555015321.053 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-04-11T20:42:01.059Z,1555015321.059 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-04-11T20:42:01.059Z,1555015321.059 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-04-11T20:42:01.065Z,1555015321.065 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-04-11T20:42:01.065Z,1555015321.065 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-04-11T20:42:01.071Z,1555015321.071 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-04-11T20:42:01.072Z,1555015321.072 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-04-11T20:42:01.077Z,1555015321.077 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-04-11T20:42:01.078Z,1555015321.078 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-04-11T20:42:01.083Z,1555015321.083 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-04-11T20:42:01.111Z,1555015321.111 [CTD_Seabird] Loaded
2019-04-11T20:42:01.111Z,1555015321.111 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-04-11T20:42:01.112Z,1555015321.112 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406654E0
2019-04-11T20:42:01.113Z,1555015321.113 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2061
2019-04-11T20:42:01.140Z,1555015321.140 [ESPComponent] Loaded
2019-04-11T20:42:01.140Z,1555015321.140 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-04-11T20:42:01.155Z,1555015321.155 [PAR_Licor] Loaded
2019-04-11T20:42:01.155Z,1555015321.155 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-04-11T20:42:01.205Z,1555015321.205 [WetLabsBB2FL] Loaded
2019-04-11T20:42:01.206Z,1555015321.206 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-04-11T20:42:01.207Z,1555015321.207 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406954E0
2019-04-11T20:42:01.207Z,1555015321.207 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2062
2019-04-11T20:42:01.208Z,1555015321.208 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-04-11T20:42:01.209Z,1555015321.209 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-04-11T20:42:01.250Z,1555015321.250 [DepthRateCalculator] Loaded
2019-04-11T20:42:01.250Z,1555015321.250 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-04-11T20:42:01.256Z,1555015321.256 [PitchRateCalculator] Loaded
2019-04-11T20:42:01.256Z,1555015321.256 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-04-11T20:42:01.273Z,1555015321.273 [SpeedCalculator] Loaded
2019-04-11T20:42:01.274Z,1555015321.274 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-04-11T20:42:01.296Z,1555015321.296 [TempGradientCalculator] Loaded
2019-04-11T20:42:01.296Z,1555015321.296 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-04-11T20:42:01.303Z,1555015321.303 [YawRateCalculator] Loaded
2019-04-11T20:42:01.303Z,1555015321.303 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-04-11T20:42:01.337Z,1555015321.337 [ElevatorOffsetCalculator] Loaded
2019-04-11T20:42:01.338Z,1555015321.338 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-04-11T20:42:01.338Z,1555015321.338 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-04-11T20:42:01.339Z,1555015321.339 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-04-11T20:42:01.366Z,1555015321.366 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-04-11T20:42:01.366Z,1555015321.366 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-04-11T20:42:01.473Z,1555015321.473 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-04-11T20:42:01.473Z,1555015321.473 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-04-11T20:42:01.536Z,1555015321.536 [DeadReckonUsingSpeedCalculator] Loaded
2019-04-11T20:42:01.536Z,1555015321.536 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-04-11T20:42:01.599Z,1555015321.599 [DeadReckonWithRespectToSeafloor] Loaded
2019-04-11T20:42:01.599Z,1555015321.599 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-04-11T20:42:01.621Z,1555015321.621 [NavChart] Loaded
2019-04-11T20:42:01.621Z,1555015321.621 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-04-11T20:42:01.626Z,1555015321.626 [UniversalFixResidualReporter] Loaded
2019-04-11T20:42:01.626Z,1555015321.626 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-04-11T20:42:01.626Z,1555015321.626 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-04-11T20:42:01.627Z,1555015321.627 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-04-11T20:42:01.943Z,1555015321.943 [AcousticModem_Benthos_ATM900] Loaded
2019-04-11T20:42:01.943Z,1555015321.943 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-04-11T20:42:02.016Z,1555015322.016 [DataOverHttps] Loaded
2019-04-11T20:42:02.016Z,1555015322.016 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-04-11T20:42:02.017Z,1555015322.017 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409714E0
2019-04-11T20:42:02.018Z,1555015322.018 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2063
2019-04-11T20:42:02.031Z,1555015322.031 [Depth_Keller] Loaded
2019-04-11T20:42:02.032Z,1555015322.032 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-04-11T20:42:02.127Z,1555015322.127 [NAL9602] Loaded
2019-04-11T20:42:02.127Z,1555015322.127 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-04-11T20:42:02.133Z,1555015322.133 [Onboard] Loaded
2019-04-11T20:42:02.133Z,1555015322.133 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-04-11T20:42:02.137Z,1555015322.137 [Radio_Surface] Loaded
2019-04-11T20:42:02.137Z,1555015322.137 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-04-11T20:42:02.138Z,1555015322.138 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A14E0
2019-04-11T20:42:02.138Z,1555015322.138 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2064
2019-04-11T20:42:03.468Z,1555015323.468 [BPC1] Loaded
2019-04-11T20:42:03.468Z,1555015323.468 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-04-11T20:42:03.476Z,1555015323.476 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minrange
2019-04-11T20:42:03.478Z,1555015323.478 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.maxrange
2019-04-11T20:42:03.480Z,1555015323.480 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.soundspeed
2019-04-11T20:42:03.482Z,1555015323.482 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.automode
2019-04-11T20:42:03.485Z,1555015323.485 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.gainindex
2019-04-11T20:42:03.487Z,1555015323.487 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minconfidence
2019-04-11T20:42:03.489Z,1555015323.489 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.pingenable
2019-04-11T20:42:03.491Z,1555015323.491 [BR_Ping1D] Loaded
2019-04-11T20:42:03.492Z,1555015323.492 [ComponentRegistry](DEBUG): SyncComponent "BR_Ping1D" handled in the control thread.
2019-04-11T20:42:03.492Z,1555015323.492 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-04-11T20:42:03.493Z,1555015323.493 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-04-11T20:42:03.558Z,1555015323.558 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-04-11T20:42:03.558Z,1555015323.559 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-04-11T20:42:03.626Z,1555015323.626 [VerticalControl](DEBUG): Construct VerticalControl.
2019-04-11T20:42:03.715Z,1555015323.715 [VerticalControl] Loaded
2019-04-11T20:42:03.716Z,1555015323.716 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-04-11T20:42:03.716Z,1555015323.716 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-04-11T20:42:03.776Z,1555015323.776 [HorizontalControl] Loaded
2019-04-11T20:42:03.777Z,1555015323.777 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-04-11T20:42:03.777Z,1555015323.777 [SpeedControl](DEBUG): Construct SpeedControl.
2019-04-11T20:42:03.779Z,1555015323.779 [SpeedControl] Loaded
2019-04-11T20:42:03.779Z,1555015323.779 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-04-11T20:42:03.780Z,1555015323.780 [LoopControl](DEBUG): Construct LoopControl.
2019-04-11T20:42:03.781Z,1555015323.781 [LoopControl] Loaded
2019-04-11T20:42:03.781Z,1555015323.781 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-04-11T20:42:03.782Z,1555015323.782 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-04-11T20:42:03.782Z,1555015323.782 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-04-11T20:42:03.795Z,1555015323.795 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-04-11T20:42:03.796Z,1555015323.796 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-04-11T20:42:04.080Z,1555015324.080 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-04-11T20:42:04.084Z,1555015324.084 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-04-11T20:42:04.085Z,1555015324.085 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-04-11T20:42:04.092Z,1555015324.092 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-04-11T20:42:04.093Z,1555015324.093 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACE4E0
2019-04-11T20:42:04.094Z,1555015324.094 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2065
2019-04-11T20:42:04.099Z,1555015324.099 [Supervisor](INFO): Main Thread ID is 803
2019-04-11T20:42:04.099Z,1555015324.099 [Supervisor](DEBUG): Running supervisor.
2019-04-11T20:42:04.100Z,1555015324.100 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2066
2019-04-11T20:42:04.102Z,1555015324.102 [controlThread ThreadHandler](INFO): Handler Thread ID is 2067
2019-04-11T20:42:04.102Z,1555015324.102 [controlThread](DEBUG): Initializing ControlThread
2019-04-11T20:42:04.104Z,1555015324.104 [SBIT](INFO): Initialize SBIT Component.
2019-04-11T20:42:04.105Z,1555015324.105 [SBIT](IMPORTANT): git: 2019-03-18-31-ge98d5dc
2019-04-11T20:42:04.105Z,1555015324.105 [SBIT](INFO): git hash: e98d5dc50cbc39e402f19b9946ff4dc0776c8725
2019-04-11T20:42:04.105Z,1555015324.105 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-04-11T20:42:04.107Z,1555015324.107 [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-11T20:42:04.108Z,1555015324.108 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-04-11T20:42:04.108Z,1555015324.108 [IBIT](INFO): Initialize IBIT Component.
2019-04-11T20:42:04.109Z,1555015324.109 [CBIT](DEBUG): Initialize CBIT Component.
2019-04-11T20:42:04.110Z,1555015324.110 [logger ThreadHandler](INFO): Handler Thread ID is 2068
2019-04-11T20:42:04.121Z,1555015324.121 [CBIT](DEBUG): Initialized mux pins.
2019-04-11T20:42:04.121Z,1555015324.121 [CBIT](DEBUG): Initializing the watchdog timer.
2019-04-11T20:42:04.129Z,1555015324.129 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2069
2019-04-11T20:42:04.130Z,1555015324.130 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-11T20:42:04.133Z,1555015324.133 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-11T20:42:04.135Z,1555015324.135 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2071
2019-04-11T20:42:04.136Z,1555015324.136 [WetLabsBB2FL](INFO): Powering down
2019-04-11T20:42:04.146Z,1555015324.146 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2019-04-11T20:42:04.146Z,1555015324.146 [CBIT](DEBUG): Initializing heartbeat.
2019-04-11T20:42:04.165Z,1555015324.165 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2072
2019-04-11T20:42:04.166Z,1555015324.166 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-04-11T20:42:04.175Z,1555015324.175 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2073
2019-04-11T20:42:04.193Z,1555015324.193 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2074
2019-04-11T20:42:04.196Z,1555015324.196 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-11T20:42:04.196Z,1555015324.196 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-04-11T20:42:04.196Z,1555015324.196 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-11T20:42:04.197Z,1555015324.197 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-04-11T20:42:04.197Z,1555015324.197 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-11T20:42:04.197Z,1555015324.197 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-04-11T20:42:04.197Z,1555015324.197 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-11T20:42:04.197Z,1555015324.197 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-04-11T20:42:04.198Z,1555015324.198 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-11T20:42:04.198Z,1555015324.198 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-04-11T20:42:04.198Z,1555015324.198 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-11T20:42:04.198Z,1555015324.198 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-04-11T20:42:04.198Z,1555015324.198 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-11T20:42:04.198Z,1555015324.198 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-04-11T20:42:04.199Z,1555015324.199 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-11T20:42:04.199Z,1555015324.199 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-04-11T20:42:04.217Z,1555015324.217 [CBIT](DEBUG): Deactivating GF circuits.
2019-04-11T20:42:04.217Z,1555015324.217 [CBIT](DEBUG): Deactivating emergency mode.
2019-04-11T20:42:04.253Z,1555015324.253 [CBIT](DEBUG): Backplane powered.
2019-04-11T20:42:04.257Z,1555015324.257 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-04-11T20:42:04.258Z,1555015324.258 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-04-11T20:42:04.258Z,1555015324.258 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-04-11T20:42:04.258Z,1555015324.258 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-04-11T20:42:04.259Z,1555015324.259 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-04-11T20:42:04.259Z,1555015324.259 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-04-11T20:42:04.260Z,1555015324.260 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-11T20:42:04.261Z,1555015324.261 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-04-11T20:42:04.262Z,1555015324.262 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-04-11T20:42:04.270Z,1555015324.270 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-04-11T20:42:04.272Z,1555015324.272 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-04-11T20:42:04.281Z,1555015324.281 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-04-11T20:42:04.281Z,1555015324.281 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-04-11T20:42:04.282Z,1555015324.282 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-04-11T20:42:04.308Z,1555015324.308 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-11T20:42:04.344Z,1555015324.344 [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-11T20:42:04.344Z,1555015324.344 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-04-11T20:42:04.449Z,1555015324.449 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-04-11T20:42:04.450Z,1555015324.450 [Default:A.Wait](DEBUG): Construct Wait.
2019-04-11T20:42:04.452Z,1555015324.452 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-11T20:42:04.471Z,1555015324.471 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-04-11T20:42:04.499Z,1555015324.499 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-04-11T20:42:04.513Z,1555015324.513 [Default:E.Execute](DEBUG): Construct Execute.
2019-04-11T20:42:04.516Z,1555015324.516 [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-11T20:42:04.529Z,1555015324.529 [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-11T20:42:04.558Z,1555015324.558 [ESPComponent](INFO): powering down ESP
2019-04-11T20:42:04.577Z,1555015324.577 [Radio_Surface](INFO): Powering up
2019-04-11T20:42:05.009Z,1555015325.009 [PAR_Licor](INFO): A/D timeout, 9 tries over 137 ms

2019-04-11T20:42:05.009Z,1555015325.009 [PAR_Licor] Data Fault, FailCount= 1
2019-04-11T20:42:05.009Z,1555015325.009 [PAR_Licor](ERROR): Data Fault
2019-04-11T20:42:05.010Z,1555015325.010 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-11T20:42:05.010Z,1555015325.010 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-11T20:42:05.034Z,1555015325.034 [Depth_Keller](ERROR): Pressure reading out of range: 1895.684326 decibar
2019-04-11T20:42:05.085Z,1555015325.085 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-04-11T20:42:05.136Z,1555015325.136 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-04-11T20:42:05.145Z,1555015325.145 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-04-11T20:42:05.146Z,1555015325.146 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-11T20:42:05.153Z,1555015325.153 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-11T20:42:05.154Z,1555015325.154 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-11T20:42:05.161Z,1555015325.161 [MassServo](DEBUG): Initializing MassServo.
2019-04-11T20:42:05.162Z,1555015325.162 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-11T20:42:05.169Z,1555015325.169 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-11T20:42:05.170Z,1555015325.170 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-04-11T20:42:05.177Z,1555015325.177 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-04-11T20:42:05.180Z,1555015325.180 [CommandLine](FAULT): Scheduling is paused
2019-04-11T20:42:05.180Z,1555015325.180 [CBIT](INFO): Critical error at 20190411T204204
2019-04-11T20:42:05.180Z,1555015325.180 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-04-11T20:42:05.184Z,1555015325.184 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-11T20:42:05.184Z,1555015325.184 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-11T20:42:05.185Z,1555015325.185 [PAR_Licor] No Fault, FailCount= 1
2019-04-11T20:42:05.729Z,1555015325.729 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-11T20:42:05.729Z,1555015325.729 [PAR_Licor] Data Fault, FailCount= 2
2019-04-11T20:42:05.729Z,1555015325.729 [PAR_Licor](ERROR): Data Fault
2019-04-11T20:42:05.769Z,1555015325.769 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-11T20:42:06.117Z,1555015326.117 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-04-11T20:42:06.117Z,1555015326.117 [RudderServo](FAULT): Rudder failed to initialize
2019-04-11T20:42:06.117Z,1555015326.117 [RudderServo] Communications Fault, FailCount= 1
2019-04-11T20:42:06.117Z,1555015326.117 [RudderServo](ERROR): Communications Fault
2019-04-11T20:42:06.228Z,1555015326.228 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-04-11T20:42:06.229Z,1555015326.229 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-11T20:42:06.229Z,1555015326.229 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-11T20:42:06.229Z,1555015326.229 [PAR_Licor] No Fault, FailCount= 2
2019-04-11T20:42:06.272Z,1555015326.272 [BR_Ping1D](INFO): UART requesting status
2019-04-11T20:42:06.434Z,1555015326.434 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-11T20:42:06.434Z,1555015326.434 [RudderServo](INFO): Powering down
2019-04-11T20:42:06.658Z,1555015326.658 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:07.177Z,1555015327.177 [PAR_Licor](INFO): A/D timeout, 9 tries over 134 ms

2019-04-11T20:42:07.177Z,1555015327.177 [PAR_Licor] Data Fault, FailCount= 1
2019-04-11T20:42:07.177Z,1555015327.177 [PAR_Licor](ERROR): Data Fault
2019-04-11T20:42:07.198Z,1555015327.198 [BR_Ping1D](INFO): startup done
2019-04-11T20:42:07.224Z,1555015327.224 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-11T20:42:07.342Z,1555015327.342 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-11T20:42:07.346Z,1555015327.346 [CBIT](INFO): Clearing failed state for component RudderServo
2019-04-11T20:42:07.346Z,1555015327.346 [RudderServo] No Fault, FailCount= 1
2019-04-11T20:42:07.346Z,1555015327.346 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-11T20:42:07.347Z,1555015327.347 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-11T20:42:07.347Z,1555015327.347 [PAR_Licor] No Fault, FailCount= 1
2019-04-11T20:42:07.471Z,1555015327.471 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:07.873Z,1555015327.873 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:07.873Z,1555015327.873 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:07.874Z,1555015327.874 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:08.286Z,1555015328.286 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:08.286Z,1555015328.286 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:08.287Z,1555015328.287 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:08.809Z,1555015328.809 [PAR_Licor](INFO): A/D timeout, 9 tries over 150 ms

2019-04-11T20:42:08.809Z,1555015328.809 [PAR_Licor] Data Fault, FailCount= 1
2019-04-11T20:42:08.809Z,1555015328.809 [PAR_Licor](ERROR): Data Fault
2019-04-11T20:42:08.845Z,1555015328.845 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:08.845Z,1555015328.845 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:08.846Z,1555015328.846 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:09.129Z,1555015329.129 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-11T20:42:09.129Z,1555015329.129 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-11T20:42:09.129Z,1555015329.129 [PAR_Licor] No Fault, FailCount= 1
2019-04-11T20:42:09.184Z,1555015329.184 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:09.184Z,1555015329.184 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:09.184Z,1555015329.184 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:09.677Z,1555015329.677 [PAR_Licor](INFO): A/D timeout, 8 tries over 138 ms

2019-04-11T20:42:09.677Z,1555015329.677 [PAR_Licor] Data Fault, FailCount= 2
2019-04-11T20:42:09.677Z,1555015329.677 [PAR_Licor](ERROR): Data Fault
2019-04-11T20:42:09.722Z,1555015329.722 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:09.723Z,1555015329.723 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:09.723Z,1555015329.723 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:09.821Z,1555015329.821 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-11T20:42:09.822Z,1555015329.822 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-11T20:42:09.822Z,1555015329.822 [PAR_Licor] No Fault, FailCount= 2
2019-04-11T20:42:09.965Z,1555015329.965 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:09.965Z,1555015329.965 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:09.965Z,1555015329.965 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:10.369Z,1555015330.369 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:10.369Z,1555015330.369 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:10.370Z,1555015330.370 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:10.772Z,1555015330.772 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:10.773Z,1555015330.773 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:10.773Z,1555015330.773 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:11.186Z,1555015331.186 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:11.186Z,1555015331.186 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:11.187Z,1555015331.187 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:11.580Z,1555015331.580 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:11.580Z,1555015331.580 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:11.581Z,1555015331.581 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:12.105Z,1555015332.105 [PAR_Licor](INFO): A/D timeout, 8 tries over 142 ms

2019-04-11T20:42:12.105Z,1555015332.105 [PAR_Licor] Data Fault, FailCount= 1
2019-04-11T20:42:12.105Z,1555015332.105 [PAR_Licor](ERROR): Data Fault
2019-04-11T20:42:12.146Z,1555015332.146 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:12.146Z,1555015332.146 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:12.147Z,1555015332.147 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:12.147Z,1555015332.147 [BR_Ping1D](FAULT): Failed to acquire valid data within timeout.
2019-04-11T20:42:12.147Z,1555015332.147 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-11T20:42:12.147Z,1555015332.147 [BR_Ping1D](ERROR): Data Fault
2019-04-11T20:42:12.270Z,1555015332.270 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-11T20:42:12.270Z,1555015332.270 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-11T20:42:12.270Z,1555015332.270 [PAR_Licor] No Fault, FailCount= 1
2019-04-11T20:42:12.271Z,1555015332.271 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-11T20:42:12.396Z,1555015332.396 [BR_Ping1D](INFO): Powering down
2019-04-11T20:42:13.714Z,1555015333.714 [PAR_Licor](INFO): A/D timeout, 8 tries over 135 ms

2019-04-11T20:42:13.714Z,1555015333.714 [PAR_Licor] Data Fault, FailCount= 1
2019-04-11T20:42:13.714Z,1555015333.714 [PAR_Licor](ERROR): Data Fault
2019-04-11T20:42:13.850Z,1555015333.850 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-11T20:42:13.850Z,1555015333.850 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-11T20:42:13.850Z,1555015333.850 [PAR_Licor] No Fault, FailCount= 1
2019-04-11T20:42:13.850Z,1555015333.850 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-11T20:42:13.850Z,1555015333.850 [BR_Ping1D] No Fault, FailCount= 1
2019-04-11T20:42:14.165Z,1555015334.165 [CBIT](CRITICAL): Environmental Failure. Press:14.843912 PSI. Humidity:37%. Temp:26 C. ABORTING MISSION
2019-04-11T20:42:14.459Z,1555015334.459 [CBIT](INFO): Critical error at 20190411T204214
2019-04-11T20:42:14.789Z,1555015334.789 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-11T20:42:14.789Z,1555015334.789 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-11T20:42:14.789Z,1555015334.789 [Aanderaa_O2](ERROR): Communications Fault
2019-04-11T20:42:14.921Z,1555015334.921 [PAR_Licor](INFO): A/D timeout, 8 tries over 130 ms

2019-04-11T20:42:14.921Z,1555015334.921 [PAR_Licor] Data Fault, FailCount= 1
2019-04-11T20:42:14.921Z,1555015334.921 [PAR_Licor](ERROR): Data Fault
2019-04-11T20:42:14.951Z,1555015334.951 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-11T20:42:15.009Z,1555015335.009 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-11T20:42:15.009Z,1555015335.009 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-11T20:42:15.009Z,1555015335.009 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-11T20:42:15.009Z,1555015335.009 [PAR_Licor] No Fault, FailCount= 1
2019-04-11T20:42:15.313Z,1555015335.313 [Aanderaa_O2](INFO): Powering down
2019-04-11T20:42:15.620Z,1555015335.620 [BR_Ping1D](INFO): UART requesting status
2019-04-11T20:42:16.129Z,1555015336.129 [PAR_Licor](INFO): A/D timeout, 8 tries over 126 ms

2019-04-11T20:42:16.129Z,1555015336.129 [PAR_Licor] Data Fault, FailCount= 1
2019-04-11T20:42:16.129Z,1555015336.129 [PAR_Licor](ERROR): Data Fault
2019-04-11T20:42:16.155Z,1555015336.155 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:16.155Z,1555015336.155 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:16.211Z,1555015336.211 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-11T20:42:16.211Z,1555015336.211 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-11T20:42:16.211Z,1555015336.211 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-11T20:42:16.211Z,1555015336.211 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-11T20:42:16.211Z,1555015336.211 [PAR_Licor] No Fault, FailCount= 1
2019-04-11T20:42:16.429Z,1555015336.429 [BR_Ping1D](INFO): startup done
2019-04-11T20:42:16.850Z,1555015336.850 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:17.181Z,1555015337.181 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-11T20:42:17.181Z,1555015337.181 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-11T20:42:17.181Z,1555015337.181 [CTD_Seabird](ERROR): Communications Fault
2019-04-11T20:42:17.236Z,1555015337.236 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:17.236Z,1555015337.236 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:17.237Z,1555015337.237 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:17.256Z,1555015337.256 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-11T20:42:17.585Z,1555015337.585 [CTD_Seabird](INFO): Powering down
2019-04-11T20:42:17.653Z,1555015337.653 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:17.653Z,1555015337.653 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:17.653Z,1555015337.653 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:18.044Z,1555015338.044 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:18.044Z,1555015338.044 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:18.045Z,1555015338.045 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:18.449Z,1555015338.449 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:18.449Z,1555015338.449 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:18.449Z,1555015338.449 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:18.969Z,1555015338.969 [PAR_Licor](INFO): A/D timeout, 9 tries over 138 ms

2019-04-11T20:42:18.969Z,1555015338.969 [PAR_Licor] Data Fault, FailCount= 1
2019-04-11T20:42:18.969Z,1555015338.969 [PAR_Licor](ERROR): Data Fault
2019-04-11T20:42:19.008Z,1555015339.008 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:19.009Z,1555015339.009 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:19.009Z,1555015339.009 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:19.034Z,1555015339.034 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-11T20:42:19.034Z,1555015339.034 [CTD_Seabird] No Fault, FailCount= 1
2019-04-11T20:42:19.034Z,1555015339.034 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-11T20:42:19.034Z,1555015339.034 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-11T20:42:19.034Z,1555015339.034 [PAR_Licor] No Fault, FailCount= 1
2019-04-11T20:42:19.258Z,1555015339.258 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:19.258Z,1555015339.258 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:19.258Z,1555015339.258 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:19.381Z,1555015339.381 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-11T20:42:19.382Z,1555015339.382 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-11T20:42:19.660Z,1555015339.660 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:19.660Z,1555015339.660 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:19.661Z,1555015339.661 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:20.177Z,1555015340.177 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-11T20:42:20.177Z,1555015340.177 [PAR_Licor] Data Fault, FailCount= 1
2019-04-11T20:42:20.177Z,1555015340.177 [PAR_Licor](ERROR): Data Fault
2019-04-11T20:42:20.198Z,1555015340.198 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:20.198Z,1555015340.198 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:20.199Z,1555015340.199 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:20.251Z,1555015340.251 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-11T20:42:20.251Z,1555015340.251 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-11T20:42:20.251Z,1555015340.251 [PAR_Licor] No Fault, FailCount= 1
2019-04-11T20:42:20.469Z,1555015340.469 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:20.469Z,1555015340.469 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:20.469Z,1555015340.469 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:20.873Z,1555015340.873 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:20.873Z,1555015340.873 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:20.873Z,1555015340.873 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:21.393Z,1555015341.393 [PAR_Licor](INFO): A/D timeout, 9 tries over 139 ms

2019-04-11T20:42:21.393Z,1555015341.393 [PAR_Licor] Data Fault, FailCount= 1
2019-04-11T20:42:21.394Z,1555015341.394 [PAR_Licor](ERROR): Data Fault
2019-04-11T20:42:21.426Z,1555015341.426 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:21.426Z,1555015341.426 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:21.427Z,1555015341.427 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:21.427Z,1555015341.427 [BR_Ping1D](FAULT): Failed to acquire valid data within timeout.
2019-04-11T20:42:21.427Z,1555015341.427 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-11T20:42:21.427Z,1555015341.427 [BR_Ping1D](ERROR): Data Fault
2019-04-11T20:42:21.473Z,1555015341.473 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-11T20:42:21.473Z,1555015341.473 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-11T20:42:21.474Z,1555015341.474 [PAR_Licor] No Fault, FailCount= 1
2019-04-11T20:42:21.474Z,1555015341.474 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-11T20:42:21.680Z,1555015341.680 [BR_Ping1D](INFO): Powering down
2019-04-11T20:42:22.605Z,1555015342.605 [PAR_Licor](INFO): A/D timeout, 8 tries over 138 ms

2019-04-11T20:42:22.606Z,1555015342.606 [PAR_Licor] Data Fault, FailCount= 1
2019-04-11T20:42:22.606Z,1555015342.606 [PAR_Licor](ERROR): Data Fault
2019-04-11T20:42:22.641Z,1555015342.641 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-11T20:42:22.641Z,1555015342.641 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-11T20:42:22.641Z,1555015342.641 [PAR_Licor] No Fault, FailCount= 1
2019-04-11T20:42:22.942Z,1555015342.942 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-11T20:42:22.942Z,1555015342.942 [BR_Ping1D] No Fault, FailCount= 1
2019-04-11T20:42:23.413Z,1555015343.413 [PAR_Licor](INFO): A/D timeout, 10 tries over 138 ms

2019-04-11T20:42:23.413Z,1555015343.413 [PAR_Licor] Data Fault, FailCount= 2
2019-04-11T20:42:23.413Z,1555015343.413 [PAR_Licor](ERROR): Data Fault
2019-04-11T20:42:23.466Z,1555015343.466 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-11T20:42:23.467Z,1555015343.467 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-11T20:42:23.467Z,1555015343.467 [PAR_Licor] No Fault, FailCount= 2
2019-04-11T20:42:24.237Z,1555015344.237 [PAR_Licor](INFO): A/D timeout, 8 tries over 154 ms

2019-04-11T20:42:24.237Z,1555015344.237 [PAR_Licor] Data Fault, FailCount= 3
2019-04-11T20:42:24.237Z,1555015344.237 [PAR_Licor](ERROR): Data Fault
2019-04-11T20:42:24.276Z,1555015344.276 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-11T20:42:24.353Z,1555015344.353 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-11T20:42:24.354Z,1555015344.354 [CBIT](CRITICAL): Data Fault in component: PAR_Licor
2019-04-11T20:42:24.520Z,1555015344.520 [CBIT](INFO): Critical error at 20190411T204224
2019-04-11T20:42:24.914Z,1555015344.914 [BR_Ping1D](INFO): UART requesting status
2019-04-11T20:42:25.293Z,1555015345.293 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-11T20:42:25.294Z,1555015345.294 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-04-11T20:42:25.294Z,1555015345.294 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-11T20:42:25.314Z,1555015345.314 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:25.314Z,1555015345.314 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:25.331Z,1555015345.331 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-11T20:42:25.697Z,1555015345.697 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-11T20:42:25.874Z,1555015345.874 [BR_Ping1D](INFO): startup done
2019-04-11T20:42:26.122Z,1555015346.122 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:26.505Z,1555015346.505 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-11T20:42:26.505Z,1555015346.505 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-11T20:42:26.505Z,1555015346.505 [Aanderaa_O2](ERROR): Communications Fault
2019-04-11T20:42:26.522Z,1555015346.522 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:26.523Z,1555015346.523 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:26.523Z,1555015346.523 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:26.542Z,1555015346.542 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-11T20:42:26.542Z,1555015346.542 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-11T20:42:26.542Z,1555015346.542 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-04-11T20:42:27.029Z,1555015347.029 [Aanderaa_O2](INFO): Powering down
2019-04-11T20:42:27.047Z,1555015347.047 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:27.047Z,1555015347.047 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:27.048Z,1555015347.048 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:27.334Z,1555015347.334 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:27.334Z,1555015347.334 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:27.334Z,1555015347.334 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:27.738Z,1555015347.738 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:27.738Z,1555015347.738 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:27.739Z,1555015347.739 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:27.755Z,1555015347.755 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-11T20:42:27.755Z,1555015347.755 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-11T20:42:28.152Z,1555015348.152 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:28.152Z,1555015348.152 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:28.153Z,1555015348.153 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:28.559Z,1555015348.559 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:28.559Z,1555015348.559 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:28.560Z,1555015348.560 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:28.585Z,1555015348.585 [SBIT](IMPORTANT): Beginning Startup BIT
2019-04-11T20:42:28.594Z,1555015348.594 [CBIT](IMPORTANT): Beginning ground fault scan
2019-04-11T20:42:28.930Z,1555015348.930 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-11T20:42:28.930Z,1555015348.930 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-11T20:42:28.965Z,1555015348.965 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:28.965Z,1555015348.965 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:28.965Z,1555015348.965 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:29.352Z,1555015349.352 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:29.353Z,1555015349.353 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:29.353Z,1555015349.353 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:29.757Z,1555015349.757 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:29.757Z,1555015349.757 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:29.757Z,1555015349.757 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:30.161Z,1555015350.161 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:30.161Z,1555015350.161 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:30.161Z,1555015350.161 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:30.565Z,1555015350.565 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:30.565Z,1555015350.565 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:30.566Z,1555015350.566 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:30.566Z,1555015350.566 [BR_Ping1D](FAULT): Failed to acquire valid data within timeout.
2019-04-11T20:42:30.566Z,1555015350.566 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-11T20:42:30.566Z,1555015350.566 [BR_Ping1D](ERROR): Data Fault
2019-04-11T20:42:30.698Z,1555015350.698 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-11T20:42:30.969Z,1555015350.969 [BR_Ping1D](INFO): Powering down
2019-04-11T20:42:32.178Z,1555015352.178 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-11T20:42:32.178Z,1555015352.178 [BR_Ping1D] No Fault, FailCount= 1
2019-04-11T20:42:32.430Z,1555015352.430 [NAL9602](INFO): Powering up NAL9602
2019-04-11T20:42:32.433Z,1555015352.433 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-11T20:42:32.433Z,1555015352.433 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-11T20:42:32.434Z,1555015352.434 [CTD_Seabird](ERROR): Communications Fault
2019-04-11T20:42:32.662Z,1555015352.662 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-11T20:42:32.837Z,1555015352.837 [CTD_Seabird](INFO): Powering down
2019-04-11T20:42:33.251Z,1555015353.251 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-11T20:42:33.829Z,1555015353.829 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-11T20:42:33.829Z,1555015353.829 [CTD_Seabird] No Fault, FailCount= 2
2019-04-11T20:42:34.060Z,1555015354.060 [BR_Ping1D](INFO): UART requesting status
2019-04-11T20:42:34.185Z,1555015354.185 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-11T20:42:34.193Z,1555015354.193 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-11T20:42:34.464Z,1555015354.464 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:34.464Z,1555015354.464 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:34.870Z,1555015354.870 [BR_Ping1D](INFO): startup done
2019-04-11T20:42:35.274Z,1555015355.274 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:35.674Z,1555015355.674 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:35.674Z,1555015355.674 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:35.674Z,1555015355.674 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:36.089Z,1555015356.089 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:36.089Z,1555015356.089 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:36.090Z,1555015356.090 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:36.485Z,1555015356.485 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:36.486Z,1555015356.486 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:36.486Z,1555015356.486 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:36.890Z,1555015356.890 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:36.891Z,1555015356.891 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:36.891Z,1555015356.891 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:37.297Z,1555015357.297 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:37.297Z,1555015357.297 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:37.297Z,1555015357.297 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:37.696Z,1555015357.696 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:37.696Z,1555015357.696 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:37.698Z,1555015357.698 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:38.101Z,1555015358.101 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:38.101Z,1555015358.101 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:38.102Z,1555015358.102 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:38.485Z,1555015358.485 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-11T20:42:38.485Z,1555015358.485 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-11T20:42:38.485Z,1555015358.485 [Aanderaa_O2](ERROR): Communications Fault
2019-04-11T20:42:38.504Z,1555015358.504 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:38.505Z,1555015358.505 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:38.506Z,1555015358.506 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:38.606Z,1555015358.606 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-11T20:42:38.606Z,1555015358.606 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-11T20:42:39.009Z,1555015359.009 [Aanderaa_O2](INFO): Powering down
2019-04-11T20:42:39.029Z,1555015359.029 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:39.029Z,1555015359.029 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:39.030Z,1555015359.030 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:39.312Z,1555015359.312 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:39.312Z,1555015359.312 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:39.313Z,1555015359.313 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:39.405Z,1555015359.405 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.007296
CHAN A1 (24V): -0.127900
CHAN A2 (12V): -0.004985
CHAN A3 (5V): -0.002852
CHAN B0 (3.3V): -0.001179
CHAN B1 (3.15aV): -0.001681
CHAN B2 (3.15bV): -0.001355
CHAN B3 (GND): -0.000057
OPEN: -0.001071
Full Scale Calc: 4.765 mA, -1.589 mA
2019-04-11T20:42:39.718Z,1555015359.718 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:39.718Z,1555015359.718 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:39.718Z,1555015359.718 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:39.718Z,1555015359.718 [BR_Ping1D](FAULT): Failed to acquire valid data within timeout.
2019-04-11T20:42:39.719Z,1555015359.719 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-11T20:42:39.719Z,1555015359.719 [BR_Ping1D](ERROR): Data Fault
2019-04-11T20:42:39.822Z,1555015359.822 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-11T20:42:40.123Z,1555015360.123 [BR_Ping1D](INFO): Powering down
2019-04-11T20:42:41.394Z,1555015361.394 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-11T20:42:41.394Z,1555015361.394 [BR_Ping1D] No Fault, FailCount= 1
2019-04-11T20:42:42.544Z,1555015362.544 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-11T20:42:43.338Z,1555015363.338 [NAL9602](INFO): NAL9602 initialized
2019-04-11T20:42:43.353Z,1555015363.353 [BR_Ping1D](INFO): UART requesting status
2019-04-11T20:42:43.771Z,1555015363.771 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:43.771Z,1555015363.771 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:44.157Z,1555015364.157 [NAL9602](DEBUG): Fix Requested
2019-04-11T20:42:44.178Z,1555015364.178 [BR_Ping1D](INFO): startup done
2019-04-11T20:42:44.728Z,1555015364.728 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:45.092Z,1555015365.092 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:45.092Z,1555015365.092 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:45.093Z,1555015365.093 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:45.486Z,1555015365.486 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:45.486Z,1555015365.486 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:45.487Z,1555015365.487 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:45.889Z,1555015365.889 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:45.889Z,1555015365.889 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:45.890Z,1555015365.890 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:46.302Z,1555015366.302 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:46.302Z,1555015366.302 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:46.303Z,1555015366.303 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:46.708Z,1555015366.708 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:46.708Z,1555015366.708 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:46.708Z,1555015366.708 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:47.115Z,1555015367.115 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:47.115Z,1555015367.115 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:47.116Z,1555015367.116 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:47.253Z,1555015367.253 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-11T20:42:47.253Z,1555015367.253 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-11T20:42:47.253Z,1555015367.253 [CTD_Seabird](ERROR): Communications Fault
2019-04-11T20:42:47.524Z,1555015367.524 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:47.524Z,1555015367.524 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:47.524Z,1555015367.524 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:47.646Z,1555015367.646 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-11T20:42:47.646Z,1555015367.646 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-11T20:42:47.657Z,1555015367.657 [CTD_Seabird](INFO): Powering down
2019-04-11T20:42:47.912Z,1555015367.912 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:47.912Z,1555015367.912 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:47.912Z,1555015367.912 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:48.313Z,1555015368.313 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:48.313Z,1555015368.313 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:48.314Z,1555015368.314 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:48.717Z,1555015368.717 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:48.718Z,1555015368.718 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:48.718Z,1555015368.718 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:49.102Z,1555015369.102 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-11T20:42:49.102Z,1555015369.102 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2019-04-11T20:42:49.102Z,1555015369.102 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-11T20:42:49.121Z,1555015369.121 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:49.121Z,1555015369.121 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:49.122Z,1555015369.122 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:42:49.122Z,1555015369.122 [BR_Ping1D](FAULT): Failed to acquire valid data within timeout.
2019-04-11T20:42:49.122Z,1555015369.122 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-11T20:42:49.122Z,1555015369.122 [BR_Ping1D](ERROR): Data Fault
2019-04-11T20:42:49.378Z,1555015369.378 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-11T20:42:49.379Z,1555015369.379 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-11T20:42:49.506Z,1555015369.506 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-11T20:42:49.656Z,1555015369.656 [BR_Ping1D](INFO): Powering down
2019-04-11T20:42:50.387Z,1555015370.387 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-11T20:42:50.387Z,1555015370.387 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2019-04-11T20:42:50.790Z,1555015370.790 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-11T20:42:50.790Z,1555015370.790 [BR_Ping1D] No Fault, FailCount= 1
2019-04-11T20:42:51.964Z,1555015371.964 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-11T20:42:52.737Z,1555015372.737 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-11T20:42:52.738Z,1555015372.738 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-11T20:42:52.784Z,1555015372.784 [BR_Ping1D](INFO): UART requesting status
2019-04-11T20:42:53.161Z,1555015373.161 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:42:53.161Z,1555015373.161 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:42:53.163Z,1555015373.163 [BR_Ping1D](INFO): not all values verified yet
2019-04-11T20:42:53.566Z,1555015373.566 [BR_Ping1D](INFO): not all values verified yet
2019-04-11T20:42:53.969Z,1555015373.969 [BR_Ping1D](INFO): not all values verified yet
2019-04-11T20:42:54.376Z,1555015374.376 [BR_Ping1D](INFO): not all values verified yet
2019-04-11T20:42:54.780Z,1555015374.780 [BR_Ping1D](INFO): not all values verified yet
2019-04-11T20:42:55.181Z,1555015375.181 [BR_Ping1D](INFO): not all values verified yet
2019-04-11T20:42:55.582Z,1555015375.582 [BR_Ping1D](INFO): not all values verified yet
2019-04-11T20:42:55.990Z,1555015375.990 [BR_Ping1D](INFO): not all values verified yet
2019-04-11T20:42:56.393Z,1555015376.393 [BR_Ping1D](INFO): not all values verified yet
2019-04-11T20:42:56.812Z,1555015376.812 [BR_Ping1D](INFO): not all values verified yet
2019-04-11T20:42:57.212Z,1555015377.212 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-11T20:42:57.212Z,1555015377.212 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-11T20:42:57.212Z,1555015377.212 [BR_Ping1D](ERROR): Data Fault
2019-04-11T20:42:57.263Z,1555015377.263 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-11T20:42:57.623Z,1555015377.623 [BR_Ping1D](INFO): Powering down
2019-04-11T20:42:58.962Z,1555015378.962 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-11T20:42:58.962Z,1555015378.962 [BR_Ping1D] No Fault, FailCount= 1
2019-04-11T20:43:00.033Z,1555015380.033 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-11T20:43:00.834Z,1555015380.834 [BR_Ping1D](INFO): UART requesting status
2019-04-11T20:43:01.242Z,1555015381.242 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:01.649Z,1555015381.649 [BR_Ping1D](INFO): startup done
2019-04-11T20:43:02.062Z,1555015382.062 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:02.468Z,1555015382.468 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:02.468Z,1555015382.468 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:02.468Z,1555015382.468 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:02.871Z,1555015382.871 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:02.871Z,1555015382.871 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:02.872Z,1555015382.872 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:03.272Z,1555015383.272 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:03.272Z,1555015383.272 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:03.272Z,1555015383.272 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:03.669Z,1555015383.669 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:03.669Z,1555015383.669 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:03.669Z,1555015383.669 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:04.069Z,1555015384.069 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:04.069Z,1555015384.069 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:04.070Z,1555015384.070 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:04.475Z,1555015384.475 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:04.475Z,1555015384.475 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:04.475Z,1555015384.475 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:04.884Z,1555015384.884 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:04.884Z,1555015384.884 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:04.885Z,1555015384.885 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:05.282Z,1555015385.282 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:05.282Z,1555015385.282 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:05.282Z,1555015385.282 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:05.714Z,1555015385.714 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:05.714Z,1555015385.714 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:05.715Z,1555015385.715 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:06.114Z,1555015386.114 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:06.114Z,1555015386.114 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:06.115Z,1555015386.115 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:06.494Z,1555015386.494 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:06.494Z,1555015386.494 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:06.495Z,1555015386.495 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:06.496Z,1555015386.496 [BR_Ping1D](FAULT): Failed to acquire valid data within timeout.
2019-04-11T20:43:06.496Z,1555015386.496 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-11T20:43:06.496Z,1555015386.496 [BR_Ping1D](ERROR): Data Fault
2019-04-11T20:43:06.511Z,1555015386.511 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-11T20:43:06.898Z,1555015386.898 [BR_Ping1D](INFO): Powering down
2019-04-11T20:43:08.147Z,1555015388.147 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-11T20:43:08.147Z,1555015388.147 [BR_Ping1D] No Fault, FailCount= 1
2019-04-11T20:43:09.322Z,1555015389.322 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-11T20:43:10.129Z,1555015390.129 [BR_Ping1D](INFO): UART requesting status
2019-04-11T20:43:10.534Z,1555015390.534 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:10.534Z,1555015390.534 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:10.941Z,1555015390.941 [BR_Ping1D](INFO): startup done
2019-04-11T20:43:11.447Z,1555015391.447 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:11.838Z,1555015391.838 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:11.838Z,1555015391.838 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:11.838Z,1555015391.838 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:12.253Z,1555015392.253 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:12.253Z,1555015392.253 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:12.253Z,1555015392.253 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:12.660Z,1555015392.660 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:12.660Z,1555015392.660 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:12.661Z,1555015392.661 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:13.029Z,1555015393.029 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-11T20:43:13.030Z,1555015393.030 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2019-04-11T20:43:13.030Z,1555015393.030 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-11T20:43:13.065Z,1555015393.065 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:13.065Z,1555015393.065 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:13.065Z,1555015393.065 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:13.199Z,1555015393.199 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-11T20:43:13.433Z,1555015393.433 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-11T20:43:13.585Z,1555015393.585 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:13.586Z,1555015393.586 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:13.586Z,1555015393.586 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:13.862Z,1555015393.862 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:13.863Z,1555015393.863 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:13.863Z,1555015393.863 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:14.266Z,1555015394.266 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:14.266Z,1555015394.266 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:14.266Z,1555015394.266 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:14.318Z,1555015394.318 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-11T20:43:14.318Z,1555015394.318 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2019-04-11T20:43:14.672Z,1555015394.672 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:14.672Z,1555015394.672 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:14.673Z,1555015394.673 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:15.073Z,1555015395.073 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:15.073Z,1555015395.073 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:15.074Z,1555015395.074 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:15.474Z,1555015395.474 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:15.474Z,1555015395.474 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:15.475Z,1555015395.475 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:15.882Z,1555015395.882 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:15.883Z,1555015395.883 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:15.883Z,1555015395.883 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:15.884Z,1555015395.884 [BR_Ping1D](FAULT): Failed to acquire valid data within timeout.
2019-04-11T20:43:15.884Z,1555015395.884 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-11T20:43:15.884Z,1555015395.884 [BR_Ping1D](ERROR): Data Fault
2019-04-11T20:43:15.899Z,1555015395.899 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-11T20:43:16.286Z,1555015396.286 [BR_Ping1D](INFO): Powering down
2019-04-11T20:43:16.665Z,1555015396.665 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-11T20:43:16.665Z,1555015396.665 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-11T20:43:17.527Z,1555015397.527 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-11T20:43:17.527Z,1555015397.527 [BR_Ping1D] No Fault, FailCount= 1
2019-04-11T20:43:18.438Z,1555015398.438 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-11T20:43:18.720Z,1555015398.720 [BPC1](ERROR): BPC1A: No match for serial number 03FE in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2019-04-11T20:43:18.721Z,1555015398.721 [BPC1](ERROR): BPC1B: No match for serial number 0451 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2019-04-11T20:43:18.721Z,1555015398.721 [BPC1](FAULT): Failed to parse data from all battery packs.
2019-04-11T20:43:18.721Z,1555015398.721 [BPC1] Data Fault, FailCount= 1
2019-04-11T20:43:18.721Z,1555015398.721 [BPC1](ERROR): Data Fault
2019-04-11T20:43:18.739Z,1555015398.739 [CBIT](ERROR): Data Fault in component: BPC1
2019-04-11T20:43:19.109Z,1555015399.109 [BR_Ping1D](INFO): UART requesting status
2019-04-11T20:43:19.513Z,1555015399.513 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:19.514Z,1555015399.514 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:19.914Z,1555015399.914 [BR_Ping1D](INFO): startup done
2019-04-11T20:43:20.321Z,1555015400.321 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:20.725Z,1555015400.725 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:20.725Z,1555015400.725 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:20.725Z,1555015400.725 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:21.129Z,1555015401.129 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:21.129Z,1555015401.129 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:21.129Z,1555015401.129 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:21.535Z,1555015401.535 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:21.535Z,1555015401.535 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:21.535Z,1555015401.535 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:21.946Z,1555015401.946 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:21.946Z,1555015401.946 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:21.946Z,1555015401.946 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:22.341Z,1555015402.341 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:22.341Z,1555015402.341 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:22.342Z,1555015402.342 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:22.354Z,1555015402.354 [SBIT](IMPORTANT): SBIT PASSED
2019-04-11T20:43:22.401Z,1555015402.401 [CommandLine](IMPORTANT): got command configSet list
2019-04-11T20:43:22.401Z,1555015402.401 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-04-11T20:43:22.402Z,1555015402.402 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-04-11T20:43:22.402Z,1555015402.402 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2019-04-11T20:43:22.755Z,1555015402.755 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:22.756Z,1555015402.756 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:22.756Z,1555015402.756 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:22.763Z,1555015402.763 [MissionManager](IMPORTANT): Started mission Startup
2019-04-11T20:43:22.763Z,1555015402.763 [Startup] Running Loop=1
2019-04-11T20:43:22.764Z,1555015402.764 [Startup](DEBUG): Aggregate::initialize Startup
2019-04-11T20:43:22.764Z,1555015402.764 [Startup:A.GoToSurface] Running Loop=1
2019-04-11T20:43:22.764Z,1555015402.764 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-11T20:43:22.764Z,1555015402.764 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-11T20:43:22.765Z,1555015402.765 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-11T20:43:22.765Z,1555015402.765 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-11T20:43:22.766Z,1555015402.766 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-11T20:43:22.766Z,1555015402.766 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-11T20:43:22.767Z,1555015402.767 [Startup:StartupSatComms] Running Loop=1
2019-04-11T20:43:22.768Z,1555015402.768 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-04-11T20:43:22.768Z,1555015402.768 [Startup:StartupSatComms:A] Running Loop=1
2019-04-11T20:43:23.159Z,1555015403.159 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:23.159Z,1555015403.159 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:23.160Z,1555015403.160 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:23.177Z,1555015403.177 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-04-11T20:43:23.564Z,1555015403.564 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:23.564Z,1555015403.564 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:23.564Z,1555015403.564 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:23.967Z,1555015403.967 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:23.967Z,1555015403.967 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:23.968Z,1555015403.968 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:24.365Z,1555015404.365 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:24.365Z,1555015404.365 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:24.366Z,1555015404.366 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:24.764Z,1555015404.764 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:24.765Z,1555015404.765 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:24.765Z,1555015404.765 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:24.766Z,1555015404.766 [BR_Ping1D](FAULT): Failed to acquire valid data within timeout.
2019-04-11T20:43:24.766Z,1555015404.766 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-11T20:43:24.767Z,1555015404.767 [BR_Ping1D](ERROR): Data Fault
2019-04-11T20:43:24.783Z,1555015404.783 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-11T20:43:25.172Z,1555015405.172 [BR_Ping1D](INFO): Powering down
2019-04-11T20:43:26.398Z,1555015406.398 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-11T20:43:26.398Z,1555015406.398 [BR_Ping1D] No Fault, FailCount= 1
2019-04-11T20:43:27.597Z,1555015407.597 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-11T20:43:28.412Z,1555015408.412 [BR_Ping1D](INFO): UART requesting status
2019-04-11T20:43:28.818Z,1555015408.818 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:28.818Z,1555015408.818 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:29.220Z,1555015409.220 [BR_Ping1D](INFO): startup done
2019-04-11T20:43:29.617Z,1555015409.617 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:30.017Z,1555015410.017 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:30.017Z,1555015410.017 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:30.017Z,1555015410.017 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:30.425Z,1555015410.425 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:30.425Z,1555015410.425 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:30.426Z,1555015410.426 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:30.822Z,1555015410.822 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:30.822Z,1555015410.822 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:30.823Z,1555015410.823 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:31.228Z,1555015411.228 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:31.228Z,1555015411.228 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:31.229Z,1555015411.229 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:31.635Z,1555015411.635 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:31.635Z,1555015411.635 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:31.635Z,1555015411.635 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:32.037Z,1555015412.037 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:32.037Z,1555015412.037 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:32.037Z,1555015412.037 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:32.441Z,1555015412.441 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:32.441Z,1555015412.441 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:32.441Z,1555015412.441 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:32.849Z,1555015412.849 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:32.849Z,1555015412.849 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:32.850Z,1555015412.850 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:33.260Z,1555015413.260 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:33.260Z,1555015413.260 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:33.260Z,1555015413.260 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:33.663Z,1555015413.663 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:33.663Z,1555015413.663 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:33.664Z,1555015413.664 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:34.076Z,1555015414.076 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:34.076Z,1555015414.076 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:34.076Z,1555015414.076 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:34.077Z,1555015414.077 [BR_Ping1D](FAULT): Failed to acquire valid data within timeout.
2019-04-11T20:43:34.078Z,1555015414.078 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-11T20:43:34.078Z,1555015414.078 [BR_Ping1D](ERROR): Data Fault
2019-04-11T20:43:34.098Z,1555015414.098 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-11T20:43:34.471Z,1555015414.471 [BR_Ping1D](INFO): Powering down
2019-04-11T20:43:35.703Z,1555015415.703 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-11T20:43:35.703Z,1555015415.703 [BR_Ping1D] No Fault, FailCount= 1
2019-04-11T20:43:36.865Z,1555015416.865 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-11T20:43:36.865Z,1555015416.865 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2019-04-11T20:43:36.865Z,1555015416.865 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-11T20:43:36.882Z,1555015416.882 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-11T20:43:36.919Z,1555015416.919 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-11T20:43:37.269Z,1555015417.269 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-11T20:43:37.409Z,1555015417.409 [BR_Ping1D](INFO): UART requesting status
2019-04-11T20:43:37.697Z,1555015417.697 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:37.697Z,1555015417.697 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:37.699Z,1555015417.699 [BR_Ping1D](INFO): not all values verified yet
2019-04-11T20:43:38.097Z,1555015418.097 [BR_Ping1D](INFO): not all values verified yet
2019-04-11T20:43:38.119Z,1555015418.119 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-11T20:43:38.119Z,1555015418.119 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2019-04-11T20:43:38.515Z,1555015418.515 [BR_Ping1D](INFO): not all values verified yet
2019-04-11T20:43:38.920Z,1555015418.920 [BR_Ping1D](INFO): not all values verified yet
2019-04-11T20:43:39.322Z,1555015419.322 [BR_Ping1D](INFO): not all values verified yet
2019-04-11T20:43:39.724Z,1555015419.724 [BR_Ping1D](INFO): not all values verified yet
2019-04-11T20:43:40.121Z,1555015420.121 [BR_Ping1D](INFO): not all values verified yet
2019-04-11T20:43:40.501Z,1555015420.501 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-11T20:43:40.501Z,1555015420.501 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-11T20:43:40.537Z,1555015420.537 [BR_Ping1D](INFO): not all values verified yet
2019-04-11T20:43:40.922Z,1555015420.922 [BR_Ping1D](INFO): not all values verified yet
2019-04-11T20:43:41.329Z,1555015421.329 [BR_Ping1D](INFO): not all values verified yet
2019-04-11T20:43:41.734Z,1555015421.734 [BR_Ping1D](INFO): not all values verified yet
2019-04-11T20:43:42.138Z,1555015422.138 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-11T20:43:42.138Z,1555015422.138 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-11T20:43:42.138Z,1555015422.138 [BR_Ping1D](ERROR): Data Fault
2019-04-11T20:43:42.153Z,1555015422.153 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-11T20:43:42.545Z,1555015422.545 [BR_Ping1D](INFO): Powering down
2019-04-11T20:43:43.787Z,1555015423.787 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-11T20:43:43.787Z,1555015423.787 [BR_Ping1D] No Fault, FailCount= 1
2019-04-11T20:43:44.979Z,1555015424.979 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-11T20:43:45.780Z,1555015425.780 [BR_Ping1D](INFO): UART requesting status
2019-04-11T20:43:46.186Z,1555015426.186 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:46.584Z,1555015426.584 [BR_Ping1D](INFO): startup done
2019-04-11T20:43:46.983Z,1555015426.983 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:47.389Z,1555015427.389 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:47.389Z,1555015427.389 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:47.389Z,1555015427.389 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:47.793Z,1555015427.793 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:47.793Z,1555015427.793 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:47.794Z,1555015427.794 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:48.197Z,1555015428.197 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:48.197Z,1555015428.197 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:48.197Z,1555015428.197 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:48.601Z,1555015428.601 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:48.602Z,1555015428.602 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:48.602Z,1555015428.602 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:49.018Z,1555015429.018 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:49.018Z,1555015429.018 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:49.019Z,1555015429.019 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:49.424Z,1555015429.424 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:49.424Z,1555015429.424 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:49.424Z,1555015429.424 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:49.827Z,1555015429.827 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:49.827Z,1555015429.827 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:49.828Z,1555015429.828 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:50.228Z,1555015430.228 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:50.228Z,1555015430.228 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:50.228Z,1555015430.228 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:50.621Z,1555015430.621 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:50.621Z,1555015430.621 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:50.621Z,1555015430.621 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:51.030Z,1555015431.030 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:51.030Z,1555015431.030 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:51.030Z,1555015431.030 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:51.433Z,1555015431.433 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:51.433Z,1555015431.433 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:51.433Z,1555015431.433 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:51.434Z,1555015431.434 [BR_Ping1D](FAULT): Failed to acquire valid data within timeout.
2019-04-11T20:43:51.435Z,1555015431.435 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-11T20:43:51.435Z,1555015431.435 [BR_Ping1D](ERROR): Data Fault
2019-04-11T20:43:51.454Z,1555015431.454 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-11T20:43:51.834Z,1555015431.834 [BR_Ping1D](INFO): Powering down
2019-04-11T20:43:53.056Z,1555015433.056 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-11T20:43:53.056Z,1555015433.056 [BR_Ping1D] No Fault, FailCount= 1
2019-04-11T20:43:54.271Z,1555015434.271 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-11T20:43:55.079Z,1555015435.079 [BR_Ping1D](INFO): UART requesting status
2019-04-11T20:43:55.483Z,1555015435.483 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:55.483Z,1555015435.483 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:55.873Z,1555015435.873 [BR_Ping1D](INFO): startup done
2019-04-11T20:43:56.281Z,1555015436.281 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:56.681Z,1555015436.681 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:56.681Z,1555015436.681 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:56.682Z,1555015436.682 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:57.089Z,1555015437.089 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:57.089Z,1555015437.089 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:57.090Z,1555015437.090 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:57.489Z,1555015437.489 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:57.489Z,1555015437.489 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:57.489Z,1555015437.489 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:57.893Z,1555015437.893 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:57.893Z,1555015437.893 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:57.893Z,1555015437.893 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:58.297Z,1555015438.297 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:58.298Z,1555015438.298 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:58.298Z,1555015438.298 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:58.706Z,1555015438.706 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:58.706Z,1555015438.706 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:58.706Z,1555015438.706 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:59.109Z,1555015439.109 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:59.109Z,1555015439.109 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:59.110Z,1555015439.110 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:59.519Z,1555015439.519 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:59.519Z,1555015439.519 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:59.520Z,1555015439.520 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:43:59.924Z,1555015439.924 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:43:59.924Z,1555015439.924 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:43:59.924Z,1555015439.924 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:00.331Z,1555015440.331 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:00.331Z,1555015440.331 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:00.331Z,1555015440.331 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:00.701Z,1555015440.701 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-11T20:44:00.701Z,1555015440.701 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2019-04-11T20:44:00.702Z,1555015440.702 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-11T20:44:00.732Z,1555015440.732 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:00.732Z,1555015440.732 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:00.732Z,1555015440.732 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:00.733Z,1555015440.733 [BR_Ping1D](FAULT): Failed to acquire valid data within timeout.
2019-04-11T20:44:00.734Z,1555015440.734 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-11T20:44:00.734Z,1555015440.734 [BR_Ping1D](ERROR): Data Fault
2019-04-11T20:44:00.755Z,1555015440.755 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-11T20:44:00.755Z,1555015440.755 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-11T20:44:01.106Z,1555015441.106 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-11T20:44:01.248Z,1555015441.248 [BR_Ping1D](INFO): Powering down
2019-04-11T20:44:01.948Z,1555015441.948 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-11T20:44:01.948Z,1555015441.948 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2019-04-11T20:44:02.359Z,1555015442.359 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-11T20:44:02.359Z,1555015442.359 [BR_Ping1D] No Fault, FailCount= 1
2019-04-11T20:44:03.552Z,1555015443.552 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-11T20:44:04.337Z,1555015444.337 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-11T20:44:04.338Z,1555015444.338 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-11T20:44:04.376Z,1555015444.376 [BR_Ping1D](INFO): UART requesting status
2019-04-11T20:44:04.384Z,1555015444.384 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-11T20:44:04.385Z,1555015444.385 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-04-11T20:44:04.385Z,1555015444.385 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-11T20:44:04.388Z,1555015444.388 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-11T20:44:04.389Z,1555015444.389 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-04-11T20:44:04.389Z,1555015444.389 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-11T20:44:04.390Z,1555015444.390 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-11T20:44:04.390Z,1555015444.390 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-04-11T20:44:04.390Z,1555015444.390 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-11T20:44:04.401Z,1555015444.401 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-11T20:44:04.401Z,1555015444.401 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-11T20:44:04.402Z,1555015444.402 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-11T20:44:04.771Z,1555015444.771 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:04.771Z,1555015444.771 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:04.787Z,1555015444.787 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-11T20:44:04.787Z,1555015444.787 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-04-11T20:44:04.787Z,1555015444.787 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-11T20:44:04.787Z,1555015444.787 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-04-11T20:44:04.788Z,1555015444.788 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-11T20:44:04.788Z,1555015444.788 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-04-11T20:44:05.174Z,1555015445.174 [BR_Ping1D](INFO): startup done
2019-04-11T20:44:05.196Z,1555015445.196 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-11T20:44:05.580Z,1555015445.580 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:05.986Z,1555015445.986 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:05.987Z,1555015445.987 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:05.987Z,1555015445.987 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:06.378Z,1555015446.378 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:06.378Z,1555015446.378 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:06.378Z,1555015446.378 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:06.781Z,1555015446.781 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:06.781Z,1555015446.781 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:06.782Z,1555015446.782 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:07.185Z,1555015447.185 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:07.185Z,1555015447.185 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:07.186Z,1555015447.186 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:07.589Z,1555015447.589 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:07.589Z,1555015447.589 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:07.590Z,1555015447.590 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:07.993Z,1555015447.993 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:07.993Z,1555015447.993 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:07.994Z,1555015447.994 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:08.397Z,1555015448.397 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:08.397Z,1555015448.397 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:08.397Z,1555015448.397 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:08.801Z,1555015448.801 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:08.801Z,1555015448.801 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:08.801Z,1555015448.801 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:09.211Z,1555015449.211 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:09.211Z,1555015449.211 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:09.212Z,1555015449.212 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:09.609Z,1555015449.609 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:09.609Z,1555015449.609 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:09.610Z,1555015449.610 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:10.023Z,1555015450.023 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:10.024Z,1555015450.024 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:10.024Z,1555015450.024 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:10.025Z,1555015450.025 [BR_Ping1D](FAULT): Failed to acquire valid data within timeout.
2019-04-11T20:44:10.025Z,1555015450.025 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-11T20:44:10.025Z,1555015450.025 [BR_Ping1D](ERROR): Data Fault
2019-04-11T20:44:10.062Z,1555015450.062 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-11T20:44:10.428Z,1555015450.428 [BR_Ping1D](INFO): Powering down
2019-04-11T20:44:11.646Z,1555015451.646 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-11T20:44:11.647Z,1555015451.647 [BR_Ping1D] No Fault, FailCount= 1
2019-04-11T20:44:12.848Z,1555015452.848 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-11T20:44:13.653Z,1555015453.653 [BR_Ping1D](INFO): UART requesting status
2019-04-11T20:44:14.057Z,1555015454.057 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:14.057Z,1555015454.057 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:14.461Z,1555015454.461 [BR_Ping1D](INFO): startup done
2019-04-11T20:44:14.858Z,1555015454.858 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:15.280Z,1555015455.280 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:15.280Z,1555015455.280 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:15.280Z,1555015455.280 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:15.679Z,1555015455.679 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:15.679Z,1555015455.679 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:15.680Z,1555015455.680 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:16.086Z,1555015456.086 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:16.086Z,1555015456.086 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:16.086Z,1555015456.086 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:16.490Z,1555015456.490 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:16.490Z,1555015456.490 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:16.490Z,1555015456.490 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:16.881Z,1555015456.881 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:16.881Z,1555015456.881 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:16.882Z,1555015456.882 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:17.283Z,1555015457.283 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:17.283Z,1555015457.283 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:17.283Z,1555015457.283 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:17.693Z,1555015457.693 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:17.693Z,1555015457.693 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:17.694Z,1555015457.694 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:18.093Z,1555015458.093 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:18.093Z,1555015458.093 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:18.093Z,1555015458.093 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:18.497Z,1555015458.497 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:18.497Z,1555015458.497 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:18.498Z,1555015458.498 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:18.905Z,1555015458.905 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:18.905Z,1555015458.905 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:18.905Z,1555015458.905 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:19.309Z,1555015459.309 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:19.309Z,1555015459.309 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:19.310Z,1555015459.310 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:19.311Z,1555015459.311 [BR_Ping1D](FAULT): Failed to acquire valid data within timeout.
2019-04-11T20:44:19.311Z,1555015459.311 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-11T20:44:19.311Z,1555015459.311 [BR_Ping1D](ERROR): Data Fault
2019-04-11T20:44:19.333Z,1555015459.333 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-11T20:44:19.712Z,1555015459.712 [BR_Ping1D](INFO): Powering down
2019-04-11T20:44:20.951Z,1555015460.951 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-11T20:44:20.951Z,1555015460.951 [BR_Ping1D] No Fault, FailCount= 1
2019-04-11T20:44:22.130Z,1555015462.130 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-11T20:44:22.941Z,1555015462.941 [BR_Ping1D](INFO): UART requesting status
2019-04-11T20:44:22.950Z,1555015462.950 [Startup:StartupSatComms:A](INFO): Timed out from 2019-04-11T20:43:22.8Z
2019-04-11T20:44:22.950Z,1555015462.950 [Startup:StartupSatComms:A] Stopped
2019-04-11T20:44:22.950Z,1555015462.950 [Startup:StartupSatComms:B] Running Loop=1
2019-04-11T20:44:23.345Z,1555015463.345 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:23.345Z,1555015463.345 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:23.365Z,1555015463.365 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-11T20:44:23.902Z,1555015463.902 [BR_Ping1D](INFO): startup done
2019-04-11T20:44:24.279Z,1555015464.279 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:24.662Z,1555015464.662 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-11T20:44:24.662Z,1555015464.662 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2019-04-11T20:44:24.662Z,1555015464.662 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-11T20:44:24.682Z,1555015464.682 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:24.682Z,1555015464.682 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:24.682Z,1555015464.682 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:24.755Z,1555015464.755 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-11T20:44:25.065Z,1555015465.065 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-11T20:44:25.202Z,1555015465.202 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:25.202Z,1555015465.202 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:25.203Z,1555015465.203 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:25.503Z,1555015465.503 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:25.503Z,1555015465.503 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:25.504Z,1555015465.504 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:25.903Z,1555015465.903 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:25.904Z,1555015465.904 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:25.904Z,1555015465.904 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:25.928Z,1555015465.928 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-11T20:44:25.928Z,1555015465.928 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2019-04-11T20:44:26.307Z,1555015466.307 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:26.307Z,1555015466.307 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:26.308Z,1555015466.308 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:26.716Z,1555015466.716 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:26.716Z,1555015466.716 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:26.717Z,1555015466.717 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:27.109Z,1555015467.109 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:27.109Z,1555015467.109 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:27.109Z,1555015467.109 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:27.511Z,1555015467.511 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:27.511Z,1555015467.511 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:27.511Z,1555015467.511 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:27.918Z,1555015467.918 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:27.918Z,1555015467.918 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:27.918Z,1555015467.918 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:28.297Z,1555015468.297 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-11T20:44:28.297Z,1555015468.297 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-11T20:44:28.330Z,1555015468.330 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:28.330Z,1555015468.330 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:28.331Z,1555015468.331 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:28.721Z,1555015468.721 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:28.721Z,1555015468.721 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:28.721Z,1555015468.721 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:28.722Z,1555015468.722 [BR_Ping1D](FAULT): Failed to acquire valid data within timeout.
2019-04-11T20:44:28.723Z,1555015468.723 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-11T20:44:28.723Z,1555015468.723 [BR_Ping1D](ERROR): Data Fault
2019-04-11T20:44:28.743Z,1555015468.743 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-11T20:44:29.125Z,1555015469.125 [BR_Ping1D](INFO): Powering down
2019-04-11T20:44:30.359Z,1555015470.359 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-11T20:44:30.359Z,1555015470.359 [BR_Ping1D] No Fault, FailCount= 1
2019-04-11T20:44:31.560Z,1555015471.560 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-11T20:44:32.361Z,1555015472.361 [BR_Ping1D](INFO): UART requesting status
2019-04-11T20:44:32.758Z,1555015472.758 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:32.758Z,1555015472.758 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:33.165Z,1555015473.165 [BR_Ping1D](INFO): startup done
2019-04-11T20:44:33.574Z,1555015473.574 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:33.977Z,1555015473.977 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:33.977Z,1555015473.977 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:33.977Z,1555015473.977 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:34.377Z,1555015474.377 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:34.377Z,1555015474.377 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:34.377Z,1555015474.377 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:34.785Z,1555015474.785 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:34.785Z,1555015474.785 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:34.786Z,1555015474.786 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:35.185Z,1555015475.185 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:35.185Z,1555015475.185 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:35.185Z,1555015475.185 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:35.218Z,1555015475.218 [CommandLine](IMPORTANT): got command restart application
2019-04-11T20:44:35.589Z,1555015475.589 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:35.589Z,1555015475.589 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:35.590Z,1555015475.590 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:36.007Z,1555015476.007 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:36.007Z,1555015476.007 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:36.008Z,1555015476.008 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:36.221Z,1555015476.221 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-11T20:44:36.221Z,1555015476.221 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-11T20:44:36.285Z,1555015476.285 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-04-11T20:44:36.285Z,1555015476.285 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-11T20:44:36.285Z,1555015476.285 [CommandLine](INFO): Join timeout helper Thread ID is 2125
2019-04-11T20:44:36.286Z,1555015476.286 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-04-11T20:44:36.286Z,1555015476.286 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-11T20:44:36.286Z,1555015476.286 [NavChartDb](INFO): Join timeout helper Thread ID is 2126
2019-04-11T20:44:36.321Z,1555015476.321 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-11T20:44:36.321Z,1555015476.321 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-11T20:44:36.325Z,1555015476.325 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-04-11T20:44:36.325Z,1555015476.325 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-11T20:44:36.325Z,1555015476.325 [Radio_Surface](INFO): Join timeout helper Thread ID is 2127
2019-04-11T20:44:36.408Z,1555015476.408 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:36.408Z,1555015476.408 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:36.408Z,1555015476.408 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:36.502Z,1555015476.502 [Radio_Surface](INFO): Powering down
2019-04-11T20:44:36.502Z,1555015476.502 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-11T20:44:36.503Z,1555015476.503 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-11T20:44:36.509Z,1555015476.509 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-04-11T20:44:36.509Z,1555015476.509 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-11T20:44:36.509Z,1555015476.509 [DataOverHttps](INFO): Join timeout helper Thread ID is 2128
2019-04-11T20:44:36.815Z,1555015476.815 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:36.815Z,1555015476.815 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:36.816Z,1555015476.816 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:36.905Z,1555015476.905 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-11T20:44:36.905Z,1555015476.905 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-11T20:44:36.910Z,1555015476.910 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-04-11T20:44:36.910Z,1555015476.910 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-11T20:44:36.910Z,1555015476.910 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2129
2019-04-11T20:44:36.921Z,1555015476.921 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-11T20:44:36.921Z,1555015476.921 [WetLabsBB2FL](INFO): Powering down
2019-04-11T20:44:36.922Z,1555015476.922 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-11T20:44:36.930Z,1555015476.930 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-04-11T20:44:36.930Z,1555015476.930 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-11T20:44:36.930Z,1555015476.930 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2130
2019-04-11T20:44:37.218Z,1555015477.218 [BR_Ping1D](ERROR): Using more than 100ms to receive one packet, resetting UART
2019-04-11T20:44:37.218Z,1555015477.218 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-04-11T20:44:37.219Z,1555015477.219 [BR_Ping1D](ERROR): Trying to receive packet that is larger than buffer, size: 22
2019-04-11T20:44:37.277Z,1555015477.277 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-11T20:44:37.277Z,1555015477.277 [CTD_Seabird](INFO): Powering down
2019-04-11T20:44:37.289Z,1555015477.289 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-11T20:44:37.290Z,1555015477.290 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-04-11T20:44:37.290Z,1555015477.290 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-11T20:44:37.291Z,1555015477.291 [logger](INFO): Join timeout helper Thread ID is 2131
2019-04-11T20:44:37.305Z,1555015477.305 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-11T20:44:37.305Z,1555015477.305 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-11T20:44:37.310Z,1555015477.310 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-04-11T20:44:37.310Z,1555015477.310 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-11T20:44:37.310Z,1555015477.310 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-04-11T20:44:37.310Z,1555015477.310 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-11T20:44:37.310Z,1555015477.310 [controlThread](INFO): Join timeout helper Thread ID is 2132
2019-04-11T20:44:37.585Z,1555015477.585 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-11T20:44:37.585Z,1555015477.585 [controlThread](DEBUG): Uninitializing ControlThread
2019-04-11T20:44:37.585Z,1555015477.585 [Aanderaa_O2](INFO): Powering down
2019-04-11T20:44:37.587Z,1555015477.587 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-11T20:44:37.705Z,1555015477.705 [NAL9602](INFO): Powering down
2019-04-11T20:44:37.707Z,1555015477.707 [BR_Ping1D](INFO): Powering down
2019-04-11T20:44:37.777Z,1555015477.777 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-04-11T20:44:37.778Z,1555015477.778 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-04-11T20:44:37.779Z,1555015477.779 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-04-11T20:44:37.780Z,1555015477.780 [MissionManager](INFO): Uninitializing Mission Default
2019-04-11T20:44:37.782Z,1555015477.782 [MissionManager](INFO): Uninitializing Mission Startup
2019-04-11T20:44:37.782Z,1555015477.782 [Startup] Stopped
2019-04-11T20:44:37.782Z,1555015477.782 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-04-11T20:44:37.782Z,1555015477.782 [Startup:A.GoToSurface] Stopped
2019-04-11T20:44:37.782Z,1555015477.782 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-11T20:44:37.782Z,1555015477.782 [Startup:StartupSatComms] Stopped
2019-04-11T20:44:37.782Z,1555015477.782 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-04-11T20:44:37.782Z,1555015477.782 [Startup:StartupSatComms:B] Stopped
2019-04-11T20:44:37.784Z,1555015477.784 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-04-11T20:44:37.784Z,1555015477.784 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-04-11T20:44:37.784Z,1555015477.784 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-04-11T20:44:37.785Z,1555015477.785 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-04-11T20:44:37.785Z,1555015477.785 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-04-11T20:44:37.785Z,1555015477.785 [BuoyancyServo](INFO): Powering down
2019-04-11T20:44:37.797Z,1555015477.797 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-11T20:44:37.797Z,1555015477.797 [ElevatorServo](INFO): Powering down
2019-04-11T20:44:37.798Z,1555015477.798 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-11T20:44:37.798Z,1555015477.798 [MassServo](INFO): Powering down
2019-04-11T20:44:37.799Z,1555015477.799 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-11T20:44:37.799Z,1555015477.799 [RudderServo](INFO): Powering down
2019-04-11T20:44:37.800Z,1555015477.800 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-04-11T20:44:37.800Z,1555015477.800 [ThrusterServo](INFO): Powering down
2019-04-11T20:44:37.800Z,1555015477.800 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-04-11T20:44:37.801Z,1555015477.801 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-04-11T20:44:37.801Z,1555015477.801 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-04-11T20:44:37.801Z,1555015477.801 [CBIT](DEBUG): Powering off loads.
2019-04-11T20:44:37.813Z,1555015477.813 [CBIT](DEBUG): Disabling WDT.
2019-04-11T20:44:37.825Z,1555015477.825 [CBIT](DEBUG): Opening all GF detection circuits.
2019-04-11T20:44:37.825Z,1555015477.825 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-11T20:44:37.856Z,1555015477.856 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-11T20:44:37.861Z,1555015477.861 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-11T20:44:37.932Z,1555015477.932 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-11T20:44:37.937Z,1555015477.937 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-11T20:44:37.987Z,1555015477.987 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-11T20:44:38.048Z,1555015478.048 [logger ThreadHandler](INFO): Thread cancelled.