2019-04-10T13:35:17.552Z,1554903317.552 [Supervisor](DEBUG): Initializing supervisor.
2019-04-10T13:35:17.555Z,1554903317.555 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-10T13:35:17.556Z,1554903317.556 [SyncHandler](INFO): Protected caller Thread ID is 5601
2019-04-10T13:35:17.557Z,1554903317.557 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-04-10T13:35:17.558Z,1554903317.558 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-04-10T13:35:17.558Z,1554903317.558 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5602
2019-04-10T13:35:17.561Z,1554903317.561 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-04-10T13:35:17.573Z,1554903317.573 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-04-10T13:35:17.574Z,1554903317.574 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-04-10T13:35:17.575Z,1554903317.575 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5603
2019-04-10T13:35:17.576Z,1554903317.576 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-04-10T13:35:17.576Z,1554903317.576 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-04-10T13:35:17.577Z,1554903317.577 [logger ThreadHandler](INFO): Protected caller Thread ID is 5604
2019-04-10T13:35:17.579Z,1554903317.579 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-04-10T13:35:17.579Z,1554903317.579 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-04-10T13:35:17.581Z,1554903317.581 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-04-10T13:35:17.739Z,1554903317.739 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-04-10T13:35:17.739Z,1554903317.739 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-04-10T13:35:17.940Z,1554903317.940 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-04-10T13:35:17.940Z,1554903317.940 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-04-10T13:35:18.076Z,1554903318.076 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-04-10T13:35:18.077Z,1554903318.077 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-04-10T13:35:18.532Z,1554903318.532 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-04-10T13:35:18.532Z,1554903318.532 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-04-10T13:35:18.000Z,1554903319.000 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-04-10T13:35:19.000Z,1554903319.000 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-04-10T13:35:19.771Z,1554903319.771 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-04-10T13:35:19.772Z,1554903319.772 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-04-10T13:35:20.077Z,1554903320.077 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-04-10T13:35:20.077Z,1554903320.077 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-04-10T13:35:20.407Z,1554903320.407 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-04-10T13:35:20.408Z,1554903320.408 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-04-10T13:35:20.800Z,1554903320.800 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-04-10T13:35:20.800Z,1554903320.800 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-04-10T13:35:20.950Z,1554903320.950 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-04-10T13:35:20.950Z,1554903320.950 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-04-10T13:35:21.055Z,1554903321.055 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-04-10T13:35:21.055Z,1554903321.055 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-04-10T13:35:21.136Z,1554903321.136 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-04-10T13:35:21.238Z,1554903321.238 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-04-10T13:35:21.238Z,1554903321.238 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-04-10T13:35:21.432Z,1554903321.432 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-04-10T13:35:21.433Z,1554903321.433 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-04-10T13:35:21.640Z,1554903321.640 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-04-10T13:35:21.642Z,1554903321.642 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-04-10T13:35:21.643Z,1554903321.643 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-04-10T13:35:21.725Z,1554903321.725 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-04-10T13:35:21.956Z,1554903321.956 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-04-10T13:35:21.957Z,1554903321.957 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-04-10T13:35:22.052Z,1554903322.052 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-04-10T13:35:22.224Z,1554903322.224 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-04-10T13:35:22.437Z,1554903322.437 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-04-10T13:35:22.521Z,1554903322.521 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-04-10T13:35:22.618Z,1554903322.618 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-04-10T13:35:22.711Z,1554903322.711 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-04-10T13:35:22.862Z,1554903322.862 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-04-10T13:35:22.966Z,1554903322.966 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-04-10T13:35:23.064Z,1554903323.064 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2019-04-10T13:35:23.065Z,1554903323.065 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-04-10T13:35:23.070Z,1554903323.070 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-04-10T13:35:23.167Z,1554903323.167 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-04-10T13:35:23.168Z,1554903323.168 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-04-10T13:35:23.292Z,1554903323.292 [BuoyancyServo] Loaded
2019-04-10T13:35:23.293Z,1554903323.293 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-04-10T13:35:23.308Z,1554903323.308 [ElevatorServo] Loaded
2019-04-10T13:35:23.308Z,1554903323.308 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-04-10T13:35:23.323Z,1554903323.323 [MassServo] Loaded
2019-04-10T13:35:23.324Z,1554903323.324 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-04-10T13:35:23.339Z,1554903323.339 [RudderServo] Loaded
2019-04-10T13:35:23.339Z,1554903323.339 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-04-10T13:35:23.353Z,1554903323.353 [ThrusterServo] Loaded
2019-04-10T13:35:23.353Z,1554903323.353 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-04-10T13:35:23.354Z,1554903323.354 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-04-10T13:35:23.354Z,1554903323.354 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-04-10T13:35:23.488Z,1554903323.488 [SBIT](DEBUG): Construct Startup Built In Test.
2019-04-10T13:35:23.516Z,1554903323.516 [SBIT] Loaded
2019-04-10T13:35:23.517Z,1554903323.517 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-04-10T13:35:23.517Z,1554903323.517 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-04-10T13:35:23.545Z,1554903323.545 [IBIT] Loaded
2019-04-10T13:35:23.545Z,1554903323.545 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-04-10T13:35:23.548Z,1554903323.548 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-04-10T13:35:23.931Z,1554903323.931 [CBIT] Loaded
2019-04-10T13:35:23.931Z,1554903323.931 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-04-10T13:35:23.932Z,1554903323.932 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-04-10T13:35:23.932Z,1554903323.932 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-04-10T13:35:24.113Z,1554903324.113 [Aanderaa_O2] Loaded
2019-04-10T13:35:24.113Z,1554903324.113 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-04-10T13:35:24.123Z,1554903324.123 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-04-10T13:35:24.128Z,1554903324.128 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-04-10T13:35:24.130Z,1554903324.130 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-04-10T13:35:24.135Z,1554903324.135 [CTD_Seabird](INFO): created writer for : depth
2019-04-10T13:35:24.135Z,1554903324.135 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-04-10T13:35:24.140Z,1554903324.140 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-04-10T13:35:24.141Z,1554903324.141 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-04-10T13:35:24.146Z,1554903324.146 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-04-10T13:35:24.147Z,1554903324.147 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-04-10T13:35:24.152Z,1554903324.152 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-04-10T13:35:24.153Z,1554903324.153 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-04-10T13:35:24.158Z,1554903324.158 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-04-10T13:35:24.159Z,1554903324.159 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-04-10T13:35:24.164Z,1554903324.164 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-04-10T13:35:24.190Z,1554903324.190 [CTD_Seabird] Loaded
2019-04-10T13:35:24.190Z,1554903324.190 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-04-10T13:35:24.191Z,1554903324.191 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406654E0
2019-04-10T13:35:24.192Z,1554903324.192 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5683
2019-04-10T13:35:24.217Z,1554903324.217 [ESPComponent] Loaded
2019-04-10T13:35:24.217Z,1554903324.217 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-04-10T13:35:24.231Z,1554903324.231 [PAR_Licor] Loaded
2019-04-10T13:35:24.232Z,1554903324.232 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-04-10T13:35:24.279Z,1554903324.279 [WetLabsBB2FL] Loaded
2019-04-10T13:35:24.279Z,1554903324.279 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-04-10T13:35:24.280Z,1554903324.280 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406954E0
2019-04-10T13:35:24.281Z,1554903324.281 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5684
2019-04-10T13:35:24.281Z,1554903324.281 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-04-10T13:35:24.282Z,1554903324.282 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-04-10T13:35:24.428Z,1554903324.428 [DepthRateCalculator] Loaded
2019-04-10T13:35:24.428Z,1554903324.428 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-04-10T13:35:24.441Z,1554903324.441 [PitchRateCalculator] Loaded
2019-04-10T13:35:24.442Z,1554903324.442 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-04-10T13:35:24.525Z,1554903324.525 [SpeedCalculator] Loaded
2019-04-10T13:35:24.525Z,1554903324.525 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-04-10T13:35:24.565Z,1554903324.565 [TempGradientCalculator] Loaded
2019-04-10T13:35:24.565Z,1554903324.565 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-04-10T13:35:24.587Z,1554903324.587 [YawRateCalculator] Loaded
2019-04-10T13:35:24.588Z,1554903324.588 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-04-10T13:35:24.727Z,1554903324.727 [ElevatorOffsetCalculator] Loaded
2019-04-10T13:35:24.727Z,1554903324.727 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-04-10T13:35:24.727Z,1554903324.727 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-04-10T13:35:24.728Z,1554903324.728 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-04-10T13:35:24.853Z,1554903324.853 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-04-10T13:35:24.865Z,1554903324.865 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-04-10T13:35:25.302Z,1554903325.302 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-04-10T13:35:25.302Z,1554903325.302 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-04-10T13:35:25.476Z,1554903325.476 [DeadReckonUsingSpeedCalculator] Loaded
2019-04-10T13:35:25.476Z,1554903325.476 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-04-10T13:35:25.536Z,1554903325.536 [DeadReckonWithRespectToSeafloor] Loaded
2019-04-10T13:35:25.536Z,1554903325.536 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-04-10T13:35:25.557Z,1554903325.557 [NavChart] Loaded
2019-04-10T13:35:25.557Z,1554903325.557 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-04-10T13:35:25.564Z,1554903325.564 [UniversalFixResidualReporter] Loaded
2019-04-10T13:35:25.565Z,1554903325.565 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-04-10T13:35:25.565Z,1554903325.565 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-04-10T13:35:25.566Z,1554903325.566 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-04-10T13:35:25.872Z,1554903325.872 [AcousticModem_Benthos_ATM900] Loaded
2019-04-10T13:35:25.873Z,1554903325.873 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-04-10T13:35:25.944Z,1554903325.944 [DataOverHttps] Loaded
2019-04-10T13:35:25.944Z,1554903325.944 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-04-10T13:35:25.945Z,1554903325.945 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409714E0
2019-04-10T13:35:25.946Z,1554903325.946 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5685
2019-04-10T13:35:25.959Z,1554903325.959 [Depth_Keller] Loaded
2019-04-10T13:35:25.959Z,1554903325.959 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-04-10T13:35:26.050Z,1554903326.050 [NAL9602] Loaded
2019-04-10T13:35:26.050Z,1554903326.050 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-04-10T13:35:26.055Z,1554903326.055 [Onboard] Loaded
2019-04-10T13:35:26.056Z,1554903326.056 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-04-10T13:35:26.059Z,1554903326.059 [Radio_Surface] Loaded
2019-04-10T13:35:26.059Z,1554903326.059 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-04-10T13:35:26.060Z,1554903326.060 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A14E0
2019-04-10T13:35:26.061Z,1554903326.061 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5686
2019-04-10T13:35:27.344Z,1554903327.344 [BPC1] Loaded
2019-04-10T13:35:27.344Z,1554903327.344 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-04-10T13:35:27.374Z,1554903327.374 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minrange
2019-04-10T13:35:27.376Z,1554903327.376 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.maxrange
2019-04-10T13:35:27.378Z,1554903327.378 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.soundspeed
2019-04-10T13:35:27.380Z,1554903327.380 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.automode
2019-04-10T13:35:27.383Z,1554903327.383 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.gainindex
2019-04-10T13:35:27.385Z,1554903327.385 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minconfidence
2019-04-10T13:35:27.387Z,1554903327.387 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.pingenable
2019-04-10T13:35:27.389Z,1554903327.389 [BR_Ping1D] Loaded
2019-04-10T13:35:27.390Z,1554903327.390 [ComponentRegistry](DEBUG): SyncComponent "BR_Ping1D" handled in the control thread.
2019-04-10T13:35:27.390Z,1554903327.390 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-04-10T13:35:27.391Z,1554903327.391 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-04-10T13:35:27.454Z,1554903327.454 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-04-10T13:35:27.454Z,1554903327.454 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-04-10T13:35:27.520Z,1554903327.520 [VerticalControl](DEBUG): Construct VerticalControl.
2019-04-10T13:35:27.604Z,1554903327.604 [VerticalControl] Loaded
2019-04-10T13:35:27.604Z,1554903327.604 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-04-10T13:35:27.605Z,1554903327.605 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-04-10T13:35:27.662Z,1554903327.662 [HorizontalControl] Loaded
2019-04-10T13:35:27.662Z,1554903327.662 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-04-10T13:35:27.663Z,1554903327.663 [SpeedControl](DEBUG): Construct SpeedControl.
2019-04-10T13:35:27.665Z,1554903327.665 [SpeedControl] Loaded
2019-04-10T13:35:27.665Z,1554903327.665 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-04-10T13:35:27.666Z,1554903327.666 [LoopControl](DEBUG): Construct LoopControl.
2019-04-10T13:35:27.666Z,1554903327.666 [LoopControl] Loaded
2019-04-10T13:35:27.667Z,1554903327.667 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-04-10T13:35:27.667Z,1554903327.667 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-04-10T13:35:27.668Z,1554903327.668 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-04-10T13:35:27.681Z,1554903327.681 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-04-10T13:35:27.681Z,1554903327.681 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-04-10T13:35:27.954Z,1554903327.954 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-04-10T13:35:27.958Z,1554903327.958 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-04-10T13:35:27.958Z,1554903327.958 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-04-10T13:35:27.965Z,1554903327.965 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-04-10T13:35:27.966Z,1554903327.966 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACE4E0
2019-04-10T13:35:27.967Z,1554903327.967 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5687
2019-04-10T13:35:27.971Z,1554903327.971 [Supervisor](INFO): Main Thread ID is 9999
2019-04-10T13:35:27.972Z,1554903327.972 [Supervisor](DEBUG): Running supervisor.
2019-04-10T13:35:27.972Z,1554903327.972 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5688
2019-04-10T13:35:27.975Z,1554903327.975 [controlThread ThreadHandler](INFO): Handler Thread ID is 5689
2019-04-10T13:35:27.975Z,1554903327.975 [controlThread](DEBUG): Initializing ControlThread
2019-04-10T13:35:27.977Z,1554903327.977 [SBIT](INFO): Initialize SBIT Component.
2019-04-10T13:35:27.977Z,1554903327.977 [SBIT](IMPORTANT): git: 2019-03-18-31-ge98d5dc
2019-04-10T13:35:27.977Z,1554903327.977 [SBIT](INFO): git hash: e98d5dc50cbc39e402f19b9946ff4dc0776c8725
2019-04-10T13:35:27.978Z,1554903327.978 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-04-10T13:35:27.979Z,1554903327.979 [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-10T13:35:27.980Z,1554903327.980 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-04-10T13:35:27.981Z,1554903327.981 [IBIT](INFO): Initialize IBIT Component.
2019-04-10T13:35:27.982Z,1554903327.982 [CBIT](DEBUG): Initialize CBIT Component.
2019-04-10T13:35:27.983Z,1554903327.983 [logger ThreadHandler](INFO): Handler Thread ID is 5690
2019-04-10T13:35:27.994Z,1554903327.994 [CBIT](DEBUG): Initialized mux pins.
2019-04-10T13:35:27.994Z,1554903327.994 [CBIT](DEBUG): Initializing the watchdog timer.
2019-04-10T13:35:28.002Z,1554903328.002 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5691
2019-04-10T13:35:28.003Z,1554903328.003 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T13:35:28.007Z,1554903328.007 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T13:35:28.008Z,1554903328.008 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5693
2019-04-10T13:35:28.009Z,1554903328.009 [WetLabsBB2FL](INFO): Powering down
2019-04-10T13:35:28.018Z,1554903328.018 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-04-10T13:35:28.019Z,1554903328.019 [CBIT](DEBUG): Initializing heartbeat.
2019-04-10T13:35:28.039Z,1554903328.039 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5694
2019-04-10T13:35:28.040Z,1554903328.040 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-04-10T13:35:28.047Z,1554903328.047 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5695
2019-04-10T13:35:28.066Z,1554903328.066 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5696
2019-04-10T13:35:28.072Z,1554903328.072 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-10T13:35:28.072Z,1554903328.072 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-04-10T13:35:28.073Z,1554903328.073 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-10T13:35:28.073Z,1554903328.073 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-04-10T13:35:28.073Z,1554903328.073 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-10T13:35:28.073Z,1554903328.073 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-04-10T13:35:28.073Z,1554903328.073 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-10T13:35:28.073Z,1554903328.073 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-04-10T13:35:28.074Z,1554903328.074 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-10T13:35:28.074Z,1554903328.074 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-04-10T13:35:28.074Z,1554903328.074 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-10T13:35:28.074Z,1554903328.074 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-04-10T13:35:28.075Z,1554903328.075 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-10T13:35:28.075Z,1554903328.075 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-04-10T13:35:28.075Z,1554903328.075 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-10T13:35:28.075Z,1554903328.075 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-04-10T13:35:28.090Z,1554903328.090 [CBIT](DEBUG): Deactivating GF circuits.
2019-04-10T13:35:28.090Z,1554903328.090 [CBIT](DEBUG): Deactivating emergency mode.
2019-04-10T13:35:28.126Z,1554903328.126 [CBIT](DEBUG): Backplane powered.
2019-04-10T13:35:28.130Z,1554903328.130 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-04-10T13:35:28.131Z,1554903328.131 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-04-10T13:35:28.131Z,1554903328.131 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-04-10T13:35:28.131Z,1554903328.131 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-04-10T13:35:28.132Z,1554903328.132 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-04-10T13:35:28.132Z,1554903328.132 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-04-10T13:35:28.133Z,1554903328.133 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-10T13:35:28.135Z,1554903328.135 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-04-10T13:35:28.135Z,1554903328.135 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-04-10T13:35:28.143Z,1554903328.143 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-04-10T13:35:28.145Z,1554903328.145 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-04-10T13:35:28.154Z,1554903328.154 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-04-10T13:35:28.154Z,1554903328.154 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-04-10T13:35:28.155Z,1554903328.155 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-04-10T13:35:28.181Z,1554903328.181 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-10T13:35:28.216Z,1554903328.216 [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-10T13:35:28.217Z,1554903328.217 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-04-10T13:35:28.306Z,1554903328.306 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-04-10T13:35:28.307Z,1554903328.307 [Default:A.Wait](DEBUG): Construct Wait.
2019-04-10T13:35:28.309Z,1554903328.309 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-10T13:35:28.353Z,1554903328.353 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-04-10T13:35:28.356Z,1554903328.356 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-04-10T13:35:28.385Z,1554903328.385 [Default:E.Execute](DEBUG): Construct Execute.
2019-04-10T13:35:28.389Z,1554903328.389 [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-10T13:35:28.393Z,1554903328.393 [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-10T13:35:28.427Z,1554903328.427 [ESPComponent](INFO): powering down ESP
2019-04-10T13:35:28.450Z,1554903328.450 [Radio_Surface](INFO): Powering up
2019-04-10T13:35:28.874Z,1554903328.874 [PAR_Licor](INFO): A/D timeout, 8 tries over 130 ms

2019-04-10T13:35:28.874Z,1554903328.874 [PAR_Licor] Data Fault, FailCount= 1
2019-04-10T13:35:28.875Z,1554903328.875 [PAR_Licor](ERROR): Data Fault
2019-04-10T13:35:28.875Z,1554903328.875 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T13:35:28.875Z,1554903328.875 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T13:35:29.084Z,1554903329.084 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-04-10T13:35:29.090Z,1554903329.090 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-04-10T13:35:29.091Z,1554903329.091 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-10T13:35:29.098Z,1554903329.098 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-10T13:35:29.099Z,1554903329.099 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-10T13:35:29.106Z,1554903329.106 [MassServo](DEBUG): Initializing MassServo.
2019-04-10T13:35:29.107Z,1554903329.107 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-10T13:35:29.114Z,1554903329.114 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-10T13:35:29.115Z,1554903329.115 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-04-10T13:35:29.122Z,1554903329.122 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-04-10T13:35:29.125Z,1554903329.125 [CommandLine](FAULT): Scheduling is paused
2019-04-10T13:35:29.125Z,1554903329.125 [CBIT](INFO): Critical error at 20190410T133527
2019-04-10T13:35:29.125Z,1554903329.125 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-04-10T13:35:29.130Z,1554903329.130 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T13:35:29.130Z,1554903329.130 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T13:35:29.130Z,1554903329.130 [PAR_Licor] No Fault, FailCount= 1
2019-04-10T13:35:29.674Z,1554903329.674 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-10T13:35:29.674Z,1554903329.674 [PAR_Licor] Data Fault, FailCount= 2
2019-04-10T13:35:29.674Z,1554903329.674 [PAR_Licor](ERROR): Data Fault
2019-04-10T13:35:29.711Z,1554903329.711 [BR_Ping1D](INFO): startup: 0
2019-04-10T13:35:29.711Z,1554903329.711 [BR_Ping1D](INFO): recvData: FFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF
2019-04-10T13:35:29.711Z,1554903329.711 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T13:35:29.722Z,1554903329.722 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T13:35:29.722Z,1554903329.722 [BR_Ping1D](INFO): XXX autoMode1: 01
2019-04-10T13:35:29.722Z,1554903329.722 [BR_Ping1D](INFO): XXX autoMode2: 
2019-04-10T13:35:29.723Z,1554903329.723 [BR_Ping1D](INFO): XXX autoMode3: 1
2019-04-10T13:35:29.723Z,1554903329.723 [BR_Ping1D](INFO): 42520100EB030000018401
2019-04-10T13:35:29.723Z,1554903329.723 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T13:35:29.723Z,1554903329.723 [BR_Ping1D](INFO): 42520100EE030000018701
2019-04-10T13:35:30.090Z,1554903330.090 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-04-10T13:35:30.090Z,1554903330.090 [RudderServo](FAULT): Rudder failed to initialize
2019-04-10T13:35:30.090Z,1554903330.090 [RudderServo] Communications Fault, FailCount= 1
2019-04-10T13:35:30.090Z,1554903330.090 [RudderServo](ERROR): Communications Fault
2019-04-10T13:35:30.202Z,1554903330.202 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-04-10T13:35:30.202Z,1554903330.202 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T13:35:30.202Z,1554903330.202 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T13:35:30.202Z,1554903330.202 [PAR_Licor] No Fault, FailCount= 2
2019-04-10T13:35:30.245Z,1554903330.245 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:30.245Z,1554903330.245 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:30.246Z,1554903330.246 [BR_Ping1D](INFO): startup: 1
2019-04-10T13:35:30.246Z,1554903330.246 [BR_Ping1D](INFO): UART requesting status
2019-04-10T13:35:30.246Z,1554903330.246 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T13:35:30.254Z,1554903330.254 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T13:35:30.262Z,1554903330.262 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T13:35:30.407Z,1554903330.407 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-10T13:35:30.407Z,1554903330.407 [RudderServo](INFO): Powering down
2019-04-10T13:35:30.633Z,1554903330.633 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:30.633Z,1554903330.633 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:30.634Z,1554903330.634 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:30.634Z,1554903330.634 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:30.634Z,1554903330.634 [BR_Ping1D](INFO): elapsed 0.9224
2019-04-10T13:35:30.634Z,1554903330.634 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:30.635Z,1554903330.635 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:30.635Z,1554903330.635 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:30.635Z,1554903330.635 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:31.158Z,1554903331.158 [PAR_Licor](INFO): A/D timeout, 9 tries over 142 ms

2019-04-10T13:35:31.158Z,1554903331.158 [PAR_Licor] Data Fault, FailCount= 1
2019-04-10T13:35:31.158Z,1554903331.158 [PAR_Licor](ERROR): Data Fault
2019-04-10T13:35:31.193Z,1554903331.193 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:31.194Z,1554903331.194 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:31.194Z,1554903331.194 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:31.194Z,1554903331.194 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:31.194Z,1554903331.194 [BR_Ping1D](INFO): elapsed 1.4827
2019-04-10T13:35:31.194Z,1554903331.194 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:31.195Z,1554903331.195 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:31.195Z,1554903331.195 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:31.195Z,1554903331.195 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:31.223Z,1554903331.223 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-10T13:35:31.343Z,1554903331.343 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-10T13:35:31.347Z,1554903331.347 [CBIT](INFO): Clearing failed state for component RudderServo
2019-04-10T13:35:31.347Z,1554903331.347 [RudderServo] No Fault, FailCount= 1
2019-04-10T13:35:31.348Z,1554903331.348 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T13:35:31.348Z,1554903331.348 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T13:35:31.348Z,1554903331.348 [PAR_Licor] No Fault, FailCount= 1
2019-04-10T13:35:31.441Z,1554903331.441 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:31.441Z,1554903331.441 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:31.442Z,1554903331.442 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:31.442Z,1554903331.442 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:31.442Z,1554903331.442 [BR_Ping1D](INFO): elapsed 1.7303
2019-04-10T13:35:31.442Z,1554903331.442 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:31.442Z,1554903331.442 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:31.442Z,1554903331.442 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:31.443Z,1554903331.443 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:31.958Z,1554903331.958 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-10T13:35:31.958Z,1554903331.958 [PAR_Licor] Data Fault, FailCount= 2
2019-04-10T13:35:31.958Z,1554903331.958 [PAR_Licor](ERROR): Data Fault
2019-04-10T13:35:31.993Z,1554903331.993 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:31.994Z,1554903331.994 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:31.994Z,1554903331.994 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:31.994Z,1554903331.994 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:31.994Z,1554903331.994 [BR_Ping1D](INFO): elapsed 2.2827
2019-04-10T13:35:31.994Z,1554903331.994 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:31.995Z,1554903331.995 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:31.995Z,1554903331.995 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:31.995Z,1554903331.995 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:32.178Z,1554903332.178 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T13:35:32.178Z,1554903332.178 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T13:35:32.178Z,1554903332.178 [PAR_Licor] No Fault, FailCount= 2
2019-04-10T13:35:32.250Z,1554903332.250 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:32.250Z,1554903332.250 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:32.250Z,1554903332.250 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:32.250Z,1554903332.250 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:32.250Z,1554903332.250 [BR_Ping1D](INFO): elapsed 2.5388
2019-04-10T13:35:32.251Z,1554903332.251 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:32.251Z,1554903332.251 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:32.251Z,1554903332.251 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:32.251Z,1554903332.251 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:32.766Z,1554903332.766 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-10T13:35:32.766Z,1554903332.766 [PAR_Licor] Data Fault, FailCount= 3
2019-04-10T13:35:32.766Z,1554903332.766 [PAR_Licor](ERROR): Data Fault
2019-04-10T13:35:32.807Z,1554903332.807 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:32.807Z,1554903332.807 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:32.807Z,1554903332.807 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:32.807Z,1554903332.807 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:32.807Z,1554903332.807 [BR_Ping1D](INFO): elapsed 3.0958
2019-04-10T13:35:32.808Z,1554903332.808 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:32.808Z,1554903332.808 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:32.808Z,1554903332.808 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:32.808Z,1554903332.808 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:33.058Z,1554903333.058 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T13:35:33.059Z,1554903333.059 [CBIT](CRITICAL): Data Fault in component: PAR_Licor
2019-04-10T13:35:33.108Z,1554903333.108 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:33.108Z,1554903333.108 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:33.108Z,1554903333.108 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:33.108Z,1554903333.108 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:33.109Z,1554903333.109 [BR_Ping1D](INFO): elapsed 3.3969
2019-04-10T13:35:33.109Z,1554903333.109 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:33.109Z,1554903333.109 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:33.109Z,1554903333.109 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:33.109Z,1554903333.109 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:33.180Z,1554903333.180 [CBIT](INFO): Critical error at 20190410T133533
2019-04-10T13:35:33.485Z,1554903333.485 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:33.486Z,1554903333.486 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:33.486Z,1554903333.486 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:33.486Z,1554903333.486 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:33.486Z,1554903333.486 [BR_Ping1D](INFO): elapsed 3.7747
2019-04-10T13:35:33.486Z,1554903333.486 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:33.487Z,1554903333.487 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:33.487Z,1554903333.487 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:33.487Z,1554903333.487 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:33.889Z,1554903333.889 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:33.889Z,1554903333.889 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:33.889Z,1554903333.889 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:33.890Z,1554903333.890 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:33.890Z,1554903333.890 [BR_Ping1D](INFO): elapsed 4.1783
2019-04-10T13:35:33.890Z,1554903333.890 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:33.890Z,1554903333.890 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:33.891Z,1554903333.891 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:33.891Z,1554903333.891 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:34.294Z,1554903334.294 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:34.294Z,1554903334.294 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:34.294Z,1554903334.294 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:34.295Z,1554903334.295 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:34.295Z,1554903334.295 [BR_Ping1D](INFO): elapsed 4.5833
2019-04-10T13:35:34.295Z,1554903334.295 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:34.295Z,1554903334.295 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:34.295Z,1554903334.295 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:34.296Z,1554903334.296 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:34.697Z,1554903334.697 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:34.697Z,1554903334.697 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:34.698Z,1554903334.698 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:34.698Z,1554903334.698 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:34.698Z,1554903334.698 [BR_Ping1D](INFO): elapsed 4.9866
2019-04-10T13:35:34.698Z,1554903334.698 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:34.699Z,1554903334.699 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:34.699Z,1554903334.699 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:34.699Z,1554903334.699 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:35.109Z,1554903335.109 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:35.110Z,1554903335.110 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:35.110Z,1554903335.110 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:35.110Z,1554903335.110 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:35.110Z,1554903335.110 [BR_Ping1D](INFO): elapsed 5.3987
2019-04-10T13:35:35.110Z,1554903335.110 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:35.111Z,1554903335.111 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T13:35:35.111Z,1554903335.111 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-10T13:35:35.111Z,1554903335.111 [BR_Ping1D](ERROR): Data Fault
2019-04-10T13:35:35.179Z,1554903335.179 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T13:35:35.507Z,1554903335.507 [BR_Ping1D](INFO): Stop
2019-04-10T13:35:35.507Z,1554903335.507 [BR_Ping1D](INFO): uninitialize
2019-04-10T13:35:35.507Z,1554903335.507 [BR_Ping1D](INFO): Powering down
2019-04-10T13:35:35.911Z,1554903335.911 [BR_Ping1D](INFO): Stopping
2019-04-10T13:35:36.326Z,1554903336.326 [BR_Ping1D](INFO): Stopping
2019-04-10T13:35:36.718Z,1554903336.718 [BR_Ping1D](INFO): Stopped
2019-04-10T13:35:36.779Z,1554903336.779 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T13:35:36.779Z,1554903336.779 [BR_Ping1D] No Fault, FailCount= 1
2019-04-10T13:35:37.121Z,1554903337.121 [BR_Ping1D](INFO): Start
2019-04-10T13:35:37.525Z,1554903337.525 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:37.930Z,1554903337.930 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:37.930Z,1554903337.930 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:37.930Z,1554903337.930 [BR_Ping1D](INFO): startup: 0
2019-04-10T13:35:37.930Z,1554903337.930 [BR_Ping1D](INFO): recvData: FFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF
2019-04-10T13:35:37.930Z,1554903337.930 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T13:35:37.942Z,1554903337.942 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T13:35:37.942Z,1554903337.942 [BR_Ping1D](INFO): XXX autoMode1: 01
2019-04-10T13:35:37.942Z,1554903337.942 [BR_Ping1D](INFO): XXX autoMode2: 
2019-04-10T13:35:37.942Z,1554903337.942 [BR_Ping1D](INFO): XXX autoMode3: 1
2019-04-10T13:35:37.943Z,1554903337.943 [BR_Ping1D](INFO): 42520100EB030000018401
2019-04-10T13:35:37.943Z,1554903337.943 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T13:35:37.943Z,1554903337.943 [BR_Ping1D](INFO): 42520100EE030000018701
2019-04-10T13:35:38.031Z,1554903338.031 [CBIT](CRITICAL): Environmental Failure. Press:14.866898 PSI. Humidity:31%. Temp:26 C. ABORTING MISSION
2019-04-10T13:35:38.338Z,1554903338.338 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:38.521Z,1554903338.521 [CBIT](INFO): Critical error at 20190410T133538
2019-04-10T13:35:38.718Z,1554903338.718 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T13:35:38.718Z,1554903338.718 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-10T13:35:38.718Z,1554903338.718 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T13:35:38.737Z,1554903338.737 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:38.738Z,1554903338.738 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:38.738Z,1554903338.738 [BR_Ping1D](INFO): startup: 1
2019-04-10T13:35:38.738Z,1554903338.738 [BR_Ping1D](INFO): UART requesting status
2019-04-10T13:35:38.738Z,1554903338.738 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T13:35:38.746Z,1554903338.746 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T13:35:38.754Z,1554903338.754 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T13:35:38.773Z,1554903338.773 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T13:35:39.242Z,1554903339.242 [Aanderaa_O2](INFO): Powering down
2019-04-10T13:35:39.319Z,1554903339.319 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:39.319Z,1554903339.319 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:39.319Z,1554903339.319 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:39.319Z,1554903339.319 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:39.319Z,1554903339.319 [BR_Ping1D](INFO): elapsed 1.3888
2019-04-10T13:35:39.320Z,1554903339.320 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:39.320Z,1554903339.320 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:39.320Z,1554903339.320 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:39.320Z,1554903339.320 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:39.545Z,1554903339.545 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:39.545Z,1554903339.545 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:39.546Z,1554903339.546 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:39.546Z,1554903339.546 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:39.546Z,1554903339.546 [BR_Ping1D](INFO): elapsed 1.6154
2019-04-10T13:35:39.546Z,1554903339.546 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:39.546Z,1554903339.546 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:39.547Z,1554903339.547 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:39.547Z,1554903339.547 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:39.950Z,1554903339.950 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:39.950Z,1554903339.950 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:39.950Z,1554903339.950 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:39.950Z,1554903339.950 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:39.950Z,1554903339.950 [BR_Ping1D](INFO): elapsed 2.0197
2019-04-10T13:35:39.951Z,1554903339.951 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:39.951Z,1554903339.951 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:39.951Z,1554903339.951 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:39.951Z,1554903339.951 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:39.967Z,1554903339.967 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T13:35:39.967Z,1554903339.967 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-10T13:35:40.354Z,1554903340.354 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:40.354Z,1554903340.354 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:40.354Z,1554903340.354 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:40.354Z,1554903340.354 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:40.354Z,1554903340.354 [BR_Ping1D](INFO): elapsed 2.4239
2019-04-10T13:35:40.355Z,1554903340.355 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:40.355Z,1554903340.355 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:40.355Z,1554903340.355 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:40.355Z,1554903340.355 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:40.770Z,1554903340.770 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:40.770Z,1554903340.770 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:40.770Z,1554903340.770 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:40.770Z,1554903340.770 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:40.770Z,1554903340.770 [BR_Ping1D](INFO): elapsed 2.8398
2019-04-10T13:35:40.771Z,1554903340.771 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:40.771Z,1554903340.771 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:40.771Z,1554903340.771 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:40.771Z,1554903340.771 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:41.054Z,1554903341.054 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T13:35:41.054Z,1554903341.054 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-10T13:35:41.054Z,1554903341.054 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T13:35:41.161Z,1554903341.161 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:41.161Z,1554903341.161 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:41.162Z,1554903341.162 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:41.162Z,1554903341.162 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:41.162Z,1554903341.162 [BR_Ping1D](INFO): elapsed 3.2314
2019-04-10T13:35:41.162Z,1554903341.162 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:41.162Z,1554903341.162 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:41.163Z,1554903341.163 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:41.163Z,1554903341.163 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:41.179Z,1554903341.179 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T13:35:41.458Z,1554903341.458 [CTD_Seabird](INFO): Powering down
2019-04-10T13:35:41.566Z,1554903341.566 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:41.566Z,1554903341.566 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:41.567Z,1554903341.567 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:41.567Z,1554903341.567 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:41.567Z,1554903341.567 [BR_Ping1D](INFO): elapsed 3.6363
2019-04-10T13:35:41.567Z,1554903341.567 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:41.567Z,1554903341.567 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:41.567Z,1554903341.567 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:41.568Z,1554903341.568 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:41.969Z,1554903341.969 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:41.969Z,1554903341.969 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:41.969Z,1554903341.969 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:41.970Z,1554903341.970 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:41.970Z,1554903341.970 [BR_Ping1D](INFO): elapsed 4.0393
2019-04-10T13:35:41.970Z,1554903341.970 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:41.970Z,1554903341.970 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:41.970Z,1554903341.970 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:41.971Z,1554903341.971 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:42.374Z,1554903342.374 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:42.374Z,1554903342.374 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:42.374Z,1554903342.374 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:42.374Z,1554903342.374 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:42.375Z,1554903342.375 [BR_Ping1D](INFO): elapsed 4.4440
2019-04-10T13:35:42.375Z,1554903342.375 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:42.375Z,1554903342.375 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:42.375Z,1554903342.375 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:42.375Z,1554903342.375 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:42.778Z,1554903342.778 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:42.778Z,1554903342.778 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:42.778Z,1554903342.778 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:42.778Z,1554903342.778 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:42.779Z,1554903342.779 [BR_Ping1D](INFO): elapsed 4.8479
2019-04-10T13:35:42.779Z,1554903342.779 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:42.779Z,1554903342.779 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:42.779Z,1554903342.779 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:42.779Z,1554903342.779 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:42.795Z,1554903342.795 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-10T13:35:42.795Z,1554903342.795 [CTD_Seabird] No Fault, FailCount= 1
2019-04-10T13:35:42.838Z,1554903342.838 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T13:35:42.840Z,1554903342.840 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T13:35:43.182Z,1554903343.182 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:43.182Z,1554903343.182 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:43.182Z,1554903343.182 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:43.182Z,1554903343.182 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:43.183Z,1554903343.183 [BR_Ping1D](INFO): elapsed 5.2519
2019-04-10T13:35:43.183Z,1554903343.183 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:43.183Z,1554903343.183 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T13:35:43.183Z,1554903343.183 [BR_Ping1D] Data Fault, FailCount= 2
2019-04-10T13:35:43.183Z,1554903343.183 [BR_Ping1D](ERROR): Data Fault
2019-04-10T13:35:43.215Z,1554903343.215 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T13:35:43.585Z,1554903343.585 [BR_Ping1D](INFO): Stop
2019-04-10T13:35:43.585Z,1554903343.585 [BR_Ping1D](INFO): uninitialize
2019-04-10T13:35:43.585Z,1554903343.585 [BR_Ping1D](INFO): Powering down
2019-04-10T13:35:43.990Z,1554903343.990 [BR_Ping1D](INFO): Stopping
2019-04-10T13:35:44.394Z,1554903344.394 [BR_Ping1D](INFO): Stopping
2019-04-10T13:35:44.798Z,1554903344.798 [BR_Ping1D](INFO): Stopped
2019-04-10T13:35:44.835Z,1554903344.835 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T13:35:44.835Z,1554903344.835 [BR_Ping1D] No Fault, FailCount= 2
2019-04-10T13:35:45.202Z,1554903345.202 [BR_Ping1D](INFO): Start
2019-04-10T13:35:45.606Z,1554903345.606 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:46.048Z,1554903346.048 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:46.048Z,1554903346.048 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:46.048Z,1554903346.048 [BR_Ping1D](INFO): startup: 0
2019-04-10T13:35:46.049Z,1554903346.049 [BR_Ping1D](INFO): recvData: FFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF
2019-04-10T13:35:46.049Z,1554903346.049 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T13:35:46.062Z,1554903346.062 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T13:35:46.062Z,1554903346.062 [BR_Ping1D](INFO): XXX autoMode1: 01
2019-04-10T13:35:46.062Z,1554903346.062 [BR_Ping1D](INFO): XXX autoMode2: 
2019-04-10T13:35:46.063Z,1554903346.063 [BR_Ping1D](INFO): XXX autoMode3: 1
2019-04-10T13:35:46.063Z,1554903346.063 [BR_Ping1D](INFO): 42520100EB030000018401
2019-04-10T13:35:46.063Z,1554903346.063 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T13:35:46.063Z,1554903346.063 [BR_Ping1D](INFO): 42520100EE030000018701
2019-04-10T13:35:46.414Z,1554903346.414 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:46.847Z,1554903346.847 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:46.847Z,1554903346.847 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:46.847Z,1554903346.847 [BR_Ping1D](INFO): startup: 1
2019-04-10T13:35:46.848Z,1554903346.848 [BR_Ping1D](INFO): UART requesting status
2019-04-10T13:35:46.848Z,1554903346.848 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T13:35:46.854Z,1554903346.854 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T13:35:46.862Z,1554903346.862 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T13:35:47.222Z,1554903347.222 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:47.222Z,1554903347.222 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:47.222Z,1554903347.222 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:47.222Z,1554903347.222 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:47.222Z,1554903347.222 [BR_Ping1D](INFO): elapsed 1.1735
2019-04-10T13:35:47.223Z,1554903347.223 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:47.223Z,1554903347.223 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:47.223Z,1554903347.223 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:47.223Z,1554903347.223 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:47.625Z,1554903347.625 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:47.625Z,1554903347.625 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:47.626Z,1554903347.626 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:47.626Z,1554903347.626 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:47.626Z,1554903347.626 [BR_Ping1D](INFO): elapsed 1.5772
2019-04-10T13:35:47.626Z,1554903347.626 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:47.626Z,1554903347.626 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:47.627Z,1554903347.627 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:47.627Z,1554903347.627 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:48.029Z,1554903348.029 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:48.030Z,1554903348.030 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:48.030Z,1554903348.030 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:48.030Z,1554903348.030 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:48.030Z,1554903348.030 [BR_Ping1D](INFO): elapsed 1.9814
2019-04-10T13:35:48.030Z,1554903348.030 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:48.031Z,1554903348.031 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:48.031Z,1554903348.031 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:48.031Z,1554903348.031 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:48.433Z,1554903348.433 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:48.433Z,1554903348.433 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:48.433Z,1554903348.433 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:48.434Z,1554903348.434 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:48.434Z,1554903348.434 [BR_Ping1D](INFO): elapsed 2.3849
2019-04-10T13:35:48.434Z,1554903348.434 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:48.434Z,1554903348.434 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:48.434Z,1554903348.434 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:48.435Z,1554903348.434 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:48.838Z,1554903348.838 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:48.838Z,1554903348.838 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:48.838Z,1554903348.838 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:48.838Z,1554903348.838 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:48.838Z,1554903348.838 [BR_Ping1D](INFO): elapsed 2.7895
2019-04-10T13:35:48.839Z,1554903348.839 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:48.839Z,1554903348.839 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:48.839Z,1554903348.839 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:48.839Z,1554903348.839 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:49.222Z,1554903349.222 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T13:35:49.223Z,1554903349.223 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-04-10T13:35:49.223Z,1554903349.223 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T13:35:49.241Z,1554903349.241 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:49.241Z,1554903349.241 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:49.241Z,1554903349.241 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:49.242Z,1554903349.242 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:49.242Z,1554903349.242 [BR_Ping1D](INFO): elapsed 3.1929
2019-04-10T13:35:49.242Z,1554903349.242 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:49.242Z,1554903349.242 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:49.242Z,1554903349.242 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:49.243Z,1554903349.243 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:49.259Z,1554903349.259 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T13:35:49.626Z,1554903349.626 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T13:35:49.798Z,1554903349.798 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:49.798Z,1554903349.798 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:49.798Z,1554903349.798 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:49.798Z,1554903349.798 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:49.798Z,1554903349.798 [BR_Ping1D](INFO): elapsed 3.7496
2019-04-10T13:35:49.799Z,1554903349.799 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:49.799Z,1554903349.799 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:49.799Z,1554903349.799 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:49.799Z,1554903349.799 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:50.049Z,1554903350.049 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:50.049Z,1554903350.049 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:50.050Z,1554903350.050 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:50.050Z,1554903350.050 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:50.050Z,1554903350.050 [BR_Ping1D](INFO): elapsed 4.0012
2019-04-10T13:35:50.050Z,1554903350.050 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:50.051Z,1554903350.051 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:50.051Z,1554903350.051 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:50.051Z,1554903350.051 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:50.434Z,1554903350.434 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T13:35:50.434Z,1554903350.434 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-10T13:35:50.434Z,1554903350.434 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T13:35:50.454Z,1554903350.454 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:50.454Z,1554903350.454 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:50.454Z,1554903350.454 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:50.454Z,1554903350.454 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:50.455Z,1554903350.455 [BR_Ping1D](INFO): elapsed 4.4057
2019-04-10T13:35:50.455Z,1554903350.455 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:50.455Z,1554903350.455 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:50.455Z,1554903350.455 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:50.455Z,1554903350.455 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:50.504Z,1554903350.504 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T13:35:50.504Z,1554903350.504 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T13:35:50.504Z,1554903350.504 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-04-10T13:35:50.958Z,1554903350.958 [Aanderaa_O2](INFO): Powering down
2019-04-10T13:35:50.977Z,1554903350.977 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:50.978Z,1554903350.978 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:50.978Z,1554903350.978 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:50.978Z,1554903350.978 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:50.978Z,1554903350.978 [BR_Ping1D](INFO): elapsed 4.9294
2019-04-10T13:35:50.979Z,1554903350.979 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:50.979Z,1554903350.979 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:50.979Z,1554903350.979 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:50.979Z,1554903350.979 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:51.262Z,1554903351.262 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:51.262Z,1554903351.262 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:51.262Z,1554903351.262 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:51.262Z,1554903351.262 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:51.262Z,1554903351.262 [BR_Ping1D](INFO): elapsed 5.2136
2019-04-10T13:35:51.263Z,1554903351.263 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:51.263Z,1554903351.263 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T13:35:51.263Z,1554903351.263 [BR_Ping1D] Data Fault, FailCount= 3
2019-04-10T13:35:51.263Z,1554903351.263 [BR_Ping1D](ERROR): Data Fault
2019-04-10T13:35:51.279Z,1554903351.279 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T13:35:51.665Z,1554903351.665 [BR_Ping1D](INFO): Stop
2019-04-10T13:35:51.665Z,1554903351.665 [BR_Ping1D](INFO): uninitialize
2019-04-10T13:35:51.665Z,1554903351.665 [BR_Ping1D](INFO): Powering down
2019-04-10T13:35:51.767Z,1554903351.767 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T13:35:51.767Z,1554903351.767 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-10T13:35:52.078Z,1554903352.078 [BR_Ping1D](INFO): Stopping
2019-04-10T13:35:52.485Z,1554903352.485 [BR_Ping1D](INFO): Stopping
2019-04-10T13:35:52.512Z,1554903352.512 [SBIT](IMPORTANT): Beginning Startup BIT
2019-04-10T13:35:52.516Z,1554903352.516 [CBIT](IMPORTANT): Beginning ground fault scan
2019-04-10T13:35:52.858Z,1554903352.858 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T13:35:52.859Z,1554903352.859 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T13:35:52.897Z,1554903352.897 [BR_Ping1D](INFO): Stopped
2019-04-10T13:35:52.988Z,1554903352.988 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T13:35:52.988Z,1554903352.988 [BR_Ping1D] No Fault, FailCount= 3
2019-04-10T13:35:53.281Z,1554903353.281 [BR_Ping1D](INFO): Start
2019-04-10T13:35:53.686Z,1554903353.686 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:54.089Z,1554903354.089 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:54.089Z,1554903354.089 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:54.089Z,1554903354.089 [BR_Ping1D](INFO): startup: 0
2019-04-10T13:35:54.090Z,1554903354.090 [BR_Ping1D](INFO): recvData: FFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF
2019-04-10T13:35:54.090Z,1554903354.090 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T13:35:54.102Z,1554903354.102 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T13:35:54.102Z,1554903354.102 [BR_Ping1D](INFO): XXX autoMode1: 01
2019-04-10T13:35:54.102Z,1554903354.102 [BR_Ping1D](INFO): XXX autoMode2: 
2019-04-10T13:35:54.102Z,1554903354.102 [BR_Ping1D](INFO): XXX autoMode3: 1
2019-04-10T13:35:54.103Z,1554903354.103 [BR_Ping1D](INFO): 42520100EB030000018401
2019-04-10T13:35:54.103Z,1554903354.103 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T13:35:54.103Z,1554903354.103 [BR_Ping1D](INFO): 42520100EE030000018701
2019-04-10T13:35:54.493Z,1554903354.493 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:54.975Z,1554903354.975 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:54.975Z,1554903354.975 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:54.975Z,1554903354.975 [BR_Ping1D](INFO): startup: 1
2019-04-10T13:35:54.975Z,1554903354.975 [BR_Ping1D](INFO): UART requesting status
2019-04-10T13:35:54.975Z,1554903354.975 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T13:35:54.982Z,1554903354.982 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T13:35:54.990Z,1554903354.990 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T13:35:55.374Z,1554903355.374 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:55.374Z,1554903355.374 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:55.374Z,1554903355.374 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:55.374Z,1554903355.374 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:55.374Z,1554903355.374 [BR_Ping1D](INFO): elapsed 1.2843
2019-04-10T13:35:55.375Z,1554903355.375 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:55.375Z,1554903355.375 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:55.375Z,1554903355.375 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:55.375Z,1554903355.375 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:55.777Z,1554903355.777 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:55.777Z,1554903355.777 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:55.777Z,1554903355.777 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:55.777Z,1554903355.777 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:55.777Z,1554903355.777 [BR_Ping1D](INFO): elapsed 1.6873
2019-04-10T13:35:55.778Z,1554903355.778 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:55.778Z,1554903355.778 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:55.778Z,1554903355.778 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:55.778Z,1554903355.778 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:55.894Z,1554903355.894 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T13:35:55.894Z,1554903355.894 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-10T13:35:55.894Z,1554903355.894 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T13:35:55.951Z,1554903355.951 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T13:35:56.167Z,1554903356.167 [NAL9602](INFO): Powering up NAL9602
2019-04-10T13:35:56.183Z,1554903356.183 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:56.183Z,1554903356.183 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:56.183Z,1554903356.183 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:56.184Z,1554903356.184 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:56.184Z,1554903356.184 [BR_Ping1D](INFO): elapsed 2.0938
2019-04-10T13:35:56.184Z,1554903356.184 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:56.184Z,1554903356.184 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:56.184Z,1554903356.184 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:56.185Z,1554903356.185 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:56.298Z,1554903356.298 [CTD_Seabird](INFO): Powering down
2019-04-10T13:35:56.585Z,1554903356.585 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:56.585Z,1554903356.585 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:56.586Z,1554903356.586 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:56.586Z,1554903356.586 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:56.586Z,1554903356.586 [BR_Ping1D](INFO): elapsed 2.4959
2019-04-10T13:35:56.586Z,1554903356.586 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:56.586Z,1554903356.586 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:56.586Z,1554903356.586 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:56.587Z,1554903356.587 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:56.990Z,1554903356.990 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:56.990Z,1554903356.990 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:56.990Z,1554903356.990 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:56.990Z,1554903356.990 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:56.990Z,1554903356.990 [BR_Ping1D](INFO): elapsed 2.9003
2019-04-10T13:35:56.991Z,1554903356.991 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:56.991Z,1554903356.991 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:56.991Z,1554903356.991 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:56.991Z,1554903356.991 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:57.393Z,1554903357.393 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:57.393Z,1554903357.393 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:57.394Z,1554903357.394 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:57.394Z,1554903357.394 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:57.394Z,1554903357.394 [BR_Ping1D](INFO): elapsed 3.3041
2019-04-10T13:35:57.394Z,1554903357.394 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:57.395Z,1554903357.395 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:57.395Z,1554903357.395 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:57.395Z,1554903357.395 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:57.571Z,1554903357.571 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-10T13:35:57.571Z,1554903357.571 [CTD_Seabird] No Fault, FailCount= 2
2019-04-10T13:35:57.642Z,1554903357.642 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T13:35:57.650Z,1554903357.650 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T13:35:57.798Z,1554903357.798 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:57.798Z,1554903357.798 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:57.798Z,1554903357.798 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:57.798Z,1554903357.798 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:57.798Z,1554903357.798 [BR_Ping1D](INFO): elapsed 3.7083
2019-04-10T13:35:57.799Z,1554903357.799 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:57.799Z,1554903357.799 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:57.799Z,1554903357.799 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:57.799Z,1554903357.799 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:58.201Z,1554903358.201 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:58.201Z,1554903358.201 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:58.202Z,1554903358.202 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:58.202Z,1554903358.202 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:58.202Z,1554903358.202 [BR_Ping1D](INFO): elapsed 4.1119
2019-04-10T13:35:58.202Z,1554903358.202 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:58.202Z,1554903358.202 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:58.202Z,1554903358.202 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:58.203Z,1554903358.203 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:58.606Z,1554903358.606 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:58.606Z,1554903358.606 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:58.606Z,1554903358.606 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:58.606Z,1554903358.606 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:58.606Z,1554903358.606 [BR_Ping1D](INFO): elapsed 4.5164
2019-04-10T13:35:58.607Z,1554903358.607 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:58.607Z,1554903358.607 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:58.607Z,1554903358.607 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:58.607Z,1554903358.607 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:59.009Z,1554903359.009 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:59.009Z,1554903359.009 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:59.010Z,1554903359.010 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:59.010Z,1554903359.010 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:59.010Z,1554903359.010 [BR_Ping1D](INFO): elapsed 4.9199
2019-04-10T13:35:59.010Z,1554903359.010 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:59.010Z,1554903359.010 [BR_Ping1D](INFO): No UART data
2019-04-10T13:35:59.010Z,1554903359.010 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:35:59.011Z,1554903359.011 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:35:59.414Z,1554903359.414 [BR_Ping1D](INFO): Starting
2019-04-10T13:35:59.414Z,1554903359.414 [BR_Ping1D](INFO): readConfig
2019-04-10T13:35:59.414Z,1554903359.414 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:35:59.414Z,1554903359.414 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:35:59.414Z,1554903359.414 [BR_Ping1D](INFO): elapsed 5.3243
2019-04-10T13:35:59.415Z,1554903359.415 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:35:59.415Z,1554903359.415 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T13:35:59.415Z,1554903359.415 [BR_Ping1D] Data Fault, FailCount= 4
2019-04-10T13:35:59.415Z,1554903359.415 [BR_Ping1D](ERROR): Data Fault
2019-04-10T13:35:59.471Z,1554903359.471 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T13:35:59.817Z,1554903359.817 [BR_Ping1D](INFO): Stop
2019-04-10T13:35:59.817Z,1554903359.817 [BR_Ping1D](INFO): uninitialize
2019-04-10T13:35:59.817Z,1554903359.817 [BR_Ping1D](INFO): Powering down
2019-04-10T13:36:00.222Z,1554903360.222 [BR_Ping1D](INFO): Stopping
2019-04-10T13:36:00.625Z,1554903360.625 [BR_Ping1D](INFO): Stopping
2019-04-10T13:36:01.029Z,1554903361.029 [BR_Ping1D](INFO): Stopped
2019-04-10T13:36:01.083Z,1554903361.083 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T13:36:01.084Z,1554903361.084 [BR_Ping1D] No Fault, FailCount= 4
2019-04-10T13:36:01.433Z,1554903361.433 [BR_Ping1D](INFO): Start
2019-04-10T13:36:01.838Z,1554903361.838 [BR_Ping1D](INFO): Starting
2019-04-10T13:36:02.222Z,1554903362.222 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T13:36:02.222Z,1554903362.222 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-10T13:36:02.222Z,1554903362.222 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T13:36:02.241Z,1554903362.241 [BR_Ping1D](INFO): Starting
2019-04-10T13:36:02.241Z,1554903362.241 [BR_Ping1D](INFO): readConfig
2019-04-10T13:36:02.242Z,1554903362.242 [BR_Ping1D](INFO): startup: 0
2019-04-10T13:36:02.242Z,1554903362.242 [BR_Ping1D](INFO): recvData: FFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF
2019-04-10T13:36:02.242Z,1554903362.242 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T13:36:02.254Z,1554903362.254 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T13:36:02.254Z,1554903362.254 [BR_Ping1D](INFO): XXX autoMode1: 01
2019-04-10T13:36:02.254Z,1554903362.254 [BR_Ping1D](INFO): XXX autoMode2: 
2019-04-10T13:36:02.254Z,1554903362.254 [BR_Ping1D](INFO): XXX autoMode3: 1
2019-04-10T13:36:02.255Z,1554903362.255 [BR_Ping1D](INFO): 42520100EB030000018401
2019-04-10T13:36:02.255Z,1554903362.255 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T13:36:02.255Z,1554903362.255 [BR_Ping1D](INFO): 42520100EE030000018701
2019-04-10T13:36:02.327Z,1554903362.327 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T13:36:02.328Z,1554903362.328 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-10T13:36:02.746Z,1554903362.746 [Aanderaa_O2](INFO): Powering down
2019-04-10T13:36:02.766Z,1554903362.766 [BR_Ping1D](INFO): Starting
2019-04-10T13:36:02.766Z,1554903362.766 [BR_Ping1D](INFO): readConfig
2019-04-10T13:36:02.767Z,1554903362.767 [BR_Ping1D](INFO): startup: 1
2019-04-10T13:36:02.767Z,1554903362.767 [BR_Ping1D](INFO): UART requesting status
2019-04-10T13:36:02.767Z,1554903362.767 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T13:36:02.774Z,1554903362.774 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T13:36:02.783Z,1554903362.783 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T13:36:03.049Z,1554903363.049 [BR_Ping1D](INFO): Starting
2019-04-10T13:36:03.049Z,1554903363.049 [BR_Ping1D](INFO): readConfig
2019-04-10T13:36:03.050Z,1554903363.050 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:36:03.050Z,1554903363.050 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:36:03.050Z,1554903363.050 [BR_Ping1D](INFO): elapsed 0.8079
2019-04-10T13:36:03.050Z,1554903363.050 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:36:03.050Z,1554903363.050 [BR_Ping1D](INFO): No UART data
2019-04-10T13:36:03.051Z,1554903363.051 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:36:03.051Z,1554903363.051 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:36:03.454Z,1554903363.454 [BR_Ping1D](INFO): Starting
2019-04-10T13:36:03.454Z,1554903363.454 [BR_Ping1D](INFO): readConfig
2019-04-10T13:36:03.455Z,1554903363.455 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:36:03.455Z,1554903363.455 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:36:03.455Z,1554903363.455 [BR_Ping1D](INFO): elapsed 1.2130
2019-04-10T13:36:03.455Z,1554903363.455 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:36:03.455Z,1554903363.455 [BR_Ping1D](INFO): No UART data
2019-04-10T13:36:03.456Z,1554903363.456 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:36:03.456Z,1554903363.456 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:36:03.527Z,1554903363.527 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.007002
CHAN A1 (24V): -0.127964
CHAN A2 (12V): -0.004982
CHAN A3 (5V): -0.003154
CHAN B0 (3.3V): -0.000613
CHAN B1 (3.15aV): -0.001594
CHAN B2 (3.15bV): -0.000988
CHAN B3 (GND): -0.000350
OPEN: -0.000991
Full Scale Calc: 4.765 mA, -1.589 mA
2019-04-10T13:36:03.858Z,1554903363.858 [BR_Ping1D](INFO): Starting
2019-04-10T13:36:03.858Z,1554903363.858 [BR_Ping1D](INFO): readConfig
2019-04-10T13:36:03.858Z,1554903363.858 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:36:03.858Z,1554903363.858 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:36:03.859Z,1554903363.859 [BR_Ping1D](INFO): elapsed 1.6165
2019-04-10T13:36:03.859Z,1554903363.859 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:36:03.859Z,1554903363.859 [BR_Ping1D](INFO): No UART data
2019-04-10T13:36:03.859Z,1554903363.859 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:36:03.859Z,1554903363.859 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:36:04.262Z,1554903364.262 [BR_Ping1D](INFO): Starting
2019-04-10T13:36:04.262Z,1554903364.262 [BR_Ping1D](INFO): readConfig
2019-04-10T13:36:04.263Z,1554903364.263 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:36:04.263Z,1554903364.263 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:36:04.263Z,1554903364.263 [BR_Ping1D](INFO): elapsed 2.0208
2019-04-10T13:36:04.263Z,1554903364.263 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:36:04.263Z,1554903364.263 [BR_Ping1D](INFO): No UART data
2019-04-10T13:36:04.263Z,1554903364.263 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:36:04.264Z,1554903364.264 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:36:04.671Z,1554903364.671 [BR_Ping1D](INFO): Starting
2019-04-10T13:36:04.671Z,1554903364.671 [BR_Ping1D](INFO): readConfig
2019-04-10T13:36:04.671Z,1554903364.671 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:36:04.671Z,1554903364.671 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:36:04.671Z,1554903364.671 [BR_Ping1D](INFO): elapsed 2.4294
2019-04-10T13:36:04.672Z,1554903364.672 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:36:04.672Z,1554903364.672 [BR_Ping1D](INFO): No UART data
2019-04-10T13:36:04.672Z,1554903364.672 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:36:04.672Z,1554903364.672 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:36:05.070Z,1554903365.070 [BR_Ping1D](INFO): Starting
2019-04-10T13:36:05.070Z,1554903365.070 [BR_Ping1D](INFO): readConfig
2019-04-10T13:36:05.070Z,1554903365.070 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:36:05.070Z,1554903365.070 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:36:05.071Z,1554903365.071 [BR_Ping1D](INFO): elapsed 2.8286
2019-04-10T13:36:05.071Z,1554903365.071 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:36:05.071Z,1554903365.071 [BR_Ping1D](INFO): No UART data
2019-04-10T13:36:05.071Z,1554903365.071 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:36:05.071Z,1554903365.071 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:36:05.473Z,1554903365.473 [BR_Ping1D](INFO): Starting
2019-04-10T13:36:05.474Z,1554903365.474 [BR_Ping1D](INFO): readConfig
2019-04-10T13:36:05.474Z,1554903365.474 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:36:05.474Z,1554903365.474 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:36:05.474Z,1554903365.474 [BR_Ping1D](INFO): elapsed 3.2322
2019-04-10T13:36:05.474Z,1554903365.474 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:36:05.475Z,1554903365.475 [BR_Ping1D](INFO): No UART data
2019-04-10T13:36:05.475Z,1554903365.475 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:36:05.475Z,1554903365.475 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:36:05.878Z,1554903365.878 [BR_Ping1D](INFO): Starting
2019-04-10T13:36:05.878Z,1554903365.878 [BR_Ping1D](INFO): readConfig
2019-04-10T13:36:05.878Z,1554903365.878 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:36:05.879Z,1554903365.879 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:36:05.879Z,1554903365.879 [BR_Ping1D](INFO): elapsed 3.6367
2019-04-10T13:36:05.879Z,1554903365.879 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:36:05.879Z,1554903365.879 [BR_Ping1D](INFO): No UART data
2019-04-10T13:36:05.879Z,1554903365.879 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:36:05.879Z,1554903365.879 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:36:06.282Z,1554903366.282 [BR_Ping1D](INFO): Starting
2019-04-10T13:36:06.282Z,1554903366.282 [BR_Ping1D](INFO): readConfig
2019-04-10T13:36:06.283Z,1554903366.283 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:36:06.283Z,1554903366.283 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:36:06.283Z,1554903366.283 [BR_Ping1D](INFO): elapsed 4.0409
2019-04-10T13:36:06.283Z,1554903366.283 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:36:06.283Z,1554903366.283 [BR_Ping1D](INFO): No UART data
2019-04-10T13:36:06.283Z,1554903366.283 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:36:06.284Z,1554903366.284 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:36:06.697Z,1554903366.697 [BR_Ping1D](INFO): Starting
2019-04-10T13:36:06.697Z,1554903366.697 [BR_Ping1D](INFO): readConfig
2019-04-10T13:36:06.697Z,1554903366.697 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:36:06.697Z,1554903366.697 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:36:06.698Z,1554903366.698 [BR_Ping1D](INFO): elapsed 4.4555
2019-04-10T13:36:06.698Z,1554903366.698 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:36:06.698Z,1554903366.698 [BR_Ping1D](INFO): No UART data
2019-04-10T13:36:06.698Z,1554903366.698 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:36:06.698Z,1554903366.698 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:36:07.075Z,1554903367.075 [NAL9602](INFO): NAL9602 initialized
2019-04-10T13:36:07.091Z,1554903367.091 [BR_Ping1D](INFO): Starting
2019-04-10T13:36:07.091Z,1554903367.091 [BR_Ping1D](INFO): readConfig
2019-04-10T13:36:07.092Z,1554903367.092 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:36:07.092Z,1554903367.092 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:36:07.092Z,1554903367.092 [BR_Ping1D](INFO): elapsed 4.8499
2019-04-10T13:36:07.092Z,1554903367.092 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:36:07.092Z,1554903367.092 [BR_Ping1D](INFO): No UART data
2019-04-10T13:36:07.093Z,1554903367.093 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:36:07.093Z,1554903367.093 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:36:07.515Z,1554903367.515 [BR_Ping1D](INFO): Starting
2019-04-10T13:36:07.515Z,1554903367.515 [BR_Ping1D](INFO): readConfig
2019-04-10T13:36:07.515Z,1554903367.515 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:36:07.515Z,1554903367.515 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:36:07.516Z,1554903367.516 [BR_Ping1D](INFO): elapsed 5.2735
2019-04-10T13:36:07.516Z,1554903367.516 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:36:07.516Z,1554903367.516 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T13:36:07.516Z,1554903367.516 [BR_Ping1D] Data Fault, FailCount= 5
2019-04-10T13:36:07.516Z,1554903367.516 [BR_Ping1D](ERROR): Data Fault
2019-04-10T13:36:07.735Z,1554903367.735 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T13:36:07.735Z,1554903367.735 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-04-10T13:36:07.894Z,1554903367.894 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:36:07.909Z,1554903367.909 [BR_Ping1D](INFO): Stop
2019-04-10T13:36:07.909Z,1554903367.909 [BR_Ping1D](INFO): uninitialize
2019-04-10T13:36:07.909Z,1554903367.909 [BR_Ping1D](INFO): Powering down
2019-04-10T13:36:08.432Z,1554903368.432 [CBIT](INFO): Critical error at 20190410T133607
2019-04-10T13:36:08.545Z,1554903368.545 [BR_Ping1D](INFO): Stopping
2019-04-10T13:36:08.874Z,1554903368.874 [BR_Ping1D](INFO): Stopped
2019-04-10T13:36:10.694Z,1554903370.694 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T13:36:10.694Z,1554903370.694 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-10T13:36:10.694Z,1554903370.694 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T13:36:11.015Z,1554903371.015 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T13:36:11.015Z,1554903371.015 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-10T13:36:11.098Z,1554903371.098 [CTD_Seabird](INFO): Powering down
2019-04-10T13:36:12.883Z,1554903372.883 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T13:36:12.884Z,1554903372.884 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2019-04-10T13:36:12.884Z,1554903372.884 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T13:36:13.195Z,1554903373.195 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T13:36:13.287Z,1554903373.287 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T13:36:14.179Z,1554903374.179 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T13:36:14.180Z,1554903374.180 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2019-04-10T13:36:16.518Z,1554903376.518 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T13:36:16.518Z,1554903376.518 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T13:36:35.603Z,1554903395.603 [BPC1](ERROR): BPC1A: No match for serial number 0441 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2019-04-10T13:36:35.604Z,1554903395.604 [BPC1](ERROR): BPC1B: No match for serial number 0480 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2019-04-10T13:36:35.604Z,1554903395.604 [BPC1](FAULT): Failed to parse data from all battery packs.
2019-04-10T13:36:35.604Z,1554903395.604 [BPC1] Data Fault, FailCount= 1
2019-04-10T13:36:35.604Z,1554903395.604 [BPC1](ERROR): Data Fault
2019-04-10T13:36:35.719Z,1554903395.719 [CBIT](ERROR): Data Fault in component: BPC1
2019-04-10T13:36:36.790Z,1554903396.790 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T13:36:36.791Z,1554903396.791 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2019-04-10T13:36:36.791Z,1554903396.791 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T13:36:37.007Z,1554903397.007 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T13:36:37.195Z,1554903397.195 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T13:36:38.127Z,1554903398.127 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T13:36:38.127Z,1554903398.127 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2019-04-10T13:36:40.478Z,1554903400.478 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T13:36:40.479Z,1554903400.479 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T13:36:45.765Z,1554903405.765 [SBIT](IMPORTANT): SBIT PASSED
2019-04-10T13:36:45.839Z,1554903405.839 [CommandLine](IMPORTANT): got command configSet list
2019-04-10T13:36:45.839Z,1554903405.839 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-04-10T13:36:45.840Z,1554903405.840 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-04-10T13:36:45.840Z,1554903405.840 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2019-04-10T13:36:46.162Z,1554903406.162 [MissionManager](IMPORTANT): Started mission Startup
2019-04-10T13:36:46.162Z,1554903406.162 [Startup] Running Loop=1
2019-04-10T13:36:46.162Z,1554903406.162 [Startup](DEBUG): Aggregate::initialize Startup
2019-04-10T13:36:46.163Z,1554903406.163 [Startup:A.GoToSurface] Running Loop=1
2019-04-10T13:36:46.163Z,1554903406.163 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-10T13:36:46.163Z,1554903406.163 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-10T13:36:46.164Z,1554903406.164 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-10T13:36:46.164Z,1554903406.164 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-10T13:36:46.164Z,1554903406.164 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-10T13:36:46.165Z,1554903406.165 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-10T13:36:46.166Z,1554903406.166 [Startup:StartupSatComms] Running Loop=1
2019-04-10T13:36:46.167Z,1554903406.167 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-04-10T13:36:46.167Z,1554903406.167 [Startup:StartupSatComms:A] Running Loop=1
2019-04-10T13:36:46.575Z,1554903406.575 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-04-10T13:37:00.678Z,1554903420.678 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T13:37:00.679Z,1554903420.679 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2019-04-10T13:37:00.679Z,1554903420.679 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T13:37:00.745Z,1554903420.745 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T13:37:01.082Z,1554903421.082 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T13:37:01.927Z,1554903421.927 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T13:37:01.927Z,1554903421.927 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2019-04-10T13:37:04.314Z,1554903424.314 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T13:37:04.315Z,1554903424.315 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T13:37:24.515Z,1554903444.515 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T13:37:24.515Z,1554903444.515 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2019-04-10T13:37:24.515Z,1554903444.515 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T13:37:24.579Z,1554903444.579 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T13:37:24.918Z,1554903444.918 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T13:37:25.764Z,1554903445.764 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T13:37:25.764Z,1554903445.764 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2019-04-10T13:37:28.151Z,1554903448.151 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T13:37:28.151Z,1554903448.151 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T13:37:28.193Z,1554903448.193 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T13:37:28.193Z,1554903448.193 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-04-10T13:37:28.193Z,1554903448.193 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-10T13:37:28.218Z,1554903448.218 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T13:37:28.218Z,1554903448.218 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-04-10T13:37:28.218Z,1554903448.218 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-10T13:37:28.219Z,1554903448.219 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T13:37:28.219Z,1554903448.219 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-04-10T13:37:28.219Z,1554903448.219 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-10T13:37:28.230Z,1554903448.230 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-10T13:37:28.231Z,1554903448.231 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-10T13:37:28.231Z,1554903448.231 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-10T13:37:28.594Z,1554903448.594 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-10T13:37:28.595Z,1554903448.595 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-04-10T13:37:28.595Z,1554903448.595 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-10T13:37:28.595Z,1554903448.595 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-04-10T13:37:28.596Z,1554903448.596 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-10T13:37:28.596Z,1554903448.596 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-04-10T13:37:28.997Z,1554903448.997 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-10T13:37:46.360Z,1554903466.360 [Startup:StartupSatComms:A](INFO): Timed out from 2019-04-10T13:36:46.2Z
2019-04-10T13:37:46.360Z,1554903466.360 [Startup:StartupSatComms:A] Stopped
2019-04-10T13:37:46.360Z,1554903466.360 [Startup:StartupSatComms:B] Running Loop=1
2019-04-10T13:37:46.767Z,1554903466.767 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-10T13:37:48.350Z,1554903468.350 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T13:37:48.351Z,1554903468.351 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2019-04-10T13:37:48.351Z,1554903468.351 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T13:37:48.398Z,1554903468.398 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T13:37:48.754Z,1554903468.754 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T13:37:49.600Z,1554903469.600 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T13:37:49.600Z,1554903469.600 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2019-04-10T13:37:51.986Z,1554903471.986 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T13:37:51.987Z,1554903471.987 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T13:38:07.451Z,1554903487.451 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004359
2019-04-10T13:38:12.186Z,1554903492.186 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T13:38:12.186Z,1554903492.186 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7
2019-04-10T13:38:12.186Z,1554903492.186 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T13:38:12.248Z,1554903492.248 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T13:38:12.591Z,1554903492.591 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T13:38:13.354Z,1554903493.354 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190410T013923/Courier0205.lzma
2019-04-10T13:38:13.436Z,1554903493.436 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T13:38:13.436Z,1554903493.436 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7
2019-04-10T13:38:14.160Z,1554903494.160 [DataOverHttps](INFO): Moved sent file to Logs/20190410T013923/Courier0205.lzma.bak
2019-04-10T13:38:14.160Z,1554903494.160 [DataOverHttps](INFO): SBD MOMSN=10530025
2019-04-10T13:38:15.822Z,1554903495.822 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T13:38:15.823Z,1554903495.823 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T13:38:32.444Z,1554903512.444 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T13:38:32.444Z,1554903512.444 [Aanderaa_O2] No Fault, FailCount= 3
2019-04-10T13:38:36.023Z,1554903516.023 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T13:38:36.023Z,1554903516.023 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 8
2019-04-10T13:38:36.023Z,1554903516.023 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T13:38:36.150Z,1554903516.150 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T13:38:36.150Z,1554903516.150 [CBIT](FAULT): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T13:38:36.427Z,1554903516.427 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T13:38:40.912Z,1554903520.912 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-10T13:38:40.912Z,1554903520.912 [CTD_Seabird] No Fault, FailCount= 3
2019-04-10T13:38:41.122Z,1554903521.122 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T13:38:41.124Z,1554903521.124 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T13:38:41.634Z,1554903521.634 [DataOverHttps](INFO): Sending 312 bytes from file Logs/20190410T133517/Courier0000.lzma
2019-04-10T13:38:42.439Z,1554903522.439 [DataOverHttps](INFO): Moved sent file to Logs/20190410T133517/Courier0000.lzma.bak
2019-04-10T13:38:42.440Z,1554903522.440 [DataOverHttps](INFO): SBD MOMSN=10530029
2019-04-10T13:38:42.890Z,1554903522.890 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T13:38:42.890Z,1554903522.890 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-10T13:38:42.890Z,1554903522.890 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T13:38:42.980Z,1554903522.980 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T13:38:43.414Z,1554903523.414 [Aanderaa_O2](INFO): Powering down
2019-04-10T13:38:44.139Z,1554903524.139 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T13:38:44.140Z,1554903524.140 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-10T13:38:46.555Z,1554903526.555 [Startup:StartupSatComms:B](INFO): Timed out from 2019-04-10T13:37:46.4Z
2019-04-10T13:38:46.555Z,1554903526.555 [Startup:StartupSatComms:B] Stopped
2019-04-10T13:38:46.555Z,1554903526.555 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-04-10T13:38:46.556Z,1554903526.556 [Startup:StartupSatComms] Stopped
2019-04-10T13:38:46.556Z,1554903526.556 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-04-10T13:38:46.556Z,1554903526.556 [Startup](INFO): Completed Startup
2019-04-10T13:38:46.557Z,1554903526.557 [MissionManager](INFO): Startup is completed.
2019-04-10T13:38:46.557Z,1554903526.557 [MissionManager](INFO): Uninitializing Mission Startup
2019-04-10T13:38:46.557Z,1554903526.557 [Startup] Stopped
2019-04-10T13:38:46.557Z,1554903526.557 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-04-10T13:38:46.557Z,1554903526.557 [Startup:A.GoToSurface] Stopped
2019-04-10T13:38:46.557Z,1554903526.557 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-10T13:38:46.955Z,1554903526.955 [MissionManager](IMPORTANT): Started mission Default
2019-04-10T13:38:46.955Z,1554903526.955 [Default] Running Loop=1
2019-04-10T13:38:46.956Z,1554903526.956 [Default](DEBUG): Aggregate::initialize Default
2019-04-10T13:38:46.956Z,1554903526.956 [Default:B.GoToSurface] Running Loop=1
2019-04-10T13:38:46.956Z,1554903526.956 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-10T13:38:46.956Z,1554903526.956 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-10T13:38:46.956Z,1554903526.956 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-10T13:38:46.957Z,1554903526.957 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-10T13:38:46.957Z,1554903526.957 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-10T13:38:46.957Z,1554903526.957 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-10T13:38:46.958Z,1554903526.958 [Default:A.Wait] Running Loop=1
2019-04-10T13:38:46.958Z,1554903526.958 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-04-10T13:38:54.178Z,1554903534.178 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T13:38:54.178Z,1554903534.178 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-10T13:38:54.178Z,1554903534.178 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T13:38:54.276Z,1554903534.276 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T13:38:54.582Z,1554903534.582 [CTD_Seabird](INFO): Powering down
2019-04-10T13:38:54.613Z,1554903534.613 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T13:38:54.613Z,1554903534.613 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-10T13:38:54.613Z,1554903534.613 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T13:38:54.656Z,1554903534.656 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T13:38:55.130Z,1554903535.130 [Aanderaa_O2](INFO): Powering down
2019-04-10T13:38:55.875Z,1554903535.875 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T13:38:55.875Z,1554903535.875 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-10T13:38:55.876Z,1554903535.876 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-10T13:38:55.876Z,1554903535.876 [CTD_Seabird] No Fault, FailCount= 1
2019-04-10T13:38:55.922Z,1554903535.922 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T13:38:55.930Z,1554903535.930 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T13:39:00.288Z,1554903540.288 [Default:A.Wait](INFO): Done Waiting.
2019-04-10T13:39:00.288Z,1554903540.288 [Default:A.Wait] Stopped
2019-04-10T13:39:00.288Z,1554903540.288 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-04-10T13:39:00.699Z,1554903540.699 [Default:CheckIn] Running Loop=1
2019-04-10T13:39:00.699Z,1554903540.699 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-10T13:39:00.699Z,1554903540.699 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-10T13:39:01.093Z,1554903541.093 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-04-10T13:39:06.322Z,1554903546.322 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T13:39:06.322Z,1554903546.322 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-10T13:39:06.323Z,1554903546.323 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T13:39:06.361Z,1554903546.361 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T13:39:06.361Z,1554903546.361 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-10T13:39:06.846Z,1554903546.846 [Aanderaa_O2](INFO): Powering down
2019-04-10T13:39:08.974Z,1554903548.974 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T13:39:08.974Z,1554903548.974 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-10T13:39:08.974Z,1554903548.974 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T13:39:09.211Z,1554903549.211 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T13:39:09.378Z,1554903549.378 [CTD_Seabird](INFO): Powering down
2019-04-10T13:39:10.396Z,1554903550.396 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-10T13:39:10.396Z,1554903550.396 [CTD_Seabird] No Fault, FailCount= 2
2019-04-10T13:39:10.531Z,1554903550.531 [DataOverHttps](INFO): Sending 746 bytes from file Logs/20190410T013923/Express0203.lzma
2019-04-10T13:39:10.722Z,1554903550.722 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T13:39:10.724Z,1554903550.724 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T13:39:11.336Z,1554903551.336 [DataOverHttps](INFO): Moved sent file to Logs/20190410T013923/Express0203.lzma.bak
2019-04-10T13:39:11.336Z,1554903551.336 [DataOverHttps](INFO): SBD MOMSN=10530055
2019-04-10T13:39:23.774Z,1554903563.774 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T13:39:23.774Z,1554903563.774 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-10T13:39:23.774Z,1554903563.774 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T13:39:24.129Z,1554903564.129 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T13:39:24.130Z,1554903564.130 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-10T13:39:24.178Z,1554903564.178 [CTD_Seabird](INFO): Powering down
2019-04-10T13:39:29.380Z,1554903569.380 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T13:39:29.380Z,1554903569.380 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-04-10T13:39:29.380Z,1554903569.380 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-10T13:39:29.382Z,1554903569.382 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T13:39:29.382Z,1554903569.382 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-04-10T13:39:29.382Z,1554903569.382 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-10T13:39:29.383Z,1554903569.383 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T13:39:29.383Z,1554903569.383 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-04-10T13:39:29.383Z,1554903569.383 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-10T13:39:29.394Z,1554903569.394 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-10T13:39:29.395Z,1554903569.395 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-10T13:39:29.395Z,1554903569.395 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-10T13:39:29.807Z,1554903569.807 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-10T13:39:29.807Z,1554903569.807 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-04-10T13:39:29.807Z,1554903569.807 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-10T13:39:29.807Z,1554903569.807 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-04-10T13:39:29.808Z,1554903569.808 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-10T13:39:29.808Z,1554903569.808 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-04-10T13:39:30.193Z,1554903570.193 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-10T13:41:07.563Z,1554903667.563 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T13:41:07.563Z,1554903667.563 [BR_Ping1D] No Fault, FailCount= 5
2019-04-10T13:41:07.953Z,1554903667.953 [BR_Ping1D](INFO): Start
2019-04-10T13:41:08.347Z,1554903668.347 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:08.758Z,1554903668.758 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:08.758Z,1554903668.758 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:08.759Z,1554903668.759 [BR_Ping1D](INFO): startup: 0
2019-04-10T13:41:08.759Z,1554903668.759 [BR_Ping1D](INFO): recvData: FFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF
2019-04-10T13:41:08.759Z,1554903668.759 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T13:41:08.770Z,1554903668.770 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T13:41:08.771Z,1554903668.771 [BR_Ping1D](INFO): XXX autoMode1: 01
2019-04-10T13:41:08.771Z,1554903668.771 [BR_Ping1D](INFO): XXX autoMode2: 
2019-04-10T13:41:08.772Z,1554903668.772 [BR_Ping1D](INFO): XXX autoMode3: 1
2019-04-10T13:41:08.772Z,1554903668.772 [BR_Ping1D](INFO): 42520100EB030000018401
2019-04-10T13:41:08.772Z,1554903668.772 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T13:41:08.773Z,1554903668.773 [BR_Ping1D](INFO): 42520100EE030000018701
2019-04-10T13:41:09.158Z,1554903669.158 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:09.573Z,1554903669.573 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:09.573Z,1554903669.573 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:09.573Z,1554903669.573 [BR_Ping1D](INFO): startup: 1
2019-04-10T13:41:09.573Z,1554903669.573 [BR_Ping1D](INFO): UART requesting status
2019-04-10T13:41:09.573Z,1554903669.573 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T13:41:09.582Z,1554903669.582 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T13:41:09.590Z,1554903669.590 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T13:41:09.977Z,1554903669.977 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:09.977Z,1554903669.977 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:09.977Z,1554903669.977 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:09.977Z,1554903669.977 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:09.978Z,1554903669.978 [BR_Ping1D](INFO): elapsed 1.2186
2019-04-10T13:41:09.978Z,1554903669.978 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:09.979Z,1554903669.979 [BR_Ping1D](INFO): received full packet, type: 1210
2019-04-10T13:41:09.979Z,1554903669.979 [BR_Ping1D](INFO): parsing data, res: 20
2019-04-10T13:41:09.979Z,1554903669.979 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-10T13:41:09.979Z,1554903669.979 [BR_Ping1D](INFO):  voltage: 5054
2019-04-10T13:41:09.980Z,1554903669.980 [BR_Ping1D](INFO):  interval: 500
2019-04-10T13:41:09.980Z,1554903669.980 [BR_Ping1D](INFO):  gain: 5
2019-04-10T13:41:09.980Z,1554903669.980 [BR_Ping1D](INFO):  auto: 1
2019-04-10T13:41:09.980Z,1554903669.980 [BR_Ping1D](INFO): received full packet, type: 1215
2019-04-10T13:41:09.980Z,1554903669.980 [BR_Ping1D](INFO): CRC error in received UART packet
2019-04-10T13:41:09.981Z,1554903669.981 [BR_Ping1D](INFO): received UART packets: 1
2019-04-10T13:41:09.981Z,1554903669.981 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:10.384Z,1554903670.384 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:10.384Z,1554903670.384 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:10.385Z,1554903670.385 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:10.385Z,1554903670.385 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:10.385Z,1554903670.385 [BR_Ping1D](INFO): elapsed 1.6259
2019-04-10T13:41:10.385Z,1554903670.385 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:10.386Z,1554903670.386 [BR_Ping1D](INFO): received full packet, type: 1203
2019-04-10T13:41:10.386Z,1554903670.386 [BR_Ping1D](INFO): parsing data, res: 14
2019-04-10T13:41:10.386Z,1554903670.386 [BR_Ping1D](INFO): Parsing UART packet of type 1203
2019-04-10T13:41:10.386Z,1554903670.386 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:10.386Z,1554903670.386 [BR_Ping1D](INFO): received UART packets: 1
2019-04-10T13:41:10.386Z,1554903670.386 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:10.759Z,1554903670.759 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-04-10T13:41:10.774Z,1554903670.774 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:10.775Z,1554903670.775 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:10.775Z,1554903670.775 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:10.775Z,1554903670.775 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:10.775Z,1554903670.775 [BR_Ping1D](INFO): elapsed 2.0162
2019-04-10T13:41:10.775Z,1554903670.775 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:10.776Z,1554903670.776 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:10.776Z,1554903670.776 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:10.776Z,1554903670.776 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:11.188Z,1554903671.188 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:11.188Z,1554903671.188 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:11.188Z,1554903671.188 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:11.188Z,1554903671.188 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:11.188Z,1554903671.188 [BR_Ping1D](INFO): elapsed 2.4292
2019-04-10T13:41:11.188Z,1554903671.188 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:11.189Z,1554903671.189 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:11.189Z,1554903671.189 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:11.189Z,1554903671.189 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:11.578Z,1554903671.578 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:41:11.598Z,1554903671.598 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:11.598Z,1554903671.598 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:11.599Z,1554903671.599 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:11.599Z,1554903671.599 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:11.599Z,1554903671.599 [BR_Ping1D](INFO): elapsed 2.8399
2019-04-10T13:41:11.599Z,1554903671.599 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:11.599Z,1554903671.599 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:11.600Z,1554903671.600 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:11.600Z,1554903671.600 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:12.001Z,1554903672.001 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:12.001Z,1554903672.001 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:12.001Z,1554903672.001 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:12.001Z,1554903672.001 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:12.001Z,1554903672.001 [BR_Ping1D](INFO): elapsed 3.2422
2019-04-10T13:41:12.001Z,1554903672.001 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:12.002Z,1554903672.002 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:12.002Z,1554903672.002 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:12.002Z,1554903672.002 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:12.405Z,1554903672.405 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:12.405Z,1554903672.405 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:12.406Z,1554903672.406 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:12.406Z,1554903672.406 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:12.406Z,1554903672.406 [BR_Ping1D](INFO): elapsed 3.6471
2019-04-10T13:41:12.406Z,1554903672.406 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:12.406Z,1554903672.406 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:12.407Z,1554903672.407 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:12.407Z,1554903672.407 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:12.794Z,1554903672.794 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:12.794Z,1554903672.794 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:12.795Z,1554903672.795 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:12.795Z,1554903672.795 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:12.795Z,1554903672.795 [BR_Ping1D](INFO): elapsed 4.0359
2019-04-10T13:41:12.795Z,1554903672.795 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:12.795Z,1554903672.795 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:12.795Z,1554903672.795 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:12.795Z,1554903672.795 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:13.199Z,1554903673.199 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:13.199Z,1554903673.199 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:13.199Z,1554903673.199 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:13.199Z,1554903673.199 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:13.199Z,1554903673.199 [BR_Ping1D](INFO): elapsed 4.4404
2019-04-10T13:41:13.200Z,1554903673.200 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:13.200Z,1554903673.200 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:13.200Z,1554903673.200 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:13.200Z,1554903673.200 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:13.607Z,1554903673.607 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:13.607Z,1554903673.607 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:13.607Z,1554903673.607 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:13.607Z,1554903673.607 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:13.608Z,1554903673.608 [BR_Ping1D](INFO): elapsed 4.8487
2019-04-10T13:41:13.608Z,1554903673.608 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:13.608Z,1554903673.608 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:13.608Z,1554903673.608 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:13.608Z,1554903673.608 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:14.008Z,1554903674.008 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:14.008Z,1554903674.008 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:14.008Z,1554903674.008 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:14.008Z,1554903674.008 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:14.009Z,1554903674.009 [BR_Ping1D](INFO): elapsed 5.2496
2019-04-10T13:41:14.009Z,1554903674.009 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:14.009Z,1554903674.009 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T13:41:14.009Z,1554903674.009 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-10T13:41:14.009Z,1554903674.009 [BR_Ping1D](ERROR): Data Fault
2019-04-10T13:41:14.024Z,1554903674.024 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T13:41:14.406Z,1554903674.406 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:41:14.423Z,1554903674.423 [BR_Ping1D](INFO): Stop
2019-04-10T13:41:14.423Z,1554903674.423 [BR_Ping1D](INFO): uninitialize
2019-04-10T13:41:14.423Z,1554903674.423 [BR_Ping1D](INFO): Powering down
2019-04-10T13:41:14.831Z,1554903674.831 [BR_Ping1D](INFO): Stopping
2019-04-10T13:41:15.233Z,1554903675.233 [BR_Ping1D](INFO): Stopping
2019-04-10T13:41:15.624Z,1554903675.624 [BR_Ping1D](INFO): Stopped
2019-04-10T13:41:15.641Z,1554903675.641 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T13:41:15.641Z,1554903675.641 [BR_Ping1D] No Fault, FailCount= 1
2019-04-10T13:41:16.031Z,1554903676.031 [BR_Ping1D](INFO): Start
2019-04-10T13:41:16.432Z,1554903676.432 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:16.837Z,1554903676.837 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:16.837Z,1554903676.837 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:16.837Z,1554903676.837 [BR_Ping1D](INFO): startup: 0
2019-04-10T13:41:16.837Z,1554903676.837 [BR_Ping1D](INFO): recvData: F4010000FFFFFFFFFFFFFFFF60E300000100000005000000FFFFFFFFBE130000FFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF
2019-04-10T13:41:16.837Z,1554903676.837 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T13:41:16.850Z,1554903676.850 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T13:41:16.850Z,1554903676.850 [BR_Ping1D](INFO): XXX autoMode1: 01
2019-04-10T13:41:16.851Z,1554903676.851 [BR_Ping1D](INFO): XXX autoMode2: 
2019-04-10T13:41:16.851Z,1554903676.851 [BR_Ping1D](INFO): XXX autoMode3: 1
2019-04-10T13:41:16.851Z,1554903676.851 [BR_Ping1D](INFO): 42520100EB030000018401
2019-04-10T13:41:16.851Z,1554903676.851 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T13:41:16.851Z,1554903676.851 [BR_Ping1D](INFO): 42520100EE030000018701
2019-04-10T13:41:17.240Z,1554903677.240 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:17.638Z,1554903677.638 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:41:17.654Z,1554903677.654 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:17.654Z,1554903677.654 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:17.654Z,1554903677.654 [BR_Ping1D](INFO): startup: 1
2019-04-10T13:41:17.654Z,1554903677.654 [BR_Ping1D](INFO): UART requesting status
2019-04-10T13:41:17.655Z,1554903677.655 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T13:41:17.662Z,1554903677.662 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T13:41:17.670Z,1554903677.670 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T13:41:18.063Z,1554903678.063 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:18.063Z,1554903678.063 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:18.063Z,1554903678.063 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:18.064Z,1554903678.064 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:18.064Z,1554903678.064 [BR_Ping1D](INFO): elapsed 1.2263
2019-04-10T13:41:18.064Z,1554903678.064 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:18.064Z,1554903678.064 [BR_Ping1D](INFO): received full packet, type: 1210
2019-04-10T13:41:18.065Z,1554903678.065 [BR_Ping1D](INFO): parsing data, res: 20
2019-04-10T13:41:18.065Z,1554903678.065 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-10T13:41:18.065Z,1554903678.065 [BR_Ping1D](INFO):  voltage: 5056
2019-04-10T13:41:18.065Z,1554903678.065 [BR_Ping1D](INFO):  interval: 500
2019-04-10T13:41:18.065Z,1554903678.065 [BR_Ping1D](INFO):  gain: 2
2019-04-10T13:41:18.065Z,1554903678.065 [BR_Ping1D](INFO):  auto: 1
2019-04-10T13:41:18.066Z,1554903678.066 [BR_Ping1D](INFO): received full packet, type: 1215
2019-04-10T13:41:18.066Z,1554903678.066 [BR_Ping1D](INFO): CRC error in received UART packet
2019-04-10T13:41:18.066Z,1554903678.066 [BR_Ping1D](INFO): received UART packets: 1
2019-04-10T13:41:18.066Z,1554903678.066 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:18.463Z,1554903678.463 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:18.463Z,1554903678.463 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:18.463Z,1554903678.463 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:18.463Z,1554903678.463 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:18.464Z,1554903678.464 [BR_Ping1D](INFO): elapsed 1.6263
2019-04-10T13:41:18.464Z,1554903678.464 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:18.464Z,1554903678.464 [BR_Ping1D](INFO): received full packet, type: 1203
2019-04-10T13:41:18.465Z,1554903678.465 [BR_Ping1D](INFO): parsing data, res: 14
2019-04-10T13:41:18.465Z,1554903678.465 [BR_Ping1D](INFO): Parsing UART packet of type 1203
2019-04-10T13:41:18.465Z,1554903678.465 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:18.465Z,1554903678.465 [BR_Ping1D](INFO): received UART packets: 1
2019-04-10T13:41:18.465Z,1554903678.465 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:18.854Z,1554903678.854 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:18.854Z,1554903678.854 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:18.855Z,1554903678.855 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:18.855Z,1554903678.855 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:18.855Z,1554903678.855 [BR_Ping1D](INFO): elapsed 2.0175
2019-04-10T13:41:18.855Z,1554903678.855 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:18.855Z,1554903678.855 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:18.856Z,1554903678.856 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:18.856Z,1554903678.856 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:19.261Z,1554903679.261 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:19.262Z,1554903679.262 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:19.262Z,1554903679.262 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:19.262Z,1554903679.262 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:19.262Z,1554903679.262 [BR_Ping1D](INFO): elapsed 2.4249
2019-04-10T13:41:19.262Z,1554903679.262 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:19.263Z,1554903679.263 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:19.263Z,1554903679.263 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:19.263Z,1554903679.263 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:19.671Z,1554903679.671 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:19.671Z,1554903679.671 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:19.672Z,1554903679.672 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:19.672Z,1554903679.672 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:19.672Z,1554903679.672 [BR_Ping1D](INFO): elapsed 2.8345
2019-04-10T13:41:19.672Z,1554903679.672 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:19.672Z,1554903679.672 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:19.672Z,1554903679.672 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:19.673Z,1554903679.673 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:20.065Z,1554903680.065 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:20.065Z,1554903680.065 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:20.066Z,1554903680.066 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:20.066Z,1554903680.066 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:20.067Z,1554903680.067 [BR_Ping1D](INFO): elapsed 3.2291
2019-04-10T13:41:20.067Z,1554903680.067 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:20.067Z,1554903680.067 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:20.067Z,1554903680.067 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:20.067Z,1554903680.067 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:20.466Z,1554903680.466 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:41:20.488Z,1554903680.488 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:20.488Z,1554903680.488 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:20.488Z,1554903680.488 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:20.488Z,1554903680.488 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:20.488Z,1554903680.488 [BR_Ping1D](INFO): elapsed 3.6510
2019-04-10T13:41:20.489Z,1554903680.489 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:20.489Z,1554903680.489 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:20.489Z,1554903680.489 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:20.489Z,1554903680.489 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:20.889Z,1554903680.889 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:20.889Z,1554903680.889 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:20.890Z,1554903680.890 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:20.890Z,1554903680.890 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:20.890Z,1554903680.890 [BR_Ping1D](INFO): elapsed 4.0526
2019-04-10T13:41:20.890Z,1554903680.890 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:20.890Z,1554903680.890 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:20.891Z,1554903680.891 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:20.891Z,1554903680.891 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:21.313Z,1554903681.313 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:21.314Z,1554903681.314 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:21.314Z,1554903681.314 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:21.314Z,1554903681.314 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:21.314Z,1554903681.314 [BR_Ping1D](INFO): elapsed 4.4769
2019-04-10T13:41:21.314Z,1554903681.314 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:21.315Z,1554903681.315 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:21.315Z,1554903681.315 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:21.315Z,1554903681.315 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:21.686Z,1554903681.686 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:21.687Z,1554903681.687 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:21.687Z,1554903681.687 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:21.687Z,1554903681.687 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:21.687Z,1554903681.687 [BR_Ping1D](INFO): elapsed 4.8497
2019-04-10T13:41:21.687Z,1554903681.687 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:21.688Z,1554903681.688 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:21.688Z,1554903681.688 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:21.688Z,1554903681.688 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:22.088Z,1554903682.088 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:22.088Z,1554903682.088 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:22.088Z,1554903682.088 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:22.088Z,1554903682.088 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:22.089Z,1554903682.089 [BR_Ping1D](INFO): elapsed 5.2512
2019-04-10T13:41:22.089Z,1554903682.089 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:22.089Z,1554903682.089 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T13:41:22.089Z,1554903682.089 [BR_Ping1D] Data Fault, FailCount= 2
2019-04-10T13:41:22.089Z,1554903682.089 [BR_Ping1D](ERROR): Data Fault
2019-04-10T13:41:22.160Z,1554903682.160 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T13:41:22.492Z,1554903682.492 [BR_Ping1D](INFO): Stop
2019-04-10T13:41:22.492Z,1554903682.492 [BR_Ping1D](INFO): uninitialize
2019-04-10T13:41:22.492Z,1554903682.492 [BR_Ping1D](INFO): Powering down
2019-04-10T13:41:22.897Z,1554903682.897 [BR_Ping1D](INFO): Stopping
2019-04-10T13:41:23.300Z,1554903683.300 [BR_Ping1D](INFO): Stopping
2019-04-10T13:41:23.698Z,1554903683.698 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:41:23.715Z,1554903683.715 [BR_Ping1D](INFO): Stopped
2019-04-10T13:41:23.732Z,1554903683.732 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T13:41:23.732Z,1554903683.732 [BR_Ping1D] No Fault, FailCount= 2
2019-04-10T13:41:24.122Z,1554903684.122 [BR_Ping1D](INFO): Start
2019-04-10T13:41:24.535Z,1554903684.535 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:24.915Z,1554903684.915 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:24.916Z,1554903684.916 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:24.916Z,1554903684.916 [BR_Ping1D](INFO): startup: 0
2019-04-10T13:41:24.916Z,1554903684.916 [BR_Ping1D](INFO): recvData: F4010000FFFFFFFFFFFFFFFF60E300000100000002000000FFFFFFFFC0130000FFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF
2019-04-10T13:41:24.916Z,1554903684.916 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T13:41:24.930Z,1554903684.930 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T13:41:24.930Z,1554903684.930 [BR_Ping1D](INFO): XXX autoMode1: 01
2019-04-10T13:41:24.930Z,1554903684.930 [BR_Ping1D](INFO): XXX autoMode2: 
2019-04-10T13:41:24.931Z,1554903684.931 [BR_Ping1D](INFO): XXX autoMode3: 1
2019-04-10T13:41:24.931Z,1554903684.931 [BR_Ping1D](INFO): 42520100EB030000018401
2019-04-10T13:41:24.931Z,1554903684.931 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T13:41:24.931Z,1554903684.931 [BR_Ping1D](INFO): 42520100EE030000018701
2019-04-10T13:41:25.319Z,1554903685.319 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:25.724Z,1554903685.724 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:25.724Z,1554903685.724 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:25.725Z,1554903685.725 [BR_Ping1D](INFO): startup: 1
2019-04-10T13:41:25.725Z,1554903685.725 [BR_Ping1D](INFO): UART requesting status
2019-04-10T13:41:25.725Z,1554903685.725 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T13:41:25.734Z,1554903685.734 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T13:41:25.742Z,1554903685.742 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T13:41:26.128Z,1554903686.128 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:26.128Z,1554903686.128 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:26.128Z,1554903686.128 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:26.128Z,1554903686.128 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:26.129Z,1554903686.129 [BR_Ping1D](INFO): elapsed 1.2124
2019-04-10T13:41:26.129Z,1554903686.129 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:26.129Z,1554903686.129 [BR_Ping1D](INFO): received full packet, type: 1210
2019-04-10T13:41:26.130Z,1554903686.130 [BR_Ping1D](INFO): parsing data, res: 20
2019-04-10T13:41:26.130Z,1554903686.130 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-10T13:41:26.130Z,1554903686.130 [BR_Ping1D](INFO):  voltage: 5056
2019-04-10T13:41:26.130Z,1554903686.130 [BR_Ping1D](INFO):  interval: 500
2019-04-10T13:41:26.130Z,1554903686.130 [BR_Ping1D](INFO):  gain: 5
2019-04-10T13:41:26.130Z,1554903686.130 [BR_Ping1D](INFO):  auto: 1
2019-04-10T13:41:26.131Z,1554903686.131 [BR_Ping1D](INFO): received full packet, type: 1215
2019-04-10T13:41:26.131Z,1554903686.131 [BR_Ping1D](INFO): CRC error in received UART packet
2019-04-10T13:41:26.131Z,1554903686.131 [BR_Ping1D](INFO): received UART packets: 1
2019-04-10T13:41:26.131Z,1554903686.131 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:26.526Z,1554903686.526 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:41:26.542Z,1554903686.542 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:26.542Z,1554903686.542 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:26.543Z,1554903686.543 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:26.543Z,1554903686.543 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:26.543Z,1554903686.543 [BR_Ping1D](INFO): elapsed 1.6267
2019-04-10T13:41:26.543Z,1554903686.543 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:26.544Z,1554903686.544 [BR_Ping1D](INFO): received full packet, type: 1203
2019-04-10T13:41:26.544Z,1554903686.544 [BR_Ping1D](INFO): parsing data, res: 14
2019-04-10T13:41:26.544Z,1554903686.544 [BR_Ping1D](INFO): Parsing UART packet of type 1203
2019-04-10T13:41:26.544Z,1554903686.544 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:26.544Z,1554903686.544 [BR_Ping1D](INFO): received UART packets: 1
2019-04-10T13:41:26.544Z,1554903686.544 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:26.951Z,1554903686.951 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:26.951Z,1554903686.951 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:26.951Z,1554903686.951 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:26.951Z,1554903686.951 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:26.952Z,1554903686.952 [BR_Ping1D](INFO): elapsed 2.0351
2019-04-10T13:41:26.952Z,1554903686.952 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:26.952Z,1554903686.952 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:26.952Z,1554903686.952 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:26.952Z,1554903686.952 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:27.351Z,1554903687.351 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:27.351Z,1554903687.351 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:27.351Z,1554903687.351 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:27.351Z,1554903687.351 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:27.351Z,1554903687.351 [BR_Ping1D](INFO): elapsed 2.4349
2019-04-10T13:41:27.351Z,1554903687.351 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:27.352Z,1554903687.352 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:27.352Z,1554903687.352 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:27.352Z,1554903687.352 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:27.743Z,1554903687.743 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:27.743Z,1554903687.743 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:27.744Z,1554903687.744 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:27.744Z,1554903687.744 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:27.744Z,1554903687.744 [BR_Ping1D](INFO): elapsed 2.8277
2019-04-10T13:41:27.744Z,1554903687.744 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:27.744Z,1554903687.744 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:27.745Z,1554903687.745 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:27.745Z,1554903687.745 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:28.154Z,1554903688.154 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:28.154Z,1554903688.154 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:28.154Z,1554903688.155 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:28.155Z,1554903688.155 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:28.155Z,1554903688.155 [BR_Ping1D](INFO): elapsed 3.2385
2019-04-10T13:41:28.155Z,1554903688.155 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:28.155Z,1554903688.155 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:28.155Z,1554903688.155 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:28.155Z,1554903688.155 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:28.553Z,1554903688.553 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:28.553Z,1554903688.553 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:28.553Z,1554903688.553 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:28.553Z,1554903688.553 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:28.554Z,1554903688.554 [BR_Ping1D](INFO): elapsed 3.6372
2019-04-10T13:41:28.578Z,1554903688.578 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:28.578Z,1554903688.578 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:28.579Z,1554903688.579 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:28.579Z,1554903688.579 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:28.957Z,1554903688.957 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:28.957Z,1554903688.957 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:28.957Z,1554903688.957 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:28.957Z,1554903688.957 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:28.957Z,1554903688.957 [BR_Ping1D](INFO): elapsed 4.0411
2019-04-10T13:41:28.958Z,1554903688.958 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:28.958Z,1554903688.958 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:28.958Z,1554903688.958 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:28.958Z,1554903688.958 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:29.354Z,1554903689.354 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:41:29.372Z,1554903689.372 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:29.372Z,1554903689.372 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:29.372Z,1554903689.372 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:29.372Z,1554903689.372 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:29.373Z,1554903689.373 [BR_Ping1D](INFO): elapsed 4.4562
2019-04-10T13:41:29.373Z,1554903689.373 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:29.373Z,1554903689.373 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:29.373Z,1554903689.373 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:29.373Z,1554903689.373 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:29.778Z,1554903689.778 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:29.778Z,1554903689.778 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:29.778Z,1554903689.778 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:29.778Z,1554903689.778 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:29.778Z,1554903689.778 [BR_Ping1D](INFO): elapsed 4.8621
2019-04-10T13:41:29.779Z,1554903689.779 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:29.779Z,1554903689.779 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:29.779Z,1554903689.779 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:29.779Z,1554903689.779 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:30.177Z,1554903690.177 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:30.177Z,1554903690.177 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:30.177Z,1554903690.177 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:30.177Z,1554903690.177 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:30.178Z,1554903690.178 [BR_Ping1D](INFO): elapsed 5.2613
2019-04-10T13:41:30.178Z,1554903690.178 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:30.178Z,1554903690.178 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T13:41:30.178Z,1554903690.178 [BR_Ping1D] Data Fault, FailCount= 3
2019-04-10T13:41:30.178Z,1554903690.178 [BR_Ping1D](ERROR): Data Fault
2019-04-10T13:41:30.205Z,1554903690.205 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T13:41:30.571Z,1554903690.571 [BR_Ping1D](INFO): Stop
2019-04-10T13:41:30.571Z,1554903690.571 [BR_Ping1D](INFO): uninitialize
2019-04-10T13:41:30.571Z,1554903690.571 [BR_Ping1D](INFO): Powering down
2019-04-10T13:41:30.661Z,1554903690.661 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T13:41:30.662Z,1554903690.662 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-04-10T13:41:30.662Z,1554903690.662 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-10T13:41:30.663Z,1554903690.663 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T13:41:30.664Z,1554903690.664 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-04-10T13:41:30.664Z,1554903690.664 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-10T13:41:30.665Z,1554903690.665 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T13:41:30.665Z,1554903690.665 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2019-04-10T13:41:30.665Z,1554903690.665 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-10T13:41:30.687Z,1554903690.687 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-10T13:41:30.688Z,1554903690.688 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-10T13:41:30.688Z,1554903690.688 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-10T13:41:30.978Z,1554903690.978 [BR_Ping1D](INFO): Stopping
2019-04-10T13:41:31.007Z,1554903691.007 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-10T13:41:31.007Z,1554903691.007 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-04-10T13:41:31.007Z,1554903691.007 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-10T13:41:31.007Z,1554903691.007 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-04-10T13:41:31.008Z,1554903691.008 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-10T13:41:31.008Z,1554903691.008 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2019-04-10T13:41:31.376Z,1554903691.376 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:41:31.392Z,1554903691.392 [BR_Ping1D](INFO): Stopping
2019-04-10T13:41:31.397Z,1554903691.397 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-10T13:41:31.801Z,1554903691.801 [BR_Ping1D](INFO): Stopped
2019-04-10T13:41:31.817Z,1554903691.817 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T13:41:31.817Z,1554903691.817 [BR_Ping1D] No Fault, FailCount= 3
2019-04-10T13:41:32.197Z,1554903692.197 [BR_Ping1D](INFO): Start
2019-04-10T13:41:32.591Z,1554903692.591 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:32.995Z,1554903692.995 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:32.995Z,1554903692.995 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:32.995Z,1554903692.995 [BR_Ping1D](INFO): startup: 0
2019-04-10T13:41:32.995Z,1554903692.995 [BR_Ping1D](INFO): recvData: F4010000FFFFFFFFFFFFFFFF60E300000100000005000000FFFFFFFFC0130000FFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF
2019-04-10T13:41:32.996Z,1554903692.996 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T13:41:33.010Z,1554903693.010 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T13:41:33.010Z,1554903693.010 [BR_Ping1D](INFO): XXX autoMode1: 01
2019-04-10T13:41:33.010Z,1554903693.010 [BR_Ping1D](INFO): XXX autoMode2: 
2019-04-10T13:41:33.011Z,1554903693.011 [BR_Ping1D](INFO): XXX autoMode3: 1
2019-04-10T13:41:33.011Z,1554903693.011 [BR_Ping1D](INFO): 42520100EB030000018401
2019-04-10T13:41:33.011Z,1554903693.011 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T13:41:33.011Z,1554903693.011 [BR_Ping1D](INFO): 42520100EE030000018701
2019-04-10T13:41:33.398Z,1554903693.398 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:33.806Z,1554903693.806 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:33.807Z,1554903693.807 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:33.807Z,1554903693.807 [BR_Ping1D](INFO): startup: 1
2019-04-10T13:41:33.807Z,1554903693.807 [BR_Ping1D](INFO): UART requesting status
2019-04-10T13:41:33.807Z,1554903693.807 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T13:41:33.814Z,1554903693.814 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T13:41:33.822Z,1554903693.822 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T13:41:34.212Z,1554903694.212 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:34.212Z,1554903694.212 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:34.212Z,1554903694.212 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:34.212Z,1554903694.212 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:34.212Z,1554903694.212 [BR_Ping1D](INFO): elapsed 1.2168
2019-04-10T13:41:34.213Z,1554903694.213 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:34.213Z,1554903694.213 [BR_Ping1D](INFO): received full packet, type: 1210
2019-04-10T13:41:34.213Z,1554903694.213 [BR_Ping1D](INFO): parsing data, res: 20
2019-04-10T13:41:34.213Z,1554903694.213 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-10T13:41:34.214Z,1554903694.214 [BR_Ping1D](INFO):  voltage: 5056
2019-04-10T13:41:34.214Z,1554903694.214 [BR_Ping1D](INFO):  interval: 500
2019-04-10T13:41:34.214Z,1554903694.214 [BR_Ping1D](INFO):  gain: 2
2019-04-10T13:41:34.214Z,1554903694.214 [BR_Ping1D](INFO):  auto: 1
2019-04-10T13:41:34.215Z,1554903694.215 [BR_Ping1D](INFO): received full packet, type: 1215
2019-04-10T13:41:34.215Z,1554903694.215 [BR_Ping1D](INFO): CRC error in received UART packet
2019-04-10T13:41:34.215Z,1554903694.215 [BR_Ping1D](INFO): received UART packets: 1
2019-04-10T13:41:34.215Z,1554903694.215 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:34.606Z,1554903694.606 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:41:34.625Z,1554903694.625 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:34.625Z,1554903694.625 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:34.626Z,1554903694.626 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:34.626Z,1554903694.626 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:34.626Z,1554903694.626 [BR_Ping1D](INFO): elapsed 1.6303
2019-04-10T13:41:34.626Z,1554903694.626 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:34.627Z,1554903694.627 [BR_Ping1D](INFO): received full packet, type: 1203
2019-04-10T13:41:34.627Z,1554903694.627 [BR_Ping1D](INFO): parsing data, res: 14
2019-04-10T13:41:34.627Z,1554903694.627 [BR_Ping1D](INFO): Parsing UART packet of type 1203
2019-04-10T13:41:34.627Z,1554903694.627 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:34.627Z,1554903694.627 [BR_Ping1D](INFO): received UART packets: 1
2019-04-10T13:41:34.627Z,1554903694.627 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:35.031Z,1554903695.031 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:35.031Z,1554903695.031 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:35.031Z,1554903695.031 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:35.031Z,1554903695.031 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:35.031Z,1554903695.031 [BR_Ping1D](INFO): elapsed 2.0356
2019-04-10T13:41:35.031Z,1554903695.031 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:35.032Z,1554903695.032 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:35.032Z,1554903695.032 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:35.032Z,1554903695.032 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:35.428Z,1554903695.428 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:35.428Z,1554903695.428 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:35.428Z,1554903695.428 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:35.428Z,1554903695.428 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:35.428Z,1554903695.428 [BR_Ping1D](INFO): elapsed 2.4327
2019-04-10T13:41:35.429Z,1554903695.429 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:35.429Z,1554903695.429 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:35.429Z,1554903695.429 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:35.429Z,1554903695.429 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:35.822Z,1554903695.822 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:35.822Z,1554903695.822 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:35.823Z,1554903695.823 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:35.823Z,1554903695.823 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:35.823Z,1554903695.823 [BR_Ping1D](INFO): elapsed 2.8273
2019-04-10T13:41:35.823Z,1554903695.823 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:35.823Z,1554903695.823 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:35.824Z,1554903695.824 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:35.824Z,1554903695.824 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:36.225Z,1554903696.225 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:36.226Z,1554903696.226 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:36.226Z,1554903696.226 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:36.226Z,1554903696.226 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:36.227Z,1554903696.227 [BR_Ping1D](INFO): elapsed 3.2310
2019-04-10T13:41:36.227Z,1554903696.227 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:36.227Z,1554903696.227 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:36.227Z,1554903696.227 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:36.227Z,1554903696.227 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:36.639Z,1554903696.639 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:36.639Z,1554903696.639 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:36.639Z,1554903696.639 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:36.640Z,1554903696.640 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:36.640Z,1554903696.640 [BR_Ping1D](INFO): elapsed 3.6441
2019-04-10T13:41:36.640Z,1554903696.640 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:36.640Z,1554903696.640 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:36.640Z,1554903696.640 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:36.640Z,1554903696.640 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:36.660Z,1554903696.660 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T13:41:36.660Z,1554903696.660 [Aanderaa_O2] No Fault, FailCount= 3
2019-04-10T13:41:37.034Z,1554903697.034 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:37.034Z,1554903697.034 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:37.035Z,1554903697.035 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:37.035Z,1554903697.035 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:37.035Z,1554903697.035 [BR_Ping1D](INFO): elapsed 4.0393
2019-04-10T13:41:37.035Z,1554903697.035 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:37.035Z,1554903697.035 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:37.036Z,1554903697.036 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:37.036Z,1554903697.036 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:37.446Z,1554903697.446 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:41:37.461Z,1554903697.461 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:37.461Z,1554903697.461 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:37.461Z,1554903697.461 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:37.461Z,1554903697.461 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:37.461Z,1554903697.461 [BR_Ping1D](INFO): elapsed 4.4658
2019-04-10T13:41:37.462Z,1554903697.462 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:37.462Z,1554903697.462 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:37.462Z,1554903697.462 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:37.462Z,1554903697.462 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:37.860Z,1554903697.860 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:37.860Z,1554903697.860 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:37.861Z,1554903697.861 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:37.861Z,1554903697.861 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:37.861Z,1554903697.861 [BR_Ping1D](INFO): elapsed 4.8653
2019-04-10T13:41:37.861Z,1554903697.861 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:37.861Z,1554903697.861 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:37.862Z,1554903697.862 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:37.862Z,1554903697.862 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:38.261Z,1554903698.261 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:38.261Z,1554903698.261 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:38.261Z,1554903698.261 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:38.262Z,1554903698.262 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:38.262Z,1554903698.262 [BR_Ping1D](INFO): elapsed 5.2661
2019-04-10T13:41:38.262Z,1554903698.262 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:38.262Z,1554903698.262 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T13:41:38.262Z,1554903698.262 [BR_Ping1D] Data Fault, FailCount= 4
2019-04-10T13:41:38.262Z,1554903698.262 [BR_Ping1D](ERROR): Data Fault
2019-04-10T13:41:38.277Z,1554903698.277 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T13:41:38.649Z,1554903698.649 [BR_Ping1D](INFO): Stop
2019-04-10T13:41:38.650Z,1554903698.650 [BR_Ping1D](INFO): uninitialize
2019-04-10T13:41:38.650Z,1554903698.650 [BR_Ping1D](INFO): Powering down
2019-04-10T13:41:39.058Z,1554903699.058 [BR_Ping1D](INFO): Stopping
2019-04-10T13:41:39.457Z,1554903699.457 [BR_Ping1D](INFO): Stopping
2019-04-10T13:41:39.863Z,1554903699.863 [BR_Ping1D](INFO): Stopped
2019-04-10T13:41:39.878Z,1554903699.878 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T13:41:39.878Z,1554903699.878 [BR_Ping1D] No Fault, FailCount= 4
2019-04-10T13:41:40.270Z,1554903700.270 [BR_Ping1D](INFO): Start
2019-04-10T13:41:40.666Z,1554903700.666 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:41:40.681Z,1554903700.681 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:41.085Z,1554903701.085 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:41.085Z,1554903701.085 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:41.085Z,1554903701.085 [BR_Ping1D](INFO): startup: 0
2019-04-10T13:41:41.085Z,1554903701.085 [BR_Ping1D](INFO): recvData: F4010000FFFFFFFFFFFFFFFF60E300000100000002000000FFFFFFFFC0130000FFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFFF
2019-04-10T13:41:41.085Z,1554903701.085 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T13:41:41.098Z,1554903701.098 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T13:41:41.099Z,1554903701.099 [BR_Ping1D](INFO): XXX autoMode1: 01
2019-04-10T13:41:41.099Z,1554903701.099 [BR_Ping1D](INFO): XXX autoMode2: 
2019-04-10T13:41:41.100Z,1554903701.100 [BR_Ping1D](INFO): XXX autoMode3: 1
2019-04-10T13:41:41.100Z,1554903701.100 [BR_Ping1D](INFO): 42520100EB030000018401
2019-04-10T13:41:41.100Z,1554903701.100 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T13:41:41.101Z,1554903701.101 [BR_Ping1D](INFO): 42520100EE030000018701
2019-04-10T13:41:41.493Z,1554903701.493 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:41.882Z,1554903701.882 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:41.882Z,1554903701.882 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:41.883Z,1554903701.883 [BR_Ping1D](INFO): startup: 1
2019-04-10T13:41:41.883Z,1554903701.883 [BR_Ping1D](INFO): UART requesting status
2019-04-10T13:41:41.883Z,1554903701.883 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T13:41:41.890Z,1554903701.890 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T13:41:41.907Z,1554903701.907 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T13:41:42.283Z,1554903702.283 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:42.283Z,1554903702.283 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:42.284Z,1554903702.284 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:42.284Z,1554903702.284 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:42.284Z,1554903702.284 [BR_Ping1D](INFO): elapsed 1.1985
2019-04-10T13:41:42.284Z,1554903702.284 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:42.285Z,1554903702.285 [BR_Ping1D](INFO): received full packet, type: 1210
2019-04-10T13:41:42.285Z,1554903702.285 [BR_Ping1D](INFO): parsing data, res: 20
2019-04-10T13:41:42.285Z,1554903702.285 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-10T13:41:42.285Z,1554903702.285 [BR_Ping1D](INFO):  voltage: 5056
2019-04-10T13:41:42.285Z,1554903702.285 [BR_Ping1D](INFO):  interval: 500
2019-04-10T13:41:42.285Z,1554903702.285 [BR_Ping1D](INFO):  gain: 2
2019-04-10T13:41:42.286Z,1554903702.286 [BR_Ping1D](INFO):  auto: 1
2019-04-10T13:41:42.287Z,1554903702.287 [BR_Ping1D](INFO): received full packet, type: 1215
2019-04-10T13:41:42.287Z,1554903702.287 [BR_Ping1D](INFO): CRC error in received UART packet
2019-04-10T13:41:42.287Z,1554903702.287 [BR_Ping1D](INFO): received UART packets: 1
2019-04-10T13:41:42.287Z,1554903702.287 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:42.690Z,1554903702.690 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:42.691Z,1554903702.691 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:42.691Z,1554903702.691 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:42.691Z,1554903702.691 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:42.691Z,1554903702.691 [BR_Ping1D](INFO): elapsed 1.6058
2019-04-10T13:41:42.691Z,1554903702.691 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:42.692Z,1554903702.692 [BR_Ping1D](INFO): received full packet, type: 1203
2019-04-10T13:41:42.692Z,1554903702.692 [BR_Ping1D](INFO): parsing data, res: 14
2019-04-10T13:41:42.692Z,1554903702.692 [BR_Ping1D](INFO): Parsing UART packet of type 1203
2019-04-10T13:41:42.692Z,1554903702.692 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:42.692Z,1554903702.693 [BR_Ping1D](INFO): received UART packets: 1
2019-04-10T13:41:42.693Z,1554903702.693 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:43.094Z,1554903703.094 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:43.095Z,1554903703.095 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:43.095Z,1554903703.095 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:43.095Z,1554903703.095 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:43.095Z,1554903703.095 [BR_Ping1D](INFO): elapsed 2.0097
2019-04-10T13:41:43.095Z,1554903703.095 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:43.096Z,1554903703.096 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:43.096Z,1554903703.096 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:43.096Z,1554903703.096 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:43.494Z,1554903703.494 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:41:43.509Z,1554903703.509 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:43.509Z,1554903703.509 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:43.509Z,1554903703.509 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:43.509Z,1554903703.509 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:43.509Z,1554903703.509 [BR_Ping1D](INFO): elapsed 2.4240
2019-04-10T13:41:43.510Z,1554903703.510 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:43.510Z,1554903703.510 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:43.510Z,1554903703.510 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:43.510Z,1554903703.510 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:43.920Z,1554903703.920 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:43.921Z,1554903703.921 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:43.921Z,1554903703.921 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:43.921Z,1554903703.921 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:43.921Z,1554903703.921 [BR_Ping1D](INFO): elapsed 2.8357
2019-04-10T13:41:43.921Z,1554903703.921 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:43.922Z,1554903703.922 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:43.922Z,1554903703.922 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:43.922Z,1554903703.922 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:44.317Z,1554903704.317 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:44.317Z,1554903704.317 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:44.317Z,1554903704.317 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:44.317Z,1554903704.317 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:44.317Z,1554903704.317 [BR_Ping1D](INFO): elapsed 3.2318
2019-04-10T13:41:44.317Z,1554903704.317 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:44.318Z,1554903704.318 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:44.318Z,1554903704.318 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:44.318Z,1554903704.318 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:44.712Z,1554903704.712 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:44.712Z,1554903704.712 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:44.713Z,1554903704.713 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:44.713Z,1554903704.713 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:44.713Z,1554903704.713 [BR_Ping1D](INFO): elapsed 3.6275
2019-04-10T13:41:44.713Z,1554903704.713 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:44.713Z,1554903704.713 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:44.714Z,1554903704.714 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:44.714Z,1554903704.714 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:45.114Z,1554903705.114 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:45.114Z,1554903705.114 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:45.115Z,1554903705.115 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:45.115Z,1554903705.115 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:45.115Z,1554903705.115 [BR_Ping1D](INFO): elapsed 4.0295
2019-04-10T13:41:45.115Z,1554903705.115 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:45.115Z,1554903705.115 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:45.116Z,1554903705.116 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:45.116Z,1554903705.116 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:45.517Z,1554903705.517 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:45.517Z,1554903705.517 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:45.517Z,1554903705.517 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:45.517Z,1554903705.517 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:45.518Z,1554903705.518 [BR_Ping1D](INFO): elapsed 4.4322
2019-04-10T13:41:45.518Z,1554903705.518 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:45.519Z,1554903705.519 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:45.519Z,1554903705.519 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:45.519Z,1554903705.519 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:45.923Z,1554903705.923 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:45.923Z,1554903705.923 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:45.923Z,1554903705.923 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:45.923Z,1554903705.923 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:45.924Z,1554903705.924 [BR_Ping1D](INFO): elapsed 4.8381
2019-04-10T13:41:45.924Z,1554903705.924 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:45.924Z,1554903705.924 [BR_Ping1D](INFO): No UART data
2019-04-10T13:41:45.924Z,1554903705.924 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T13:41:45.924Z,1554903705.924 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T13:41:46.330Z,1554903706.330 [BR_Ping1D](INFO): Starting
2019-04-10T13:41:46.331Z,1554903706.331 [BR_Ping1D](INFO): readConfig
2019-04-10T13:41:46.331Z,1554903706.331 [BR_Ping1D](INFO): startup: 2
2019-04-10T13:41:46.331Z,1554903706.331 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T13:41:46.331Z,1554903706.331 [BR_Ping1D](INFO): elapsed 5.2458
2019-04-10T13:41:46.331Z,1554903706.331 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T13:41:46.332Z,1554903706.332 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T13:41:46.332Z,1554903706.332 [BR_Ping1D] Data Fault, FailCount= 5
2019-04-10T13:41:46.332Z,1554903706.332 [BR_Ping1D](ERROR): Data Fault
2019-04-10T13:41:46.352Z,1554903706.352 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T13:41:46.352Z,1554903706.352 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-04-10T13:41:46.734Z,1554903706.734 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:41:46.748Z,1554903706.748 [BR_Ping1D](INFO): Stop
2019-04-10T13:41:46.748Z,1554903706.748 [BR_Ping1D](INFO): uninitialize
2019-04-10T13:41:46.748Z,1554903706.748 [BR_Ping1D](INFO): Powering down
2019-04-10T13:41:46.833Z,1554903706.833 [CBIT](INFO): Critical error at 20190410T134146
2019-04-10T13:41:47.114Z,1554903707.114 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T13:41:47.114Z,1554903707.114 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-10T13:41:47.114Z,1554903707.114 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T13:41:47.145Z,1554903707.145 [BR_Ping1D](INFO): Stopping
2019-04-10T13:41:47.164Z,1554903707.164 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T13:41:47.638Z,1554903707.638 [Aanderaa_O2](INFO): Powering down
2019-04-10T13:41:47.672Z,1554903707.672 [BR_Ping1D](INFO): Stopping
2019-04-10T13:41:47.946Z,1554903707.946 [BR_Ping1D](INFO): Stopped
2019-04-10T13:41:48.365Z,1554903708.365 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T13:41:48.365Z,1554903708.365 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-10T13:41:49.558Z,1554903709.558 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:41:52.386Z,1554903712.386 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:41:54.020Z,1554903714.020 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-10T13:41:54.020Z,1554903714.020 [CTD_Seabird] No Fault, FailCount= 3
2019-04-10T13:41:54.198Z,1554903714.198 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T13:41:54.200Z,1554903714.200 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T13:41:54.402Z,1554903714.402 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:41:57.638Z,1554903717.638 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:41:58.830Z,1554903718.830 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T13:41:58.830Z,1554903718.830 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-10T13:41:58.830Z,1554903718.830 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T13:41:58.888Z,1554903718.888 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T13:41:59.354Z,1554903719.354 [Aanderaa_O2](INFO): Powering down
2019-04-10T13:42:00.075Z,1554903720.075 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T13:42:00.076Z,1554903720.076 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-10T13:42:00.462Z,1554903720.462 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:42:03.694Z,1554903723.694 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:42:06.522Z,1554903726.522 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:42:07.250Z,1554903727.250 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T13:42:07.250Z,1554903727.250 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-10T13:42:07.250Z,1554903727.250 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T13:42:07.367Z,1554903727.367 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T13:42:07.654Z,1554903727.654 [CTD_Seabird](INFO): Powering down
2019-04-10T13:42:08.970Z,1554903728.970 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-10T13:42:08.971Z,1554903728.971 [CTD_Seabird] No Fault, FailCount= 1
2019-04-10T13:42:08.998Z,1554903728.998 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T13:42:08.000Z,1554903729.000 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T13:42:09.758Z,1554903729.758 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:42:10.547Z,1554903730.547 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T13:42:10.547Z,1554903730.547 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-10T13:42:10.547Z,1554903730.547 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T13:42:10.600Z,1554903730.600 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T13:42:10.600Z,1554903730.600 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-10T13:42:11.070Z,1554903731.070 [Aanderaa_O2](INFO): Powering down
2019-04-10T13:42:12.586Z,1554903732.586 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:42:15.414Z,1554903735.414 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:42:17.430Z,1554903737.430 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:42:20.666Z,1554903740.666 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:42:22.054Z,1554903742.054 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T13:42:22.054Z,1554903742.054 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-10T13:42:22.054Z,1554903742.054 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T13:42:22.296Z,1554903742.296 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T13:42:22.458Z,1554903742.458 [CTD_Seabird](INFO): Powering down
2019-04-10T13:42:23.490Z,1554903743.490 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:42:23.537Z,1554903743.537 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-10T13:42:23.537Z,1554903743.537 [CTD_Seabird] No Fault, FailCount= 2
2019-04-10T13:42:23.802Z,1554903743.802 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T13:42:23.804Z,1554903743.804 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T13:42:26.726Z,1554903746.726 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:42:29.554Z,1554903749.554 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:42:32.782Z,1554903752.782 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:42:35.614Z,1554903755.614 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:42:36.854Z,1554903756.854 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T13:42:36.854Z,1554903756.854 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-10T13:42:36.854Z,1554903756.854 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T13:42:37.259Z,1554903757.259 [CTD_Seabird](INFO): Powering down
2019-04-10T13:42:37.289Z,1554903757.289 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T13:42:37.289Z,1554903757.289 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-10T13:42:38.446Z,1554903758.446 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:42:40.466Z,1554903760.466 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:42:43.702Z,1554903763.702 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:42:46.024Z,1554903766.024 [CommandLine](IMPORTANT): got command restart application
2019-04-10T13:42:46.526Z,1554903766.526 [NAL9602](DEBUG): Fix Requested
2019-04-10T13:42:47.030Z,1554903767.030 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T13:42:47.030Z,1554903767.030 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-10T13:42:47.090Z,1554903767.090 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-04-10T13:42:47.090Z,1554903767.090 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-10T13:42:47.091Z,1554903767.091 [CommandLine](INFO): Join timeout helper Thread ID is 5765
2019-04-10T13:42:47.091Z,1554903767.091 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-04-10T13:42:47.091Z,1554903767.091 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-10T13:42:47.092Z,1554903767.092 [NavChartDb](INFO): Join timeout helper Thread ID is 5766
2019-04-10T13:42:47.330Z,1554903767.330 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T13:42:47.330Z,1554903767.330 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-10T13:42:47.350Z,1554903767.350 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-04-10T13:42:47.350Z,1554903767.350 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-10T13:42:47.351Z,1554903767.351 [Radio_Surface](INFO): Join timeout helper Thread ID is 5767
2019-04-10T13:42:47.614Z,1554903767.614 [Radio_Surface](INFO): Powering down
2019-04-10T13:42:47.615Z,1554903767.615 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T13:42:47.615Z,1554903767.615 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-10T13:42:47.630Z,1554903767.630 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-04-10T13:42:47.630Z,1554903767.630 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-10T13:42:47.630Z,1554903767.630 [DataOverHttps](INFO): Join timeout helper Thread ID is 5768
2019-04-10T13:42:47.634Z,1554903767.634 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T13:42:47.634Z,1554903767.634 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-10T13:42:47.650Z,1554903767.650 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-04-10T13:42:47.650Z,1554903767.650 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-10T13:42:47.650Z,1554903767.650 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5769
2019-04-10T13:42:48.086Z,1554903768.086 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T13:42:48.087Z,1554903768.087 [WetLabsBB2FL](INFO): Powering down
2019-04-10T13:42:48.087Z,1554903768.087 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-10T13:42:48.099Z,1554903768.099 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-04-10T13:42:48.099Z,1554903768.099 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-10T13:42:48.099Z,1554903768.099 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5770
2019-04-10T13:42:48.310Z,1554903768.310 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T13:42:48.310Z,1554903768.310 [CTD_Seabird](INFO): Powering down
2019-04-10T13:42:48.322Z,1554903768.322 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-10T13:42:48.323Z,1554903768.323 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-04-10T13:42:48.323Z,1554903768.323 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-10T13:42:48.324Z,1554903768.324 [logger](INFO): Join timeout helper Thread ID is 5771
2019-04-10T13:42:48.390Z,1554903768.390 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T13:42:48.390Z,1554903768.390 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-10T13:42:48.403Z,1554903768.403 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-04-10T13:42:48.403Z,1554903768.403 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-10T13:42:48.403Z,1554903768.403 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-04-10T13:42:48.403Z,1554903768.403 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-10T13:42:48.403Z,1554903768.403 [controlThread](INFO): Join timeout helper Thread ID is 5772
2019-04-10T13:42:48.526Z,1554903768.526 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T13:42:48.526Z,1554903768.526 [controlThread](DEBUG): Uninitializing ControlThread
2019-04-10T13:42:48.527Z,1554903768.527 [Aanderaa_O2](INFO): Powering down
2019-04-10T13:42:48.529Z,1554903768.529 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T13:42:48.530Z,1554903768.530 [NAL9602](INFO): Powering down
2019-04-10T13:42:48.531Z,1554903768.531 [BR_Ping1D](INFO): uninitialize
2019-04-10T13:42:48.531Z,1554903768.531 [BR_Ping1D](INFO): Powering down
2019-04-10T13:42:48.532Z,1554903768.532 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-04-10T13:42:48.533Z,1554903768.533 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-04-10T13:42:48.533Z,1554903768.533 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-04-10T13:42:48.534Z,1554903768.534 [MissionManager](INFO): Uninitializing Mission Default
2019-04-10T13:42:48.534Z,1554903768.534 [Default] Stopped
2019-04-10T13:42:48.534Z,1554903768.534 [Default](DEBUG): Aggregate::uninitialize Default
2019-04-10T13:42:48.534Z,1554903768.534 [Default:B.GoToSurface] Stopped
2019-04-10T13:42:48.534Z,1554903768.534 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-10T13:42:48.534Z,1554903768.534 [Default:CheckIn] Stopped
2019-04-10T13:42:48.534Z,1554903768.534 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-10T13:42:48.534Z,1554903768.534 [Default:CheckIn:Read_GPS] Stopped
2019-04-10T13:42:48.537Z,1554903768.537 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-04-10T13:42:48.537Z,1554903768.537 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-04-10T13:42:48.537Z,1554903768.537 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-04-10T13:42:48.537Z,1554903768.537 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-04-10T13:42:48.538Z,1554903768.538 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-04-10T13:42:48.538Z,1554903768.538 [BuoyancyServo](INFO): Powering down
2019-04-10T13:42:48.550Z,1554903768.550 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-10T13:42:48.550Z,1554903768.550 [ElevatorServo](INFO): Powering down
2019-04-10T13:42:48.551Z,1554903768.551 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-10T13:42:48.551Z,1554903768.551 [MassServo](INFO): Powering down
2019-04-10T13:42:48.552Z,1554903768.552 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-10T13:42:48.552Z,1554903768.552 [RudderServo](INFO): Powering down
2019-04-10T13:42:48.553Z,1554903768.553 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-04-10T13:42:48.553Z,1554903768.553 [ThrusterServo](INFO): Powering down
2019-04-10T13:42:48.554Z,1554903768.554 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-04-10T13:42:48.554Z,1554903768.554 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-04-10T13:42:48.554Z,1554903768.554 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-04-10T13:42:48.554Z,1554903768.554 [CBIT](DEBUG): Powering off loads.
2019-04-10T13:42:48.566Z,1554903768.566 [CBIT](DEBUG): Disabling WDT.
2019-04-10T13:42:48.578Z,1554903768.578 [CBIT](DEBUG): Opening all GF detection circuits.
2019-04-10T13:42:48.579Z,1554903768.579 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-10T13:42:48.609Z,1554903768.609 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-10T13:42:48.615Z,1554903768.615 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-10T13:42:48.683Z,1554903768.683 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-10T13:42:48.687Z,1554903768.687 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-10T13:42:48.739Z,1554903768.739 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-10T13:42:48.795Z,1554903768.795 [logger ThreadHandler](INFO): Thread cancelled.