2019-05-15T20:40:36.265Z,1557952836.265 [Supervisor](DEBUG): Initializing supervisor.
2019-05-15T20:40:36.268Z,1557952836.268 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-05-15T20:40:36.268Z,1557952836.268 [SyncHandler](INFO): Protected caller Thread ID is 2874
2019-05-15T20:40:36.269Z,1557952836.269 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-05-15T20:40:36.270Z,1557952836.270 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-05-15T20:40:36.270Z,1557952836.270 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2875
2019-05-15T20:40:36.273Z,1557952836.273 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-05-15T20:40:36.286Z,1557952836.286 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-05-15T20:40:36.286Z,1557952836.286 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-05-15T20:40:36.287Z,1557952836.287 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2876
2019-05-15T20:40:36.288Z,1557952836.288 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-05-15T20:40:36.289Z,1557952836.289 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-05-15T20:40:36.289Z,1557952836.289 [logger ThreadHandler](INFO): Protected caller Thread ID is 2877
2019-05-15T20:40:36.291Z,1557952836.291 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-05-15T20:40:36.291Z,1557952836.291 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-05-15T20:40:36.293Z,1557952836.293 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-05-15T20:40:36.820Z,1557952836.820 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-05-15T20:40:36.820Z,1557952836.820 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-05-15T20:40:36.964Z,1557952836.964 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-05-15T20:40:36.964Z,1557952836.964 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-05-15T20:40:37.044Z,1557952837.044 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-05-15T20:40:37.142Z,1557952837.142 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-05-15T20:40:37.143Z,1557952837.143 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-05-15T20:40:37.237Z,1557952837.237 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-05-15T20:40:37.238Z,1557952837.238 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-05-15T20:40:37.614Z,1557952837.614 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-05-15T20:40:37.614Z,1557952837.614 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-05-15T20:40:37.716Z,1557952837.716 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-05-15T20:40:37.717Z,1557952837.717 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-05-15T20:40:38.171Z,1557952838.171 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-05-15T20:40:38.172Z,1557952838.172 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-05-15T20:40:38.373Z,1557952838.373 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-05-15T20:40:38.374Z,1557952838.374 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-05-15T20:40:38.676Z,1557952838.676 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-05-15T20:40:38.677Z,1557952838.677 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-05-15T20:40:38.888Z,1557952838.888 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-05-15T20:40:38.888Z,1557952838.888 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-05-15T20:40:39.030Z,1557952839.030 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-05-15T20:40:39.030Z,1557952839.030 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-05-15T20:40:39.604Z,1557952839.604 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-05-15T20:40:39.605Z,1557952839.605 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-05-15T20:40:39.937Z,1557952839.937 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-05-15T20:40:39.938Z,1557952839.938 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-05-15T20:40:40.143Z,1557952840.143 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-05-15T20:40:40.145Z,1557952840.145 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2019-05-15T20:40:40.146Z,1557952840.146 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2019-05-15T20:40:40.332Z,1557952840.332 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2019-05-15T20:40:40.415Z,1557952840.415 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2019-05-15T20:40:40.533Z,1557952840.533 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2019-05-15T20:40:40.618Z,1557952840.618 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2019-05-15T20:40:40.845Z,1557952840.845 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-05-15T20:40:40.846Z,1557952840.846 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2019-05-15T20:40:40.942Z,1557952840.942 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2019-05-15T20:40:41.052Z,1557952841.052 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2019-05-15T20:40:41.147Z,1557952841.147 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2019-05-15T20:40:41.302Z,1557952841.302 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2019-05-15T20:40:41.403Z,1557952841.403 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2019-05-15T20:40:41.502Z,1557952841.502 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2019-05-15T20:40:41.502Z,1557952841.502 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-05-15T20:40:41.505Z,1557952841.505 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-05-15T20:40:41.518Z,1557952841.518 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-05-15T20:40:41.519Z,1557952841.519 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-05-15T20:40:41.583Z,1557952841.583 [VerticalControl](DEBUG): Construct VerticalControl.
2019-05-15T20:40:41.695Z,1557952841.695 [VerticalControl] Loaded
2019-05-15T20:40:41.695Z,1557952841.695 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-05-15T20:40:41.696Z,1557952841.696 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-05-15T20:40:41.763Z,1557952841.763 [HorizontalControl] Loaded
2019-05-15T20:40:41.763Z,1557952841.763 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-05-15T20:40:41.764Z,1557952841.764 [SpeedControl](DEBUG): Construct SpeedControl.
2019-05-15T20:40:41.769Z,1557952841.769 [SpeedControl] Loaded
2019-05-15T20:40:41.770Z,1557952841.770 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-05-15T20:40:41.770Z,1557952841.770 [LoopControl](DEBUG): Construct LoopControl.
2019-05-15T20:40:41.771Z,1557952841.771 [LoopControl] Loaded
2019-05-15T20:40:41.771Z,1557952841.771 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-05-15T20:40:41.772Z,1557952841.772 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-05-15T20:40:41.772Z,1557952841.772 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-05-15T20:40:42.079Z,1557952842.079 [BuoyancyServo] Loaded
2019-05-15T20:40:42.079Z,1557952842.079 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-05-15T20:40:42.090Z,1557952842.090 [ElevatorServo] Loaded
2019-05-15T20:40:42.090Z,1557952842.090 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-05-15T20:40:42.101Z,1557952842.101 [MassServo] Loaded
2019-05-15T20:40:42.101Z,1557952842.101 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-05-15T20:40:42.154Z,1557952842.154 [RudderServo] Loaded
2019-05-15T20:40:42.154Z,1557952842.154 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-05-15T20:40:42.185Z,1557952842.185 [ThrusterServo] Loaded
2019-05-15T20:40:42.186Z,1557952842.186 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-05-15T20:40:42.186Z,1557952842.186 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-05-15T20:40:42.187Z,1557952842.187 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-05-15T20:40:42.287Z,1557952842.287 [DepthRateCalculator] Loaded
2019-05-15T20:40:42.287Z,1557952842.287 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-05-15T20:40:42.292Z,1557952842.292 [PitchRateCalculator] Loaded
2019-05-15T20:40:42.292Z,1557952842.292 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-05-15T20:40:42.322Z,1557952842.322 [SpeedCalculator] Loaded
2019-05-15T20:40:42.322Z,1557952842.322 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-05-15T20:40:42.343Z,1557952842.343 [TempGradientCalculator] Loaded
2019-05-15T20:40:42.343Z,1557952842.343 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-05-15T20:40:42.348Z,1557952842.348 [YawRateCalculator] Loaded
2019-05-15T20:40:42.348Z,1557952842.348 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-05-15T20:40:42.381Z,1557952842.381 [ElevatorOffsetCalculator] Loaded
2019-05-15T20:40:42.381Z,1557952842.381 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-05-15T20:40:42.381Z,1557952842.381 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-05-15T20:40:42.382Z,1557952842.382 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-05-15T20:40:42.780Z,1557952842.780 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-05-15T20:40:42.781Z,1557952842.781 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-05-15T20:40:42.801Z,1557952842.801 [NavChart] Loaded
2019-05-15T20:40:42.801Z,1557952842.801 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-05-15T20:40:42.805Z,1557952842.805 [UniversalFixResidualReporter] Loaded
2019-05-15T20:40:42.805Z,1557952842.805 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-05-15T20:40:42.805Z,1557952842.805 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-05-15T20:40:42.806Z,1557952842.806 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-05-15T20:40:43.170Z,1557952843.170 [AHRS_M2] Loaded
2019-05-15T20:40:43.170Z,1557952843.170 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-05-15T20:40:43.247Z,1557952843.247 [DataOverHttps] Loaded
2019-05-15T20:40:43.247Z,1557952843.247 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-05-15T20:40:43.248Z,1557952843.248 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408114E0
2019-05-15T20:40:43.248Z,1557952843.248 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2956
2019-05-15T20:40:43.262Z,1557952843.262 [Depth_Keller] Loaded
2019-05-15T20:40:43.262Z,1557952843.262 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-05-15T20:40:43.267Z,1557952843.267 [DropWeight] Loaded
2019-05-15T20:40:43.267Z,1557952843.267 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-05-15T20:40:43.363Z,1557952843.363 [NAL9602] Loaded
2019-05-15T20:40:43.363Z,1557952843.363 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-05-15T20:40:43.379Z,1557952843.379 [Onboard] Loaded
2019-05-15T20:40:43.379Z,1557952843.379 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-05-15T20:40:43.382Z,1557952843.382 [Radio_Surface] Loaded
2019-05-15T20:40:43.382Z,1557952843.382 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-05-15T20:40:43.383Z,1557952843.383 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408414E0
2019-05-15T20:40:43.384Z,1557952843.384 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2957
2019-05-15T20:40:44.855Z,1557952844.855 [BPC1] Loaded
2019-05-15T20:40:44.855Z,1557952844.855 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-05-15T20:40:44.867Z,1557952844.867 [BR_Ping1D] Loaded
2019-05-15T20:40:44.868Z,1557952844.868 [ComponentRegistry](DEBUG): SyncComponent "BR_Ping1D" handled in the control thread.
2019-05-15T20:40:44.868Z,1557952844.868 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-05-15T20:40:44.868Z,1557952844.868 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-05-15T20:40:44.936Z,1557952844.936 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-05-15T20:40:44.937Z,1557952844.937 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-05-15T20:40:45.030Z,1557952845.030 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-05-15T20:40:45.031Z,1557952845.031 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-05-15T20:40:45.056Z,1557952845.056 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-05-15T20:40:45.057Z,1557952845.057 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-05-15T20:40:45.297Z,1557952845.297 [CTD_NeilBrown] Loaded
2019-05-15T20:40:45.297Z,1557952845.297 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-05-15T20:40:45.298Z,1557952845.298 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409CD4E0
2019-05-15T20:40:45.299Z,1557952845.299 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 2958
2019-05-15T20:40:45.308Z,1557952845.308 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-05-15T20:40:45.313Z,1557952845.313 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-05-15T20:40:45.314Z,1557952845.314 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-05-15T20:40:45.319Z,1557952845.319 [CTD_Seabird](INFO): created writer for : depth
2019-05-15T20:40:45.320Z,1557952845.320 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-05-15T20:40:45.324Z,1557952845.324 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-05-15T20:40:45.325Z,1557952845.325 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-05-15T20:40:45.330Z,1557952845.330 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-05-15T20:40:45.330Z,1557952845.330 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-05-15T20:40:45.335Z,1557952845.335 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-05-15T20:40:45.336Z,1557952845.336 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-05-15T20:40:45.341Z,1557952845.341 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-05-15T20:40:45.341Z,1557952845.341 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-05-15T20:40:45.346Z,1557952845.346 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-05-15T20:40:45.372Z,1557952845.372 [CTD_Seabird] Loaded
2019-05-15T20:40:45.373Z,1557952845.373 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-05-15T20:40:45.374Z,1557952845.374 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409FD4E0
2019-05-15T20:40:45.374Z,1557952845.374 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2959
2019-05-15T20:40:45.388Z,1557952845.388 [PAR_Licor] Loaded
2019-05-15T20:40:45.389Z,1557952845.389 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-05-15T20:40:45.435Z,1557952845.435 [WetLabsBB2FL] Loaded
2019-05-15T20:40:45.435Z,1557952845.435 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-05-15T20:40:45.436Z,1557952845.436 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A2D4E0
2019-05-15T20:40:45.437Z,1557952845.437 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2960
2019-05-15T20:40:45.437Z,1557952845.437 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-05-15T20:40:45.438Z,1557952845.438 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-05-15T20:40:45.723Z,1557952845.723 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-05-15T20:40:45.724Z,1557952845.724 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-05-15T20:40:45.853Z,1557952845.853 [SBIT](DEBUG): Construct Startup Built In Test.
2019-05-15T20:40:45.864Z,1557952845.864 [SBIT] Loaded
2019-05-15T20:40:45.864Z,1557952845.864 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-05-15T20:40:45.865Z,1557952845.865 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-05-15T20:40:45.876Z,1557952845.876 [IBIT] Loaded
2019-05-15T20:40:45.877Z,1557952845.877 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-05-15T20:40:45.880Z,1557952845.880 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-05-15T20:40:46.016Z,1557952846.016 [CBIT] Loaded
2019-05-15T20:40:46.017Z,1557952846.017 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-05-15T20:40:46.017Z,1557952846.017 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-05-15T20:40:46.021Z,1557952846.021 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-05-15T20:40:46.022Z,1557952846.022 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-05-15T20:40:46.028Z,1557952846.028 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-05-15T20:40:46.029Z,1557952846.029 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B0C4E0
2019-05-15T20:40:46.030Z,1557952846.030 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2961
2019-05-15T20:40:46.034Z,1557952846.034 [Supervisor](INFO): Main Thread ID is 803
2019-05-15T20:40:46.034Z,1557952846.034 [Supervisor](DEBUG): Running supervisor.
2019-05-15T20:40:46.035Z,1557952846.035 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2962
2019-05-15T20:40:46.037Z,1557952846.037 [controlThread ThreadHandler](INFO): Handler Thread ID is 2963
2019-05-15T20:40:46.038Z,1557952846.038 [controlThread](DEBUG): Initializing ControlThread
2019-05-15T20:40:46.039Z,1557952846.039 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-05-15T20:40:46.040Z,1557952846.040 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-05-15T20:40:46.041Z,1557952846.041 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-05-15T20:40:46.041Z,1557952846.041 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-05-15T20:40:46.043Z,1557952846.043 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-05-15T20:40:46.043Z,1557952846.043 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-05-15T20:40:46.043Z,1557952846.043 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-05-15T20:40:46.044Z,1557952846.044 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-05-15T20:40:46.044Z,1557952846.044 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-05-15T20:40:46.045Z,1557952846.045 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-05-15T20:40:46.046Z,1557952846.046 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-05-15T20:40:46.046Z,1557952846.046 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-05-15T20:40:46.053Z,1557952846.053 [SBIT](INFO): Initialize SBIT Component.
2019-05-15T20:40:46.054Z,1557952846.054 [SBIT](IMPORTANT): git: 2019-05-01-16-g58f4cc3
2019-05-15T20:40:46.054Z,1557952846.054 [SBIT](INFO): git hash: 58f4cc369d803afb19aea4821bdb4c673a350570
2019-05-15T20:40:46.054Z,1557952846.054 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-05-15T20:40:46.055Z,1557952846.055 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-05-15T20:40:46.056Z,1557952846.056 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-05-15T20:40:46.057Z,1557952846.057 [IBIT](INFO): Initialize IBIT Component.
2019-05-15T20:40:46.058Z,1557952846.058 [CBIT](DEBUG): Initialize CBIT Component.
2019-05-15T20:40:46.059Z,1557952846.059 [logger ThreadHandler](INFO): Handler Thread ID is 2964
2019-05-15T20:40:46.069Z,1557952846.069 [CBIT](DEBUG): Initialized mux pins.
2019-05-15T20:40:46.069Z,1557952846.069 [CBIT](DEBUG): Initializing the watchdog timer.
2019-05-15T20:40:46.077Z,1557952846.077 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2965
2019-05-15T20:40:46.078Z,1557952846.078 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-05-15T20:40:46.089Z,1557952846.089 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2966
2019-05-15T20:40:46.093Z,1557952846.093 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2019-05-15T20:40:46.093Z,1557952846.093 [CBIT](DEBUG): Initializing heartbeat.
2019-05-15T20:40:46.101Z,1557952846.101 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 2967
2019-05-15T20:40:46.109Z,1557952846.109 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2968
2019-05-15T20:40:46.110Z,1557952846.110 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-15T20:40:46.113Z,1557952846.113 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-15T20:40:46.115Z,1557952846.115 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2970
2019-05-15T20:40:46.116Z,1557952846.116 [WetLabsBB2FL](INFO): Powering down
2019-05-15T20:40:46.142Z,1557952846.142 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2971
2019-05-15T20:40:46.145Z,1557952846.145 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-05-15T20:40:46.146Z,1557952846.146 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-05-15T20:40:46.146Z,1557952846.146 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-05-15T20:40:46.146Z,1557952846.146 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-05-15T20:40:46.146Z,1557952846.146 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-05-15T20:40:46.146Z,1557952846.146 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-05-15T20:40:46.146Z,1557952846.146 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-05-15T20:40:46.147Z,1557952846.147 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-05-15T20:40:46.147Z,1557952846.147 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-05-15T20:40:46.147Z,1557952846.147 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-05-15T20:40:46.147Z,1557952846.147 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-05-15T20:40:46.147Z,1557952846.147 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-05-15T20:40:46.148Z,1557952846.148 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-05-15T20:40:46.148Z,1557952846.148 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-05-15T20:40:46.148Z,1557952846.148 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-05-15T20:40:46.148Z,1557952846.148 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-05-15T20:40:46.165Z,1557952846.165 [CBIT](DEBUG): Deactivating GF circuits.
2019-05-15T20:40:46.165Z,1557952846.165 [CBIT](DEBUG): Deactivating emergency mode.
2019-05-15T20:40:46.201Z,1557952846.201 [CBIT](DEBUG): Backplane powered.
2019-05-15T20:40:46.202Z,1557952846.202 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-05-15T20:40:46.212Z,1557952846.212 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-15T20:40:46.222Z,1557952846.222 [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-05-15T20:40:46.223Z,1557952846.223 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-05-15T20:40:46.307Z,1557952846.307 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-05-15T20:40:46.308Z,1557952846.308 [Default:A.Wait](DEBUG): Construct Wait.
2019-05-15T20:40:46.309Z,1557952846.309 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-15T20:40:46.332Z,1557952846.332 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-05-15T20:40:46.355Z,1557952846.355 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-05-15T20:40:46.360Z,1557952846.360 [Default:E.Execute](DEBUG): Construct Execute.
2019-05-15T20:40:46.405Z,1557952846.405 [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-05-15T20:40:46.410Z,1557952846.410 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,BPC1,BR_Ping1D,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-05-15T20:40:46.415Z,1557952846.415 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-05-15T20:40:46.483Z,1557952846.483 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar
2019-05-15T20:40:46.494Z,1557952846.494 [Radio_Surface](INFO): Powering up
2019-05-15T20:40:46.531Z,1557952846.531 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-05-15T20:40:46.561Z,1557952846.561 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-05-15T20:40:46.569Z,1557952846.569 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-05-15T20:40:46.570Z,1557952846.570 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-05-15T20:40:46.577Z,1557952846.577 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-05-15T20:40:46.582Z,1557952846.582 [MassServo](DEBUG): Initializing EZServoServo.
2019-05-15T20:40:46.589Z,1557952846.589 [MassServo](DEBUG): Initializing MassServo.
2019-05-15T20:40:46.590Z,1557952846.590 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-15T20:40:46.597Z,1557952846.597 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-15T20:40:46.598Z,1557952846.598 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-05-15T20:40:46.605Z,1557952846.605 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-05-15T20:40:46.607Z,1557952846.607 [CommandLine](FAULT): Scheduling is paused
2019-05-15T20:40:46.608Z,1557952846.608 [CBIT](INFO): Critical error at 20190515T204046
2019-05-15T20:40:46.608Z,1557952846.608 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-05-15T20:40:46.835Z,1557952846.835 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-05-15T20:40:46.835Z,1557952846.835 [DropWeight] Hardware Fault, FailCount= 1
2019-05-15T20:40:46.835Z,1557952846.835 [DropWeight](ERROR): Hardware Fault
2019-05-15T20:40:46.889Z,1557952846.889 [CBIT](INFO): Critical error at 20190515T204046
2019-05-15T20:40:46.892Z,1557952846.892 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-05-15T20:40:46.892Z,1557952846.892 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-05-15T20:40:47.241Z,1557952847.241 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T20:40:47.253Z,1557952847.253 [BR_Ping1D](INFO): Sending UART packet: 42520200EC030000F4017A02
2019-05-15T20:40:47.253Z,1557952847.253 [BR_Ping1D](INFO): Sending UART packet: 42520100EB030000018401
2019-05-15T20:40:47.254Z,1557952847.254 [BR_Ping1D](INFO): Sending UART packet: 42520400EA03000000BBEC4D7903
2019-05-15T20:40:47.254Z,1557952847.254 [BR_Ping1D](INFO): Sending UART packet: 42520100EE030000018701
2019-05-15T20:40:47.558Z,1557952847.558 [CBIT](INFO): Critical error at 20190515T204046
2019-05-15T20:40:48.048Z,1557952848.048 [BR_Ping1D](INFO): readConfig
2019-05-15T20:40:48.049Z,1557952848.049 [BR_Ping1D](INFO): UART requesting status
2019-05-15T20:40:48.049Z,1557952848.049 [BR_Ping1D](INFO): Sending UART packet: 42520000BA0400005201
2019-05-15T20:40:48.049Z,1557952848.049 [BR_Ping1D](INFO): Sending UART packet: 42520000BF0400005701
2019-05-15T20:40:48.049Z,1557952848.049 [BR_Ping1D](INFO): Sending UART packet: 42520000B30400004B01
2019-05-15T20:40:48.453Z,1557952848.453 [BR_Ping1D](INFO): readConfig
2019-05-15T20:40:48.453Z,1557952848.453 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:40:48.454Z,1557952848.454 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T20:40:48.454Z,1557952848.454 [BR_Ping1D](INFO): No UART data
2019-05-15T20:40:48.454Z,1557952848.454 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:40:48.454Z,1557952848.454 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:40:48.856Z,1557952848.856 [BR_Ping1D](INFO): readConfig
2019-05-15T20:40:48.856Z,1557952848.856 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:40:48.857Z,1557952848.857 [BR_Ping1D](INFO): No UART data
2019-05-15T20:40:48.857Z,1557952848.857 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:40:48.857Z,1557952848.857 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:40:49.260Z,1557952849.260 [BR_Ping1D](INFO): readConfig
2019-05-15T20:40:49.261Z,1557952849.261 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:40:49.261Z,1557952849.261 [BR_Ping1D](INFO): No UART data
2019-05-15T20:40:49.261Z,1557952849.261 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:40:49.261Z,1557952849.261 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:40:49.708Z,1557952849.708 [BR_Ping1D](INFO): readConfig
2019-05-15T20:40:49.708Z,1557952849.708 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:40:49.709Z,1557952849.709 [BR_Ping1D](INFO): No UART data
2019-05-15T20:40:49.709Z,1557952849.709 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:40:49.709Z,1557952849.709 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:40:50.092Z,1557952850.092 [BR_Ping1D](INFO): readConfig
2019-05-15T20:40:50.093Z,1557952850.093 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:40:50.093Z,1557952850.093 [BR_Ping1D](INFO): No UART data
2019-05-15T20:40:50.093Z,1557952850.093 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:40:50.093Z,1557952850.093 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:40:50.497Z,1557952850.497 [BR_Ping1D](INFO): readConfig
2019-05-15T20:40:50.497Z,1557952850.497 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:40:50.497Z,1557952850.497 [BR_Ping1D](INFO): No UART data
2019-05-15T20:40:50.497Z,1557952850.497 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:40:50.497Z,1557952850.497 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:40:50.936Z,1557952850.936 [BR_Ping1D](INFO): readConfig
2019-05-15T20:40:50.936Z,1557952850.936 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:40:50.936Z,1557952850.936 [BR_Ping1D](INFO): No UART data
2019-05-15T20:40:50.937Z,1557952850.937 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:40:50.938Z,1557952850.938 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:40:51.308Z,1557952851.308 [BR_Ping1D](INFO): readConfig
2019-05-15T20:40:51.308Z,1557952851.308 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:40:51.309Z,1557952851.309 [BR_Ping1D](INFO): No UART data
2019-05-15T20:40:51.309Z,1557952851.309 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:40:51.309Z,1557952851.309 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:40:51.712Z,1557952851.712 [BR_Ping1D](INFO): readConfig
2019-05-15T20:40:51.713Z,1557952851.713 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:40:51.713Z,1557952851.713 [BR_Ping1D](INFO): No UART data
2019-05-15T20:40:51.713Z,1557952851.713 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:40:51.713Z,1557952851.713 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:40:52.116Z,1557952852.116 [BR_Ping1D](INFO): readConfig
2019-05-15T20:40:52.116Z,1557952852.116 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:40:52.117Z,1557952852.117 [BR_Ping1D](INFO): No UART data
2019-05-15T20:40:52.117Z,1557952852.117 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:40:52.117Z,1557952852.117 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:40:52.521Z,1557952852.521 [BR_Ping1D](INFO): readConfig
2019-05-15T20:40:52.521Z,1557952852.521 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:40:52.521Z,1557952852.521 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-05-15T20:40:52.522Z,1557952852.522 [BR_Ping1D] Data Fault, FailCount= 1
2019-05-15T20:40:52.522Z,1557952852.522 [BR_Ping1D](ERROR): Data Fault
2019-05-15T20:40:52.621Z,1557952852.621 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T20:40:52.924Z,1557952852.924 [BR_Ping1D](INFO): Stop
2019-05-15T20:40:52.924Z,1557952852.924 [BR_Ping1D](INFO): uninitialize
2019-05-15T20:40:52.924Z,1557952852.924 [BR_Ping1D](INFO): Powering down
2019-05-15T20:40:53.330Z,1557952853.330 [BR_Ping1D](INFO): Stopping
2019-05-15T20:40:53.733Z,1557952853.733 [BR_Ping1D](INFO): Stopping
2019-05-15T20:40:54.137Z,1557952854.137 [BR_Ping1D](INFO): Stopped
2019-05-15T20:40:54.282Z,1557952854.282 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T20:40:54.282Z,1557952854.282 [BR_Ping1D] No Fault, FailCount= 1
2019-05-15T20:40:54.541Z,1557952854.541 [BR_Ping1D](INFO): Start
2019-05-15T20:40:55.336Z,1557952855.336 [BR_Ping1D](INFO): readConfig
2019-05-15T20:40:55.336Z,1557952855.336 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T20:40:55.349Z,1557952855.349 [BR_Ping1D](INFO): Sending UART packet: 42520200EC030000F4017A02
2019-05-15T20:40:55.349Z,1557952855.349 [BR_Ping1D](INFO): Sending UART packet: 42520100EB030000018401
2019-05-15T20:40:55.349Z,1557952855.349 [BR_Ping1D](INFO): Sending UART packet: 42520400EA03000000EED11C6003
2019-05-15T20:40:55.350Z,1557952855.350 [BR_Ping1D](INFO): Sending UART packet: 42520100EE030000018701
2019-05-15T20:40:56.145Z,1557952856.145 [BR_Ping1D](INFO): readConfig
2019-05-15T20:40:56.145Z,1557952856.145 [BR_Ping1D](INFO): UART requesting status
2019-05-15T20:40:56.145Z,1557952856.145 [BR_Ping1D](INFO): Sending UART packet: 42520000BA0400005201
2019-05-15T20:40:56.146Z,1557952856.146 [BR_Ping1D](INFO): Sending UART packet: 42520000BF0400005701
2019-05-15T20:40:56.147Z,1557952856.147 [BR_Ping1D](INFO): Sending UART packet: 42520000B30400004B01
2019-05-15T20:40:56.549Z,1557952856.549 [BR_Ping1D](INFO): readConfig
2019-05-15T20:40:56.549Z,1557952856.549 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:40:56.549Z,1557952856.549 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T20:40:56.549Z,1557952856.549 [BR_Ping1D](INFO): No UART data
2019-05-15T20:40:56.550Z,1557952856.550 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:40:56.550Z,1557952856.550 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:40:56.964Z,1557952856.964 [BR_Ping1D](INFO): readConfig
2019-05-15T20:40:56.964Z,1557952856.964 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:40:56.965Z,1557952856.965 [BR_Ping1D](INFO): No UART data
2019-05-15T20:40:56.965Z,1557952856.965 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:40:56.965Z,1557952856.965 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:40:57.369Z,1557952857.369 [BR_Ping1D](INFO): readConfig
2019-05-15T20:40:57.369Z,1557952857.369 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:40:57.370Z,1557952857.370 [BR_Ping1D](INFO): No UART data
2019-05-15T20:40:57.370Z,1557952857.370 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:40:57.370Z,1557952857.370 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:40:57.760Z,1557952857.760 [BR_Ping1D](INFO): readConfig
2019-05-15T20:40:57.761Z,1557952857.761 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:40:57.761Z,1557952857.761 [BR_Ping1D](INFO): No UART data
2019-05-15T20:40:57.761Z,1557952857.761 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:40:57.761Z,1557952857.761 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:40:58.165Z,1557952858.165 [BR_Ping1D](INFO): readConfig
2019-05-15T20:40:58.166Z,1557952858.166 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:40:58.166Z,1557952858.166 [BR_Ping1D](INFO): No UART data
2019-05-15T20:40:58.166Z,1557952858.166 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:40:58.166Z,1557952858.166 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:40:58.569Z,1557952858.569 [BR_Ping1D](INFO): readConfig
2019-05-15T20:40:58.569Z,1557952858.569 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:40:58.570Z,1557952858.570 [BR_Ping1D](INFO): No UART data
2019-05-15T20:40:58.570Z,1557952858.570 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:40:58.570Z,1557952858.570 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:40:58.973Z,1557952858.973 [BR_Ping1D](INFO): readConfig
2019-05-15T20:40:58.973Z,1557952858.973 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:40:58.973Z,1557952858.973 [BR_Ping1D](INFO): No UART data
2019-05-15T20:40:58.974Z,1557952858.974 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:40:58.974Z,1557952858.974 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:40:59.161Z,1557952859.161 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-15T20:40:59.161Z,1557952859.161 [CTD_Seabird] Communications Fault, FailCount= 1
2019-05-15T20:40:59.161Z,1557952859.161 [CTD_Seabird](ERROR): Communications Fault
2019-05-15T20:40:59.376Z,1557952859.376 [BR_Ping1D](INFO): readConfig
2019-05-15T20:40:59.376Z,1557952859.376 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:40:59.377Z,1557952859.377 [BR_Ping1D](INFO): No UART data
2019-05-15T20:40:59.377Z,1557952859.377 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:40:59.377Z,1557952859.377 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:40:59.449Z,1557952859.449 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-15T20:40:59.565Z,1557952859.565 [CTD_Seabird](INFO): Powering down
2019-05-15T20:40:59.781Z,1557952859.781 [BR_Ping1D](INFO): readConfig
2019-05-15T20:40:59.781Z,1557952859.781 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:40:59.782Z,1557952859.782 [BR_Ping1D](INFO): No UART data
2019-05-15T20:40:59.782Z,1557952859.782 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:40:59.782Z,1557952859.782 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:00.184Z,1557952860.184 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:00.184Z,1557952860.184 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:00.185Z,1557952860.185 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:00.185Z,1557952860.185 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:00.185Z,1557952860.185 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:00.589Z,1557952860.589 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:00.589Z,1557952860.589 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:00.589Z,1557952860.589 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-05-15T20:41:00.589Z,1557952860.589 [BR_Ping1D] Data Fault, FailCount= 2
2019-05-15T20:41:00.589Z,1557952860.589 [BR_Ping1D](ERROR): Data Fault
2019-05-15T20:41:00.682Z,1557952860.682 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T20:41:00.682Z,1557952860.682 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-15T20:41:00.682Z,1557952860.682 [CTD_Seabird] No Fault, FailCount= 1
2019-05-15T20:41:00.941Z,1557952860.941 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-15T20:41:00.943Z,1557952860.943 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-15T20:41:00.993Z,1557952860.993 [BR_Ping1D](INFO): Stop
2019-05-15T20:41:00.993Z,1557952860.993 [BR_Ping1D](INFO): uninitialize
2019-05-15T20:41:00.993Z,1557952860.993 [BR_Ping1D](INFO): Powering down
2019-05-15T20:41:01.396Z,1557952861.396 [BR_Ping1D](INFO): Stopping
2019-05-15T20:41:01.800Z,1557952861.800 [BR_Ping1D](INFO): Stopping
2019-05-15T20:41:02.205Z,1557952862.205 [BR_Ping1D](INFO): Stopped
2019-05-15T20:41:02.242Z,1557952862.242 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T20:41:02.242Z,1557952862.242 [BR_Ping1D] No Fault, FailCount= 2
2019-05-15T20:41:02.609Z,1557952862.609 [BR_Ping1D](INFO): Start
2019-05-15T20:41:03.417Z,1557952863.417 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:03.417Z,1557952863.417 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T20:41:03.429Z,1557952863.429 [BR_Ping1D](INFO): Sending UART packet: 42520200EC030000F4017A02
2019-05-15T20:41:03.429Z,1557952863.429 [BR_Ping1D](INFO): Sending UART packet: 42520100EB030000018401
2019-05-15T20:41:03.429Z,1557952863.429 [BR_Ping1D](INFO): Sending UART packet: 42520400EA03000000EED11C6003
2019-05-15T20:41:03.430Z,1557952863.430 [BR_Ping1D](INFO): Sending UART packet: 42520100EE030000018701
2019-05-15T20:41:04.224Z,1557952864.224 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:04.225Z,1557952864.225 [BR_Ping1D](INFO): UART requesting status
2019-05-15T20:41:04.225Z,1557952864.225 [BR_Ping1D](INFO): Sending UART packet: 42520000BA0400005201
2019-05-15T20:41:04.225Z,1557952864.225 [BR_Ping1D](INFO): Sending UART packet: 42520000BF0400005701
2019-05-15T20:41:04.225Z,1557952864.225 [BR_Ping1D](INFO): Sending UART packet: 42520000B30400004B01
2019-05-15T20:41:04.609Z,1557952864.609 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-05-15T20:41:04.609Z,1557952864.609 [AHRS_M2] Data Fault, FailCount= 1
2019-05-15T20:41:04.609Z,1557952864.609 [AHRS_M2](ERROR): Data Fault
2019-05-15T20:41:04.628Z,1557952864.628 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:04.628Z,1557952864.628 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:04.629Z,1557952864.629 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T20:41:04.629Z,1557952864.629 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:04.629Z,1557952864.629 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:04.629Z,1557952864.629 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:04.654Z,1557952864.654 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-05-15T20:41:05.001Z,1557952865.001 [AHRS_M2](INFO): Powering down
2019-05-15T20:41:05.090Z,1557952865.090 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:05.091Z,1557952865.091 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:05.091Z,1557952865.091 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:05.091Z,1557952865.091 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:05.091Z,1557952865.091 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:05.425Z,1557952865.425 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:05.425Z,1557952865.425 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:05.426Z,1557952865.426 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:05.426Z,1557952865.426 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:05.426Z,1557952865.426 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:05.829Z,1557952865.829 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:05.829Z,1557952865.829 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:05.829Z,1557952865.829 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:05.829Z,1557952865.829 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:05.829Z,1557952865.829 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:05.968Z,1557952865.968 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-05-15T20:41:05.969Z,1557952865.969 [AHRS_M2] No Fault, FailCount= 1
2019-05-15T20:41:06.213Z,1557952866.213 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-05-15T20:41:06.238Z,1557952866.238 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:06.239Z,1557952866.239 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:06.239Z,1557952866.239 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:06.239Z,1557952866.239 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:06.239Z,1557952866.239 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:06.636Z,1557952866.636 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:06.637Z,1557952866.637 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:06.637Z,1557952866.637 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:06.637Z,1557952866.637 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:06.637Z,1557952866.637 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:07.042Z,1557952867.042 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:07.042Z,1557952867.042 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:07.042Z,1557952867.042 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:07.042Z,1557952867.042 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:07.043Z,1557952867.043 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:07.444Z,1557952867.444 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:07.444Z,1557952867.444 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:07.445Z,1557952867.445 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:07.445Z,1557952867.445 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:07.445Z,1557952867.445 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:07.849Z,1557952867.849 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:07.849Z,1557952867.849 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:07.849Z,1557952867.849 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:07.849Z,1557952867.849 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:07.849Z,1557952867.849 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:08.253Z,1557952868.253 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:08.253Z,1557952868.253 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:08.253Z,1557952868.253 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:08.253Z,1557952868.253 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:08.253Z,1557952868.253 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:08.658Z,1557952868.658 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:08.658Z,1557952868.658 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:08.658Z,1557952868.658 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-05-15T20:41:08.658Z,1557952868.658 [BR_Ping1D] Data Fault, FailCount= 3
2019-05-15T20:41:08.658Z,1557952868.658 [BR_Ping1D](ERROR): Data Fault
2019-05-15T20:41:08.722Z,1557952868.722 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T20:41:09.061Z,1557952869.061 [BR_Ping1D](INFO): Stop
2019-05-15T20:41:09.061Z,1557952869.061 [BR_Ping1D](INFO): uninitialize
2019-05-15T20:41:09.061Z,1557952869.061 [BR_Ping1D](INFO): Powering down
2019-05-15T20:41:09.489Z,1557952869.489 [BR_Ping1D](INFO): Stopping
2019-05-15T20:41:09.881Z,1557952869.881 [BR_Ping1D](INFO): Stopping
2019-05-15T20:41:10.286Z,1557952870.286 [BR_Ping1D](INFO): Stopped
2019-05-15T20:41:10.335Z,1557952870.335 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T20:41:10.335Z,1557952870.335 [BR_Ping1D] No Fault, FailCount= 3
2019-05-15T20:41:10.688Z,1557952870.688 [BR_Ping1D](INFO): Start
2019-05-15T20:41:11.496Z,1557952871.496 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:11.497Z,1557952871.497 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T20:41:11.509Z,1557952871.509 [BR_Ping1D](INFO): Sending UART packet: 42520200EC030000F4017A02
2019-05-15T20:41:11.509Z,1557952871.509 [BR_Ping1D](INFO): Sending UART packet: 42520100EB030000018401
2019-05-15T20:41:11.509Z,1557952871.509 [BR_Ping1D](INFO): Sending UART packet: 42520400EA03000000EED11C6003
2019-05-15T20:41:11.510Z,1557952871.510 [BR_Ping1D](INFO): Sending UART packet: 42520100EE030000018701
2019-05-15T20:41:12.304Z,1557952872.304 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:12.304Z,1557952872.304 [BR_Ping1D](INFO): UART requesting status
2019-05-15T20:41:12.305Z,1557952872.305 [BR_Ping1D](INFO): Sending UART packet: 42520000BA0400005201
2019-05-15T20:41:12.305Z,1557952872.305 [BR_Ping1D](INFO): Sending UART packet: 42520000BF0400005701
2019-05-15T20:41:12.305Z,1557952872.305 [BR_Ping1D](INFO): Sending UART packet: 42520000B30400004B01
2019-05-15T20:41:12.713Z,1557952872.713 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:12.713Z,1557952872.713 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:12.713Z,1557952872.713 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T20:41:12.714Z,1557952872.714 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:12.714Z,1557952872.714 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:12.714Z,1557952872.714 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:13.112Z,1557952873.112 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:13.112Z,1557952873.112 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:13.130Z,1557952873.130 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:13.130Z,1557952873.130 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:13.131Z,1557952873.131 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:13.502Z,1557952873.502 [NAL9602](INFO): Powering up NAL9602
2019-05-15T20:41:13.518Z,1557952873.518 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:13.518Z,1557952873.518 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:13.518Z,1557952873.518 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:13.519Z,1557952873.519 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:13.519Z,1557952873.519 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:13.920Z,1557952873.920 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:13.921Z,1557952873.921 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:13.921Z,1557952873.921 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:13.921Z,1557952873.921 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:13.921Z,1557952873.921 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:13.993Z,1557952873.993 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-15T20:41:13.993Z,1557952873.993 [CTD_Seabird] Communications Fault, FailCount= 2
2019-05-15T20:41:13.993Z,1557952873.993 [CTD_Seabird](ERROR): Communications Fault
2019-05-15T20:41:14.324Z,1557952874.324 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:14.325Z,1557952874.325 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:14.325Z,1557952874.325 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:14.325Z,1557952874.325 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:14.325Z,1557952874.325 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:14.397Z,1557952874.397 [CTD_Seabird](INFO): Powering down
2019-05-15T20:41:14.412Z,1557952874.412 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-15T20:41:14.728Z,1557952874.728 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:14.728Z,1557952874.728 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:14.728Z,1557952874.728 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:14.729Z,1557952874.729 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:14.729Z,1557952874.729 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:14.766Z,1557952874.766 [SBIT](IMPORTANT): Beginning Startup BIT
2019-05-15T20:41:14.770Z,1557952874.770 [CBIT](IMPORTANT): Beginning ground fault scan
2019-05-15T20:41:15.133Z,1557952875.133 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:15.133Z,1557952875.133 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:15.133Z,1557952875.133 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:15.134Z,1557952875.134 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:15.134Z,1557952875.134 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:15.540Z,1557952875.540 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:15.541Z,1557952875.541 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:15.541Z,1557952875.541 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:15.541Z,1557952875.541 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:15.541Z,1557952875.541 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:15.642Z,1557952875.642 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-15T20:41:15.642Z,1557952875.642 [CTD_Seabird] No Fault, FailCount= 2
2019-05-15T20:41:15.741Z,1557952875.741 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-15T20:41:15.753Z,1557952875.753 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-15T20:41:15.943Z,1557952875.943 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:15.944Z,1557952875.944 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:15.944Z,1557952875.944 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:15.944Z,1557952875.944 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:15.944Z,1557952875.944 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:16.347Z,1557952876.347 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:16.348Z,1557952876.348 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:16.348Z,1557952876.348 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:16.348Z,1557952876.348 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:16.348Z,1557952876.348 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:16.822Z,1557952876.822 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:16.822Z,1557952876.822 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:16.822Z,1557952876.822 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-05-15T20:41:16.822Z,1557952876.822 [BR_Ping1D] Data Fault, FailCount= 4
2019-05-15T20:41:16.822Z,1557952876.822 [BR_Ping1D](ERROR): Data Fault
2019-05-15T20:41:17.306Z,1557952877.306 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T20:41:17.344Z,1557952877.344 [BR_Ping1D](INFO): Stop
2019-05-15T20:41:17.345Z,1557952877.345 [BR_Ping1D](INFO): uninitialize
2019-05-15T20:41:17.345Z,1557952877.345 [BR_Ping1D](INFO): Powering down
2019-05-15T20:41:17.745Z,1557952877.745 [BR_Ping1D](INFO): Stopping
2019-05-15T20:41:18.148Z,1557952878.148 [BR_Ping1D](INFO): Stopping
2019-05-15T20:41:18.556Z,1557952878.556 [BR_Ping1D](INFO): Stopped
2019-05-15T20:41:18.786Z,1557952878.786 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-05-15T20:41:18.786Z,1557952878.786 [BR_Ping1D] No Fault, FailCount= 4
2019-05-15T20:41:18.958Z,1557952878.958 [BR_Ping1D](INFO): Start
2019-05-15T20:41:19.745Z,1557952879.745 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-05-15T20:41:19.745Z,1557952879.745 [AHRS_M2] Data Fault, FailCount= 2
2019-05-15T20:41:19.745Z,1557952879.745 [AHRS_M2](ERROR): Data Fault
2019-05-15T20:41:19.765Z,1557952879.765 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:19.765Z,1557952879.765 [BR_Ping1D](INFO): UART setting parameters ...
2019-05-15T20:41:19.782Z,1557952879.782 [BR_Ping1D](INFO): Sending UART packet: 42520200EC030000F4017A02
2019-05-15T20:41:19.783Z,1557952879.783 [BR_Ping1D](INFO): Sending UART packet: 42520100EB030000018401
2019-05-15T20:41:19.784Z,1557952879.784 [BR_Ping1D](INFO): Sending UART packet: 42520400EA03000000EED11C6003
2019-05-15T20:41:19.796Z,1557952879.796 [BR_Ping1D](INFO): Sending UART packet: 42520100EE030000018701
2019-05-15T20:41:20.021Z,1557952880.021 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-05-15T20:41:20.137Z,1557952880.137 [AHRS_M2](INFO): Powering down
2019-05-15T20:41:20.560Z,1557952880.560 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:20.560Z,1557952880.560 [BR_Ping1D](INFO): UART requesting status
2019-05-15T20:41:20.560Z,1557952880.560 [BR_Ping1D](INFO): Sending UART packet: 42520000BA0400005201
2019-05-15T20:41:20.562Z,1557952880.562 [BR_Ping1D](INFO): Sending UART packet: 42520000BF0400005701
2019-05-15T20:41:20.563Z,1557952880.563 [BR_Ping1D](INFO): Sending UART packet: 42520000B30400004B01
2019-05-15T20:41:20.964Z,1557952880.964 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:20.965Z,1557952880.965 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:20.965Z,1557952880.965 [BR_Ping1D](INFO): Reset of UART receiver requested
2019-05-15T20:41:20.965Z,1557952880.965 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:20.965Z,1557952880.965 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:20.965Z,1557952880.965 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:21.054Z,1557952881.054 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-05-15T20:41:21.054Z,1557952881.054 [AHRS_M2] No Fault, FailCount= 2
2019-05-15T20:41:21.351Z,1557952881.351 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-05-15T20:41:21.376Z,1557952881.376 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:21.377Z,1557952881.377 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:21.377Z,1557952881.377 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:21.377Z,1557952881.377 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:21.377Z,1557952881.377 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:21.777Z,1557952881.777 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:21.777Z,1557952881.777 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:21.777Z,1557952881.777 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:21.777Z,1557952881.777 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:21.778Z,1557952881.778 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:22.176Z,1557952882.176 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:22.176Z,1557952882.176 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:22.177Z,1557952882.177 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:22.177Z,1557952882.177 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:22.177Z,1557952882.177 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:22.581Z,1557952882.581 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:22.581Z,1557952882.581 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:22.581Z,1557952882.581 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:22.582Z,1557952882.582 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:22.582Z,1557952882.582 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:22.984Z,1557952882.984 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:22.984Z,1557952882.984 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:22.985Z,1557952882.985 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:22.985Z,1557952882.985 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:22.985Z,1557952882.985 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:23.389Z,1557952883.389 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:23.389Z,1557952883.389 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:23.389Z,1557952883.389 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:23.389Z,1557952883.389 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:23.389Z,1557952883.389 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:23.800Z,1557952883.800 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:23.801Z,1557952883.801 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:23.801Z,1557952883.801 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:23.801Z,1557952883.801 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:23.801Z,1557952883.801 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:24.208Z,1557952884.208 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:24.208Z,1557952884.208 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:24.208Z,1557952884.208 [BR_Ping1D](INFO): No UART data
2019-05-15T20:41:24.208Z,1557952884.208 [BR_Ping1D](INFO): received UART packets: 0
2019-05-15T20:41:24.208Z,1557952884.208 [BR_Ping1D](INFO): not all values verified yet
2019-05-15T20:41:24.893Z,1557952884.893 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-05-15T20:41:24.911Z,1557952884.911 [NAL9602](INFO): NAL9602 initialized
2019-05-15T20:41:24.932Z,1557952884.932 [BR_Ping1D](INFO): readConfig
2019-05-15T20:41:24.932Z,1557952884.932 [BR_Ping1D](INFO): UART waiting for response
2019-05-15T20:41:24.932Z,1557952884.932 [BR_Ping1D](FAULT): Failed to set parameters in time during initialization.
2019-05-15T20:41:24.932Z,1557952884.932 [BR_Ping1D] Data Fault, FailCount= 5
2019-05-15T20:41:24.932Z,1557952884.932 [BR_Ping1D](ERROR): Data Fault
2019-05-15T20:41:25.018Z,1557952885.018 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-05-15T20:41:25.019Z,1557952885.019 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-05-15T20:41:25.373Z,1557952885.373 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-05-15T20:41:25.393Z,1557952885.393 [BR_Ping1D](INFO): Stop
2019-05-15T20:41:25.393Z,1557952885.393 [BR_Ping1D](INFO): uninitialize
2019-05-15T20:41:25.394Z,1557952885.394 [BR_Ping1D](INFO): Powering down
2019-05-15T20:41:25.615Z,1557952885.615 [CBIT](INFO): Critical error at 20190515T204125
2019-05-15T20:41:26.033Z,1557952886.033 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-05-15T20:41:26.065Z,1557952886.065 [NAL9602](DEBUG): Fix Requested
2019-05-15T20:41:26.079Z,1557952886.079 [BR_Ping1D](INFO): Stopping
2019-05-15T20:41:26.111Z,1557952886.111 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.029078
CHAN A1 (24V): 0.001312
CHAN A2 (12V): -0.004446
CHAN A3 (5V): -0.003016
CHAN B0 (3.3V): -0.001352
CHAN B1 (3.15aV): -0.001169
CHAN B2 (3.15bV): -0.001471
CHAN B3 (GND): -0.000403
OPEN: 0.003323
Full Scale Calc: 4.765 mA, -1.589 mA
2019-05-15T20:41:26.204Z,1557952886.204 [BR_Ping1D](INFO): Stopped
2019-05-15T20:41:28.797Z,1557952888.797 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-15T20:41:28.797Z,1557952888.797 [CTD_Seabird] Communications Fault, FailCount= 3
2019-05-15T20:41:28.797Z,1557952888.797 [CTD_Seabird](ERROR): Communications Fault
2019-05-15T20:41:29.026Z,1557952889.026 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-15T20:41:29.026Z,1557952889.026 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-05-15T20:41:29.201Z,1557952889.201 [CTD_Seabird](INFO): Powering down
2019-05-15T20:41:36.417Z,1557952896.417 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-05-15T20:41:36.417Z,1557952896.417 [AHRS_M2] Data Fault, FailCount= 3
2019-05-15T20:41:36.417Z,1557952896.417 [AHRS_M2](ERROR): Data Fault
2019-05-15T20:41:36.506Z,1557952896.506 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-05-15T20:41:36.809Z,1557952896.809 [AHRS_M2](INFO): Powering down
2019-05-15T20:41:37.702Z,1557952897.702 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-05-15T20:41:37.702Z,1557952897.702 [AHRS_M2] No Fault, FailCount= 3
2019-05-15T20:41:38.022Z,1557952898.022 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-05-15T20:41:51.367Z,1557952911.367 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-05-15T20:41:51.367Z,1557952911.367 [AHRS_M2] Data Fault, FailCount= 4
2019-05-15T20:41:51.367Z,1557952911.367 [AHRS_M2](ERROR): Data Fault
2019-05-15T20:41:51.404Z,1557952911.404 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-05-15T20:41:51.757Z,1557952911.757 [AHRS_M2](INFO): Powering down
2019-05-15T20:41:52.604Z,1557952912.604 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-05-15T20:41:52.604Z,1557952912.604 [AHRS_M2] No Fault, FailCount= 4
2019-05-15T20:41:52.969Z,1557952912.969 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-05-15T20:42:06.477Z,1557952926.477 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-05-15T20:42:06.477Z,1557952926.477 [AHRS_M2] Data Fault, FailCount= 5
2019-05-15T20:42:06.477Z,1557952926.477 [AHRS_M2](ERROR): Data Fault
2019-05-15T20:42:06.592Z,1557952926.592 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-05-15T20:42:06.593Z,1557952926.593 [CBIT](CRITICAL): Data Fault in component: AHRS_M2
2019-05-15T20:42:06.869Z,1557952926.869 [AHRS_M2](INFO): Powering down
2019-05-15T20:42:07.065Z,1557952927.065 [CBIT](INFO): Critical error at 20190515T204206
2019-05-15T20:42:08.136Z,1557952928.136 [SBIT](IMPORTANT): SBIT PASSED
2019-05-15T20:42:08.230Z,1557952928.230 [CommandLine](IMPORTANT): got command configSet list
2019-05-15T20:42:08.230Z,1557952928.230 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-05-15T20:42:08.231Z,1557952928.231 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;

2019-05-15T20:42:08.519Z,1557952928.519 [MissionManager](IMPORTANT): Started mission Startup
2019-05-15T20:42:08.519Z,1557952928.519 [Startup] Running Loop=1
2019-05-15T20:42:08.519Z,1557952928.519 [Startup](DEBUG): Aggregate::initialize Startup
2019-05-15T20:42:08.519Z,1557952928.519 [Startup:A.GoToSurface] Running Loop=1
2019-05-15T20:42:08.519Z,1557952928.519 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-15T20:42:08.520Z,1557952928.520 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-15T20:42:08.520Z,1557952928.520 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-15T20:42:08.521Z,1557952928.521 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-15T20:42:08.522Z,1557952928.522 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-15T20:42:08.522Z,1557952928.522 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-15T20:42:08.523Z,1557952928.523 [Startup:StartupSatComms] Running Loop=1
2019-05-15T20:42:08.524Z,1557952928.524 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-05-15T20:42:08.524Z,1557952928.524 [Startup:StartupSatComms:A] Running Loop=1
2019-05-15T20:42:08.930Z,1557952928.930 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-05-15T20:42:46.109Z,1557952966.109 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-15T20:42:46.109Z,1557952966.109 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-05-15T20:42:46.109Z,1557952966.109 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-15T20:42:46.121Z,1557952966.121 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-15T20:42:46.535Z,1557952966.535 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-15T20:42:46.535Z,1557952966.535 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-05-15T20:43:08.753Z,1557952988.753 [Startup:StartupSatComms:A](INFO): Timed out from 2019-05-15T20:42:08.5Z
2019-05-15T20:43:08.753Z,1557952988.753 [Startup:StartupSatComms:A] Stopped
2019-05-15T20:43:08.753Z,1557952988.753 [Startup:StartupSatComms:B] Running Loop=1
2019-05-15T20:43:09.119Z,1557952989.119 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-05-15T20:43:12.563Z,1557952992.563 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004561
2019-05-15T20:43:58.939Z,1557953038.939 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-15T20:43:58.939Z,1557953038.939 [CTD_Seabird] No Fault, FailCount= 3
2019-05-15T20:43:59.225Z,1557953039.225 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-15T20:43:59.229Z,1557953039.229 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-15T20:44:08.999Z,1557953048.999 [Startup:StartupSatComms:B](INFO): Timed out from 2019-05-15T20:43:08.8Z
2019-05-15T20:44:08.999Z,1557953048.999 [Startup:StartupSatComms:B] Stopped
2019-05-15T20:44:08.999Z,1557953048.999 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-05-15T20:44:08.999Z,1557953048.999 [Startup:StartupSatComms] Stopped
2019-05-15T20:44:08.999Z,1557953048.999 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-05-15T20:44:09.000Z,1557953049.000 [Startup](INFO): Completed Startup
2019-05-15T20:44:09.000Z,1557953049.000 [MissionManager](INFO): Startup is completed.
2019-05-15T20:44:09.000Z,1557953049.000 [MissionManager](INFO): Uninitializing Mission Startup
2019-05-15T20:44:09.000Z,1557953049.000 [Startup] Stopped
2019-05-15T20:44:09.033Z,1557953049.033 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-05-15T20:44:09.033Z,1557953049.033 [Startup:A.GoToSurface] Stopped
2019-05-15T20:44:09.033Z,1557953049.033 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-15T20:44:09.481Z,1557953049.481 [MissionManager](IMPORTANT): Started mission Default
2019-05-15T20:44:09.481Z,1557953049.481 [Default] Running Loop=1
2019-05-15T20:44:09.481Z,1557953049.481 [Default](DEBUG): Aggregate::initialize Default
2019-05-15T20:44:09.482Z,1557953049.482 [Default:B.GoToSurface] Running Loop=1
2019-05-15T20:44:09.482Z,1557953049.482 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-15T20:44:09.482Z,1557953049.482 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-15T20:44:09.482Z,1557953049.482 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-15T20:44:09.483Z,1557953049.483 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-15T20:44:09.483Z,1557953049.483 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-15T20:44:09.483Z,1557953049.483 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-15T20:44:09.483Z,1557953049.483 [Default:A.Wait] Running Loop=1
2019-05-15T20:44:09.483Z,1557953049.483 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-05-15T20:44:12.277Z,1557953052.277 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-15T20:44:12.277Z,1557953052.277 [CTD_Seabird] Communications Fault, FailCount= 1
2019-05-15T20:44:12.277Z,1557953052.277 [CTD_Seabird](ERROR): Communications Fault
2019-05-15T20:44:12.639Z,1557953052.639 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-15T20:44:12.681Z,1557953052.681 [CTD_Seabird](INFO): Powering down
2019-05-15T20:44:13.856Z,1557953053.856 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-15T20:44:13.856Z,1557953053.856 [CTD_Seabird] No Fault, FailCount= 1
2019-05-15T20:44:14.025Z,1557953054.025 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-15T20:44:14.027Z,1557953054.027 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-15T20:44:22.735Z,1557953062.735 [Default:A.Wait](INFO): Done Waiting.
2019-05-15T20:44:22.735Z,1557953062.735 [Default:A.Wait] Stopped
2019-05-15T20:44:22.735Z,1557953062.735 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-05-15T20:44:23.139Z,1557953063.139 [Default:CheckIn] Running Loop=1
2019-05-15T20:44:23.139Z,1557953063.139 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-05-15T20:44:23.139Z,1557953063.139 [Default:CheckIn:Read_GPS] Running Loop=1
2019-05-15T20:44:23.546Z,1557953063.546 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-05-15T20:44:27.089Z,1557953067.089 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-15T20:44:27.089Z,1557953067.089 [CTD_Seabird] Communications Fault, FailCount= 2
2019-05-15T20:44:27.089Z,1557953067.089 [CTD_Seabird](ERROR): Communications Fault
2019-05-15T20:44:27.187Z,1557953067.187 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-15T20:44:27.493Z,1557953067.493 [CTD_Seabird](INFO): Powering down
2019-05-15T20:44:28.808Z,1557953068.808 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-15T20:44:28.808Z,1557953068.808 [CTD_Seabird] No Fault, FailCount= 2
2019-05-15T20:44:28.837Z,1557953068.837 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-15T20:44:28.838Z,1557953068.838 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-15T20:44:41.889Z,1557953081.889 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-15T20:44:41.889Z,1557953081.889 [CTD_Seabird] Communications Fault, FailCount= 3
2019-05-15T20:44:41.889Z,1557953081.889 [CTD_Seabird](ERROR): Communications Fault
2019-05-15T20:44:42.151Z,1557953082.151 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-15T20:44:42.152Z,1557953082.152 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-05-15T20:44:42.293Z,1557953082.293 [CTD_Seabird](INFO): Powering down
2019-05-15T20:44:47.105Z,1557953087.105 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-15T20:44:47.105Z,1557953087.105 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-05-15T20:44:47.105Z,1557953087.105 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-15T20:44:47.147Z,1557953087.147 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-15T20:44:47.556Z,1557953087.556 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-15T20:44:47.556Z,1557953087.556 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-05-15T20:45:24.755Z,1557953124.755 [CommandLine](IMPORTANT): got command restart application
2019-05-15T20:45:25.762Z,1557953125.762 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-15T20:45:25.762Z,1557953125.762 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-15T20:45:25.801Z,1557953125.801 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-05-15T20:45:25.801Z,1557953125.801 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-15T20:45:25.802Z,1557953125.802 [CommandLine](INFO): Join timeout helper Thread ID is 3014
2019-05-15T20:45:25.802Z,1557953125.802 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-05-15T20:45:25.802Z,1557953125.802 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-15T20:45:25.803Z,1557953125.803 [NavChartDb](INFO): Join timeout helper Thread ID is 3015
2019-05-15T20:45:26.085Z,1557953126.085 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-15T20:45:26.085Z,1557953126.085 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-15T20:45:26.101Z,1557953126.101 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-05-15T20:45:26.101Z,1557953126.101 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-15T20:45:26.101Z,1557953126.101 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3016
2019-05-15T20:45:26.445Z,1557953126.445 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-15T20:45:26.446Z,1557953126.446 [WetLabsBB2FL](INFO): Powering down
2019-05-15T20:45:26.446Z,1557953126.446 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-15T20:45:26.449Z,1557953126.449 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-05-15T20:45:26.449Z,1557953126.449 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-05-15T20:45:26.449Z,1557953126.449 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3017
2019-05-15T20:45:26.461Z,1557953126.461 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-15T20:45:26.461Z,1557953126.461 [CTD_Seabird](INFO): Powering down
2019-05-15T20:45:26.473Z,1557953126.473 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-05-15T20:45:26.473Z,1557953126.473 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-05-15T20:45:26.474Z,1557953126.474 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-15T20:45:26.474Z,1557953126.474 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 3018
2019-05-15T20:45:26.669Z,1557953126.669 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-15T20:45:26.669Z,1557953126.669 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-15T20:45:26.690Z,1557953126.690 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-05-15T20:45:26.690Z,1557953126.690 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-15T20:45:26.690Z,1557953126.690 [Radio_Surface](INFO): Join timeout helper Thread ID is 3019
2019-05-15T20:45:26.901Z,1557953126.901 [Radio_Surface](INFO): Powering down
2019-05-15T20:45:26.902Z,1557953126.902 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-15T20:45:26.902Z,1557953126.902 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-15T20:45:26.914Z,1557953126.914 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-05-15T20:45:26.914Z,1557953126.914 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-15T20:45:26.914Z,1557953126.914 [DataOverHttps](INFO): Join timeout helper Thread ID is 3020
2019-05-15T20:45:27.121Z,1557953127.121 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-15T20:45:27.121Z,1557953127.121 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-15T20:45:27.142Z,1557953127.142 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-05-15T20:45:27.142Z,1557953127.142 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-15T20:45:27.142Z,1557953127.142 [logger](INFO): Join timeout helper Thread ID is 3021
2019-05-15T20:45:27.193Z,1557953127.193 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-15T20:45:27.193Z,1557953127.193 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-15T20:45:27.202Z,1557953127.202 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-05-15T20:45:27.202Z,1557953127.202 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-15T20:45:27.202Z,1557953127.202 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-05-15T20:45:27.202Z,1557953127.202 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-15T20:45:27.202Z,1557953127.202 [controlThread](INFO): Join timeout helper Thread ID is 3022
2019-05-15T20:45:27.353Z,1557953127.353 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-15T20:45:27.353Z,1557953127.353 [controlThread](DEBUG): Uninitializing ControlThread
2019-05-15T20:45:27.353Z,1557953127.353 [AHRS_M2](INFO): Powering down
2019-05-15T20:45:27.355Z,1557953127.355 [NAL9602](INFO): Powering down
2019-05-15T20:45:27.356Z,1557953127.356 [BR_Ping1D](INFO): uninitialize
2019-05-15T20:45:27.356Z,1557953127.356 [BR_Ping1D](INFO): Powering down
2019-05-15T20:45:27.358Z,1557953127.358 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-05-15T20:45:27.358Z,1557953127.358 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-05-15T20:45:27.358Z,1557953127.358 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-05-15T20:45:27.359Z,1557953127.359 [MissionManager](INFO): Uninitializing Mission Default
2019-05-15T20:45:27.359Z,1557953127.359 [Default] Stopped
2019-05-15T20:45:27.359Z,1557953127.359 [Default](DEBUG): Aggregate::uninitialize Default
2019-05-15T20:45:27.359Z,1557953127.359 [Default:B.GoToSurface] Stopped
2019-05-15T20:45:27.359Z,1557953127.359 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-15T20:45:27.359Z,1557953127.359 [Default:CheckIn] Stopped
2019-05-15T20:45:27.359Z,1557953127.359 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-05-15T20:45:27.359Z,1557953127.359 [Default:CheckIn:Read_GPS] Stopped
2019-05-15T20:45:27.362Z,1557953127.362 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-05-15T20:45:27.362Z,1557953127.362 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-05-15T20:45:27.363Z,1557953127.363 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-05-15T20:45:27.363Z,1557953127.363 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-05-15T20:45:27.363Z,1557953127.363 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-05-15T20:45:27.363Z,1557953127.363 [BuoyancyServo](INFO): Powering down
2019-05-15T20:45:27.377Z,1557953127.377 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-05-15T20:45:27.377Z,1557953127.377 [ElevatorServo](INFO): Powering down
2019-05-15T20:45:27.378Z,1557953127.378 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-05-15T20:45:27.378Z,1557953127.378 [MassServo](INFO): Powering down
2019-05-15T20:45:27.379Z,1557953127.379 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-05-15T20:45:27.379Z,1557953127.379 [RudderServo](INFO): Powering down
2019-05-15T20:45:27.380Z,1557953127.380 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-05-15T20:45:27.380Z,1557953127.380 [ThrusterServo](INFO): Powering down
2019-05-15T20:45:27.381Z,1557953127.381 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-05-15T20:45:27.381Z,1557953127.381 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-05-15T20:45:27.381Z,1557953127.381 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-05-15T20:45:27.381Z,1557953127.381 [CBIT](DEBUG): Powering off loads.
2019-05-15T20:45:27.393Z,1557953127.393 [CBIT](DEBUG): Disabling WDT.
2019-05-15T20:45:27.405Z,1557953127.405 [CBIT](DEBUG): Opening all GF detection circuits.
2019-05-15T20:45:27.406Z,1557953127.406 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-15T20:45:27.486Z,1557953127.486 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-15T20:45:27.492Z,1557953127.492 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-15T20:45:27.531Z,1557953127.531 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-15T20:45:27.534Z,1557953127.534 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-05-15T20:45:27.537Z,1557953127.537 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-05-15T20:45:27.567Z,1557953127.567 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-15T20:45:27.624Z,1557953127.624 [logger ThreadHandler](INFO): Thread cancelled.