2019-04-10T01:20:34.615Z,1554859234.615 [Supervisor](DEBUG): Initializing supervisor.
2019-04-10T01:20:34.618Z,1554859234.618 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-10T01:20:34.619Z,1554859234.619 [SyncHandler](INFO): Protected caller Thread ID is 1308
2019-04-10T01:20:34.620Z,1554859234.620 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-04-10T01:20:34.621Z,1554859234.621 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-04-10T01:20:34.621Z,1554859234.621 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1309
2019-04-10T01:20:34.624Z,1554859234.624 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-04-10T01:20:34.637Z,1554859234.637 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-04-10T01:20:34.638Z,1554859234.638 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-04-10T01:20:34.639Z,1554859234.639 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1310
2019-04-10T01:20:34.640Z,1554859234.640 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-04-10T01:20:34.641Z,1554859234.641 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-04-10T01:20:34.641Z,1554859234.641 [logger ThreadHandler](INFO): Protected caller Thread ID is 1311
2019-04-10T01:20:34.643Z,1554859234.643 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-04-10T01:20:34.643Z,1554859234.643 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-04-10T01:20:34.645Z,1554859234.645 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-04-10T01:20:34.739Z,1554859234.739 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-04-10T01:20:34.740Z,1554859234.740 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-04-10T01:20:34.941Z,1554859234.941 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-04-10T01:20:34.941Z,1554859234.941 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-04-10T01:20:35.077Z,1554859235.077 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-04-10T01:20:35.078Z,1554859235.078 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-04-10T01:20:35.541Z,1554859235.541 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-04-10T01:20:35.542Z,1554859235.542 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-04-10T01:20:36.015Z,1554859236.015 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-04-10T01:20:36.015Z,1554859236.015 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-04-10T01:20:36.796Z,1554859236.796 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-04-10T01:20:36.797Z,1554859236.797 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-04-10T01:20:37.105Z,1554859237.105 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-04-10T01:20:37.105Z,1554859237.105 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-04-10T01:20:37.437Z,1554859237.437 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-04-10T01:20:37.438Z,1554859237.438 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-04-10T01:20:37.833Z,1554859237.833 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-04-10T01:20:37.834Z,1554859237.834 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-04-10T01:20:37.983Z,1554859237.983 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-04-10T01:20:37.984Z,1554859237.984 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-04-10T01:20:38.088Z,1554859238.088 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-04-10T01:20:38.089Z,1554859238.089 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-04-10T01:20:38.168Z,1554859238.168 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-04-10T01:20:38.269Z,1554859238.269 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-04-10T01:20:38.270Z,1554859238.270 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-04-10T01:20:38.464Z,1554859238.464 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-04-10T01:20:38.465Z,1554859238.465 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-04-10T01:20:38.673Z,1554859238.673 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-04-10T01:20:38.675Z,1554859238.675 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-04-10T01:20:38.675Z,1554859238.675 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-04-10T01:20:38.757Z,1554859238.757 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-04-10T01:20:38.989Z,1554859238.989 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-04-10T01:20:38.990Z,1554859238.990 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-04-10T01:20:39.084Z,1554859239.084 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-04-10T01:20:39.257Z,1554859239.257 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-04-10T01:20:39.470Z,1554859239.470 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-04-10T01:20:39.555Z,1554859239.555 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-04-10T01:20:39.653Z,1554859239.653 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-04-10T01:20:39.745Z,1554859239.745 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-04-10T01:20:39.896Z,1554859239.896 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-04-10T01:20:39.999Z,1554859239.999 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-04-10T01:20:40.097Z,1554859240.097 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2019-04-10T01:20:40.097Z,1554859240.097 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-04-10T01:20:40.101Z,1554859240.101 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-04-10T01:20:40.200Z,1554859240.200 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-04-10T01:20:40.200Z,1554859240.200 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-04-10T01:20:40.334Z,1554859240.334 [BuoyancyServo] Loaded
2019-04-10T01:20:40.334Z,1554859240.334 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-04-10T01:20:40.350Z,1554859240.350 [ElevatorServo] Loaded
2019-04-10T01:20:40.350Z,1554859240.350 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-04-10T01:20:40.366Z,1554859240.366 [MassServo] Loaded
2019-04-10T01:20:40.366Z,1554859240.366 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-04-10T01:20:40.381Z,1554859240.381 [RudderServo] Loaded
2019-04-10T01:20:40.382Z,1554859240.382 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-04-10T01:20:40.396Z,1554859240.396 [ThrusterServo] Loaded
2019-04-10T01:20:40.397Z,1554859240.397 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-04-10T01:20:40.397Z,1554859240.397 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-04-10T01:20:40.398Z,1554859240.398 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-04-10T01:20:40.533Z,1554859240.533 [SBIT](DEBUG): Construct Startup Built In Test.
2019-04-10T01:20:40.562Z,1554859240.562 [SBIT] Loaded
2019-04-10T01:20:40.562Z,1554859240.562 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-04-10T01:20:40.563Z,1554859240.563 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-04-10T01:20:40.592Z,1554859240.592 [IBIT] Loaded
2019-04-10T01:20:40.592Z,1554859240.592 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-04-10T01:20:40.595Z,1554859240.595 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-04-10T01:20:40.991Z,1554859240.991 [CBIT] Loaded
2019-04-10T01:20:40.992Z,1554859240.992 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-04-10T01:20:40.992Z,1554859240.992 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-04-10T01:20:40.993Z,1554859240.993 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-04-10T01:20:41.720Z,1554859241.720 [Aanderaa_O2] Loaded
2019-04-10T01:20:41.721Z,1554859241.721 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-04-10T01:20:41.738Z,1554859241.738 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-04-10T01:20:41.784Z,1554859241.784 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-04-10T01:20:41.786Z,1554859241.786 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-04-10T01:20:41.798Z,1554859241.798 [CTD_Seabird](INFO): created writer for : depth
2019-04-10T01:20:41.799Z,1554859241.799 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-04-10T01:20:41.804Z,1554859241.804 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-04-10T01:20:41.805Z,1554859241.805 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-04-10T01:20:41.910Z,1554859241.910 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-04-10T01:20:41.910Z,1554859241.910 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-04-10T01:20:41.935Z,1554859241.935 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-04-10T01:20:41.936Z,1554859241.936 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-04-10T01:20:41.962Z,1554859241.962 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-04-10T01:20:41.962Z,1554859241.962 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-04-10T01:20:41.968Z,1554859241.968 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-04-10T01:20:42.070Z,1554859242.070 [CTD_Seabird] Loaded
2019-04-10T01:20:42.070Z,1554859242.070 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-04-10T01:20:42.071Z,1554859242.071 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406654E0
2019-04-10T01:20:42.072Z,1554859242.072 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1390
2019-04-10T01:20:42.099Z,1554859242.099 [ESPComponent] Loaded
2019-04-10T01:20:42.099Z,1554859242.099 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-04-10T01:20:42.113Z,1554859242.113 [PAR_Licor] Loaded
2019-04-10T01:20:42.114Z,1554859242.114 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-04-10T01:20:42.167Z,1554859242.167 [WetLabsBB2FL] Loaded
2019-04-10T01:20:42.167Z,1554859242.167 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-04-10T01:20:42.168Z,1554859242.168 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406954E0
2019-04-10T01:20:42.169Z,1554859242.169 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1391
2019-04-10T01:20:42.169Z,1554859242.169 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-04-10T01:20:42.170Z,1554859242.170 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-04-10T01:20:42.211Z,1554859242.211 [DepthRateCalculator] Loaded
2019-04-10T01:20:42.211Z,1554859242.211 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-04-10T01:20:42.217Z,1554859242.217 [PitchRateCalculator] Loaded
2019-04-10T01:20:42.217Z,1554859242.217 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-04-10T01:20:42.234Z,1554859242.234 [SpeedCalculator] Loaded
2019-04-10T01:20:42.234Z,1554859242.234 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-04-10T01:20:42.256Z,1554859242.256 [TempGradientCalculator] Loaded
2019-04-10T01:20:42.256Z,1554859242.256 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-04-10T01:20:42.263Z,1554859242.263 [YawRateCalculator] Loaded
2019-04-10T01:20:42.263Z,1554859242.263 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-04-10T01:20:42.297Z,1554859242.297 [ElevatorOffsetCalculator] Loaded
2019-04-10T01:20:42.298Z,1554859242.298 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-04-10T01:20:42.298Z,1554859242.298 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-04-10T01:20:42.299Z,1554859242.299 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-04-10T01:20:42.326Z,1554859242.326 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-04-10T01:20:42.326Z,1554859242.326 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-04-10T01:20:42.431Z,1554859242.431 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-04-10T01:20:42.431Z,1554859242.431 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-04-10T01:20:42.492Z,1554859242.492 [DeadReckonUsingSpeedCalculator] Loaded
2019-04-10T01:20:42.493Z,1554859242.493 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-04-10T01:20:42.555Z,1554859242.555 [DeadReckonWithRespectToSeafloor] Loaded
2019-04-10T01:20:42.555Z,1554859242.555 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-04-10T01:20:42.577Z,1554859242.577 [NavChart] Loaded
2019-04-10T01:20:42.577Z,1554859242.577 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-04-10T01:20:42.581Z,1554859242.581 [UniversalFixResidualReporter] Loaded
2019-04-10T01:20:42.581Z,1554859242.581 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-04-10T01:20:42.582Z,1554859242.582 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-04-10T01:20:42.582Z,1554859242.582 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-04-10T01:20:42.894Z,1554859242.894 [AcousticModem_Benthos_ATM900] Loaded
2019-04-10T01:20:42.895Z,1554859242.895 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-04-10T01:20:42.967Z,1554859242.967 [DataOverHttps] Loaded
2019-04-10T01:20:42.968Z,1554859242.968 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-04-10T01:20:42.969Z,1554859242.969 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409714E0
2019-04-10T01:20:42.969Z,1554859242.969 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1392
2019-04-10T01:20:42.983Z,1554859242.983 [Depth_Keller] Loaded
2019-04-10T01:20:42.983Z,1554859242.983 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-04-10T01:20:43.077Z,1554859243.077 [NAL9602] Loaded
2019-04-10T01:20:43.077Z,1554859243.077 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-04-10T01:20:43.082Z,1554859243.082 [Onboard] Loaded
2019-04-10T01:20:43.083Z,1554859243.083 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-04-10T01:20:43.086Z,1554859243.086 [Radio_Surface] Loaded
2019-04-10T01:20:43.087Z,1554859243.087 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-04-10T01:20:43.088Z,1554859243.088 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A14E0
2019-04-10T01:20:43.088Z,1554859243.088 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1393
2019-04-10T01:20:44.397Z,1554859244.397 [BPC1] Loaded
2019-04-10T01:20:44.397Z,1554859244.397 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-04-10T01:20:44.428Z,1554859244.428 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minrange
2019-04-10T01:20:44.431Z,1554859244.431 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.maxrange
2019-04-10T01:20:44.433Z,1554859244.433 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.soundspeed
2019-04-10T01:20:44.435Z,1554859244.435 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.automode
2019-04-10T01:20:44.437Z,1554859244.437 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.gainindex
2019-04-10T01:20:44.439Z,1554859244.439 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minconfidence
2019-04-10T01:20:44.441Z,1554859244.441 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.pingenable
2019-04-10T01:20:44.444Z,1554859244.444 [BR_Ping1D] Loaded
2019-04-10T01:20:44.444Z,1554859244.444 [ComponentRegistry](DEBUG): SyncComponent "BR_Ping1D" handled in the control thread.
2019-04-10T01:20:44.444Z,1554859244.444 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-04-10T01:20:44.445Z,1554859244.445 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-04-10T01:20:44.510Z,1554859244.510 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-04-10T01:20:44.511Z,1554859244.511 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-04-10T01:20:44.577Z,1554859244.577 [VerticalControl](DEBUG): Construct VerticalControl.
2019-04-10T01:20:44.664Z,1554859244.664 [VerticalControl] Loaded
2019-04-10T01:20:44.665Z,1554859244.665 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-04-10T01:20:44.665Z,1554859244.665 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-04-10T01:20:44.725Z,1554859244.725 [HorizontalControl] Loaded
2019-04-10T01:20:44.725Z,1554859244.725 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-04-10T01:20:44.726Z,1554859244.726 [SpeedControl](DEBUG): Construct SpeedControl.
2019-04-10T01:20:44.728Z,1554859244.728 [SpeedControl] Loaded
2019-04-10T01:20:44.728Z,1554859244.728 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-04-10T01:20:44.729Z,1554859244.729 [LoopControl](DEBUG): Construct LoopControl.
2019-04-10T01:20:44.730Z,1554859244.730 [LoopControl] Loaded
2019-04-10T01:20:44.730Z,1554859244.730 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-04-10T01:20:44.730Z,1554859244.730 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-04-10T01:20:44.731Z,1554859244.731 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-04-10T01:20:44.744Z,1554859244.744 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-04-10T01:20:44.745Z,1554859244.745 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-04-10T01:20:45.023Z,1554859245.023 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-04-10T01:20:45.027Z,1554859245.027 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-04-10T01:20:45.028Z,1554859245.028 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-04-10T01:20:45.035Z,1554859245.035 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-04-10T01:20:45.036Z,1554859245.036 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACE4E0
2019-04-10T01:20:45.036Z,1554859245.036 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1394
2019-04-10T01:20:45.041Z,1554859245.041 [Supervisor](INFO): Main Thread ID is 9999
2019-04-10T01:20:45.041Z,1554859245.041 [Supervisor](DEBUG): Running supervisor.
2019-04-10T01:20:45.042Z,1554859245.042 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1395
2019-04-10T01:20:45.044Z,1554859245.044 [controlThread ThreadHandler](INFO): Handler Thread ID is 1396
2019-04-10T01:20:45.045Z,1554859245.045 [controlThread](DEBUG): Initializing ControlThread
2019-04-10T01:20:45.047Z,1554859245.047 [SBIT](INFO): Initialize SBIT Component.
2019-04-10T01:20:45.047Z,1554859245.047 [SBIT](IMPORTANT): git: 2019-03-18-31-ge98d5dc
2019-04-10T01:20:45.047Z,1554859245.047 [SBIT](INFO): git hash: e98d5dc50cbc39e402f19b9946ff4dc0776c8725
2019-04-10T01:20:45.048Z,1554859245.048 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-04-10T01:20:45.049Z,1554859245.049 [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-10T01:20:45.050Z,1554859245.050 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-04-10T01:20:45.051Z,1554859245.051 [IBIT](INFO): Initialize IBIT Component.
2019-04-10T01:20:45.052Z,1554859245.052 [CBIT](DEBUG): Initialize CBIT Component.
2019-04-10T01:20:45.053Z,1554859245.053 [logger ThreadHandler](INFO): Handler Thread ID is 1397
2019-04-10T01:20:45.066Z,1554859245.066 [CBIT](DEBUG): Initialized mux pins.
2019-04-10T01:20:45.066Z,1554859245.066 [CBIT](DEBUG): Initializing the watchdog timer.
2019-04-10T01:20:45.078Z,1554859245.078 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1398
2019-04-10T01:20:45.079Z,1554859245.079 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T01:20:45.082Z,1554859245.082 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T01:20:45.084Z,1554859245.084 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1400
2019-04-10T01:20:45.085Z,1554859245.085 [WetLabsBB2FL](INFO): Powering down
2019-04-10T01:20:45.090Z,1554859245.090 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-04-10T01:20:45.090Z,1554859245.090 [CBIT](DEBUG): Initializing heartbeat.
2019-04-10T01:20:45.114Z,1554859245.114 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1401
2019-04-10T01:20:45.115Z,1554859245.115 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-04-10T01:20:45.123Z,1554859245.123 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1402
2019-04-10T01:20:45.138Z,1554859245.138 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1403
2019-04-10T01:20:45.141Z,1554859245.141 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-10T01:20:45.141Z,1554859245.141 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-04-10T01:20:45.142Z,1554859245.142 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-10T01:20:45.142Z,1554859245.142 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-04-10T01:20:45.142Z,1554859245.142 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-10T01:20:45.143Z,1554859245.143 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-04-10T01:20:45.143Z,1554859245.143 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-10T01:20:45.143Z,1554859245.143 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-04-10T01:20:45.143Z,1554859245.143 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-10T01:20:45.143Z,1554859245.143 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-04-10T01:20:45.144Z,1554859245.144 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-10T01:20:45.144Z,1554859245.144 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-04-10T01:20:45.144Z,1554859245.144 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-10T01:20:45.144Z,1554859245.144 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-04-10T01:20:45.144Z,1554859245.144 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-10T01:20:45.144Z,1554859245.144 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-04-10T01:20:45.162Z,1554859245.162 [CBIT](DEBUG): Deactivating GF circuits.
2019-04-10T01:20:45.162Z,1554859245.162 [CBIT](DEBUG): Deactivating emergency mode.
2019-04-10T01:20:45.198Z,1554859245.198 [CBIT](DEBUG): Backplane powered.
2019-04-10T01:20:45.202Z,1554859245.202 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-04-10T01:20:45.203Z,1554859245.203 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-04-10T01:20:45.203Z,1554859245.203 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-04-10T01:20:45.203Z,1554859245.203 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-04-10T01:20:45.204Z,1554859245.204 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-04-10T01:20:45.204Z,1554859245.204 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-04-10T01:20:45.205Z,1554859245.205 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-10T01:20:45.206Z,1554859245.206 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-04-10T01:20:45.207Z,1554859245.207 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-04-10T01:20:45.211Z,1554859245.211 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-04-10T01:20:45.213Z,1554859245.213 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-04-10T01:20:45.214Z,1554859245.214 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-04-10T01:20:45.214Z,1554859245.214 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-04-10T01:20:45.215Z,1554859245.215 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-04-10T01:20:45.237Z,1554859245.237 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-10T01:20:45.273Z,1554859245.273 [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-10T01:20:45.314Z,1554859245.314 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-04-10T01:20:45.428Z,1554859245.428 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-04-10T01:20:45.429Z,1554859245.429 [Default:A.Wait](DEBUG): Construct Wait.
2019-04-10T01:20:45.443Z,1554859245.443 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-10T01:20:45.510Z,1554859245.510 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-04-10T01:20:45.513Z,1554859245.513 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-04-10T01:20:45.526Z,1554859245.526 [Radio_Surface](INFO): Powering up
2019-04-10T01:20:45.551Z,1554859245.551 [Default:E.Execute](DEBUG): Construct Execute.
2019-04-10T01:20:45.578Z,1554859245.578 [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-10T01:20:45.584Z,1554859245.584 [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-10T01:20:45.672Z,1554859245.672 [ESPComponent](INFO): powering down ESP
2019-04-10T01:20:46.358Z,1554859246.358 [PAR_Licor](INFO): A/D timeout, 10 tries over 126 ms

2019-04-10T01:20:46.359Z,1554859246.359 [PAR_Licor] Data Fault, FailCount= 1
2019-04-10T01:20:46.359Z,1554859246.359 [PAR_Licor](ERROR): Data Fault
2019-04-10T01:20:46.359Z,1554859246.359 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T01:20:46.360Z,1554859246.360 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T01:20:46.654Z,1554859246.654 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-04-10T01:20:46.666Z,1554859246.666 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-04-10T01:20:46.668Z,1554859246.668 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-10T01:20:46.678Z,1554859246.678 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-10T01:20:46.679Z,1554859246.679 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-10T01:20:46.750Z,1554859246.750 [MassServo](DEBUG): Initializing MassServo.
2019-04-10T01:20:46.751Z,1554859246.751 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-10T01:20:46.821Z,1554859246.821 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-10T01:20:46.822Z,1554859246.822 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-04-10T01:20:46.898Z,1554859246.898 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-04-10T01:20:46.901Z,1554859246.901 [CommandLine](FAULT): Scheduling is paused
2019-04-10T01:20:46.901Z,1554859246.901 [CBIT](INFO): Critical error at 20190410T012044
2019-04-10T01:20:46.901Z,1554859246.901 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-04-10T01:20:46.906Z,1554859246.906 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T01:20:46.906Z,1554859246.906 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T01:20:46.906Z,1554859246.906 [PAR_Licor] No Fault, FailCount= 1
2019-04-10T01:20:47.450Z,1554859247.450 [PAR_Licor](INFO): A/D timeout, 7 tries over 134 ms

2019-04-10T01:20:47.450Z,1554859247.450 [PAR_Licor] Data Fault, FailCount= 2
2019-04-10T01:20:47.450Z,1554859247.450 [PAR_Licor](ERROR): Data Fault
2019-04-10T01:20:47.484Z,1554859247.484 [BR_Ping1D](INFO): startup: 0
2019-04-10T01:20:47.484Z,1554859247.484 [BR_Ping1D](INFO): recvData: FFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF
2019-04-10T01:20:47.485Z,1554859247.485 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T01:20:47.498Z,1554859247.498 [BR_Ping1D](INFO): 3131313130313030
2019-04-10T01:20:47.498Z,1554859247.498 [BR_Ping1D](INFO): 31
2019-04-10T01:20:47.498Z,1554859247.498 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T01:20:47.499Z,1554859247.499 [BR_Ping1D](INFO): XXX autoMode1: 01
2019-04-10T01:20:47.499Z,1554859247.499 [BR_Ping1D](INFO): XXX autoMode2: 
2019-04-10T01:20:47.499Z,1554859247.499 [BR_Ping1D](INFO): XXX autoMode3: 1
2019-04-10T01:20:47.499Z,1554859247.499 [BR_Ping1D](INFO): 31
2019-04-10T01:20:47.499Z,1554859247.499 [BR_Ping1D](INFO): 42520100EB030000840145
2019-04-10T01:20:47.499Z,1554859247.499 [BR_Ping1D](INFO): 31313030303030
2019-04-10T01:20:47.500Z,1554859247.500 [BR_Ping1D](INFO): 3131313030303131
2019-04-10T01:20:47.500Z,1554859247.500 [BR_Ping1D](INFO): 3130313130
2019-04-10T01:20:47.500Z,1554859247.500 [BR_Ping1D](INFO): 30
2019-04-10T01:20:47.500Z,1554859247.500 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T01:20:47.500Z,1554859247.500 [BR_Ping1D](INFO): 31
2019-04-10T01:20:47.500Z,1554859247.500 [BR_Ping1D](INFO): 42520100EE030000870116
2019-04-10T01:20:47.821Z,1554859247.821 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T01:20:47.822Z,1554859247.822 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T01:20:47.822Z,1554859247.822 [PAR_Licor] No Fault, FailCount= 2
2019-04-10T01:20:47.861Z,1554859247.861 [BR_Ping1D](INFO): Starting
2019-04-10T01:20:48.362Z,1554859248.362 [PAR_Licor](INFO): A/D timeout, 8 tries over 130 ms

2019-04-10T01:20:48.362Z,1554859248.362 [PAR_Licor] Data Fault, FailCount= 3
2019-04-10T01:20:48.362Z,1554859248.362 [PAR_Licor](ERROR): Data Fault
2019-04-10T01:20:48.397Z,1554859248.397 [BR_Ping1D](INFO): Starting
2019-04-10T01:20:48.397Z,1554859248.397 [BR_Ping1D](INFO): readConfig
2019-04-10T01:20:48.397Z,1554859248.397 [BR_Ping1D](INFO): startup: 1
2019-04-10T01:20:48.398Z,1554859248.398 [BR_Ping1D](INFO): UART requesting status
2019-04-10T01:20:48.398Z,1554859248.398 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T01:20:48.406Z,1554859248.406 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T01:20:48.414Z,1554859248.414 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T01:20:48.438Z,1554859248.438 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T01:20:48.439Z,1554859248.439 [CBIT](CRITICAL): Data Fault in component: PAR_Licor
2019-04-10T01:20:48.654Z,1554859248.654 [BR_Ping1D](INFO): Starting
2019-04-10T01:20:48.654Z,1554859248.654 [BR_Ping1D](INFO): readConfig
2019-04-10T01:20:48.654Z,1554859248.654 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:20:48.654Z,1554859248.654 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:20:48.655Z,1554859248.655 [BR_Ping1D](INFO): elapsed 1.1699
2019-04-10T01:20:48.655Z,1554859248.655 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:20:48.655Z,1554859248.655 [BR_Ping1D](INFO): No UART data
2019-04-10T01:20:48.655Z,1554859248.655 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:20:48.655Z,1554859248.655 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:20:48.668Z,1554859248.668 [CBIT](INFO): Critical error at 20190410T012048
2019-04-10T01:20:49.057Z,1554859249.057 [BR_Ping1D](INFO): Starting
2019-04-10T01:20:49.057Z,1554859249.057 [BR_Ping1D](INFO): readConfig
2019-04-10T01:20:49.058Z,1554859249.058 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:20:49.058Z,1554859249.058 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:20:49.058Z,1554859249.058 [BR_Ping1D](INFO): elapsed 1.5735
2019-04-10T01:20:49.058Z,1554859249.058 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:20:49.059Z,1554859249.059 [BR_Ping1D](INFO): No UART data
2019-04-10T01:20:49.059Z,1554859249.059 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:20:49.059Z,1554859249.059 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:20:49.462Z,1554859249.462 [BR_Ping1D](INFO): Starting
2019-04-10T01:20:49.462Z,1554859249.462 [BR_Ping1D](INFO): readConfig
2019-04-10T01:20:49.463Z,1554859249.463 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:20:49.463Z,1554859249.463 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:20:49.463Z,1554859249.463 [BR_Ping1D](INFO): elapsed 1.9783
2019-04-10T01:20:49.463Z,1554859249.463 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:20:49.463Z,1554859249.463 [BR_Ping1D](INFO): No UART data
2019-04-10T01:20:49.464Z,1554859249.464 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:20:49.464Z,1554859249.464 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:20:49.865Z,1554859249.865 [BR_Ping1D](INFO): Starting
2019-04-10T01:20:49.865Z,1554859249.865 [BR_Ping1D](INFO): readConfig
2019-04-10T01:20:49.865Z,1554859249.865 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:20:49.865Z,1554859249.865 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:20:49.866Z,1554859249.866 [BR_Ping1D](INFO): elapsed 2.3810
2019-04-10T01:20:49.866Z,1554859249.866 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:20:49.866Z,1554859249.866 [BR_Ping1D](INFO): No UART data
2019-04-10T01:20:49.866Z,1554859249.866 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:20:49.866Z,1554859249.866 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:20:50.270Z,1554859250.270 [BR_Ping1D](INFO): Starting
2019-04-10T01:20:50.270Z,1554859250.270 [BR_Ping1D](INFO): readConfig
2019-04-10T01:20:50.270Z,1554859250.270 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:20:50.270Z,1554859250.270 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:20:50.271Z,1554859250.271 [BR_Ping1D](INFO): elapsed 2.7858
2019-04-10T01:20:50.271Z,1554859250.271 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:20:50.271Z,1554859250.271 [BR_Ping1D](INFO): No UART data
2019-04-10T01:20:50.271Z,1554859250.271 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:20:50.271Z,1554859250.271 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:20:50.673Z,1554859250.673 [BR_Ping1D](INFO): Starting
2019-04-10T01:20:50.674Z,1554859250.674 [BR_Ping1D](INFO): readConfig
2019-04-10T01:20:50.674Z,1554859250.674 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:20:50.674Z,1554859250.674 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:20:50.674Z,1554859250.674 [BR_Ping1D](INFO): elapsed 3.1895
2019-04-10T01:20:50.674Z,1554859250.674 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:20:50.675Z,1554859250.675 [BR_Ping1D](INFO): No UART data
2019-04-10T01:20:50.675Z,1554859250.675 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:20:50.675Z,1554859250.675 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:20:51.078Z,1554859251.078 [BR_Ping1D](INFO): Starting
2019-04-10T01:20:51.079Z,1554859251.079 [BR_Ping1D](INFO): readConfig
2019-04-10T01:20:51.079Z,1554859251.079 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:20:51.079Z,1554859251.079 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:20:51.080Z,1554859251.080 [BR_Ping1D](INFO): elapsed 3.5949
2019-04-10T01:20:51.080Z,1554859251.080 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:20:51.080Z,1554859251.080 [BR_Ping1D](INFO): No UART data
2019-04-10T01:20:51.080Z,1554859251.080 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:20:51.080Z,1554859251.080 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:20:51.481Z,1554859251.481 [BR_Ping1D](INFO): Starting
2019-04-10T01:20:51.482Z,1554859251.482 [BR_Ping1D](INFO): readConfig
2019-04-10T01:20:51.482Z,1554859251.482 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:20:51.482Z,1554859251.482 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:20:51.482Z,1554859251.482 [BR_Ping1D](INFO): elapsed 3.9976
2019-04-10T01:20:51.482Z,1554859251.482 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:20:51.483Z,1554859251.483 [BR_Ping1D](INFO): No UART data
2019-04-10T01:20:51.483Z,1554859251.483 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:20:51.483Z,1554859251.483 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:20:51.885Z,1554859251.885 [BR_Ping1D](INFO): Starting
2019-04-10T01:20:51.886Z,1554859251.886 [BR_Ping1D](INFO): readConfig
2019-04-10T01:20:51.886Z,1554859251.886 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:20:51.886Z,1554859251.886 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:20:51.886Z,1554859251.886 [BR_Ping1D](INFO): elapsed 4.4015
2019-04-10T01:20:51.886Z,1554859251.886 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:20:51.887Z,1554859251.887 [BR_Ping1D](INFO): No UART data
2019-04-10T01:20:51.887Z,1554859251.887 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:20:51.887Z,1554859251.887 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:20:52.289Z,1554859252.289 [BR_Ping1D](INFO): Starting
2019-04-10T01:20:52.289Z,1554859252.289 [BR_Ping1D](INFO): readConfig
2019-04-10T01:20:52.290Z,1554859252.290 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:20:52.290Z,1554859252.290 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:20:52.290Z,1554859252.290 [BR_Ping1D](INFO): elapsed 4.8053
2019-04-10T01:20:52.290Z,1554859252.290 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:20:52.290Z,1554859252.290 [BR_Ping1D](INFO): No UART data
2019-04-10T01:20:52.291Z,1554859252.291 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:20:52.291Z,1554859252.291 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:20:52.694Z,1554859252.694 [BR_Ping1D](INFO): Starting
2019-04-10T01:20:52.694Z,1554859252.694 [BR_Ping1D](INFO): readConfig
2019-04-10T01:20:52.694Z,1554859252.694 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:20:52.694Z,1554859252.694 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:20:52.694Z,1554859252.694 [BR_Ping1D](INFO): elapsed 5.2097
2019-04-10T01:20:52.695Z,1554859252.695 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:20:52.695Z,1554859252.695 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T01:20:52.695Z,1554859252.695 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-10T01:20:52.695Z,1554859252.695 [BR_Ping1D](ERROR): Data Fault
2019-04-10T01:20:52.755Z,1554859252.755 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T01:20:53.097Z,1554859253.097 [BR_Ping1D](INFO): Stop
2019-04-10T01:20:53.097Z,1554859253.097 [BR_Ping1D](INFO): uninitialize
2019-04-10T01:20:53.097Z,1554859253.097 [BR_Ping1D](INFO): Powering down
2019-04-10T01:20:53.502Z,1554859253.502 [BR_Ping1D](INFO): Stopping
2019-04-10T01:20:53.905Z,1554859253.905 [BR_Ping1D](INFO): Stopping
2019-04-10T01:20:54.309Z,1554859254.309 [BR_Ping1D](INFO): Stopped
2019-04-10T01:20:54.363Z,1554859254.363 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T01:20:54.363Z,1554859254.363 [BR_Ping1D] No Fault, FailCount= 1
2019-04-10T01:20:54.713Z,1554859254.713 [BR_Ping1D](INFO): Start
2019-04-10T01:20:55.117Z,1554859255.117 [BR_Ping1D](INFO): Starting
2019-04-10T01:20:55.282Z,1554859255.282 [CBIT](CRITICAL): Environmental Failure. Press:14.871092 PSI. Humidity:37%. Temp:26 C. ABORTING MISSION
2019-04-10T01:20:55.521Z,1554859255.521 [BR_Ping1D](INFO): Starting
2019-04-10T01:20:55.521Z,1554859255.521 [BR_Ping1D](INFO): readConfig
2019-04-10T01:20:55.522Z,1554859255.522 [BR_Ping1D](INFO): startup: 0
2019-04-10T01:20:55.522Z,1554859255.522 [BR_Ping1D](INFO): recvData: FFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF
2019-04-10T01:20:55.522Z,1554859255.522 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T01:20:55.534Z,1554859255.534 [BR_Ping1D](INFO): 3131313130313030
2019-04-10T01:20:55.534Z,1554859255.534 [BR_Ping1D](INFO): 31
2019-04-10T01:20:55.534Z,1554859255.534 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T01:20:55.534Z,1554859255.534 [BR_Ping1D](INFO): XXX autoMode1: 01
2019-04-10T01:20:55.534Z,1554859255.534 [BR_Ping1D](INFO): XXX autoMode2: 
2019-04-10T01:20:55.535Z,1554859255.535 [BR_Ping1D](INFO): XXX autoMode3: 1
2019-04-10T01:20:55.535Z,1554859255.535 [BR_Ping1D](INFO): 31
2019-04-10T01:20:55.535Z,1554859255.535 [BR_Ping1D](INFO): 42520100EB030000840145
2019-04-10T01:20:55.535Z,1554859255.535 [BR_Ping1D](INFO): 31313030303030
2019-04-10T01:20:55.535Z,1554859255.535 [BR_Ping1D](INFO): 3131313030303131
2019-04-10T01:20:55.535Z,1554859255.535 [BR_Ping1D](INFO): 3130313130
2019-04-10T01:20:55.535Z,1554859255.535 [BR_Ping1D](INFO): 30
2019-04-10T01:20:55.536Z,1554859255.536 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T01:20:55.536Z,1554859255.536 [BR_Ping1D](INFO): 31
2019-04-10T01:20:55.536Z,1554859255.536 [BR_Ping1D](INFO): 42520100EE030000870116
2019-04-10T01:20:55.576Z,1554859255.576 [CBIT](INFO): Critical error at 20190410T012055
2019-04-10T01:20:55.906Z,1554859255.906 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T01:20:55.906Z,1554859255.906 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-10T01:20:55.907Z,1554859255.907 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T01:20:55.926Z,1554859255.926 [BR_Ping1D](INFO): Starting
2019-04-10T01:20:55.955Z,1554859255.955 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T01:20:56.430Z,1554859256.430 [Aanderaa_O2](INFO): Powering down
2019-04-10T01:20:56.530Z,1554859256.530 [BR_Ping1D](INFO): Starting
2019-04-10T01:20:56.530Z,1554859256.530 [BR_Ping1D](INFO): readConfig
2019-04-10T01:20:56.531Z,1554859256.531 [BR_Ping1D](INFO): startup: 1
2019-04-10T01:20:56.531Z,1554859256.531 [BR_Ping1D](INFO): UART requesting status
2019-04-10T01:20:56.531Z,1554859256.531 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T01:20:56.538Z,1554859256.538 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T01:20:56.546Z,1554859256.546 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T01:20:56.733Z,1554859256.733 [BR_Ping1D](INFO): Starting
2019-04-10T01:20:56.734Z,1554859256.734 [BR_Ping1D](INFO): readConfig
2019-04-10T01:20:56.734Z,1554859256.734 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:20:56.734Z,1554859256.734 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:20:56.734Z,1554859256.734 [BR_Ping1D](INFO): elapsed 1.2121
2019-04-10T01:20:56.734Z,1554859256.734 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:20:56.735Z,1554859256.735 [BR_Ping1D](INFO): No UART data
2019-04-10T01:20:56.735Z,1554859256.735 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:20:56.735Z,1554859256.735 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:20:57.138Z,1554859257.138 [BR_Ping1D](INFO): Starting
2019-04-10T01:20:57.138Z,1554859257.138 [BR_Ping1D](INFO): readConfig
2019-04-10T01:20:57.138Z,1554859257.138 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:20:57.138Z,1554859257.138 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:20:57.138Z,1554859257.138 [BR_Ping1D](INFO): elapsed 1.6163
2019-04-10T01:20:57.139Z,1554859257.139 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:20:57.139Z,1554859257.139 [BR_Ping1D](INFO): No UART data
2019-04-10T01:20:57.139Z,1554859257.139 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:20:57.139Z,1554859257.139 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:20:57.171Z,1554859257.171 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T01:20:57.171Z,1554859257.171 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-10T01:20:57.546Z,1554859257.546 [BR_Ping1D](INFO): Starting
2019-04-10T01:20:57.546Z,1554859257.546 [BR_Ping1D](INFO): readConfig
2019-04-10T01:20:57.546Z,1554859257.546 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:20:57.546Z,1554859257.546 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:20:57.547Z,1554859257.547 [BR_Ping1D](INFO): elapsed 2.0245
2019-04-10T01:20:57.547Z,1554859257.547 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:20:57.547Z,1554859257.547 [BR_Ping1D](INFO): No UART data
2019-04-10T01:20:57.547Z,1554859257.547 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:20:57.547Z,1554859257.547 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:20:57.957Z,1554859257.957 [BR_Ping1D](INFO): Starting
2019-04-10T01:20:57.958Z,1554859257.958 [BR_Ping1D](INFO): readConfig
2019-04-10T01:20:57.958Z,1554859257.958 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:20:57.958Z,1554859257.958 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:20:57.958Z,1554859257.958 [BR_Ping1D](INFO): elapsed 2.4361
2019-04-10T01:20:57.958Z,1554859257.958 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:20:57.959Z,1554859257.959 [BR_Ping1D](INFO): No UART data
2019-04-10T01:20:57.959Z,1554859257.959 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:20:57.959Z,1554859257.959 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:20:58.130Z,1554859258.130 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T01:20:58.130Z,1554859258.130 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-10T01:20:58.130Z,1554859258.130 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T01:20:58.350Z,1554859258.350 [BR_Ping1D](INFO): Starting
2019-04-10T01:20:58.350Z,1554859258.350 [BR_Ping1D](INFO): readConfig
2019-04-10T01:20:58.350Z,1554859258.350 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:20:58.350Z,1554859258.350 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:20:58.351Z,1554859258.351 [BR_Ping1D](INFO): elapsed 2.8285
2019-04-10T01:20:58.351Z,1554859258.351 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:20:58.351Z,1554859258.351 [BR_Ping1D](INFO): No UART data
2019-04-10T01:20:58.351Z,1554859258.351 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:20:58.351Z,1554859258.351 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:20:58.367Z,1554859258.367 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T01:20:58.534Z,1554859258.534 [CTD_Seabird](INFO): Powering down
2019-04-10T01:20:58.753Z,1554859258.753 [BR_Ping1D](INFO): Starting
2019-04-10T01:20:58.753Z,1554859258.753 [BR_Ping1D](INFO): readConfig
2019-04-10T01:20:58.754Z,1554859258.754 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:20:58.754Z,1554859258.754 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:20:58.754Z,1554859258.754 [BR_Ping1D](INFO): elapsed 3.2320
2019-04-10T01:20:58.754Z,1554859258.754 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:20:58.755Z,1554859258.755 [BR_Ping1D](INFO): No UART data
2019-04-10T01:20:58.755Z,1554859258.755 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:20:58.755Z,1554859258.755 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:20:59.158Z,1554859259.158 [BR_Ping1D](INFO): Starting
2019-04-10T01:20:59.158Z,1554859259.158 [BR_Ping1D](INFO): readConfig
2019-04-10T01:20:59.158Z,1554859259.158 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:20:59.158Z,1554859259.158 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:20:59.158Z,1554859259.158 [BR_Ping1D](INFO): elapsed 3.6363
2019-04-10T01:20:59.159Z,1554859259.159 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:20:59.159Z,1554859259.159 [BR_Ping1D](INFO): No UART data
2019-04-10T01:20:59.159Z,1554859259.159 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:20:59.159Z,1554859259.159 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:20:59.565Z,1554859259.565 [BR_Ping1D](INFO): Starting
2019-04-10T01:20:59.565Z,1554859259.565 [BR_Ping1D](INFO): readConfig
2019-04-10T01:20:59.565Z,1554859259.565 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:20:59.566Z,1554859259.566 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:20:59.566Z,1554859259.566 [BR_Ping1D](INFO): elapsed 4.0437
2019-04-10T01:20:59.566Z,1554859259.566 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:20:59.566Z,1554859259.566 [BR_Ping1D](INFO): No UART data
2019-04-10T01:20:59.566Z,1554859259.566 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:20:59.567Z,1554859259.567 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:20:59.583Z,1554859259.583 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-10T01:20:59.583Z,1554859259.583 [CTD_Seabird] No Fault, FailCount= 1
2019-04-10T01:20:59.914Z,1554859259.914 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T01:20:59.916Z,1554859259.916 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T01:20:59.966Z,1554859259.966 [BR_Ping1D](INFO): Starting
2019-04-10T01:20:59.966Z,1554859259.966 [BR_Ping1D](INFO): readConfig
2019-04-10T01:20:59.966Z,1554859259.966 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:20:59.966Z,1554859259.966 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:20:59.966Z,1554859259.966 [BR_Ping1D](INFO): elapsed 4.4443
2019-04-10T01:20:59.967Z,1554859259.967 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:20:59.967Z,1554859259.967 [BR_Ping1D](INFO): No UART data
2019-04-10T01:20:59.967Z,1554859259.967 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:20:59.967Z,1554859259.967 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:00.369Z,1554859260.369 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:00.369Z,1554859260.369 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:00.369Z,1554859260.369 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:00.369Z,1554859260.369 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:00.370Z,1554859260.370 [BR_Ping1D](INFO): elapsed 4.8475
2019-04-10T01:21:00.370Z,1554859260.370 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:00.370Z,1554859260.370 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:00.370Z,1554859260.370 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:00.370Z,1554859260.370 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:00.774Z,1554859260.774 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:00.774Z,1554859260.774 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:00.774Z,1554859260.774 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:00.774Z,1554859260.774 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:00.774Z,1554859260.774 [BR_Ping1D](INFO): elapsed 5.2522
2019-04-10T01:21:00.775Z,1554859260.775 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:00.775Z,1554859260.775 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T01:21:00.775Z,1554859260.775 [BR_Ping1D] Data Fault, FailCount= 2
2019-04-10T01:21:00.775Z,1554859260.775 [BR_Ping1D](ERROR): Data Fault
2019-04-10T01:21:00.791Z,1554859260.791 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T01:21:01.177Z,1554859261.177 [BR_Ping1D](INFO): Stop
2019-04-10T01:21:01.177Z,1554859261.177 [BR_Ping1D](INFO): uninitialize
2019-04-10T01:21:01.178Z,1554859261.178 [BR_Ping1D](INFO): Powering down
2019-04-10T01:21:01.583Z,1554859261.583 [BR_Ping1D](INFO): Stopping
2019-04-10T01:21:01.985Z,1554859261.985 [BR_Ping1D](INFO): Stopping
2019-04-10T01:21:02.390Z,1554859262.390 [BR_Ping1D](INFO): Stopped
2019-04-10T01:21:02.415Z,1554859262.415 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T01:21:02.416Z,1554859262.416 [BR_Ping1D] No Fault, FailCount= 2
2019-04-10T01:21:02.793Z,1554859262.793 [BR_Ping1D](INFO): Start
2019-04-10T01:21:03.199Z,1554859263.199 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:03.602Z,1554859263.602 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:03.602Z,1554859263.602 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:03.602Z,1554859263.602 [BR_Ping1D](INFO): startup: 0
2019-04-10T01:21:03.603Z,1554859263.603 [BR_Ping1D](INFO): recvData: FFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF
2019-04-10T01:21:03.603Z,1554859263.603 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T01:21:03.614Z,1554859263.614 [BR_Ping1D](INFO): 3131313130313030
2019-04-10T01:21:03.614Z,1554859263.614 [BR_Ping1D](INFO): 31
2019-04-10T01:21:03.614Z,1554859263.614 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T01:21:03.614Z,1554859263.614 [BR_Ping1D](INFO): XXX autoMode1: 01
2019-04-10T01:21:03.615Z,1554859263.615 [BR_Ping1D](INFO): XXX autoMode2: 
2019-04-10T01:21:03.615Z,1554859263.615 [BR_Ping1D](INFO): XXX autoMode3: 1
2019-04-10T01:21:03.615Z,1554859263.615 [BR_Ping1D](INFO): 31
2019-04-10T01:21:03.615Z,1554859263.615 [BR_Ping1D](INFO): 42520100EB030000840145
2019-04-10T01:21:03.615Z,1554859263.615 [BR_Ping1D](INFO): 31313030303030
2019-04-10T01:21:03.615Z,1554859263.615 [BR_Ping1D](INFO): 3131313030303131
2019-04-10T01:21:03.615Z,1554859263.615 [BR_Ping1D](INFO): 3130313130
2019-04-10T01:21:03.616Z,1554859263.616 [BR_Ping1D](INFO): 30
2019-04-10T01:21:03.616Z,1554859263.616 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T01:21:03.616Z,1554859263.616 [BR_Ping1D](INFO): 31
2019-04-10T01:21:03.616Z,1554859263.616 [BR_Ping1D](INFO): 42520100EE030000870116
2019-04-10T01:21:04.006Z,1554859264.006 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:04.409Z,1554859264.409 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:04.409Z,1554859264.409 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:04.409Z,1554859264.409 [BR_Ping1D](INFO): startup: 1
2019-04-10T01:21:04.410Z,1554859264.410 [BR_Ping1D](INFO): UART requesting status
2019-04-10T01:21:04.410Z,1554859264.410 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T01:21:04.418Z,1554859264.418 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T01:21:04.426Z,1554859264.426 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T01:21:04.813Z,1554859264.813 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:04.814Z,1554859264.814 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:04.814Z,1554859264.814 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:04.814Z,1554859264.814 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:04.814Z,1554859264.814 [BR_Ping1D](INFO): elapsed 1.2114
2019-04-10T01:21:04.814Z,1554859264.814 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:04.815Z,1554859264.815 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:04.815Z,1554859264.815 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:04.815Z,1554859264.815 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:05.217Z,1554859265.217 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:05.217Z,1554859265.217 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:05.217Z,1554859265.217 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:05.218Z,1554859265.218 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:05.218Z,1554859265.218 [BR_Ping1D](INFO): elapsed 1.6149
2019-04-10T01:21:05.218Z,1554859265.218 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:05.218Z,1554859265.218 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:05.218Z,1554859265.218 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:05.219Z,1554859265.219 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:05.621Z,1554859265.621 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:05.622Z,1554859265.622 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:05.622Z,1554859265.622 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:05.622Z,1554859265.622 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:05.622Z,1554859265.622 [BR_Ping1D](INFO): elapsed 2.0193
2019-04-10T01:21:05.622Z,1554859265.622 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:05.623Z,1554859265.623 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:05.623Z,1554859265.623 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:05.623Z,1554859265.623 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:06.025Z,1554859266.025 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:06.025Z,1554859266.025 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:06.025Z,1554859266.025 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:06.026Z,1554859266.026 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:06.026Z,1554859266.026 [BR_Ping1D](INFO): elapsed 2.4228
2019-04-10T01:21:06.026Z,1554859266.026 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:06.026Z,1554859266.026 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:06.026Z,1554859266.026 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:06.026Z,1554859266.026 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:06.411Z,1554859266.411 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T01:21:06.411Z,1554859266.411 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-04-10T01:21:06.411Z,1554859266.411 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T01:21:06.429Z,1554859266.429 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:06.429Z,1554859266.429 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:06.430Z,1554859266.430 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:06.430Z,1554859266.430 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:06.430Z,1554859266.430 [BR_Ping1D](INFO): elapsed 2.8272
2019-04-10T01:21:06.430Z,1554859266.430 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:06.431Z,1554859266.431 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:06.431Z,1554859266.431 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:06.431Z,1554859266.431 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:06.459Z,1554859266.459 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T01:21:06.814Z,1554859266.814 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T01:21:06.986Z,1554859266.986 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:06.986Z,1554859266.986 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:06.986Z,1554859266.986 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:06.986Z,1554859266.986 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:06.986Z,1554859266.986 [BR_Ping1D](INFO): elapsed 3.3836
2019-04-10T01:21:06.987Z,1554859266.987 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:06.987Z,1554859266.987 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:06.987Z,1554859266.987 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:06.987Z,1554859266.987 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:07.234Z,1554859267.234 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:07.235Z,1554859267.235 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:07.235Z,1554859267.235 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:07.235Z,1554859267.235 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:07.235Z,1554859267.235 [BR_Ping1D](INFO): elapsed 3.6323
2019-04-10T01:21:07.235Z,1554859267.235 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:07.236Z,1554859267.236 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:07.236Z,1554859267.236 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:07.236Z,1554859267.236 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:07.622Z,1554859267.622 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T01:21:07.622Z,1554859267.622 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-10T01:21:07.622Z,1554859267.622 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T01:21:07.641Z,1554859267.641 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:07.641Z,1554859267.641 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:07.642Z,1554859267.642 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:07.642Z,1554859267.642 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:07.642Z,1554859267.642 [BR_Ping1D](INFO): elapsed 4.0391
2019-04-10T01:21:07.642Z,1554859267.642 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:07.642Z,1554859267.642 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:07.643Z,1554859267.643 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:07.643Z,1554859267.643 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:07.659Z,1554859267.659 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T01:21:07.659Z,1554859267.659 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T01:21:07.659Z,1554859267.659 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-04-10T01:21:08.146Z,1554859268.146 [Aanderaa_O2](INFO): Powering down
2019-04-10T01:21:08.165Z,1554859268.165 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:08.165Z,1554859268.165 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:08.165Z,1554859268.165 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:08.165Z,1554859268.165 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:08.165Z,1554859268.165 [BR_Ping1D](INFO): elapsed 4.5624
2019-04-10T01:21:08.166Z,1554859268.166 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:08.166Z,1554859268.166 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:08.166Z,1554859268.166 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:08.166Z,1554859268.166 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:08.449Z,1554859268.449 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:08.449Z,1554859268.449 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:08.450Z,1554859268.450 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:08.450Z,1554859268.450 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:08.450Z,1554859268.450 [BR_Ping1D](INFO): elapsed 4.8471
2019-04-10T01:21:08.450Z,1554859268.450 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:08.450Z,1554859268.450 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:08.451Z,1554859268.451 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:08.451Z,1554859268.451 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:08.854Z,1554859268.854 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:08.854Z,1554859268.854 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:08.854Z,1554859268.854 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:08.854Z,1554859268.854 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:08.854Z,1554859268.854 [BR_Ping1D](INFO): elapsed 5.2515
2019-04-10T01:21:08.855Z,1554859268.855 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:08.855Z,1554859268.855 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T01:21:08.855Z,1554859268.855 [BR_Ping1D] Data Fault, FailCount= 3
2019-04-10T01:21:08.855Z,1554859268.855 [BR_Ping1D](ERROR): Data Fault
2019-04-10T01:21:08.871Z,1554859268.871 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T01:21:08.871Z,1554859268.871 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-10T01:21:08.871Z,1554859268.871 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T01:21:09.265Z,1554859269.265 [BR_Ping1D](INFO): Stop
2019-04-10T01:21:09.265Z,1554859269.265 [BR_Ping1D](INFO): uninitialize
2019-04-10T01:21:09.266Z,1554859269.266 [BR_Ping1D](INFO): Powering down
2019-04-10T01:21:09.674Z,1554859269.674 [BR_Ping1D](INFO): Stopping
2019-04-10T01:21:09.688Z,1554859269.688 [SBIT](IMPORTANT): Beginning Startup BIT
2019-04-10T01:21:09.692Z,1554859269.692 [CBIT](IMPORTANT): Beginning ground fault scan
2019-04-10T01:21:10.046Z,1554859270.046 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T01:21:10.047Z,1554859270.047 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T01:21:10.085Z,1554859270.085 [BR_Ping1D](INFO): Stopping
2019-04-10T01:21:10.470Z,1554859270.470 [BR_Ping1D](INFO): Stopped
2019-04-10T01:21:10.539Z,1554859270.539 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T01:21:10.539Z,1554859270.539 [BR_Ping1D] No Fault, FailCount= 3
2019-04-10T01:21:10.873Z,1554859270.873 [BR_Ping1D](INFO): Start
2019-04-10T01:21:11.278Z,1554859271.278 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:11.681Z,1554859271.681 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:11.681Z,1554859271.681 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:11.681Z,1554859271.681 [BR_Ping1D](INFO): startup: 0
2019-04-10T01:21:11.682Z,1554859271.682 [BR_Ping1D](INFO): recvData: FFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF
2019-04-10T01:21:11.682Z,1554859271.682 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T01:21:11.694Z,1554859271.694 [BR_Ping1D](INFO): 3131313130313030
2019-04-10T01:21:11.694Z,1554859271.694 [BR_Ping1D](INFO): 31
2019-04-10T01:21:11.694Z,1554859271.694 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T01:21:11.694Z,1554859271.694 [BR_Ping1D](INFO): XXX autoMode1: 01
2019-04-10T01:21:11.695Z,1554859271.695 [BR_Ping1D](INFO): XXX autoMode2: 
2019-04-10T01:21:11.695Z,1554859271.695 [BR_Ping1D](INFO): XXX autoMode3: 1
2019-04-10T01:21:11.695Z,1554859271.695 [BR_Ping1D](INFO): 31
2019-04-10T01:21:11.695Z,1554859271.695 [BR_Ping1D](INFO): 42520100EB030000840145
2019-04-10T01:21:11.695Z,1554859271.695 [BR_Ping1D](INFO): 31313030303030
2019-04-10T01:21:11.695Z,1554859271.695 [BR_Ping1D](INFO): 3131313030303131
2019-04-10T01:21:11.695Z,1554859271.695 [BR_Ping1D](INFO): 3130313130
2019-04-10T01:21:11.695Z,1554859271.695 [BR_Ping1D](INFO): 30
2019-04-10T01:21:11.696Z,1554859271.696 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T01:21:11.696Z,1554859271.696 [BR_Ping1D](INFO): 31
2019-04-10T01:21:11.696Z,1554859271.696 [BR_Ping1D](INFO): 42520100EE030000870116
2019-04-10T01:21:12.086Z,1554859272.086 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:12.689Z,1554859272.689 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:12.689Z,1554859272.689 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:12.689Z,1554859272.689 [BR_Ping1D](INFO): startup: 1
2019-04-10T01:21:12.689Z,1554859272.689 [BR_Ping1D](INFO): UART requesting status
2019-04-10T01:21:12.690Z,1554859272.690 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T01:21:12.698Z,1554859272.698 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T01:21:12.706Z,1554859272.706 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T01:21:12.966Z,1554859272.966 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T01:21:12.966Z,1554859272.966 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-10T01:21:12.966Z,1554859272.966 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T01:21:13.070Z,1554859273.070 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:13.070Z,1554859273.070 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:13.070Z,1554859273.070 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:13.070Z,1554859273.070 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:13.071Z,1554859273.071 [BR_Ping1D](INFO): elapsed 1.3886
2019-04-10T01:21:13.071Z,1554859273.071 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:13.071Z,1554859273.071 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:13.071Z,1554859273.071 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:13.071Z,1554859273.071 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:13.211Z,1554859273.211 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T01:21:13.370Z,1554859273.370 [CTD_Seabird](INFO): Powering down
2019-04-10T01:21:13.459Z,1554859273.459 [NAL9602](INFO): Powering up NAL9602
2019-04-10T01:21:13.475Z,1554859273.475 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:13.475Z,1554859273.475 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:13.475Z,1554859273.475 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:13.475Z,1554859273.475 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:13.475Z,1554859273.475 [BR_Ping1D](INFO): elapsed 1.7935
2019-04-10T01:21:13.476Z,1554859273.476 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:13.476Z,1554859273.476 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:13.476Z,1554859273.476 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:13.476Z,1554859273.476 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:13.877Z,1554859273.877 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:13.878Z,1554859273.878 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:13.878Z,1554859273.878 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:13.878Z,1554859273.878 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:13.878Z,1554859273.878 [BR_Ping1D](INFO): elapsed 2.1963
2019-04-10T01:21:13.879Z,1554859273.879 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:13.879Z,1554859273.879 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:13.879Z,1554859273.879 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:13.879Z,1554859273.879 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:14.281Z,1554859274.281 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:14.281Z,1554859274.281 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:14.282Z,1554859274.282 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:14.282Z,1554859274.282 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:14.282Z,1554859274.282 [BR_Ping1D](INFO): elapsed 2.6001
2019-04-10T01:21:14.282Z,1554859274.282 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:14.283Z,1554859274.283 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:14.283Z,1554859274.283 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:14.283Z,1554859274.283 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:14.399Z,1554859274.399 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-10T01:21:14.399Z,1554859274.399 [CTD_Seabird] No Fault, FailCount= 2
2019-04-10T01:21:14.686Z,1554859274.686 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:14.686Z,1554859274.686 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:14.686Z,1554859274.686 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:14.686Z,1554859274.686 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:14.686Z,1554859274.686 [BR_Ping1D](INFO): elapsed 3.0043
2019-04-10T01:21:14.687Z,1554859274.687 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:14.687Z,1554859274.687 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:14.687Z,1554859274.687 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:14.687Z,1554859274.687 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:14.714Z,1554859274.714 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T01:21:14.716Z,1554859274.716 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T01:21:15.089Z,1554859275.089 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:15.089Z,1554859275.089 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:15.089Z,1554859275.089 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:15.090Z,1554859275.090 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:15.090Z,1554859275.090 [BR_Ping1D](INFO): elapsed 3.4079
2019-04-10T01:21:15.090Z,1554859275.090 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:15.090Z,1554859275.090 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:15.090Z,1554859275.090 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:15.091Z,1554859275.091 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:15.494Z,1554859275.494 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:15.494Z,1554859275.494 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:15.495Z,1554859275.495 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:15.495Z,1554859275.495 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:15.495Z,1554859275.495 [BR_Ping1D](INFO): elapsed 3.8130
2019-04-10T01:21:15.495Z,1554859275.495 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:15.495Z,1554859275.495 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:15.496Z,1554859275.496 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:15.496Z,1554859275.496 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:15.897Z,1554859275.897 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:15.897Z,1554859275.897 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:15.898Z,1554859275.898 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:15.898Z,1554859275.898 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:15.898Z,1554859275.898 [BR_Ping1D](INFO): elapsed 4.2159
2019-04-10T01:21:15.898Z,1554859275.898 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:15.898Z,1554859275.898 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:15.898Z,1554859275.898 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:15.899Z,1554859275.899 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:16.302Z,1554859276.302 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:16.302Z,1554859276.302 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:16.302Z,1554859276.302 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:16.302Z,1554859276.302 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:16.302Z,1554859276.302 [BR_Ping1D](INFO): elapsed 4.6203
2019-04-10T01:21:16.303Z,1554859276.303 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:16.303Z,1554859276.303 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:16.303Z,1554859276.303 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:16.303Z,1554859276.303 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:16.705Z,1554859276.705 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:16.705Z,1554859276.705 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:16.706Z,1554859276.706 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:16.706Z,1554859276.706 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:16.706Z,1554859276.706 [BR_Ping1D](INFO): elapsed 5.0242
2019-04-10T01:21:16.706Z,1554859276.706 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:16.707Z,1554859276.707 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T01:21:16.707Z,1554859276.707 [BR_Ping1D] Data Fault, FailCount= 4
2019-04-10T01:21:16.707Z,1554859276.707 [BR_Ping1D](ERROR): Data Fault
2019-04-10T01:21:16.767Z,1554859276.767 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T01:21:17.110Z,1554859277.110 [BR_Ping1D](INFO): Stop
2019-04-10T01:21:17.110Z,1554859277.110 [BR_Ping1D](INFO): uninitialize
2019-04-10T01:21:17.110Z,1554859277.110 [BR_Ping1D](INFO): Powering down
2019-04-10T01:21:17.513Z,1554859277.513 [BR_Ping1D](INFO): Stopping
2019-04-10T01:21:17.918Z,1554859277.918 [BR_Ping1D](INFO): Stopping
2019-04-10T01:21:18.321Z,1554859278.321 [BR_Ping1D](INFO): Stopped
2019-04-10T01:21:18.375Z,1554859278.375 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T01:21:18.375Z,1554859278.375 [BR_Ping1D] No Fault, FailCount= 4
2019-04-10T01:21:18.725Z,1554859278.725 [BR_Ping1D](INFO): Start
2019-04-10T01:21:19.129Z,1554859279.129 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:19.514Z,1554859279.514 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T01:21:19.514Z,1554859279.514 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-10T01:21:19.514Z,1554859279.514 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T01:21:19.534Z,1554859279.534 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:19.534Z,1554859279.534 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:19.534Z,1554859279.534 [BR_Ping1D](INFO): startup: 0
2019-04-10T01:21:19.534Z,1554859279.534 [BR_Ping1D](INFO): recvData: FFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF
2019-04-10T01:21:19.534Z,1554859279.534 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T01:21:19.546Z,1554859279.546 [BR_Ping1D](INFO): 3131313130313030
2019-04-10T01:21:19.546Z,1554859279.546 [BR_Ping1D](INFO): 31
2019-04-10T01:21:19.546Z,1554859279.546 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T01:21:19.546Z,1554859279.546 [BR_Ping1D](INFO): XXX autoMode1: 01
2019-04-10T01:21:19.546Z,1554859279.546 [BR_Ping1D](INFO): XXX autoMode2: 
2019-04-10T01:21:19.547Z,1554859279.547 [BR_Ping1D](INFO): XXX autoMode3: 1
2019-04-10T01:21:19.547Z,1554859279.547 [BR_Ping1D](INFO): 31
2019-04-10T01:21:19.547Z,1554859279.547 [BR_Ping1D](INFO): 42520100EB030000840145
2019-04-10T01:21:19.547Z,1554859279.547 [BR_Ping1D](INFO): 31313030303030
2019-04-10T01:21:19.547Z,1554859279.547 [BR_Ping1D](INFO): 3131313030303131
2019-04-10T01:21:19.547Z,1554859279.547 [BR_Ping1D](INFO): 3130313130
2019-04-10T01:21:19.547Z,1554859279.547 [BR_Ping1D](INFO): 30
2019-04-10T01:21:19.548Z,1554859279.548 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T01:21:19.548Z,1554859279.548 [BR_Ping1D](INFO): 31
2019-04-10T01:21:19.548Z,1554859279.548 [BR_Ping1D](INFO): 42520100EE030000870116
2019-04-10T01:21:19.619Z,1554859279.619 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T01:21:19.619Z,1554859279.619 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-10T01:21:20.038Z,1554859280.038 [Aanderaa_O2](INFO): Powering down
2019-04-10T01:21:20.057Z,1554859280.057 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:20.058Z,1554859280.058 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:20.058Z,1554859280.058 [BR_Ping1D](INFO): startup: 1
2019-04-10T01:21:20.058Z,1554859280.058 [BR_Ping1D](INFO): UART requesting status
2019-04-10T01:21:20.058Z,1554859280.058 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T01:21:20.066Z,1554859280.066 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T01:21:20.074Z,1554859280.074 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T01:21:20.342Z,1554859280.342 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:20.342Z,1554859280.342 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:20.342Z,1554859280.342 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:20.342Z,1554859280.342 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:20.343Z,1554859280.343 [BR_Ping1D](INFO): elapsed 0.8081
2019-04-10T01:21:20.343Z,1554859280.343 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:20.343Z,1554859280.343 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:20.343Z,1554859280.343 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:20.343Z,1554859280.343 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:20.411Z,1554859280.411 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.006692
CHAN A1 (24V): -0.128250
CHAN A2 (12V): -0.005040
CHAN A3 (5V): -0.002748
CHAN B0 (3.3V): -0.001348
CHAN B1 (3.15aV): -0.001558
CHAN B2 (3.15bV): -0.001558
CHAN B3 (GND): -0.000465
OPEN: -0.000812
Full Scale Calc: 4.765 mA, -1.589 mA
2019-04-10T01:21:20.746Z,1554859280.746 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:20.746Z,1554859280.746 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:20.746Z,1554859280.746 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:20.746Z,1554859280.746 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:20.746Z,1554859280.746 [BR_Ping1D](INFO): elapsed 1.2118
2019-04-10T01:21:20.747Z,1554859280.747 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:20.747Z,1554859280.747 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:20.747Z,1554859280.747 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:20.747Z,1554859280.747 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:21.150Z,1554859281.150 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:21.150Z,1554859281.150 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:21.150Z,1554859281.150 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:21.150Z,1554859281.150 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:21.150Z,1554859281.150 [BR_Ping1D](INFO): elapsed 1.6159
2019-04-10T01:21:21.151Z,1554859281.151 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:21.151Z,1554859281.151 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:21.151Z,1554859281.151 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:21.151Z,1554859281.151 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:21.554Z,1554859281.554 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:21.554Z,1554859281.554 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:21.554Z,1554859281.554 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:21.555Z,1554859281.555 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:21.555Z,1554859281.555 [BR_Ping1D](INFO): elapsed 2.0203
2019-04-10T01:21:21.555Z,1554859281.555 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:21.555Z,1554859281.555 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:21.555Z,1554859281.555 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:21.555Z,1554859281.555 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:21.958Z,1554859281.958 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:21.959Z,1554859281.959 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:21.959Z,1554859281.959 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:21.959Z,1554859281.959 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:21.959Z,1554859281.959 [BR_Ping1D](INFO): elapsed 2.4247
2019-04-10T01:21:21.959Z,1554859281.959 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:21.960Z,1554859281.960 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:21.960Z,1554859281.960 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:21.960Z,1554859281.960 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:22.362Z,1554859282.362 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:22.362Z,1554859282.362 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:22.362Z,1554859282.362 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:22.362Z,1554859282.362 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:22.362Z,1554859282.362 [BR_Ping1D](INFO): elapsed 2.8280
2019-04-10T01:21:22.363Z,1554859282.363 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:22.363Z,1554859282.363 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:22.363Z,1554859282.363 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:22.363Z,1554859282.363 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:22.767Z,1554859282.767 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:22.767Z,1554859282.767 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:22.767Z,1554859282.767 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:22.767Z,1554859282.767 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:22.768Z,1554859282.768 [BR_Ping1D](INFO): elapsed 3.2332
2019-04-10T01:21:22.768Z,1554859282.768 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:22.768Z,1554859282.768 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:22.768Z,1554859282.768 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:22.768Z,1554859282.768 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:23.170Z,1554859283.170 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:23.170Z,1554859283.170 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:23.170Z,1554859283.170 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:23.170Z,1554859283.170 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:23.171Z,1554859283.171 [BR_Ping1D](INFO): elapsed 3.6360
2019-04-10T01:21:23.171Z,1554859283.171 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:23.171Z,1554859283.171 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:23.171Z,1554859283.171 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:23.171Z,1554859283.171 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:23.574Z,1554859283.574 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:23.574Z,1554859283.574 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:23.574Z,1554859283.574 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:23.574Z,1554859283.574 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:23.575Z,1554859283.575 [BR_Ping1D](INFO): elapsed 4.0400
2019-04-10T01:21:23.575Z,1554859283.575 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:23.575Z,1554859283.575 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:23.575Z,1554859283.575 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:23.575Z,1554859283.575 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:23.989Z,1554859283.989 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:23.989Z,1554859283.989 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:23.989Z,1554859283.989 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:23.989Z,1554859283.989 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:23.990Z,1554859283.990 [BR_Ping1D](INFO): elapsed 4.4550
2019-04-10T01:21:23.990Z,1554859283.990 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:23.990Z,1554859283.990 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:23.991Z,1554859283.991 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:23.991Z,1554859283.991 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:24.367Z,1554859284.367 [NAL9602](INFO): NAL9602 initialized
2019-04-10T01:21:24.382Z,1554859284.382 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:24.382Z,1554859284.382 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:24.383Z,1554859284.383 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:24.383Z,1554859284.383 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:24.383Z,1554859284.383 [BR_Ping1D](INFO): elapsed 4.8483
2019-04-10T01:21:24.383Z,1554859284.383 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:24.383Z,1554859284.383 [BR_Ping1D](INFO): No UART data
2019-04-10T01:21:24.383Z,1554859284.383 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:21:24.383Z,1554859284.383 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:21:24.795Z,1554859284.795 [BR_Ping1D](INFO): Starting
2019-04-10T01:21:24.795Z,1554859284.795 [BR_Ping1D](INFO): readConfig
2019-04-10T01:21:24.795Z,1554859284.795 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:21:24.795Z,1554859284.795 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:21:24.796Z,1554859284.796 [BR_Ping1D](INFO): elapsed 5.2611
2019-04-10T01:21:24.796Z,1554859284.796 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:21:24.796Z,1554859284.796 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T01:21:24.796Z,1554859284.796 [BR_Ping1D] Data Fault, FailCount= 5
2019-04-10T01:21:24.796Z,1554859284.796 [BR_Ping1D](ERROR): Data Fault
2019-04-10T01:21:24.979Z,1554859284.979 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T01:21:24.979Z,1554859284.979 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-04-10T01:21:25.186Z,1554859285.186 [NAL9602](DEBUG): Fix Requested
2019-04-10T01:21:25.202Z,1554859285.202 [BR_Ping1D](INFO): Stop
2019-04-10T01:21:25.203Z,1554859285.203 [BR_Ping1D](INFO): uninitialize
2019-04-10T01:21:25.203Z,1554859285.203 [BR_Ping1D](INFO): Powering down
2019-04-10T01:21:25.789Z,1554859285.789 [CBIT](INFO): Critical error at 20190410T012124
2019-04-10T01:21:25.856Z,1554859285.856 [BR_Ping1D](INFO): Stopping
2019-04-10T01:21:26.229Z,1554859286.229 [BR_Ping1D](INFO): Stopped
2019-04-10T01:21:27.766Z,1554859287.766 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T01:21:27.766Z,1554859287.766 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-10T01:21:27.766Z,1554859287.766 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T01:21:27.944Z,1554859287.944 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T01:21:27.944Z,1554859287.944 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-10T01:21:28.170Z,1554859288.170 [CTD_Seabird](INFO): Powering down
2019-04-10T01:21:30.239Z,1554859290.239 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T01:21:30.239Z,1554859290.239 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2019-04-10T01:21:30.239Z,1554859290.239 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T01:21:30.547Z,1554859290.547 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T01:21:30.642Z,1554859290.642 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T01:21:31.543Z,1554859291.543 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T01:21:31.543Z,1554859291.543 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2019-04-10T01:21:33.874Z,1554859293.874 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T01:21:33.874Z,1554859293.874 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T01:21:53.786Z,1554859313.786 [BPC1](ERROR): BPC1A: No match for serial number 04CC in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2019-04-10T01:21:53.787Z,1554859313.787 [BPC1](ERROR): BPC1B: No match for serial number 018D in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2019-04-10T01:21:53.787Z,1554859313.787 [BPC1](FAULT): Failed to parse data from all battery packs.
2019-04-10T01:21:53.787Z,1554859313.787 [BPC1] Data Fault, FailCount= 1
2019-04-10T01:21:53.787Z,1554859313.787 [BPC1](ERROR): Data Fault
2019-04-10T01:21:53.896Z,1554859313.896 [CBIT](ERROR): Data Fault in component: BPC1
2019-04-10T01:21:54.159Z,1554859314.159 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T01:21:54.159Z,1554859314.159 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2019-04-10T01:21:54.159Z,1554859314.159 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T01:21:54.319Z,1554859314.319 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T01:21:54.563Z,1554859314.563 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T01:21:55.446Z,1554859315.446 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T01:21:55.447Z,1554859315.447 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2019-04-10T01:21:57.794Z,1554859317.794 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T01:21:57.795Z,1554859317.795 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T01:22:03.478Z,1554859323.478 [SBIT](IMPORTANT): SBIT PASSED
2019-04-10T01:22:03.575Z,1554859323.575 [CommandLine](IMPORTANT): got command configSet list
2019-04-10T01:22:03.575Z,1554859323.575 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-04-10T01:22:03.576Z,1554859323.576 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-04-10T01:22:03.576Z,1554859323.576 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2019-04-10T01:22:03.899Z,1554859323.899 [MissionManager](IMPORTANT): Started mission Startup
2019-04-10T01:22:03.899Z,1554859323.899 [Startup] Running Loop=1
2019-04-10T01:22:03.899Z,1554859323.899 [Startup](DEBUG): Aggregate::initialize Startup
2019-04-10T01:22:03.899Z,1554859323.899 [Startup:A.GoToSurface] Running Loop=1
2019-04-10T01:22:03.899Z,1554859323.899 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-10T01:22:03.900Z,1554859323.900 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-10T01:22:03.901Z,1554859323.901 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-10T01:22:03.901Z,1554859323.901 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-10T01:22:03.901Z,1554859323.901 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-10T01:22:03.902Z,1554859323.902 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-10T01:22:03.903Z,1554859323.903 [Startup:StartupSatComms] Running Loop=1
2019-04-10T01:22:03.903Z,1554859323.903 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-04-10T01:22:03.903Z,1554859323.903 [Startup:StartupSatComms:A] Running Loop=1
2019-04-10T01:22:04.303Z,1554859324.303 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-04-10T01:22:17.994Z,1554859337.994 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T01:22:17.995Z,1554859337.995 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2019-04-10T01:22:17.995Z,1554859337.995 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T01:22:18.048Z,1554859338.048 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T01:22:18.398Z,1554859338.398 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T01:22:19.239Z,1554859339.239 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T01:22:19.239Z,1554859339.239 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2019-04-10T01:22:21.630Z,1554859341.630 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T01:22:21.631Z,1554859341.631 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T01:22:41.830Z,1554859361.830 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T01:22:41.831Z,1554859361.831 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2019-04-10T01:22:41.831Z,1554859361.831 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T01:22:41.887Z,1554859361.887 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T01:22:42.234Z,1554859362.234 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T01:22:43.076Z,1554859363.076 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T01:22:43.076Z,1554859363.076 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2019-04-10T01:22:45.466Z,1554859365.466 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T01:22:45.467Z,1554859365.467 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T01:22:45.514Z,1554859365.514 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T01:22:45.514Z,1554859365.514 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-04-10T01:22:45.515Z,1554859365.515 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-10T01:22:45.516Z,1554859365.516 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T01:22:45.516Z,1554859365.516 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-04-10T01:22:45.516Z,1554859365.516 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-10T01:22:45.517Z,1554859365.517 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T01:22:45.518Z,1554859365.518 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-04-10T01:22:45.518Z,1554859365.518 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-10T01:22:45.529Z,1554859365.529 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-10T01:22:45.529Z,1554859365.529 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-10T01:22:45.529Z,1554859365.529 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-10T01:22:45.926Z,1554859365.926 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-10T01:22:45.926Z,1554859365.926 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-04-10T01:22:45.927Z,1554859365.927 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-10T01:22:45.927Z,1554859365.927 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-04-10T01:22:45.928Z,1554859365.928 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-10T01:22:45.928Z,1554859365.928 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-04-10T01:22:46.313Z,1554859366.313 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-10T01:23:04.075Z,1554859384.075 [Startup:StartupSatComms:A](INFO): Timed out from 2019-04-10T01:22:03.9Z
2019-04-10T01:23:04.075Z,1554859384.075 [Startup:StartupSatComms:A] Stopped
2019-04-10T01:23:04.076Z,1554859384.076 [Startup:StartupSatComms:B] Running Loop=1
2019-04-10T01:23:04.477Z,1554859384.477 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-10T01:23:05.666Z,1554859385.666 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T01:23:05.667Z,1554859385.667 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2019-04-10T01:23:05.667Z,1554859385.667 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T01:23:05.715Z,1554859385.715 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T01:23:06.070Z,1554859386.070 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T01:23:06.922Z,1554859386.922 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T01:23:06.922Z,1554859386.922 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2019-04-10T01:23:09.303Z,1554859389.303 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T01:23:09.303Z,1554859389.303 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T01:23:23.704Z,1554859403.704 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005124
2019-04-10T01:23:29.505Z,1554859409.505 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T01:23:29.505Z,1554859409.505 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7
2019-04-10T01:23:29.505Z,1554859409.505 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T01:23:29.557Z,1554859409.557 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T01:23:29.906Z,1554859409.906 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T01:23:30.791Z,1554859410.791 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T01:23:30.791Z,1554859410.791 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7
2019-04-10T01:23:33.138Z,1554859413.138 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T01:23:33.139Z,1554859413.139 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T01:23:37.358Z,1554859417.358 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20190410T011352/Courier0004.lzma
2019-04-10T01:23:38.164Z,1554859418.164 [DataOverHttps](INFO): Moved sent file to Logs/20190410T011352/Courier0004.lzma.bak
2019-04-10T01:23:38.164Z,1554859418.164 [DataOverHttps](INFO): SBD MOMSN=10522734
2019-04-10T01:23:49.752Z,1554859429.752 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T01:23:49.752Z,1554859429.752 [Aanderaa_O2] No Fault, FailCount= 3
2019-04-10T01:23:53.118Z,1554859433.118 [DataOverHttps](INFO): Sending 312 bytes from file Logs/20190410T012034/Courier0000.lzma
2019-04-10T01:23:53.339Z,1554859433.339 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T01:23:53.339Z,1554859433.339 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 8
2019-04-10T01:23:53.339Z,1554859433.339 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T01:23:53.391Z,1554859433.391 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T01:23:53.391Z,1554859433.391 [CBIT](FAULT): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T01:23:53.743Z,1554859433.743 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T01:23:53.923Z,1554859433.923 [DataOverHttps](INFO): Moved sent file to Logs/20190410T012034/Courier0000.lzma.bak
2019-04-10T01:23:53.924Z,1554859433.924 [DataOverHttps](INFO): SBD MOMSN=10522737
2019-04-10T01:23:57.820Z,1554859437.820 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-10T01:23:57.820Z,1554859437.820 [CTD_Seabird] No Fault, FailCount= 3
2019-04-10T01:23:58.195Z,1554859438.195 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T01:23:58.197Z,1554859438.197 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T01:24:00.207Z,1554859440.207 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T01:24:00.207Z,1554859440.207 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-10T01:24:00.207Z,1554859440.207 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T01:24:00.264Z,1554859440.264 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T01:24:00.730Z,1554859440.730 [Aanderaa_O2](INFO): Powering down
2019-04-10T01:24:01.455Z,1554859441.455 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T01:24:01.455Z,1554859441.455 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-10T01:24:04.292Z,1554859444.292 [Startup:StartupSatComms:B](INFO): Timed out from 2019-04-10T01:23:04.1Z
2019-04-10T01:24:04.292Z,1554859444.292 [Startup:StartupSatComms:B] Stopped
2019-04-10T01:24:04.292Z,1554859444.292 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-04-10T01:24:04.292Z,1554859444.292 [Startup:StartupSatComms] Stopped
2019-04-10T01:24:04.292Z,1554859444.292 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-04-10T01:24:04.293Z,1554859444.293 [Startup](INFO): Completed Startup
2019-04-10T01:24:04.293Z,1554859444.293 [MissionManager](INFO): Startup is completed.
2019-04-10T01:24:04.293Z,1554859444.293 [MissionManager](INFO): Uninitializing Mission Startup
2019-04-10T01:24:04.293Z,1554859444.293 [Startup] Stopped
2019-04-10T01:24:04.294Z,1554859444.294 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-04-10T01:24:04.294Z,1554859444.294 [Startup:A.GoToSurface] Stopped
2019-04-10T01:24:04.294Z,1554859444.294 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-10T01:24:04.691Z,1554859444.691 [MissionManager](IMPORTANT): Started mission Default
2019-04-10T01:24:04.691Z,1554859444.691 [Default] Running Loop=1
2019-04-10T01:24:04.691Z,1554859444.691 [Default](DEBUG): Aggregate::initialize Default
2019-04-10T01:24:04.691Z,1554859444.691 [Default:B.GoToSurface] Running Loop=1
2019-04-10T01:24:04.691Z,1554859444.691 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-10T01:24:04.692Z,1554859444.692 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-10T01:24:04.692Z,1554859444.692 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-10T01:24:04.692Z,1554859444.692 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-10T01:24:04.693Z,1554859444.693 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-10T01:24:04.693Z,1554859444.693 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-10T01:24:04.693Z,1554859444.693 [Default:A.Wait] Running Loop=1
2019-04-10T01:24:04.693Z,1554859444.693 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-04-10T01:24:08.439Z,1554859448.439 [DataOverHttps](INFO): Sending 555 bytes from file Logs/20190410T004526/Express0005.lzma
2019-04-10T01:24:09.244Z,1554859449.244 [DataOverHttps](INFO): Moved sent file to Logs/20190410T004526/Express0005.lzma.bak
2019-04-10T01:24:09.244Z,1554859449.244 [DataOverHttps](INFO): SBD MOMSN=10522750
2019-04-10T01:24:11.250Z,1554859451.250 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T01:24:11.250Z,1554859451.250 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-10T01:24:11.250Z,1554859451.250 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T01:24:11.555Z,1554859451.555 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T01:24:11.654Z,1554859451.654 [CTD_Seabird](INFO): Powering down
2019-04-10T01:24:11.922Z,1554859451.922 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T01:24:11.922Z,1554859451.922 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-10T01:24:11.922Z,1554859451.922 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T01:24:11.967Z,1554859451.967 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T01:24:12.446Z,1554859452.446 [Aanderaa_O2](INFO): Powering down
2019-04-10T01:24:12.772Z,1554859452.772 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-10T01:24:12.772Z,1554859452.772 [CTD_Seabird] No Fault, FailCount= 1
2019-04-10T01:24:12.998Z,1554859452.998 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T01:24:13.006Z,1554859453.006 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T01:24:13.167Z,1554859453.167 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T01:24:13.168Z,1554859453.168 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-10T01:24:18.007Z,1554859458.007 [Default:A.Wait](INFO): Done Waiting.
2019-04-10T01:24:18.007Z,1554859458.007 [Default:A.Wait] Stopped
2019-04-10T01:24:18.007Z,1554859458.007 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-04-10T01:24:18.409Z,1554859458.409 [Default:CheckIn] Running Loop=1
2019-04-10T01:24:18.409Z,1554859458.409 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-10T01:24:18.409Z,1554859458.409 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-10T01:24:18.824Z,1554859458.824 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-04-10T01:24:23.640Z,1554859463.640 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T01:24:23.640Z,1554859463.640 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-10T01:24:23.640Z,1554859463.640 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T01:24:23.680Z,1554859463.680 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T01:24:23.680Z,1554859463.680 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-10T01:24:24.162Z,1554859464.162 [Aanderaa_O2](INFO): Powering down
2019-04-10T01:24:26.050Z,1554859466.050 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T01:24:26.050Z,1554859466.050 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-10T01:24:26.050Z,1554859466.050 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T01:24:26.110Z,1554859466.110 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T01:24:26.454Z,1554859466.454 [CTD_Seabird](INFO): Powering down
2019-04-10T01:24:27.715Z,1554859467.715 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-10T01:24:27.715Z,1554859467.715 [CTD_Seabird] No Fault, FailCount= 2
2019-04-10T01:24:27.794Z,1554859467.794 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T01:24:27.796Z,1554859467.796 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T01:24:40.846Z,1554859480.846 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T01:24:40.846Z,1554859480.846 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-10T01:24:40.846Z,1554859480.846 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T01:24:41.058Z,1554859481.058 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T01:24:41.058Z,1554859481.058 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-10T01:24:41.250Z,1554859481.250 [CTD_Seabird](INFO): Powering down
2019-04-10T01:24:46.701Z,1554859486.701 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T01:24:46.701Z,1554859486.701 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-04-10T01:24:46.701Z,1554859486.701 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-10T01:24:46.703Z,1554859486.703 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T01:24:46.703Z,1554859486.703 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-04-10T01:24:46.703Z,1554859486.703 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-10T01:24:46.704Z,1554859486.704 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T01:24:46.704Z,1554859486.704 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-04-10T01:24:46.704Z,1554859486.704 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-10T01:24:46.727Z,1554859486.727 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-10T01:24:46.727Z,1554859486.727 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-10T01:24:46.727Z,1554859486.727 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-10T01:24:47.113Z,1554859487.113 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-10T01:24:47.113Z,1554859487.113 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-04-10T01:24:47.114Z,1554859487.114 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-10T01:24:47.114Z,1554859487.114 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-04-10T01:24:47.114Z,1554859487.114 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-10T01:24:47.114Z,1554859487.114 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-04-10T01:24:47.513Z,1554859487.513 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-10T01:25:45.559Z,1554859545.559 [CommandLine](IMPORTANT): got command restart application
2019-04-10T01:25:46.562Z,1554859546.562 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T01:25:46.562Z,1554859546.562 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:25:46.626Z,1554859546.626 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-04-10T01:25:46.626Z,1554859546.626 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:25:46.627Z,1554859546.627 [CommandLine](INFO): Join timeout helper Thread ID is 1456
2019-04-10T01:25:46.627Z,1554859546.627 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-04-10T01:25:46.627Z,1554859546.627 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:25:46.628Z,1554859546.628 [NavChartDb](INFO): Join timeout helper Thread ID is 1457
2019-04-10T01:25:46.762Z,1554859546.762 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T01:25:46.762Z,1554859546.762 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:25:46.770Z,1554859546.770 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-04-10T01:25:46.770Z,1554859546.770 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:25:46.770Z,1554859546.770 [Radio_Surface](INFO): Join timeout helper Thread ID is 1458
2019-04-10T01:25:47.010Z,1554859547.010 [Radio_Surface](INFO): Powering down
2019-04-10T01:25:47.011Z,1554859547.011 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T01:25:47.011Z,1554859547.011 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:25:47.030Z,1554859547.030 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-04-10T01:25:47.030Z,1554859547.030 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:25:47.030Z,1554859547.030 [DataOverHttps](INFO): Join timeout helper Thread ID is 1459
2019-04-10T01:25:47.426Z,1554859547.426 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T01:25:47.430Z,1554859547.430 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:25:47.434Z,1554859547.434 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-04-10T01:25:47.434Z,1554859547.434 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:25:47.435Z,1554859547.435 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1460
2019-04-10T01:25:47.546Z,1554859547.546 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T01:25:47.547Z,1554859547.547 [WetLabsBB2FL](INFO): Powering down
2019-04-10T01:25:47.547Z,1554859547.547 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:25:47.555Z,1554859547.555 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-04-10T01:25:47.555Z,1554859547.555 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:25:47.555Z,1554859547.555 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1461
2019-04-10T01:25:47.638Z,1554859547.638 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T01:25:47.638Z,1554859547.638 [CTD_Seabird](INFO): Powering down
2019-04-10T01:25:47.650Z,1554859547.650 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:25:47.655Z,1554859547.655 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-04-10T01:25:47.655Z,1554859547.655 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:25:47.655Z,1554859547.655 [logger](INFO): Join timeout helper Thread ID is 1462
2019-04-10T01:25:47.722Z,1554859547.722 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T01:25:47.722Z,1554859547.722 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:25:47.739Z,1554859547.739 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-04-10T01:25:47.739Z,1554859547.739 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:25:47.739Z,1554859547.739 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-04-10T01:25:47.739Z,1554859547.739 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:25:47.739Z,1554859547.739 [controlThread](INFO): Join timeout helper Thread ID is 1463
2019-04-10T01:25:48.070Z,1554859548.070 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T01:25:48.070Z,1554859548.070 [controlThread](DEBUG): Uninitializing ControlThread
2019-04-10T01:25:48.071Z,1554859548.071 [Aanderaa_O2](INFO): Powering down
2019-04-10T01:25:48.073Z,1554859548.073 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T01:25:48.074Z,1554859548.074 [NAL9602](INFO): Powering down
2019-04-10T01:25:48.075Z,1554859548.075 [BR_Ping1D](INFO): uninitialize
2019-04-10T01:25:48.075Z,1554859548.075 [BR_Ping1D](INFO): Powering down
2019-04-10T01:25:48.076Z,1554859548.076 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-04-10T01:25:48.077Z,1554859548.077 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-04-10T01:25:48.077Z,1554859548.077 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-04-10T01:25:48.078Z,1554859548.078 [MissionManager](INFO): Uninitializing Mission Default
2019-04-10T01:25:48.078Z,1554859548.078 [Default] Stopped
2019-04-10T01:25:48.078Z,1554859548.078 [Default](DEBUG): Aggregate::uninitialize Default
2019-04-10T01:25:48.078Z,1554859548.078 [Default:B.GoToSurface] Stopped
2019-04-10T01:25:48.078Z,1554859548.078 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-10T01:25:48.078Z,1554859548.078 [Default:CheckIn] Stopped
2019-04-10T01:25:48.079Z,1554859548.079 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-10T01:25:48.079Z,1554859548.079 [Default:CheckIn:Read_GPS] Stopped
2019-04-10T01:25:48.081Z,1554859548.081 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-04-10T01:25:48.081Z,1554859548.081 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-04-10T01:25:48.081Z,1554859548.081 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-04-10T01:25:48.082Z,1554859548.082 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-04-10T01:25:48.082Z,1554859548.082 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-04-10T01:25:48.082Z,1554859548.082 [BuoyancyServo](INFO): Powering down
2019-04-10T01:25:48.094Z,1554859548.094 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-10T01:25:48.094Z,1554859548.094 [ElevatorServo](INFO): Powering down
2019-04-10T01:25:48.095Z,1554859548.095 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-10T01:25:48.095Z,1554859548.095 [MassServo](INFO): Powering down
2019-04-10T01:25:48.096Z,1554859548.096 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-10T01:25:48.096Z,1554859548.096 [RudderServo](INFO): Powering down
2019-04-10T01:25:48.097Z,1554859548.097 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-04-10T01:25:48.097Z,1554859548.097 [ThrusterServo](INFO): Powering down
2019-04-10T01:25:48.098Z,1554859548.098 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-04-10T01:25:48.098Z,1554859548.098 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-04-10T01:25:48.098Z,1554859548.098 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-04-10T01:25:48.099Z,1554859548.099 [CBIT](DEBUG): Powering off loads.
2019-04-10T01:25:48.110Z,1554859548.110 [CBIT](DEBUG): Disabling WDT.
2019-04-10T01:25:48.122Z,1554859548.122 [CBIT](DEBUG): Opening all GF detection circuits.
2019-04-10T01:25:48.123Z,1554859548.123 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:25:48.170Z,1554859548.170 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:25:48.175Z,1554859548.175 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:25:48.243Z,1554859548.243 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:25:48.248Z,1554859548.248 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:25:48.299Z,1554859548.299 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:25:48.360Z,1554859548.360 [logger ThreadHandler](INFO): Thread cancelled.