2019-04-10T01:08:59.460Z,1554858539.460 [Supervisor](DEBUG): Initializing supervisor.
2019-04-10T01:08:59.463Z,1554858539.463 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-10T01:08:59.463Z,1554858539.463 [SyncHandler](INFO): Protected caller Thread ID is 991
2019-04-10T01:08:59.464Z,1554858539.464 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-04-10T01:08:59.465Z,1554858539.465 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-04-10T01:08:59.465Z,1554858539.465 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 992
2019-04-10T01:08:59.469Z,1554858539.469 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-04-10T01:08:59.481Z,1554858539.481 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-04-10T01:08:59.482Z,1554858539.482 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-04-10T01:08:59.482Z,1554858539.482 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 993
2019-04-10T01:08:59.483Z,1554858539.483 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-04-10T01:08:59.484Z,1554858539.484 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-04-10T01:08:59.484Z,1554858539.484 [logger ThreadHandler](INFO): Protected caller Thread ID is 994
2019-04-10T01:08:59.487Z,1554858539.487 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-04-10T01:08:59.487Z,1554858539.487 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-04-10T01:08:59.488Z,1554858539.488 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-04-10T01:08:59.583Z,1554858539.583 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-04-10T01:08:59.584Z,1554858539.584 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-04-10T01:08:59.793Z,1554858539.793 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-04-10T01:08:59.794Z,1554858539.794 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-04-10T01:08:59.929Z,1554858539.929 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-04-10T01:08:59.930Z,1554858539.930 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-04-10T01:09:00.387Z,1554858540.387 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-04-10T01:09:00.387Z,1554858540.387 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-04-10T01:09:00.849Z,1554858540.849 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-04-10T01:09:00.849Z,1554858540.849 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-04-10T01:09:01.316Z,1554858541.316 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-04-10T01:09:01.317Z,1554858541.317 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-04-10T01:09:01.622Z,1554858541.622 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-04-10T01:09:01.623Z,1554858541.623 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-04-10T01:09:01.971Z,1554858541.971 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-04-10T01:09:01.971Z,1554858541.971 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-04-10T01:09:02.757Z,1554858542.757 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-04-10T01:09:02.757Z,1554858542.757 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-04-10T01:09:02.906Z,1554858542.906 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-04-10T01:09:02.907Z,1554858542.907 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-04-10T01:09:03.011Z,1554858543.011 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-04-10T01:09:03.011Z,1554858543.011 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-04-10T01:09:03.091Z,1554858543.091 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-04-10T01:09:03.191Z,1554858543.191 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-04-10T01:09:03.192Z,1554858543.192 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-04-10T01:09:03.385Z,1554858543.385 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-04-10T01:09:03.386Z,1554858543.386 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-04-10T01:09:03.593Z,1554858543.593 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-04-10T01:09:03.595Z,1554858543.595 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-04-10T01:09:03.596Z,1554858543.596 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-04-10T01:09:03.678Z,1554858543.678 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-04-10T01:09:03.909Z,1554858543.909 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-04-10T01:09:03.910Z,1554858543.910 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-04-10T01:09:04.004Z,1554858544.004 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-04-10T01:09:04.176Z,1554858544.176 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-04-10T01:09:04.388Z,1554858544.388 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-04-10T01:09:04.472Z,1554858544.472 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-04-10T01:09:04.569Z,1554858544.569 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-04-10T01:09:04.661Z,1554858544.661 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-04-10T01:09:04.812Z,1554858544.812 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-04-10T01:09:04.915Z,1554858544.915 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-04-10T01:09:05.012Z,1554858545.012 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2019-04-10T01:09:05.013Z,1554858545.013 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-04-10T01:09:05.017Z,1554858545.017 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-04-10T01:09:05.116Z,1554858545.116 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-04-10T01:09:05.116Z,1554858545.116 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-04-10T01:09:05.245Z,1554858545.245 [BuoyancyServo] Loaded
2019-04-10T01:09:05.245Z,1554858545.245 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-04-10T01:09:05.260Z,1554858545.260 [ElevatorServo] Loaded
2019-04-10T01:09:05.261Z,1554858545.261 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-04-10T01:09:05.276Z,1554858545.276 [MassServo] Loaded
2019-04-10T01:09:05.276Z,1554858545.276 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-04-10T01:09:05.292Z,1554858545.292 [RudderServo] Loaded
2019-04-10T01:09:05.292Z,1554858545.292 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-04-10T01:09:05.306Z,1554858545.306 [ThrusterServo] Loaded
2019-04-10T01:09:05.307Z,1554858545.307 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-04-10T01:09:05.307Z,1554858545.307 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-04-10T01:09:05.308Z,1554858545.308 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-04-10T01:09:05.444Z,1554858545.444 [SBIT](DEBUG): Construct Startup Built In Test.
2019-04-10T01:09:05.472Z,1554858545.472 [SBIT] Loaded
2019-04-10T01:09:05.473Z,1554858545.473 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-04-10T01:09:05.473Z,1554858545.473 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-04-10T01:09:05.502Z,1554858545.502 [IBIT] Loaded
2019-04-10T01:09:05.502Z,1554858545.502 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-04-10T01:09:05.506Z,1554858545.506 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-04-10T01:09:05.897Z,1554858545.897 [CBIT] Loaded
2019-04-10T01:09:05.897Z,1554858545.897 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-04-10T01:09:05.897Z,1554858545.897 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-04-10T01:09:05.898Z,1554858545.898 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-04-10T01:09:06.082Z,1554858546.082 [Aanderaa_O2] Loaded
2019-04-10T01:09:06.082Z,1554858546.082 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-04-10T01:09:06.092Z,1554858546.092 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-04-10T01:09:06.097Z,1554858546.097 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-04-10T01:09:06.099Z,1554858546.099 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-04-10T01:09:06.104Z,1554858546.104 [CTD_Seabird](INFO): created writer for : depth
2019-04-10T01:09:06.104Z,1554858546.104 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-04-10T01:09:06.110Z,1554858546.110 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-04-10T01:09:06.110Z,1554858546.110 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-04-10T01:09:06.116Z,1554858546.116 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-04-10T01:09:06.116Z,1554858546.116 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-04-10T01:09:06.121Z,1554858546.121 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-04-10T01:09:06.122Z,1554858546.122 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-04-10T01:09:06.128Z,1554858546.128 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-04-10T01:09:06.128Z,1554858546.128 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-04-10T01:09:06.133Z,1554858546.133 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-04-10T01:09:06.160Z,1554858546.160 [CTD_Seabird] Loaded
2019-04-10T01:09:06.161Z,1554858546.161 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-04-10T01:09:06.162Z,1554858546.162 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406654E0
2019-04-10T01:09:06.162Z,1554858546.162 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1073
2019-04-10T01:09:06.188Z,1554858546.188 [ESPComponent] Loaded
2019-04-10T01:09:06.188Z,1554858546.188 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-04-10T01:09:06.202Z,1554858546.202 [PAR_Licor] Loaded
2019-04-10T01:09:06.203Z,1554858546.203 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-04-10T01:09:06.251Z,1554858546.251 [WetLabsBB2FL] Loaded
2019-04-10T01:09:06.252Z,1554858546.252 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-04-10T01:09:06.253Z,1554858546.253 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406954E0
2019-04-10T01:09:06.253Z,1554858546.253 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1074
2019-04-10T01:09:06.254Z,1554858546.254 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-04-10T01:09:06.254Z,1554858546.254 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-04-10T01:09:06.295Z,1554858546.295 [DepthRateCalculator] Loaded
2019-04-10T01:09:06.295Z,1554858546.295 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-04-10T01:09:06.301Z,1554858546.301 [PitchRateCalculator] Loaded
2019-04-10T01:09:06.301Z,1554858546.301 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-04-10T01:09:06.318Z,1554858546.318 [SpeedCalculator] Loaded
2019-04-10T01:09:06.318Z,1554858546.318 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-04-10T01:09:06.340Z,1554858546.340 [TempGradientCalculator] Loaded
2019-04-10T01:09:06.340Z,1554858546.340 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-04-10T01:09:06.346Z,1554858546.346 [YawRateCalculator] Loaded
2019-04-10T01:09:06.346Z,1554858546.346 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-04-10T01:09:06.379Z,1554858546.379 [ElevatorOffsetCalculator] Loaded
2019-04-10T01:09:06.380Z,1554858546.380 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-04-10T01:09:06.380Z,1554858546.380 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-04-10T01:09:06.381Z,1554858546.381 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-04-10T01:09:06.408Z,1554858546.408 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-04-10T01:09:06.408Z,1554858546.408 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-04-10T01:09:06.512Z,1554858546.512 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-04-10T01:09:06.512Z,1554858546.512 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-04-10T01:09:06.573Z,1554858546.573 [DeadReckonUsingSpeedCalculator] Loaded
2019-04-10T01:09:06.573Z,1554858546.573 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-04-10T01:09:06.634Z,1554858546.634 [DeadReckonWithRespectToSeafloor] Loaded
2019-04-10T01:09:06.634Z,1554858546.634 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-04-10T01:09:06.655Z,1554858546.655 [NavChart] Loaded
2019-04-10T01:09:06.655Z,1554858546.655 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-04-10T01:09:06.659Z,1554858546.659 [UniversalFixResidualReporter] Loaded
2019-04-10T01:09:06.660Z,1554858546.660 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-04-10T01:09:06.660Z,1554858546.660 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-04-10T01:09:06.661Z,1554858546.661 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-04-10T01:09:06.973Z,1554858546.973 [AcousticModem_Benthos_ATM900] Loaded
2019-04-10T01:09:06.973Z,1554858546.973 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-04-10T01:09:07.045Z,1554858547.045 [DataOverHttps] Loaded
2019-04-10T01:09:07.045Z,1554858547.045 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-04-10T01:09:07.046Z,1554858547.046 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409714E0
2019-04-10T01:09:07.047Z,1554858547.047 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1075
2019-04-10T01:09:07.060Z,1554858547.060 [Depth_Keller] Loaded
2019-04-10T01:09:07.060Z,1554858547.060 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-04-10T01:09:07.154Z,1554858547.154 [NAL9602] Loaded
2019-04-10T01:09:07.154Z,1554858547.154 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-04-10T01:09:07.159Z,1554858547.159 [Onboard] Loaded
2019-04-10T01:09:07.160Z,1554858547.160 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-04-10T01:09:07.163Z,1554858547.163 [Radio_Surface] Loaded
2019-04-10T01:09:07.163Z,1554858547.163 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-04-10T01:09:07.164Z,1554858547.164 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A14E0
2019-04-10T01:09:07.165Z,1554858547.165 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1076
2019-04-10T01:09:09.258Z,1554858549.258 [BPC1] Loaded
2019-04-10T01:09:09.258Z,1554858549.258 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-04-10T01:09:09.289Z,1554858549.289 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minrange
2019-04-10T01:09:09.291Z,1554858549.291 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.maxrange
2019-04-10T01:09:09.293Z,1554858549.293 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.soundspeed
2019-04-10T01:09:09.295Z,1554858549.295 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.automode
2019-04-10T01:09:09.298Z,1554858549.298 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.gainindex
2019-04-10T01:09:09.300Z,1554858549.300 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minconfidence
2019-04-10T01:09:09.302Z,1554858549.302 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.pingenable
2019-04-10T01:09:09.304Z,1554858549.304 [BR_Ping1D] Loaded
2019-04-10T01:09:09.305Z,1554858549.305 [ComponentRegistry](DEBUG): SyncComponent "BR_Ping1D" handled in the control thread.
2019-04-10T01:09:09.305Z,1554858549.305 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-04-10T01:09:09.306Z,1554858549.306 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-04-10T01:09:09.370Z,1554858549.370 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-04-10T01:09:09.370Z,1554858549.370 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-04-10T01:09:09.437Z,1554858549.437 [VerticalControl](DEBUG): Construct VerticalControl.
2019-04-10T01:09:09.523Z,1554858549.523 [VerticalControl] Loaded
2019-04-10T01:09:09.523Z,1554858549.523 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-04-10T01:09:09.524Z,1554858549.524 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-04-10T01:09:09.582Z,1554858549.582 [HorizontalControl] Loaded
2019-04-10T01:09:09.583Z,1554858549.583 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-04-10T01:09:09.583Z,1554858549.583 [SpeedControl](DEBUG): Construct SpeedControl.
2019-04-10T01:09:09.585Z,1554858549.585 [SpeedControl] Loaded
2019-04-10T01:09:09.585Z,1554858549.585 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-04-10T01:09:09.586Z,1554858549.586 [LoopControl](DEBUG): Construct LoopControl.
2019-04-10T01:09:09.587Z,1554858549.587 [LoopControl] Loaded
2019-04-10T01:09:09.587Z,1554858549.587 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-04-10T01:09:09.587Z,1554858549.587 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-04-10T01:09:09.588Z,1554858549.588 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-04-10T01:09:09.602Z,1554858549.602 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-04-10T01:09:09.602Z,1554858549.602 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-04-10T01:09:09.880Z,1554858549.880 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-04-10T01:09:09.884Z,1554858549.884 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-04-10T01:09:09.885Z,1554858549.885 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-04-10T01:09:09.892Z,1554858549.892 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-04-10T01:09:09.893Z,1554858549.893 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACE4E0
2019-04-10T01:09:09.893Z,1554858549.893 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1077
2019-04-10T01:09:09.898Z,1554858549.898 [Supervisor](INFO): Main Thread ID is 9999
2019-04-10T01:09:09.898Z,1554858549.898 [Supervisor](DEBUG): Running supervisor.
2019-04-10T01:09:09.899Z,1554858549.899 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1078
2019-04-10T01:09:09.901Z,1554858549.901 [controlThread ThreadHandler](INFO): Handler Thread ID is 1079
2019-04-10T01:09:09.901Z,1554858549.901 [controlThread](DEBUG): Initializing ControlThread
2019-04-10T01:09:09.903Z,1554858549.903 [SBIT](INFO): Initialize SBIT Component.
2019-04-10T01:09:09.904Z,1554858549.904 [SBIT](IMPORTANT): git: 2019-03-18-31-ge98d5dc
2019-04-10T01:09:09.904Z,1554858549.904 [SBIT](INFO): git hash: e98d5dc50cbc39e402f19b9946ff4dc0776c8725
2019-04-10T01:09:09.904Z,1554858549.904 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-04-10T01:09:09.906Z,1554858549.906 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-04-10T01:09:09.907Z,1554858549.907 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-04-10T01:09:09.907Z,1554858549.907 [IBIT](INFO): Initialize IBIT Component.
2019-04-10T01:09:09.908Z,1554858549.908 [CBIT](DEBUG): Initialize CBIT Component.
2019-04-10T01:09:09.909Z,1554858549.909 [logger ThreadHandler](INFO): Handler Thread ID is 1080
2019-04-10T01:09:09.922Z,1554858549.922 [CBIT](DEBUG): Initialized mux pins.
2019-04-10T01:09:09.922Z,1554858549.922 [CBIT](DEBUG): Initializing the watchdog timer.
2019-04-10T01:09:09.934Z,1554858549.934 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1081
2019-04-10T01:09:09.935Z,1554858549.935 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T01:09:09.938Z,1554858549.938 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T01:09:09.940Z,1554858549.940 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1083
2019-04-10T01:09:09.941Z,1554858549.941 [WetLabsBB2FL](INFO): Powering down
2019-04-10T01:09:09.946Z,1554858549.946 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-04-10T01:09:09.946Z,1554858549.946 [CBIT](DEBUG): Initializing heartbeat.
2019-04-10T01:09:09.970Z,1554858549.970 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1084
2019-04-10T01:09:09.971Z,1554858549.971 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-04-10T01:09:09.979Z,1554858549.979 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1085
2019-04-10T01:09:09.994Z,1554858549.994 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1086
2019-04-10T01:09:09.997Z,1554858549.997 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-10T01:09:09.998Z,1554858549.998 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-04-10T01:09:09.998Z,1554858549.998 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-10T01:09:09.998Z,1554858549.998 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-04-10T01:09:09.998Z,1554858549.998 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-10T01:09:09.999Z,1554858549.999 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-04-10T01:09:09.999Z,1554858549.999 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-10T01:09:09.999Z,1554858549.999 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-04-10T01:09:09.999Z,1554858549.999 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-10T01:09:09.999Z,1554858549.999 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-04-10T01:09:09.000Z,1554858550.000 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-10T01:09:09.000Z,1554858550.000 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-04-10T01:09:09.000Z,1554858550.000 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-10T01:09:10.000Z,1554858550.000 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-04-10T01:09:10.000Z,1554858550.000 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-10T01:09:10.000Z,1554858550.000 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-04-10T01:09:10.018Z,1554858550.018 [CBIT](DEBUG): Deactivating GF circuits.
2019-04-10T01:09:10.018Z,1554858550.018 [CBIT](DEBUG): Deactivating emergency mode.
2019-04-10T01:09:10.054Z,1554858550.054 [CBIT](DEBUG): Backplane powered.
2019-04-10T01:09:10.058Z,1554858550.058 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-04-10T01:09:10.059Z,1554858550.059 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-04-10T01:09:10.059Z,1554858550.059 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-04-10T01:09:10.059Z,1554858550.059 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-04-10T01:09:10.060Z,1554858550.060 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-04-10T01:09:10.060Z,1554858550.060 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-04-10T01:09:10.061Z,1554858550.061 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-10T01:09:10.062Z,1554858550.062 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-04-10T01:09:10.063Z,1554858550.063 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-04-10T01:09:10.067Z,1554858550.067 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-04-10T01:09:10.069Z,1554858550.069 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-04-10T01:09:10.070Z,1554858550.070 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-04-10T01:09:10.070Z,1554858550.070 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-04-10T01:09:10.071Z,1554858550.071 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-04-10T01:09:10.093Z,1554858550.093 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-10T01:09:10.129Z,1554858550.129 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2019-04-10T01:09:10.138Z,1554858550.138 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-04-10T01:09:10.215Z,1554858550.215 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-04-10T01:09:10.216Z,1554858550.216 [Default:A.Wait](DEBUG): Construct Wait.
2019-04-10T01:09:10.218Z,1554858550.218 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-10T01:09:10.257Z,1554858550.257 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-04-10T01:09:10.272Z,1554858550.272 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-04-10T01:09:10.277Z,1554858550.277 [Default:E.Execute](DEBUG): Construct Execute.
2019-04-10T01:09:10.309Z,1554858550.309 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2019-04-10T01:09:10.314Z,1554858550.314 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,Depth_Keller,NAL9602,Onboard,BPC1,BR_Ping1D,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-04-10T01:09:10.347Z,1554858550.347 [ESPComponent](INFO): powering down ESP
2019-04-10T01:09:10.382Z,1554858550.382 [Radio_Surface](INFO): Powering up
2019-04-10T01:09:10.787Z,1554858550.787 [PAR_Licor](INFO): A/D timeout, 8 tries over 133 ms

2019-04-10T01:09:10.787Z,1554858550.787 [PAR_Licor] Data Fault, FailCount= 1
2019-04-10T01:09:10.787Z,1554858550.787 [PAR_Licor](ERROR): Data Fault
2019-04-10T01:09:10.788Z,1554858550.788 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T01:09:10.788Z,1554858550.788 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T01:09:10.811Z,1554858550.811 [Depth_Keller](ERROR): Pressure reading out of range: 1895.684326 decibar
2019-04-10T01:09:10.862Z,1554858550.862 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-04-10T01:09:10.903Z,1554858550.903 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-04-10T01:09:10.910Z,1554858550.910 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-04-10T01:09:10.912Z,1554858550.912 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-10T01:09:10.918Z,1554858550.918 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-10T01:09:10.919Z,1554858550.919 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-10T01:09:10.926Z,1554858550.926 [MassServo](DEBUG): Initializing MassServo.
2019-04-10T01:09:10.927Z,1554858550.927 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-10T01:09:10.934Z,1554858550.934 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-10T01:09:10.935Z,1554858550.935 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-04-10T01:09:10.942Z,1554858550.942 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-04-10T01:09:10.945Z,1554858550.945 [CommandLine](FAULT): Scheduling is paused
2019-04-10T01:09:10.945Z,1554858550.945 [CBIT](INFO): Critical error at 20190410T010909
2019-04-10T01:09:10.945Z,1554858550.945 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-04-10T01:09:10.950Z,1554858550.950 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T01:09:10.951Z,1554858550.951 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T01:09:10.951Z,1554858550.951 [PAR_Licor] No Fault, FailCount= 1
2019-04-10T01:09:11.498Z,1554858551.498 [PAR_Licor](INFO): A/D timeout, 8 tries over 138 ms

2019-04-10T01:09:11.498Z,1554858551.498 [PAR_Licor] Data Fault, FailCount= 2
2019-04-10T01:09:11.498Z,1554858551.498 [PAR_Licor](ERROR): Data Fault
2019-04-10T01:09:11.533Z,1554858551.533 [BR_Ping1D](INFO): startup: 0
2019-04-10T01:09:11.534Z,1554858551.534 [BR_Ping1D](INFO): recvData: ÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿ
2019-04-10T01:09:11.534Z,1554858551.534 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T01:09:11.546Z,1554858551.546 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T01:09:11.546Z,1554858551.546 [BR_Ping1D](INFO): XXX autoMode1: 01
2019-04-10T01:09:11.547Z,1554858551.547 [BR_Ping1D](INFO): XXX autoMode2: 
2019-04-10T01:09:11.547Z,1554858551.547 [BR_Ping1D](INFO): XXX autoMode3: 1
2019-04-10T01:09:11.547Z,1554858551.547 [BR_Ping1D](INFO): 42520100EB030000840100
2019-04-10T01:09:11.547Z,1554858551.547 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T01:09:11.547Z,1554858551.547 [BR_Ping1D](INFO): 42520100EE030000870116
2019-04-10T01:09:11.906Z,1554858551.906 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-04-10T01:09:11.906Z,1554858551.906 [RudderServo](FAULT): Rudder failed to initialize
2019-04-10T01:09:11.906Z,1554858551.906 [RudderServo] Communications Fault, FailCount= 1
2019-04-10T01:09:11.906Z,1554858551.906 [RudderServo](ERROR): Communications Fault
2019-04-10T01:09:12.017Z,1554858552.017 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-04-10T01:09:12.018Z,1554858552.018 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T01:09:12.018Z,1554858552.018 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T01:09:12.018Z,1554858552.018 [PAR_Licor] No Fault, FailCount= 2
2019-04-10T01:09:12.057Z,1554858552.057 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:12.057Z,1554858552.057 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:12.058Z,1554858552.058 [BR_Ping1D](INFO): startup: 1
2019-04-10T01:09:12.058Z,1554858552.058 [BR_Ping1D](INFO): UART requesting status
2019-04-10T01:09:12.058Z,1554858552.058 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T01:09:12.066Z,1554858552.066 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T01:09:12.074Z,1554858552.074 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T01:09:12.243Z,1554858552.243 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-10T01:09:12.243Z,1554858552.243 [RudderServo](INFO): Powering down
2019-04-10T01:09:12.446Z,1554858552.446 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:12.447Z,1554858552.447 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:12.447Z,1554858552.447 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:12.447Z,1554858552.447 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:12.447Z,1554858552.447 [BR_Ping1D](INFO): elapsed 0.9131
2019-04-10T01:09:12.447Z,1554858552.447 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:12.448Z,1554858552.448 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:12.448Z,1554858552.448 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:12.448Z,1554858552.448 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:12.963Z,1554858552.963 [PAR_Licor](INFO): A/D timeout, 8 tries over 131 ms

2019-04-10T01:09:12.963Z,1554858552.963 [PAR_Licor] Data Fault, FailCount= 1
2019-04-10T01:09:12.963Z,1554858552.963 [PAR_Licor](ERROR): Data Fault
2019-04-10T01:09:12.983Z,1554858552.983 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:12.983Z,1554858552.983 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:12.983Z,1554858552.983 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:12.983Z,1554858552.983 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:12.984Z,1554858552.984 [BR_Ping1D](INFO): elapsed 1.4494
2019-04-10T01:09:12.984Z,1554858552.984 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:12.984Z,1554858552.984 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:12.984Z,1554858552.984 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:12.984Z,1554858552.984 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:13.003Z,1554858553.003 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-10T01:09:13.123Z,1554858553.123 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-10T01:09:13.127Z,1554858553.127 [CBIT](INFO): Clearing failed state for component RudderServo
2019-04-10T01:09:13.127Z,1554858553.127 [RudderServo] No Fault, FailCount= 1
2019-04-10T01:09:13.128Z,1554858553.128 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T01:09:13.128Z,1554858553.128 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T01:09:13.128Z,1554858553.128 [PAR_Licor] No Fault, FailCount= 1
2019-04-10T01:09:13.257Z,1554858553.257 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:13.257Z,1554858553.257 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:13.258Z,1554858553.258 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:13.258Z,1554858553.258 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:13.258Z,1554858553.258 [BR_Ping1D](INFO): elapsed 1.7240
2019-04-10T01:09:13.258Z,1554858553.258 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:13.258Z,1554858553.258 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:13.259Z,1554858553.259 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:13.259Z,1554858553.259 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:13.774Z,1554858553.774 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-10T01:09:13.774Z,1554858553.774 [PAR_Licor] Data Fault, FailCount= 2
2019-04-10T01:09:13.774Z,1554858553.774 [PAR_Licor](ERROR): Data Fault
2019-04-10T01:09:13.811Z,1554858553.811 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:13.811Z,1554858553.811 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:13.811Z,1554858553.811 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:13.811Z,1554858553.811 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:13.811Z,1554858553.811 [BR_Ping1D](INFO): elapsed 2.2774
2019-04-10T01:09:13.812Z,1554858553.812 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:13.812Z,1554858553.812 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:13.812Z,1554858553.812 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:13.812Z,1554858553.812 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:13.990Z,1554858553.990 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T01:09:13.990Z,1554858553.990 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-10T01:09:13.990Z,1554858553.990 [PAR_Licor] No Fault, FailCount= 2
2019-04-10T01:09:14.061Z,1554858554.061 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:14.061Z,1554858554.061 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:14.062Z,1554858554.062 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:14.062Z,1554858554.062 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:14.062Z,1554858554.062 [BR_Ping1D](INFO): elapsed 2.5279
2019-04-10T01:09:14.062Z,1554858554.062 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:14.062Z,1554858554.062 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:14.063Z,1554858554.063 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:14.063Z,1554858554.063 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:14.590Z,1554858554.590 [PAR_Licor](INFO): A/D timeout, 8 tries over 142 ms

2019-04-10T01:09:14.590Z,1554858554.590 [PAR_Licor] Data Fault, FailCount= 3
2019-04-10T01:09:14.590Z,1554858554.590 [PAR_Licor](ERROR): Data Fault
2019-04-10T01:09:14.631Z,1554858554.631 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:14.631Z,1554858554.631 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:14.632Z,1554858554.632 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:14.632Z,1554858554.632 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:14.632Z,1554858554.632 [BR_Ping1D](INFO): elapsed 3.0980
2019-04-10T01:09:14.632Z,1554858554.632 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:14.632Z,1554858554.632 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:14.633Z,1554858554.633 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:14.633Z,1554858554.633 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:14.906Z,1554858554.906 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-10T01:09:14.907Z,1554858554.907 [CBIT](CRITICAL): Data Fault in component: PAR_Licor
2019-04-10T01:09:14.949Z,1554858554.949 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:14.949Z,1554858554.949 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:14.949Z,1554858554.949 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:14.949Z,1554858554.949 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:14.950Z,1554858554.950 [BR_Ping1D](INFO): elapsed 3.4156
2019-04-10T01:09:14.950Z,1554858554.950 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:14.950Z,1554858554.950 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:14.950Z,1554858554.950 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:14.950Z,1554858554.950 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:15.028Z,1554858555.028 [CBIT](INFO): Critical error at 20190410T010914
2019-04-10T01:09:15.333Z,1554858555.333 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:15.334Z,1554858555.334 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:15.334Z,1554858555.334 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:15.334Z,1554858555.334 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:15.334Z,1554858555.334 [BR_Ping1D](INFO): elapsed 3.8002
2019-04-10T01:09:15.334Z,1554858555.334 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:15.335Z,1554858555.335 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:15.335Z,1554858555.335 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:15.335Z,1554858555.335 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:15.737Z,1554858555.737 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:15.737Z,1554858555.737 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:15.738Z,1554858555.738 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:15.738Z,1554858555.738 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:15.738Z,1554858555.738 [BR_Ping1D](INFO): elapsed 4.2039
2019-04-10T01:09:15.738Z,1554858555.738 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:15.738Z,1554858555.738 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:15.739Z,1554858555.739 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:15.739Z,1554858555.739 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:16.142Z,1554858556.142 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:16.142Z,1554858556.142 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:16.142Z,1554858556.142 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:16.143Z,1554858556.143 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:16.143Z,1554858556.143 [BR_Ping1D](INFO): elapsed 4.6087
2019-04-10T01:09:16.143Z,1554858556.143 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:16.143Z,1554858556.143 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:16.143Z,1554858556.143 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:16.143Z,1554858556.143 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:16.545Z,1554858556.545 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:16.546Z,1554858556.546 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:16.546Z,1554858556.546 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:16.546Z,1554858556.546 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:16.546Z,1554858556.546 [BR_Ping1D](INFO): elapsed 5.0122
2019-04-10T01:09:16.546Z,1554858556.546 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:16.547Z,1554858556.547 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T01:09:16.547Z,1554858556.547 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-10T01:09:16.547Z,1554858556.547 [BR_Ping1D](ERROR): Data Fault
2019-04-10T01:09:16.922Z,1554858556.922 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T01:09:16.959Z,1554858556.959 [BR_Ping1D](INFO): Stop
2019-04-10T01:09:16.959Z,1554858556.959 [BR_Ping1D](INFO): uninitialize
2019-04-10T01:09:16.959Z,1554858556.959 [BR_Ping1D](INFO): Powering down
2019-04-10T01:09:17.350Z,1554858557.350 [BR_Ping1D](INFO): Stopping
2019-04-10T01:09:17.754Z,1554858557.754 [BR_Ping1D](INFO): Stopping
2019-04-10T01:09:18.157Z,1554858558.157 [BR_Ping1D](INFO): Stopped
2019-04-10T01:09:18.242Z,1554858558.242 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T01:09:18.242Z,1554858558.242 [BR_Ping1D] No Fault, FailCount= 1
2019-04-10T01:09:18.562Z,1554858558.562 [BR_Ping1D](INFO): Start
2019-04-10T01:09:18.965Z,1554858558.965 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:19.370Z,1554858559.370 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:19.370Z,1554858559.370 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:19.370Z,1554858559.370 [BR_Ping1D](INFO): startup: 0
2019-04-10T01:09:19.370Z,1554858559.370 [BR_Ping1D](INFO): recvData: ÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿ
2019-04-10T01:09:19.370Z,1554858559.370 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T01:09:19.382Z,1554858559.382 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T01:09:19.382Z,1554858559.382 [BR_Ping1D](INFO): XXX autoMode1: 01
2019-04-10T01:09:19.382Z,1554858559.382 [BR_Ping1D](INFO): XXX autoMode2: 
2019-04-10T01:09:19.382Z,1554858559.382 [BR_Ping1D](INFO): XXX autoMode3: 1
2019-04-10T01:09:19.383Z,1554858559.383 [BR_Ping1D](INFO): 42520100EB030000840100
2019-04-10T01:09:19.383Z,1554858559.383 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T01:09:19.383Z,1554858559.383 [BR_Ping1D](INFO): 42520100EE030000870116
2019-04-10T01:09:19.773Z,1554858559.773 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:19.934Z,1554858559.934 [CBIT](CRITICAL): Environmental Failure. Press:14.820937 PSI. Humidity:37%. Temp:25 C. ABORTING MISSION
2019-04-10T01:09:20.178Z,1554858560.178 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:20.178Z,1554858560.178 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:20.178Z,1554858560.178 [BR_Ping1D](INFO): startup: 1
2019-04-10T01:09:20.178Z,1554858560.178 [BR_Ping1D](INFO): UART requesting status
2019-04-10T01:09:20.178Z,1554858560.178 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T01:09:20.186Z,1554858560.186 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T01:09:20.194Z,1554858560.194 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T01:09:20.227Z,1554858560.227 [CBIT](INFO): Critical error at 20190410T010919
2019-04-10T01:09:20.562Z,1554858560.562 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T01:09:20.562Z,1554858560.562 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-10T01:09:20.563Z,1554858560.563 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T01:09:20.581Z,1554858560.581 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:20.581Z,1554858560.581 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:20.581Z,1554858560.581 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:20.582Z,1554858560.582 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:20.582Z,1554858560.582 [BR_Ping1D](INFO): elapsed 1.2113
2019-04-10T01:09:20.582Z,1554858560.582 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:20.582Z,1554858560.582 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:20.582Z,1554858560.582 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:20.583Z,1554858560.583 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:20.615Z,1554858560.615 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T01:09:21.086Z,1554858561.086 [Aanderaa_O2](INFO): Powering down
2019-04-10T01:09:21.159Z,1554858561.159 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:21.159Z,1554858561.159 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:21.159Z,1554858561.159 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:21.159Z,1554858561.159 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:21.159Z,1554858561.159 [BR_Ping1D](INFO): elapsed 1.7889
2019-04-10T01:09:21.160Z,1554858561.160 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:21.160Z,1554858561.160 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:21.160Z,1554858561.160 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:21.160Z,1554858561.160 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:21.389Z,1554858561.389 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:21.389Z,1554858561.389 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:21.389Z,1554858561.389 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:21.390Z,1554858561.390 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:21.390Z,1554858561.390 [BR_Ping1D](INFO): elapsed 2.0194
2019-04-10T01:09:21.390Z,1554858561.390 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:21.390Z,1554858561.390 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:21.390Z,1554858561.390 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:21.391Z,1554858561.391 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:21.794Z,1554858561.794 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:21.794Z,1554858561.794 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:21.794Z,1554858561.794 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:21.794Z,1554858561.794 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:21.794Z,1554858561.794 [BR_Ping1D](INFO): elapsed 2.4240
2019-04-10T01:09:21.795Z,1554858561.795 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:21.795Z,1554858561.795 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:21.795Z,1554858561.795 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:21.795Z,1554858561.795 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:21.827Z,1554858561.827 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T01:09:21.827Z,1554858561.827 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-10T01:09:22.198Z,1554858562.198 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:22.198Z,1554858562.198 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:22.198Z,1554858562.198 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:22.199Z,1554858562.199 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:22.199Z,1554858562.199 [BR_Ping1D](INFO): elapsed 2.8283
2019-04-10T01:09:22.199Z,1554858562.199 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:22.199Z,1554858562.199 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:22.199Z,1554858562.199 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:22.199Z,1554858562.199 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:22.614Z,1554858562.614 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:22.614Z,1554858562.614 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:22.614Z,1554858562.614 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:22.614Z,1554858562.614 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:22.615Z,1554858562.615 [BR_Ping1D](INFO): elapsed 3.2441
2019-04-10T01:09:22.615Z,1554858562.615 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:22.615Z,1554858562.615 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:22.615Z,1554858562.615 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:22.615Z,1554858562.615 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:22.986Z,1554858562.986 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T01:09:22.986Z,1554858562.986 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-10T01:09:22.986Z,1554858562.986 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T01:09:23.005Z,1554858563.005 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:23.005Z,1554858563.005 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:23.006Z,1554858563.006 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:23.006Z,1554858563.006 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:23.006Z,1554858563.006 [BR_Ping1D](INFO): elapsed 3.6357
2019-04-10T01:09:23.006Z,1554858563.006 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:23.007Z,1554858563.007 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:23.007Z,1554858563.007 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:23.007Z,1554858563.007 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:23.023Z,1554858563.023 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T01:09:23.390Z,1554858563.390 [CTD_Seabird](INFO): Powering down
2019-04-10T01:09:23.430Z,1554858563.430 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:23.430Z,1554858563.430 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:23.431Z,1554858563.431 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:23.431Z,1554858563.431 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:23.431Z,1554858563.431 [BR_Ping1D](INFO): elapsed 4.0604
2019-04-10T01:09:23.431Z,1554858563.431 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:23.431Z,1554858563.431 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:23.431Z,1554858563.431 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:23.432Z,1554858563.432 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:23.813Z,1554858563.813 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:23.814Z,1554858563.814 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:23.814Z,1554858563.814 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:23.814Z,1554858563.814 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:23.814Z,1554858563.814 [BR_Ping1D](INFO): elapsed 4.4437
2019-04-10T01:09:23.814Z,1554858563.814 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:23.815Z,1554858563.815 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:23.815Z,1554858563.815 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:23.815Z,1554858563.815 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:24.218Z,1554858564.218 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:24.218Z,1554858564.218 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:24.218Z,1554858564.218 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:24.218Z,1554858564.218 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:24.219Z,1554858564.218 [BR_Ping1D](INFO): elapsed 4.8480
2019-04-10T01:09:24.219Z,1554858564.219 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:24.219Z,1554858564.219 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:24.219Z,1554858564.219 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:24.219Z,1554858564.219 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:24.622Z,1554858564.622 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:24.622Z,1554858564.622 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:24.622Z,1554858564.622 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:24.622Z,1554858564.622 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:24.622Z,1554858564.622 [BR_Ping1D](INFO): elapsed 5.2519
2019-04-10T01:09:24.623Z,1554858564.623 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:24.623Z,1554858564.623 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T01:09:24.623Z,1554858564.623 [BR_Ping1D] Data Fault, FailCount= 2
2019-04-10T01:09:24.623Z,1554858564.623 [BR_Ping1D](ERROR): Data Fault
2019-04-10T01:09:24.647Z,1554858564.647 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-10T01:09:24.647Z,1554858564.647 [CTD_Seabird] No Fault, FailCount= 1
2019-04-10T01:09:24.648Z,1554858564.648 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T01:09:24.754Z,1554858564.754 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T01:09:24.756Z,1554858564.756 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T01:09:25.026Z,1554858565.026 [BR_Ping1D](INFO): Stop
2019-04-10T01:09:25.026Z,1554858565.026 [BR_Ping1D](INFO): uninitialize
2019-04-10T01:09:25.026Z,1554858565.026 [BR_Ping1D](INFO): Powering down
2019-04-10T01:09:25.430Z,1554858565.430 [BR_Ping1D](INFO): Stopping
2019-04-10T01:09:25.834Z,1554858565.834 [BR_Ping1D](INFO): Stopping
2019-04-10T01:09:26.237Z,1554858566.237 [BR_Ping1D](INFO): Stopped
2019-04-10T01:09:26.251Z,1554858566.251 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T01:09:26.251Z,1554858566.251 [BR_Ping1D] No Fault, FailCount= 2
2019-04-10T01:09:26.642Z,1554858566.642 [BR_Ping1D](INFO): Start
2019-04-10T01:09:27.046Z,1554858567.046 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:27.450Z,1554858567.450 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:27.450Z,1554858567.450 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:27.451Z,1554858567.451 [BR_Ping1D](INFO): startup: 0
2019-04-10T01:09:27.451Z,1554858567.451 [BR_Ping1D](INFO): recvData: ÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿ
2019-04-10T01:09:27.451Z,1554858567.451 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T01:09:27.462Z,1554858567.462 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T01:09:27.462Z,1554858567.462 [BR_Ping1D](INFO): XXX autoMode1: 01
2019-04-10T01:09:27.462Z,1554858567.462 [BR_Ping1D](INFO): XXX autoMode2: 
2019-04-10T01:09:27.463Z,1554858567.463 [BR_Ping1D](INFO): XXX autoMode3: 1
2019-04-10T01:09:27.463Z,1554858567.463 [BR_Ping1D](INFO): 42520100EB030000840100
2019-04-10T01:09:27.463Z,1554858567.463 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T01:09:27.463Z,1554858567.463 [BR_Ping1D](INFO): 42520100EE030000870116
2019-04-10T01:09:27.854Z,1554858567.854 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:28.263Z,1554858568.263 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:28.263Z,1554858568.263 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:28.263Z,1554858568.263 [BR_Ping1D](INFO): startup: 1
2019-04-10T01:09:28.263Z,1554858568.263 [BR_Ping1D](INFO): UART requesting status
2019-04-10T01:09:28.263Z,1554858568.263 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T01:09:28.270Z,1554858568.270 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T01:09:28.278Z,1554858568.278 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T01:09:28.662Z,1554858568.662 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:28.662Z,1554858568.662 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:28.662Z,1554858568.662 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:28.663Z,1554858568.663 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:28.663Z,1554858568.663 [BR_Ping1D](INFO): elapsed 1.2117
2019-04-10T01:09:28.663Z,1554858568.663 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:28.663Z,1554858568.663 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:28.663Z,1554858568.663 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:28.663Z,1554858568.663 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:29.065Z,1554858569.065 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:29.066Z,1554858569.066 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:29.066Z,1554858569.066 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:29.066Z,1554858569.066 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:29.066Z,1554858569.066 [BR_Ping1D](INFO): elapsed 1.6151
2019-04-10T01:09:29.066Z,1554858569.066 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:29.067Z,1554858569.067 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:29.067Z,1554858569.067 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:29.067Z,1554858569.067 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:29.469Z,1554858569.469 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:29.469Z,1554858569.469 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:29.470Z,1554858569.470 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:29.470Z,1554858569.470 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:29.470Z,1554858569.470 [BR_Ping1D](INFO): elapsed 2.0190
2019-04-10T01:09:29.470Z,1554858569.470 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:29.471Z,1554858569.471 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:29.471Z,1554858569.471 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:29.471Z,1554858569.471 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:29.873Z,1554858569.873 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:29.874Z,1554858569.874 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:29.874Z,1554858569.874 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:29.874Z,1554858569.874 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:29.874Z,1554858569.874 [BR_Ping1D](INFO): elapsed 2.4231
2019-04-10T01:09:29.874Z,1554858569.874 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:29.875Z,1554858569.875 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:29.875Z,1554858569.875 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:29.875Z,1554858569.875 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:30.277Z,1554858570.277 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:30.277Z,1554858570.277 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:30.277Z,1554858570.277 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:30.278Z,1554858570.278 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:30.280Z,1554858570.280 [BR_Ping1D](INFO): elapsed 2.8266
2019-04-10T01:09:30.280Z,1554858570.280 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:30.281Z,1554858570.281 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:30.281Z,1554858570.281 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:30.281Z,1554858570.281 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:30.682Z,1554858570.682 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:30.682Z,1554858570.682 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:30.682Z,1554858570.682 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:30.682Z,1554858570.682 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:30.682Z,1554858570.682 [BR_Ping1D](INFO): elapsed 3.2313
2019-04-10T01:09:30.683Z,1554858570.683 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:30.683Z,1554858570.683 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:30.683Z,1554858570.683 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:30.683Z,1554858570.683 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:31.066Z,1554858571.066 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T01:09:31.067Z,1554858571.067 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-04-10T01:09:31.067Z,1554858571.067 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T01:09:31.085Z,1554858571.085 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:31.085Z,1554858571.085 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:31.085Z,1554858571.085 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:31.086Z,1554858571.086 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:31.086Z,1554858571.086 [BR_Ping1D](INFO): elapsed 3.6348
2019-04-10T01:09:31.086Z,1554858571.086 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:31.086Z,1554858571.086 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:31.086Z,1554858571.086 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:31.087Z,1554858571.087 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:31.103Z,1554858571.103 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T01:09:31.470Z,1554858571.470 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T01:09:31.626Z,1554858571.626 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:31.627Z,1554858571.627 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:31.627Z,1554858571.627 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:31.627Z,1554858571.627 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:31.627Z,1554858571.627 [BR_Ping1D](INFO): elapsed 4.1761
2019-04-10T01:09:31.627Z,1554858571.627 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:31.628Z,1554858571.628 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:31.628Z,1554858571.628 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:31.628Z,1554858571.628 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:31.893Z,1554858571.893 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:31.893Z,1554858571.893 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:31.894Z,1554858571.894 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:31.894Z,1554858571.894 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:31.894Z,1554858571.894 [BR_Ping1D](INFO): elapsed 4.4430
2019-04-10T01:09:31.894Z,1554858571.894 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:31.895Z,1554858571.895 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:31.895Z,1554858571.895 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:31.895Z,1554858571.895 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:32.278Z,1554858572.278 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T01:09:32.278Z,1554858572.278 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-10T01:09:32.278Z,1554858572.278 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T01:09:32.297Z,1554858572.297 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:32.298Z,1554858572.298 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:32.298Z,1554858572.298 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:32.298Z,1554858572.298 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:32.298Z,1554858572.298 [BR_Ping1D](INFO): elapsed 4.8471
2019-04-10T01:09:32.298Z,1554858572.298 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:32.299Z,1554858572.299 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:32.299Z,1554858572.299 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:32.299Z,1554858572.299 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:32.327Z,1554858572.327 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T01:09:32.327Z,1554858572.327 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T01:09:32.327Z,1554858572.327 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-04-10T01:09:32.802Z,1554858572.802 [Aanderaa_O2](INFO): Powering down
2019-04-10T01:09:32.821Z,1554858572.821 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:32.822Z,1554858572.822 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:32.822Z,1554858572.822 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:32.822Z,1554858572.822 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:32.822Z,1554858572.822 [BR_Ping1D](INFO): elapsed 5.3712
2019-04-10T01:09:32.822Z,1554858572.822 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:32.823Z,1554858572.823 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T01:09:32.823Z,1554858572.823 [BR_Ping1D] Data Fault, FailCount= 3
2019-04-10T01:09:32.823Z,1554858572.823 [BR_Ping1D](ERROR): Data Fault
2019-04-10T01:09:32.851Z,1554858572.851 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T01:09:33.114Z,1554858573.114 [BR_Ping1D](INFO): Stop
2019-04-10T01:09:33.114Z,1554858573.114 [BR_Ping1D](INFO): uninitialize
2019-04-10T01:09:33.114Z,1554858573.114 [BR_Ping1D](INFO): Powering down
2019-04-10T01:09:33.510Z,1554858573.510 [BR_Ping1D](INFO): Stopping
2019-04-10T01:09:33.526Z,1554858573.526 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T01:09:33.527Z,1554858573.527 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-10T01:09:33.922Z,1554858573.922 [BR_Ping1D](INFO): Stopping
2019-04-10T01:09:34.330Z,1554858574.330 [BR_Ping1D](INFO): Stopped
2019-04-10T01:09:34.356Z,1554858574.356 [SBIT](IMPORTANT): Beginning Startup BIT
2019-04-10T01:09:34.365Z,1554858574.365 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T01:09:34.365Z,1554858574.365 [BR_Ping1D] No Fault, FailCount= 3
2019-04-10T01:09:34.365Z,1554858574.365 [CBIT](IMPORTANT): Beginning ground fault scan
2019-04-10T01:09:34.702Z,1554858574.702 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T01:09:34.703Z,1554858574.703 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T01:09:34.741Z,1554858574.741 [BR_Ping1D](INFO): Start
2019-04-10T01:09:35.125Z,1554858575.125 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:35.531Z,1554858575.531 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:35.531Z,1554858575.531 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:35.532Z,1554858575.532 [BR_Ping1D](INFO): startup: 0
2019-04-10T01:09:35.532Z,1554858575.532 [BR_Ping1D](INFO): recvData: ÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿ
2019-04-10T01:09:35.532Z,1554858575.532 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T01:09:35.546Z,1554858575.546 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T01:09:35.546Z,1554858575.546 [BR_Ping1D](INFO): XXX autoMode1: 01
2019-04-10T01:09:35.546Z,1554858575.546 [BR_Ping1D](INFO): XXX autoMode2: 
2019-04-10T01:09:35.547Z,1554858575.547 [BR_Ping1D](INFO): XXX autoMode3: 1
2019-04-10T01:09:35.547Z,1554858575.547 [BR_Ping1D](INFO): 42520100EB030000840100
2019-04-10T01:09:35.547Z,1554858575.547 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T01:09:35.547Z,1554858575.547 [BR_Ping1D](INFO): 42520100EE030000870116
2019-04-10T01:09:35.933Z,1554858575.933 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:36.337Z,1554858576.337 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:36.338Z,1554858576.338 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:36.338Z,1554858576.338 [BR_Ping1D](INFO): startup: 1
2019-04-10T01:09:36.338Z,1554858576.338 [BR_Ping1D](INFO): UART requesting status
2019-04-10T01:09:36.338Z,1554858576.338 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T01:09:36.346Z,1554858576.346 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T01:09:36.354Z,1554858576.354 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T01:09:36.843Z,1554858576.843 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:36.843Z,1554858576.843 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:36.843Z,1554858576.843 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:36.843Z,1554858576.843 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:36.843Z,1554858576.843 [BR_Ping1D](INFO): elapsed 1.3113
2019-04-10T01:09:36.843Z,1554858576.843 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:36.844Z,1554858576.844 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:36.844Z,1554858576.844 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:36.844Z,1554858576.844 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:37.231Z,1554858577.231 [NAL9602](INFO): Powering up NAL9602
2019-04-10T01:09:37.247Z,1554858577.247 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:37.247Z,1554858577.247 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:37.247Z,1554858577.247 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:37.248Z,1554858577.248 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:37.248Z,1554858577.248 [BR_Ping1D](INFO): elapsed 1.7158
2019-04-10T01:09:37.248Z,1554858577.248 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:37.248Z,1554858577.248 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:37.248Z,1554858577.248 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:37.248Z,1554858577.248 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:37.649Z,1554858577.649 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:37.649Z,1554858577.649 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:37.649Z,1554858577.649 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:37.650Z,1554858577.650 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:37.650Z,1554858577.650 [BR_Ping1D](INFO): elapsed 2.1179
2019-04-10T01:09:37.650Z,1554858577.650 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:37.650Z,1554858577.650 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:37.650Z,1554858577.650 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:37.651Z,1554858577.651 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:37.806Z,1554858577.806 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T01:09:37.806Z,1554858577.806 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-10T01:09:37.806Z,1554858577.806 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T01:09:38.054Z,1554858578.054 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:38.054Z,1554858578.054 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:38.054Z,1554858578.054 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:38.054Z,1554858578.054 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:38.055Z,1554858578.055 [BR_Ping1D](INFO): elapsed 2.5227
2019-04-10T01:09:38.055Z,1554858578.055 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:38.055Z,1554858578.055 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:38.055Z,1554858578.055 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:38.055Z,1554858578.055 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:38.210Z,1554858578.210 [CTD_Seabird](INFO): Powering down
2019-04-10T01:09:38.252Z,1554858578.252 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T01:09:38.457Z,1554858578.457 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:38.457Z,1554858578.457 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:38.457Z,1554858578.457 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:38.458Z,1554858578.458 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:38.458Z,1554858578.458 [BR_Ping1D](INFO): elapsed 2.9259
2019-04-10T01:09:38.458Z,1554858578.458 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:38.458Z,1554858578.458 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:38.458Z,1554858578.458 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:38.459Z,1554858578.459 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:38.862Z,1554858578.862 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:38.862Z,1554858578.862 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:38.862Z,1554858578.862 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:38.862Z,1554858578.862 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:38.862Z,1554858578.862 [BR_Ping1D](INFO): elapsed 3.3303
2019-04-10T01:09:38.863Z,1554858578.863 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:38.863Z,1554858578.863 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:38.863Z,1554858578.863 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:38.863Z,1554858578.863 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:39.265Z,1554858579.265 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:39.265Z,1554858579.265 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:39.266Z,1554858579.266 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:39.266Z,1554858579.266 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:39.266Z,1554858579.266 [BR_Ping1D](INFO): elapsed 3.7341
2019-04-10T01:09:39.266Z,1554858579.266 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:39.267Z,1554858579.267 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:39.267Z,1554858579.267 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:39.267Z,1554858579.267 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:39.443Z,1554858579.443 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-10T01:09:39.443Z,1554858579.443 [CTD_Seabird] No Fault, FailCount= 2
2019-04-10T01:09:39.554Z,1554858579.554 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T01:09:39.562Z,1554858579.562 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T01:09:39.670Z,1554858579.670 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:39.670Z,1554858579.670 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:39.670Z,1554858579.670 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:39.670Z,1554858579.670 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:39.671Z,1554858579.671 [BR_Ping1D](INFO): elapsed 4.1385
2019-04-10T01:09:39.671Z,1554858579.671 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:39.671Z,1554858579.671 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:39.671Z,1554858579.671 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:39.671Z,1554858579.671 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:40.073Z,1554858580.073 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:40.073Z,1554858580.073 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:40.073Z,1554858580.073 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:40.074Z,1554858580.074 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:40.074Z,1554858580.074 [BR_Ping1D](INFO): elapsed 4.5419
2019-04-10T01:09:40.074Z,1554858580.074 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:40.074Z,1554858580.074 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:40.074Z,1554858580.074 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:40.075Z,1554858580.075 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:40.477Z,1554858580.477 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:40.478Z,1554858580.478 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:40.478Z,1554858580.478 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:40.478Z,1554858580.478 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:40.478Z,1554858580.478 [BR_Ping1D](INFO): elapsed 4.9462
2019-04-10T01:09:40.478Z,1554858580.478 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:40.479Z,1554858580.479 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:40.479Z,1554858580.479 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:40.479Z,1554858580.479 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:40.881Z,1554858580.881 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:40.881Z,1554858580.881 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:40.882Z,1554858580.882 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:40.882Z,1554858580.882 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:40.882Z,1554858580.882 [BR_Ping1D](INFO): elapsed 5.3499
2019-04-10T01:09:40.882Z,1554858580.882 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:40.882Z,1554858580.882 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T01:09:40.882Z,1554858580.882 [BR_Ping1D] Data Fault, FailCount= 4
2019-04-10T01:09:40.882Z,1554858580.882 [BR_Ping1D](ERROR): Data Fault
2019-04-10T01:09:40.947Z,1554858580.947 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T01:09:41.285Z,1554858581.285 [BR_Ping1D](INFO): Stop
2019-04-10T01:09:41.286Z,1554858581.286 [BR_Ping1D](INFO): uninitialize
2019-04-10T01:09:41.286Z,1554858581.286 [BR_Ping1D](INFO): Powering down
2019-04-10T01:09:41.690Z,1554858581.690 [BR_Ping1D](INFO): Stopping
2019-04-10T01:09:42.091Z,1554858582.091 [BR_Ping1D](INFO): Stopping
2019-04-10T01:09:42.497Z,1554858582.497 [BR_Ping1D](INFO): Stopped
2019-04-10T01:09:42.555Z,1554858582.555 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-10T01:09:42.555Z,1554858582.555 [BR_Ping1D] No Fault, FailCount= 4
2019-04-10T01:09:42.902Z,1554858582.902 [BR_Ping1D](INFO): Start
2019-04-10T01:09:43.305Z,1554858583.305 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:43.711Z,1554858583.711 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:43.711Z,1554858583.711 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:43.711Z,1554858583.711 [BR_Ping1D](INFO): startup: 0
2019-04-10T01:09:43.711Z,1554858583.711 [BR_Ping1D](INFO): recvData: ÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿ
2019-04-10T01:09:43.711Z,1554858583.711 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-10T01:09:43.722Z,1554858583.722 [BR_Ping1D](INFO): 42520200EC030000F4017A02
2019-04-10T01:09:43.722Z,1554858583.722 [BR_Ping1D](INFO): XXX autoMode1: 01
2019-04-10T01:09:43.722Z,1554858583.722 [BR_Ping1D](INFO): XXX autoMode2: 
2019-04-10T01:09:43.722Z,1554858583.722 [BR_Ping1D](INFO): XXX autoMode3: 1
2019-04-10T01:09:43.723Z,1554858583.723 [BR_Ping1D](INFO): 42520100EB030000840100
2019-04-10T01:09:43.723Z,1554858583.723 [BR_Ping1D](INFO): 42520400EA03000060E31600DE02
2019-04-10T01:09:43.723Z,1554858583.723 [BR_Ping1D](INFO): 42520100EE030000870116
2019-04-10T01:09:44.095Z,1554858584.095 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T01:09:44.095Z,1554858584.095 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-10T01:09:44.095Z,1554858584.095 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T01:09:44.113Z,1554858584.113 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:44.179Z,1554858584.179 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T01:09:44.180Z,1554858584.180 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-10T01:09:44.618Z,1554858584.618 [Aanderaa_O2](INFO): Powering down
2019-04-10T01:09:44.638Z,1554858584.638 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:44.638Z,1554858584.638 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:44.638Z,1554858584.638 [BR_Ping1D](INFO): startup: 1
2019-04-10T01:09:44.638Z,1554858584.638 [BR_Ping1D](INFO): UART requesting status
2019-04-10T01:09:44.639Z,1554858584.639 [BR_Ping1D](INFO): 42520000BA0400005201
2019-04-10T01:09:44.646Z,1554858584.646 [BR_Ping1D](INFO): 42520000BF0400005701
2019-04-10T01:09:44.654Z,1554858584.654 [BR_Ping1D](INFO): 42520000B30400004B01
2019-04-10T01:09:44.921Z,1554858584.921 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:44.921Z,1554858584.921 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:44.921Z,1554858584.921 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:44.922Z,1554858584.922 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:44.922Z,1554858584.922 [BR_Ping1D](INFO): elapsed 1.2105
2019-04-10T01:09:44.922Z,1554858584.922 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:44.922Z,1554858584.922 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:44.922Z,1554858584.922 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:44.923Z,1554858584.923 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:45.326Z,1554858585.326 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:45.326Z,1554858585.326 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:45.326Z,1554858585.326 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:45.326Z,1554858585.326 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:45.327Z,1554858585.327 [BR_Ping1D](INFO): elapsed 1.6153
2019-04-10T01:09:45.327Z,1554858585.327 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:45.327Z,1554858585.327 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:45.327Z,1554858585.327 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:45.327Z,1554858585.327 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:45.383Z,1554858585.383 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.006919
CHAN A1 (24V): -0.127856
CHAN A2 (12V): -0.005917
CHAN A3 (5V): -0.002940
CHAN B0 (3.3V): -0.001269
CHAN B1 (3.15aV): -0.000861
CHAN B2 (3.15bV): -0.000799
CHAN B3 (GND): 0.000185
OPEN: -0.000959
Full Scale Calc: 4.765 mA, -1.589 mA
2019-04-10T01:09:45.730Z,1554858585.730 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:45.730Z,1554858585.730 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:45.730Z,1554858585.730 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:45.730Z,1554858585.730 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:45.731Z,1554858585.731 [BR_Ping1D](INFO): elapsed 2.0194
2019-04-10T01:09:45.731Z,1554858585.731 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:45.731Z,1554858585.731 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:45.731Z,1554858585.731 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:45.731Z,1554858585.731 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:46.134Z,1554858586.134 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:46.134Z,1554858586.134 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:46.134Z,1554858586.134 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:46.134Z,1554858586.134 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:46.134Z,1554858586.134 [BR_Ping1D](INFO): elapsed 2.4230
2019-04-10T01:09:46.134Z,1554858586.134 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:46.135Z,1554858586.135 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:46.135Z,1554858586.135 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:46.135Z,1554858586.135 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:46.537Z,1554858586.537 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:46.537Z,1554858586.537 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:46.538Z,1554858586.538 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:46.538Z,1554858586.538 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:46.538Z,1554858586.538 [BR_Ping1D](INFO): elapsed 2.8268
2019-04-10T01:09:46.538Z,1554858586.538 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:46.539Z,1554858586.539 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:46.539Z,1554858586.539 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:46.539Z,1554858586.539 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:46.942Z,1554858586.942 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:46.942Z,1554858586.942 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:46.942Z,1554858586.942 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:46.942Z,1554858586.942 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:46.942Z,1554858586.942 [BR_Ping1D](INFO): elapsed 3.2310
2019-04-10T01:09:46.943Z,1554858586.943 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:46.943Z,1554858586.943 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:46.943Z,1554858586.943 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:46.943Z,1554858586.943 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:47.345Z,1554858587.345 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:47.345Z,1554858587.345 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:47.346Z,1554858587.346 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:47.346Z,1554858587.346 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:47.346Z,1554858587.346 [BR_Ping1D](INFO): elapsed 3.6347
2019-04-10T01:09:47.346Z,1554858587.346 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:47.346Z,1554858587.346 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:47.347Z,1554858587.347 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:47.347Z,1554858587.347 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:47.761Z,1554858587.761 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:47.761Z,1554858587.761 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:47.761Z,1554858587.761 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:47.761Z,1554858587.761 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:47.762Z,1554858587.762 [BR_Ping1D](INFO): elapsed 4.0502
2019-04-10T01:09:47.762Z,1554858587.762 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:47.762Z,1554858587.762 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:47.762Z,1554858587.762 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:47.762Z,1554858587.762 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:48.139Z,1554858588.139 [NAL9602](INFO): NAL9602 initialized
2019-04-10T01:09:48.154Z,1554858588.154 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:48.154Z,1554858588.154 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:48.154Z,1554858588.154 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:48.154Z,1554858588.154 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:48.154Z,1554858588.154 [BR_Ping1D](INFO): elapsed 4.4431
2019-04-10T01:09:48.155Z,1554858588.155 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:48.155Z,1554858588.155 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:48.155Z,1554858588.155 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:48.155Z,1554858588.155 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:48.567Z,1554858588.567 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:48.567Z,1554858588.567 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:48.567Z,1554858588.567 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:48.567Z,1554858588.567 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:48.568Z,1554858588.568 [BR_Ping1D](INFO): elapsed 4.8563
2019-04-10T01:09:48.568Z,1554858588.568 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:48.568Z,1554858588.568 [BR_Ping1D](INFO): No UART data
2019-04-10T01:09:48.568Z,1554858588.568 [BR_Ping1D](INFO): received UART packets: 0
2019-04-10T01:09:48.568Z,1554858588.568 [BR_Ping1D](INFO): not all values verified yet
2019-04-10T01:09:48.958Z,1554858588.958 [NAL9602](DEBUG): Fix Requested
2019-04-10T01:09:48.973Z,1554858588.973 [BR_Ping1D](INFO): Starting
2019-04-10T01:09:48.973Z,1554858588.973 [BR_Ping1D](INFO): readConfig
2019-04-10T01:09:48.973Z,1554858588.973 [BR_Ping1D](INFO): startup: 2
2019-04-10T01:09:48.973Z,1554858588.973 [BR_Ping1D](INFO): UART waiting for response
2019-04-10T01:09:48.973Z,1554858588.973 [BR_Ping1D](INFO): elapsed 5.2621
2019-04-10T01:09:48.974Z,1554858588.974 [BR_Ping1D](INFO): timeout 5.0000
2019-04-10T01:09:48.974Z,1554858588.974 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-04-10T01:09:48.974Z,1554858588.974 [BR_Ping1D] Data Fault, FailCount= 5
2019-04-10T01:09:48.974Z,1554858588.974 [BR_Ping1D](ERROR): Data Fault
2019-04-10T01:09:49.035Z,1554858589.035 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-10T01:09:49.035Z,1554858589.035 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-04-10T01:09:49.381Z,1554858589.381 [BR_Ping1D](INFO): Stop
2019-04-10T01:09:49.381Z,1554858589.381 [BR_Ping1D](INFO): uninitialize
2019-04-10T01:09:49.381Z,1554858589.381 [BR_Ping1D](INFO): Powering down
2019-04-10T01:09:49.981Z,1554858589.981 [CBIT](INFO): Critical error at 20190410T010949
2019-04-10T01:09:50.041Z,1554858590.041 [BR_Ping1D](INFO): Stopping
2019-04-10T01:09:50.410Z,1554858590.410 [BR_Ping1D](INFO): Stopped
2019-04-10T01:09:52.606Z,1554858592.606 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T01:09:52.606Z,1554858592.606 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-10T01:09:52.606Z,1554858592.606 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T01:09:52.947Z,1554858592.947 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T01:09:52.948Z,1554858592.948 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-10T01:09:53.010Z,1554858593.010 [CTD_Seabird](INFO): Powering down
2019-04-10T01:09:54.835Z,1554858594.835 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T01:09:54.835Z,1554858594.835 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2019-04-10T01:09:54.835Z,1554858594.835 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T01:09:55.091Z,1554858595.091 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T01:09:55.238Z,1554858595.238 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T01:09:56.124Z,1554858596.124 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T01:09:56.124Z,1554858596.124 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2019-04-10T01:09:58.471Z,1554858598.471 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T01:09:58.471Z,1554858598.471 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T01:10:17.974Z,1554858617.974 [BPC1](ERROR): BPC1A: No match for serial number 04CC in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2019-04-10T01:10:17.975Z,1554858617.975 [BPC1](ERROR): BPC1B: No match for serial number 018D in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2019-04-10T01:10:17.975Z,1554858617.975 [BPC1](FAULT): Failed to parse data from all battery packs.
2019-04-10T01:10:17.975Z,1554858617.975 [BPC1] Data Fault, FailCount= 1
2019-04-10T01:10:17.975Z,1554858617.975 [BPC1](ERROR): Data Fault
2019-04-10T01:10:18.091Z,1554858618.091 [CBIT](ERROR): Data Fault in component: BPC1
2019-04-10T01:10:18.747Z,1554858618.747 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T01:10:18.747Z,1554858618.747 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2019-04-10T01:10:18.747Z,1554858618.747 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T01:10:18.963Z,1554858618.963 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T01:10:19.150Z,1554858619.150 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T01:10:20.067Z,1554858620.067 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T01:10:20.067Z,1554858620.067 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2019-04-10T01:10:22.414Z,1554858622.414 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T01:10:22.415Z,1554858622.415 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T01:10:27.712Z,1554858627.712 [SBIT](IMPORTANT): SBIT PASSED
2019-04-10T01:10:27.727Z,1554858627.727 [CommandLine](IMPORTANT): got command configSet list
2019-04-10T01:10:27.727Z,1554858627.727 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-04-10T01:10:27.728Z,1554858627.728 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

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

2019-04-10T01:10:28.110Z,1554858628.110 [MissionManager](IMPORTANT): Started mission Startup
2019-04-10T01:10:28.111Z,1554858628.111 [Startup] Running Loop=1
2019-04-10T01:10:28.111Z,1554858628.111 [Startup](DEBUG): Aggregate::initialize Startup
2019-04-10T01:10:28.111Z,1554858628.111 [Startup:A.GoToSurface] Running Loop=1
2019-04-10T01:10:28.111Z,1554858628.111 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-10T01:10:28.112Z,1554858628.112 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-10T01:10:28.112Z,1554858628.112 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-10T01:10:28.112Z,1554858628.112 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-10T01:10:28.113Z,1554858628.113 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-10T01:10:28.113Z,1554858628.113 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-10T01:10:28.116Z,1554858628.116 [Startup:StartupSatComms] Running Loop=1
2019-04-10T01:10:28.116Z,1554858628.116 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-04-10T01:10:28.116Z,1554858628.116 [Startup:StartupSatComms:A] Running Loop=1
2019-04-10T01:10:28.515Z,1554858628.515 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-04-10T01:10:42.614Z,1554858642.614 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T01:10:42.615Z,1554858642.615 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2019-04-10T01:10:42.615Z,1554858642.615 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T01:10:42.652Z,1554858642.652 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T01:10:43.018Z,1554858643.018 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T01:10:43.874Z,1554858643.874 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T01:10:43.874Z,1554858643.874 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2019-04-10T01:10:46.250Z,1554858646.250 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T01:10:46.251Z,1554858646.251 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T01:11:06.451Z,1554858666.451 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T01:11:06.451Z,1554858666.451 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2019-04-10T01:11:06.451Z,1554858666.451 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T01:11:06.489Z,1554858666.489 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T01:11:06.854Z,1554858666.854 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T01:11:07.700Z,1554858667.700 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T01:11:07.700Z,1554858667.700 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2019-04-10T01:11:10.087Z,1554858670.087 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T01:11:10.087Z,1554858670.087 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T01:11:10.152Z,1554858670.152 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T01:11:10.152Z,1554858670.152 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-04-10T01:11:10.152Z,1554858670.152 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-10T01:11:10.155Z,1554858670.155 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T01:11:10.156Z,1554858670.156 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-04-10T01:11:10.156Z,1554858670.156 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-10T01:11:10.157Z,1554858670.157 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T01:11:10.158Z,1554858670.158 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-04-10T01:11:10.158Z,1554858670.158 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-10T01:11:10.168Z,1554858670.168 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-10T01:11:10.169Z,1554858670.169 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-10T01:11:10.169Z,1554858670.169 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-10T01:11:10.533Z,1554858670.533 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-10T01:11:10.533Z,1554858670.533 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-04-10T01:11:10.534Z,1554858670.534 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-10T01:11:10.534Z,1554858670.534 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-04-10T01:11:10.534Z,1554858670.534 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-10T01:11:10.534Z,1554858670.534 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-04-10T01:11:10.933Z,1554858670.933 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-10T01:11:28.296Z,1554858688.296 [Startup:StartupSatComms:A](INFO): Timed out from 2019-04-10T01:10:28.1Z
2019-04-10T01:11:28.296Z,1554858688.296 [Startup:StartupSatComms:A] Stopped
2019-04-10T01:11:28.296Z,1554858688.296 [Startup:StartupSatComms:B] Running Loop=1
2019-04-10T01:11:28.699Z,1554858688.699 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-10T01:11:30.286Z,1554858690.286 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T01:11:30.287Z,1554858690.287 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2019-04-10T01:11:30.287Z,1554858690.287 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T01:11:30.318Z,1554858690.318 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T01:11:30.690Z,1554858690.690 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T01:11:31.547Z,1554858691.547 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T01:11:31.547Z,1554858691.547 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2019-04-10T01:11:33.922Z,1554858693.922 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T01:11:33.923Z,1554858693.923 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T01:11:51.808Z,1554858711.808 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005088
2019-04-10T01:11:54.122Z,1554858714.122 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T01:11:54.122Z,1554858714.122 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7
2019-04-10T01:11:54.122Z,1554858714.122 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T01:11:54.161Z,1554858714.161 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T01:11:54.526Z,1554858714.526 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T01:11:55.384Z,1554858715.384 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-10T01:11:55.384Z,1554858715.384 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7
2019-04-10T01:11:56.458Z,1554858716.458 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190410T005834/Courier0007.lzma
2019-04-10T01:11:57.264Z,1554858717.264 [DataOverHttps](INFO): Moved sent file to Logs/20190410T005834/Courier0007.lzma.bak
2019-04-10T01:11:57.264Z,1554858717.264 [DataOverHttps](INFO): SBD MOMSN=10522606
2019-04-10T01:11:57.758Z,1554858717.758 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-10T01:11:57.759Z,1554858717.759 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-10T01:12:09.682Z,1554858729.682 [DataOverHttps](INFO): Sending 313 bytes from file Logs/20190410T010859/Courier0000.lzma
2019-04-10T01:12:10.487Z,1554858730.487 [DataOverHttps](INFO): Moved sent file to Logs/20190410T010859/Courier0000.lzma.bak
2019-04-10T01:12:10.488Z,1554858730.488 [DataOverHttps](INFO): SBD MOMSN=10522608
2019-04-10T01:12:14.360Z,1554858734.360 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T01:12:14.361Z,1554858734.361 [Aanderaa_O2] No Fault, FailCount= 3
2019-04-10T01:12:17.959Z,1554858737.959 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-10T01:12:17.959Z,1554858737.959 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 8
2019-04-10T01:12:17.959Z,1554858737.959 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-10T01:12:18.008Z,1554858738.008 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T01:12:18.009Z,1554858738.009 [CBIT](FAULT): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-10T01:12:18.362Z,1554858738.362 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T01:12:22.848Z,1554858742.848 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-10T01:12:22.848Z,1554858742.848 [CTD_Seabird] No Fault, FailCount= 3
2019-04-10T01:12:23.030Z,1554858743.030 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T01:12:23.038Z,1554858743.038 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T01:12:24.826Z,1554858744.826 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T01:12:24.826Z,1554858744.826 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-10T01:12:24.826Z,1554858744.826 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T01:12:24.871Z,1554858744.871 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T01:12:25.350Z,1554858745.350 [Aanderaa_O2](INFO): Powering down
2019-04-10T01:12:25.578Z,1554858745.578 [DataOverHttps](INFO): Sending 1136 bytes from file Logs/20190410T003737/Express0001.lzma
2019-04-10T01:12:26.071Z,1554858746.071 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T01:12:26.071Z,1554858746.071 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-10T01:12:26.379Z,1554858746.379 [DataOverHttps](INFO): Moved sent file to Logs/20190410T003737/Express0001.lzma.bak
2019-04-10T01:12:26.380Z,1554858746.380 [DataOverHttps](INFO): SBD MOMSN=10522621
2019-04-10T01:12:28.501Z,1554858748.501 [Startup:StartupSatComms:B](INFO): Timed out from 2019-04-10T01:11:28.3Z
2019-04-10T01:12:28.501Z,1554858748.501 [Startup:StartupSatComms:B] Stopped
2019-04-10T01:12:28.501Z,1554858748.501 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-04-10T01:12:28.502Z,1554858748.502 [Startup:StartupSatComms] Stopped
2019-04-10T01:12:28.502Z,1554858748.502 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-04-10T01:12:28.502Z,1554858748.502 [Startup](INFO): Completed Startup
2019-04-10T01:12:28.503Z,1554858748.503 [MissionManager](INFO): Startup is completed.
2019-04-10T01:12:28.503Z,1554858748.503 [MissionManager](INFO): Uninitializing Mission Startup
2019-04-10T01:12:28.503Z,1554858748.503 [Startup] Stopped
2019-04-10T01:12:28.503Z,1554858748.503 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-04-10T01:12:28.503Z,1554858748.503 [Startup:A.GoToSurface] Stopped
2019-04-10T01:12:28.503Z,1554858748.503 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-10T01:12:28.907Z,1554858748.907 [MissionManager](IMPORTANT): Started mission Default
2019-04-10T01:12:28.907Z,1554858748.907 [Default] Running Loop=1
2019-04-10T01:12:28.907Z,1554858748.907 [Default](DEBUG): Aggregate::initialize Default
2019-04-10T01:12:28.907Z,1554858748.907 [Default:B.GoToSurface] Running Loop=1
2019-04-10T01:12:28.907Z,1554858748.907 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-10T01:12:28.908Z,1554858748.908 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-10T01:12:28.908Z,1554858748.908 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-10T01:12:28.908Z,1554858748.908 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-10T01:12:28.909Z,1554858748.909 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-10T01:12:28.909Z,1554858748.909 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-10T01:12:28.909Z,1554858748.909 [Default:A.Wait] Running Loop=1
2019-04-10T01:12:28.909Z,1554858748.909 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-04-10T01:12:36.082Z,1554858756.082 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T01:12:36.082Z,1554858756.082 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-10T01:12:36.082Z,1554858756.082 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T01:12:36.180Z,1554858756.180 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T01:12:36.486Z,1554858756.486 [CTD_Seabird](INFO): Powering down
2019-04-10T01:12:36.545Z,1554858756.545 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T01:12:36.545Z,1554858756.545 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-10T01:12:36.545Z,1554858756.545 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T01:12:36.580Z,1554858756.580 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T01:12:37.066Z,1554858757.066 [Aanderaa_O2](INFO): Powering down
2019-04-10T01:12:37.810Z,1554858757.810 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-10T01:12:37.810Z,1554858757.810 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-10T01:12:37.810Z,1554858757.810 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-10T01:12:37.810Z,1554858757.810 [CTD_Seabird] No Fault, FailCount= 1
2019-04-10T01:12:37.830Z,1554858757.830 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T01:12:37.838Z,1554858757.838 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T01:12:42.227Z,1554858762.227 [Default:A.Wait](INFO): Done Waiting.
2019-04-10T01:12:42.228Z,1554858762.228 [Default:A.Wait] Stopped
2019-04-10T01:12:42.228Z,1554858762.228 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-04-10T01:12:42.631Z,1554858762.631 [Default:CheckIn] Running Loop=1
2019-04-10T01:12:42.631Z,1554858762.631 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-10T01:12:42.631Z,1554858762.631 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-10T01:12:43.035Z,1554858763.035 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-04-10T01:12:48.258Z,1554858768.258 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-10T01:12:48.258Z,1554858768.258 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-10T01:12:48.259Z,1554858768.259 [Aanderaa_O2](ERROR): Communications Fault
2019-04-10T01:12:48.321Z,1554858768.321 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-10T01:12:48.322Z,1554858768.322 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-10T01:12:48.782Z,1554858768.782 [Aanderaa_O2](INFO): Powering down
2019-04-10T01:12:50.897Z,1554858770.897 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T01:12:50.897Z,1554858770.897 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-10T01:12:50.897Z,1554858770.897 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T01:12:51.141Z,1554858771.141 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T01:12:51.298Z,1554858771.298 [CTD_Seabird](INFO): Powering down
2019-04-10T01:12:52.335Z,1554858772.335 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-10T01:12:52.336Z,1554858772.336 [CTD_Seabird] No Fault, FailCount= 2
2019-04-10T01:12:52.642Z,1554858772.642 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-10T01:12:52.650Z,1554858772.650 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-10T01:12:54.818Z,1554858774.818 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20190410T003737/Express0005.lzma
2019-04-10T01:12:55.623Z,1554858775.623 [DataOverHttps](INFO): Moved sent file to Logs/20190410T003737/Express0005.lzma.bak
2019-04-10T01:12:55.624Z,1554858775.624 [DataOverHttps](INFO): SBD MOMSN=10522659
2019-04-10T01:13:05.694Z,1554858785.694 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-10T01:13:05.694Z,1554858785.694 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-10T01:13:05.694Z,1554858785.694 [CTD_Seabird](ERROR): Communications Fault
2019-04-10T01:13:06.084Z,1554858786.084 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-10T01:13:06.084Z,1554858786.084 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-10T01:13:06.098Z,1554858786.098 [CTD_Seabird](INFO): Powering down
2019-04-10T01:13:11.329Z,1554858791.329 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T01:13:11.329Z,1554858791.329 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-04-10T01:13:11.329Z,1554858791.329 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-10T01:13:11.330Z,1554858791.330 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T01:13:11.331Z,1554858791.331 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-04-10T01:13:11.331Z,1554858791.331 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-10T01:13:11.332Z,1554858791.332 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-10T01:13:11.332Z,1554858791.332 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-04-10T01:13:11.332Z,1554858791.332 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-10T01:13:11.343Z,1554858791.343 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-10T01:13:11.343Z,1554858791.343 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-10T01:13:11.343Z,1554858791.343 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-10T01:13:11.746Z,1554858791.746 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-10T01:13:11.747Z,1554858791.747 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-04-10T01:13:11.747Z,1554858791.747 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-10T01:13:11.747Z,1554858791.747 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-04-10T01:13:11.748Z,1554858791.748 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-10T01:13:11.748Z,1554858791.748 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-04-10T01:13:12.114Z,1554858792.114 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-10T01:13:49.212Z,1554858829.212 [CommandLine](IMPORTANT): got command restart application
2019-04-10T01:13:50.218Z,1554858830.218 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T01:13:50.218Z,1554858830.218 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:13:50.250Z,1554858830.250 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-04-10T01:13:50.250Z,1554858830.250 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:13:50.251Z,1554858830.251 [CommandLine](INFO): Join timeout helper Thread ID is 1135
2019-04-10T01:13:50.251Z,1554858830.251 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-04-10T01:13:50.251Z,1554858830.251 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:13:50.252Z,1554858830.252 [NavChartDb](INFO): Join timeout helper Thread ID is 1136
2019-04-10T01:13:50.254Z,1554858830.254 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T01:13:50.254Z,1554858830.254 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:13:50.270Z,1554858830.270 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-04-10T01:13:50.270Z,1554858830.270 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:13:50.270Z,1554858830.270 [Radio_Surface](INFO): Join timeout helper Thread ID is 1137
2019-04-10T01:13:50.358Z,1554858830.358 [Radio_Surface](INFO): Powering down
2019-04-10T01:13:50.359Z,1554858830.359 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T01:13:50.359Z,1554858830.359 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:13:50.374Z,1554858830.374 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-04-10T01:13:50.374Z,1554858830.374 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:13:50.374Z,1554858830.374 [DataOverHttps](INFO): Join timeout helper Thread ID is 1138
2019-04-10T01:13:50.438Z,1554858830.438 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T01:13:50.438Z,1554858830.438 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:13:50.454Z,1554858830.454 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-04-10T01:13:50.454Z,1554858830.454 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:13:50.454Z,1554858830.454 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1139
2019-04-10T01:13:50.726Z,1554858830.726 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T01:13:50.727Z,1554858830.727 [WetLabsBB2FL](INFO): Powering down
2019-04-10T01:13:50.727Z,1554858830.727 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:13:50.747Z,1554858830.747 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-04-10T01:13:50.747Z,1554858830.747 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:13:50.747Z,1554858830.747 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1140
2019-04-10T01:13:51.074Z,1554858831.074 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T01:13:51.074Z,1554858831.074 [CTD_Seabird](INFO): Powering down
2019-04-10T01:13:51.086Z,1554858831.086 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:13:51.095Z,1554858831.095 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-04-10T01:13:51.095Z,1554858831.095 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:13:51.095Z,1554858831.095 [logger](INFO): Join timeout helper Thread ID is 1141
2019-04-10T01:13:51.154Z,1554858831.154 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T01:13:51.154Z,1554858831.154 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:13:51.175Z,1554858831.175 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-04-10T01:13:51.175Z,1554858831.175 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:13:51.175Z,1554858831.175 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-04-10T01:13:51.175Z,1554858831.175 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:13:51.175Z,1554858831.175 [controlThread](INFO): Join timeout helper Thread ID is 1142
2019-04-10T01:13:51.278Z,1554858831.278 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-10T01:13:51.278Z,1554858831.278 [controlThread](DEBUG): Uninitializing ControlThread
2019-04-10T01:13:51.279Z,1554858831.279 [Aanderaa_O2](INFO): Powering down
2019-04-10T01:13:51.281Z,1554858831.281 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-10T01:13:51.282Z,1554858831.282 [NAL9602](INFO): Powering down
2019-04-10T01:13:51.283Z,1554858831.283 [BR_Ping1D](INFO): uninitialize
2019-04-10T01:13:51.283Z,1554858831.283 [BR_Ping1D](INFO): Powering down
2019-04-10T01:13:51.284Z,1554858831.284 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-04-10T01:13:51.285Z,1554858831.285 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-04-10T01:13:51.285Z,1554858831.285 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-04-10T01:13:51.286Z,1554858831.286 [MissionManager](INFO): Uninitializing Mission Default
2019-04-10T01:13:51.286Z,1554858831.286 [Default] Stopped
2019-04-10T01:13:51.286Z,1554858831.286 [Default](DEBUG): Aggregate::uninitialize Default
2019-04-10T01:13:51.286Z,1554858831.286 [Default:B.GoToSurface] Stopped
2019-04-10T01:13:51.286Z,1554858831.286 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-10T01:13:51.286Z,1554858831.286 [Default:CheckIn] Stopped
2019-04-10T01:13:51.287Z,1554858831.287 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-10T01:13:51.287Z,1554858831.287 [Default:CheckIn:Read_GPS] Stopped
2019-04-10T01:13:51.289Z,1554858831.289 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-04-10T01:13:51.289Z,1554858831.289 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-04-10T01:13:51.289Z,1554858831.289 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-04-10T01:13:51.290Z,1554858831.290 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-04-10T01:13:51.290Z,1554858831.290 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-04-10T01:13:51.290Z,1554858831.290 [BuoyancyServo](INFO): Powering down
2019-04-10T01:13:51.302Z,1554858831.302 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-10T01:13:51.302Z,1554858831.302 [ElevatorServo](INFO): Powering down
2019-04-10T01:13:51.303Z,1554858831.303 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-10T01:13:51.303Z,1554858831.303 [MassServo](INFO): Powering down
2019-04-10T01:13:51.304Z,1554858831.304 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-10T01:13:51.304Z,1554858831.304 [RudderServo](INFO): Powering down
2019-04-10T01:13:51.305Z,1554858831.305 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-04-10T01:13:51.305Z,1554858831.305 [ThrusterServo](INFO): Powering down
2019-04-10T01:13:51.306Z,1554858831.306 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-04-10T01:13:51.306Z,1554858831.306 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-04-10T01:13:51.306Z,1554858831.306 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-04-10T01:13:51.306Z,1554858831.306 [CBIT](DEBUG): Powering off loads.
2019-04-10T01:13:51.318Z,1554858831.318 [CBIT](DEBUG): Disabling WDT.
2019-04-10T01:13:51.330Z,1554858831.330 [CBIT](DEBUG): Opening all GF detection circuits.
2019-04-10T01:13:51.330Z,1554858831.330 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:13:51.362Z,1554858831.362 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:13:51.367Z,1554858831.367 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:13:51.434Z,1554858831.434 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:13:51.439Z,1554858831.439 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:13:51.491Z,1554858831.491 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-10T01:13:51.549Z,1554858831.549 [logger ThreadHandler](INFO): Thread cancelled.