2019-10-15T22:45:01.476Z,1571179501.476 [Supervisor](DEBUG): Initializing supervisor.
2019-10-15T22:45:01.478Z,1571179501.478 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-10-15T22:45:01.479Z,1571179501.479 [SyncHandler](INFO): Protected caller Thread ID is 7581
2019-10-15T22:45:01.479Z,1571179501.479 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-10-15T22:45:01.480Z,1571179501.480 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-10-15T22:45:01.481Z,1571179501.481 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7582
2019-10-15T22:45:01.483Z,1571179501.483 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-10-15T22:45:01.495Z,1571179501.495 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-10-15T22:45:01.496Z,1571179501.496 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-10-15T22:45:01.496Z,1571179501.496 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7583
2019-10-15T22:45:01.497Z,1571179501.497 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-10-15T22:45:01.498Z,1571179501.498 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-10-15T22:45:01.499Z,1571179501.499 [logger ThreadHandler](INFO): Protected caller Thread ID is 7584
2019-10-15T22:45:01.501Z,1571179501.501 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-10-15T22:45:01.501Z,1571179501.501 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-10-15T22:45:01.505Z,1571179501.505 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-10-15T22:45:01.946Z,1571179501.946 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-10-15T22:45:01.947Z,1571179501.947 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-10-15T22:45:02.093Z,1571179502.093 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-10-15T22:45:02.094Z,1571179502.094 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-10-15T22:45:02.174Z,1571179502.174 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-10-15T22:45:02.273Z,1571179502.273 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-10-15T22:45:02.273Z,1571179502.273 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-10-15T22:45:02.369Z,1571179502.369 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-10-15T22:45:02.370Z,1571179502.370 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-10-15T22:45:02.763Z,1571179502.763 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-10-15T22:45:02.764Z,1571179502.764 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-10-15T22:45:02.866Z,1571179502.866 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-10-15T22:45:02.867Z,1571179502.867 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-10-15T22:45:03.343Z,1571179503.343 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-10-15T22:45:03.344Z,1571179503.344 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-10-15T22:45:03.552Z,1571179503.552 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-10-15T22:45:03.554Z,1571179503.554 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-10-15T22:45:03.869Z,1571179503.869 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-10-15T22:45:03.869Z,1571179503.869 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-10-15T22:45:04.105Z,1571179504.105 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-10-15T22:45:04.106Z,1571179504.106 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-10-15T22:45:04.306Z,1571179504.306 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-10-15T22:45:04.306Z,1571179504.306 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-10-15T22:45:04.979Z,1571179504.979 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-10-15T22:45:04.979Z,1571179504.979 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-10-15T22:45:05.326Z,1571179505.326 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-10-15T22:45:05.327Z,1571179505.327 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-10-15T22:45:05.539Z,1571179505.539 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-10-15T22:45:05.541Z,1571179505.541 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2019-10-15T22:45:05.542Z,1571179505.542 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2019-10-15T22:45:05.741Z,1571179505.741 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2019-10-15T22:45:05.823Z,1571179505.823 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2019-10-15T22:45:05.963Z,1571179505.963 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2019-10-15T22:45:06.048Z,1571179506.048 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2019-10-15T22:45:06.277Z,1571179506.277 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-10-15T22:45:06.277Z,1571179506.277 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2019-10-15T22:45:06.372Z,1571179506.372 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2019-10-15T22:45:06.481Z,1571179506.481 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2019-10-15T22:45:06.589Z,1571179506.589 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2019-10-15T22:45:07.120Z,1571179507.120 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2019-10-15T22:45:07.222Z,1571179507.222 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2019-10-15T22:45:07.323Z,1571179507.323 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2019-10-15T22:45:07.323Z,1571179507.323 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2019-10-15T22:45:07.324Z,1571179507.324 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-10-15T22:45:07.338Z,1571179507.338 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-10-15T22:45:07.339Z,1571179507.339 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-10-15T22:45:07.409Z,1571179507.409 [VerticalControl](DEBUG): Construct VerticalControl.
2019-10-15T22:45:07.527Z,1571179507.527 [VerticalControl] Loaded
2019-10-15T22:45:07.528Z,1571179507.528 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-10-15T22:45:07.528Z,1571179507.528 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-10-15T22:45:07.601Z,1571179507.601 [HorizontalControl] Loaded
2019-10-15T22:45:07.601Z,1571179507.601 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-10-15T22:45:07.602Z,1571179507.602 [SpeedControl](DEBUG): Construct SpeedControl.
2019-10-15T22:45:07.608Z,1571179507.608 [SpeedControl] Loaded
2019-10-15T22:45:07.608Z,1571179507.608 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-10-15T22:45:07.609Z,1571179507.609 [LoopControl](DEBUG): Construct LoopControl.
2019-10-15T22:45:07.609Z,1571179507.609 [LoopControl] Loaded
2019-10-15T22:45:07.610Z,1571179507.610 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-10-15T22:45:07.610Z,1571179507.610 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-10-15T22:45:07.611Z,1571179507.611 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-10-15T22:45:07.716Z,1571179507.716 [BuoyancyServo] Loaded
2019-10-15T22:45:07.716Z,1571179507.716 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-10-15T22:45:07.728Z,1571179507.728 [ElevatorServo] Loaded
2019-10-15T22:45:07.728Z,1571179507.728 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-10-15T22:45:07.739Z,1571179507.739 [MassServo] Loaded
2019-10-15T22:45:07.739Z,1571179507.739 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-10-15T22:45:07.751Z,1571179507.751 [RudderServo] Loaded
2019-10-15T22:45:07.751Z,1571179507.751 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-10-15T22:45:07.762Z,1571179507.762 [ThrusterServo] Loaded
2019-10-15T22:45:07.763Z,1571179507.763 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-10-15T22:45:07.763Z,1571179507.763 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-10-15T22:45:07.764Z,1571179507.764 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-10-15T22:45:07.807Z,1571179507.807 [DepthRateCalculator] Loaded
2019-10-15T22:45:07.807Z,1571179507.807 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-10-15T22:45:07.813Z,1571179507.813 [PitchRateCalculator] Loaded
2019-10-15T22:45:07.813Z,1571179507.813 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-10-15T22:45:07.829Z,1571179507.829 [SpeedCalculator] Loaded
2019-10-15T22:45:07.829Z,1571179507.829 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-10-15T22:45:07.851Z,1571179507.851 [TempGradientCalculator] Loaded
2019-10-15T22:45:07.852Z,1571179507.852 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-10-15T22:45:07.857Z,1571179507.857 [YawRateCalculator] Loaded
2019-10-15T22:45:07.857Z,1571179507.857 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-10-15T22:45:07.888Z,1571179507.888 [ElevatorOffsetCalculator] Loaded
2019-10-15T22:45:07.889Z,1571179507.889 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-10-15T22:45:07.889Z,1571179507.889 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-10-15T22:45:07.890Z,1571179507.890 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-10-15T22:45:07.995Z,1571179507.995 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-10-15T22:45:07.996Z,1571179507.996 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-10-15T22:45:08.018Z,1571179508.018 [NavChart] Loaded
2019-10-15T22:45:08.018Z,1571179508.018 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-10-15T22:45:08.022Z,1571179508.022 [UniversalFixResidualReporter] Loaded
2019-10-15T22:45:08.022Z,1571179508.022 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-10-15T22:45:08.023Z,1571179508.023 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-10-15T22:45:08.023Z,1571179508.023 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-10-15T22:45:08.270Z,1571179508.270 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2019-10-15T22:45:08.275Z,1571179508.275 [AHRS_M2](INFO): created writer for : platform_orientation
2019-10-15T22:45:08.277Z,1571179508.277 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2019-10-15T22:45:08.282Z,1571179508.282 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2019-10-15T22:45:08.283Z,1571179508.283 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2019-10-15T22:45:08.288Z,1571179508.288 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2019-10-15T22:45:08.289Z,1571179508.289 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2019-10-15T22:45:08.294Z,1571179508.294 [AHRS_M2](INFO): created writer for : platform_roll_angle
2019-10-15T22:45:08.367Z,1571179508.367 [AHRS_M2] Loaded
2019-10-15T22:45:08.368Z,1571179508.368 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-10-15T22:45:08.442Z,1571179508.442 [DataOverHttps] Loaded
2019-10-15T22:45:08.442Z,1571179508.442 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-10-15T22:45:08.443Z,1571179508.443 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407F84E0
2019-10-15T22:45:08.444Z,1571179508.444 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7664
2019-10-15T22:45:08.458Z,1571179508.458 [Depth_Keller] Loaded
2019-10-15T22:45:08.458Z,1571179508.458 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-10-15T22:45:08.463Z,1571179508.463 [DropWeight] Loaded
2019-10-15T22:45:08.464Z,1571179508.464 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-10-15T22:45:08.511Z,1571179508.511 [DVL_micro] Loaded
2019-10-15T22:45:08.512Z,1571179508.512 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2019-10-15T22:45:08.612Z,1571179508.612 [NAL9602] Loaded
2019-10-15T22:45:08.613Z,1571179508.613 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-10-15T22:45:08.629Z,1571179508.629 [Onboard] Loaded
2019-10-15T22:45:08.630Z,1571179508.630 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-10-15T22:45:08.633Z,1571179508.633 [Radio_Surface] Loaded
2019-10-15T22:45:08.633Z,1571179508.633 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-10-15T22:45:08.634Z,1571179508.634 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408284E0
2019-10-15T22:45:08.635Z,1571179508.635 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7665
2019-10-15T22:45:08.768Z,1571179508.768 [DAT] Loaded
2019-10-15T22:45:08.769Z,1571179508.769 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2019-10-15T22:45:10.315Z,1571179510.315 [BPC1] Loaded
2019-10-15T22:45:10.316Z,1571179510.316 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-10-15T22:45:10.316Z,1571179510.316 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-10-15T22:45:10.317Z,1571179510.317 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-10-15T22:45:10.390Z,1571179510.390 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-10-15T22:45:10.390Z,1571179510.390 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-10-15T22:45:10.493Z,1571179510.493 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-10-15T22:45:10.493Z,1571179510.493 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-10-15T22:45:10.526Z,1571179510.526 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-10-15T22:45:10.527Z,1571179510.527 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-10-15T22:45:10.707Z,1571179510.707 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-10-15T22:45:10.712Z,1571179510.712 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-10-15T22:45:10.712Z,1571179510.712 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-10-15T22:45:10.717Z,1571179510.717 [CTD_Seabird](INFO): created writer for : depth
2019-10-15T22:45:10.718Z,1571179510.718 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-10-15T22:45:10.723Z,1571179510.723 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-10-15T22:45:10.724Z,1571179510.724 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-10-15T22:45:10.729Z,1571179510.729 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-10-15T22:45:10.730Z,1571179510.730 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-10-15T22:45:10.735Z,1571179510.735 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-10-15T22:45:10.736Z,1571179510.736 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-10-15T22:45:10.742Z,1571179510.742 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-10-15T22:45:10.742Z,1571179510.742 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-10-15T22:45:10.748Z,1571179510.748 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-10-15T22:45:10.748Z,1571179510.748 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): mass_concentration_of_oxygen_in_sea_water
2019-10-15T22:45:10.754Z,1571179510.754 [CTD_Seabird](INFO): created writer for : mass_concentration_of_oxygen_in_sea_water
2019-10-15T22:45:10.755Z,1571179510.755 [CTD_Seabird](DEBUG): LcmSlateWriter::add(DAtaURI): CTD_Seabird.sea_water_dissolved_oxygen_freq
2019-10-15T22:45:10.755Z,1571179510.755 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): dataName: sea_water_dissolved_oxygen_freq
2019-10-15T22:45:10.789Z,1571179510.789 [CTD_Seabird] Loaded
2019-10-15T22:45:10.790Z,1571179510.790 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-10-15T22:45:10.791Z,1571179510.791 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409BF4E0
2019-10-15T22:45:10.791Z,1571179510.791 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7666
2019-10-15T22:45:10.823Z,1571179510.823 [ESPComponent] Loaded
2019-10-15T22:45:10.824Z,1571179510.824 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-10-15T22:45:10.838Z,1571179510.838 [PAR_Licor] Loaded
2019-10-15T22:45:10.838Z,1571179510.838 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-10-15T22:45:10.889Z,1571179510.889 [WetLabsBB2FL] Loaded
2019-10-15T22:45:10.889Z,1571179510.889 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-10-15T22:45:10.890Z,1571179510.890 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409EF4E0
2019-10-15T22:45:10.890Z,1571179510.890 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7667
2019-10-15T22:45:10.891Z,1571179510.891 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-10-15T22:45:10.892Z,1571179510.892 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-10-15T22:45:11.201Z,1571179511.201 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-10-15T22:45:11.202Z,1571179511.202 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-10-15T22:45:11.341Z,1571179511.341 [SBIT](DEBUG): Construct Startup Built In Test.
2019-10-15T22:45:11.352Z,1571179511.352 [SBIT] Loaded
2019-10-15T22:45:11.353Z,1571179511.353 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-10-15T22:45:11.353Z,1571179511.353 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-10-15T22:45:11.365Z,1571179511.365 [IBIT] Loaded
2019-10-15T22:45:11.365Z,1571179511.365 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-10-15T22:45:11.368Z,1571179511.368 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-10-15T22:45:11.515Z,1571179511.515 [CBIT] Loaded
2019-10-15T22:45:11.515Z,1571179511.515 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-10-15T22:45:11.516Z,1571179511.516 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-10-15T22:45:11.520Z,1571179511.520 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-10-15T22:45:11.521Z,1571179511.521 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-10-15T22:45:11.527Z,1571179511.527 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-10-15T22:45:11.529Z,1571179511.529 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACE4E0
2019-10-15T22:45:11.529Z,1571179511.529 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7668
2019-10-15T22:45:11.534Z,1571179511.534 [Supervisor](INFO): Main Thread ID is 7580
2019-10-15T22:45:11.534Z,1571179511.534 [Supervisor](DEBUG): Running supervisor.
2019-10-15T22:45:11.535Z,1571179511.535 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7669
2019-10-15T22:45:11.537Z,1571179511.537 [controlThread ThreadHandler](INFO): Handler Thread ID is 7670
2019-10-15T22:45:11.537Z,1571179511.537 [controlThread](DEBUG): Initializing ControlThread
2019-10-15T22:45:11.538Z,1571179511.538 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-10-15T22:45:11.540Z,1571179511.540 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-10-15T22:45:11.540Z,1571179511.540 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-10-15T22:45:11.541Z,1571179511.541 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-10-15T22:45:11.542Z,1571179511.542 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-10-15T22:45:11.543Z,1571179511.543 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-10-15T22:45:11.543Z,1571179511.543 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-10-15T22:45:11.543Z,1571179511.543 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-10-15T22:45:11.544Z,1571179511.544 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-10-15T22:45:11.544Z,1571179511.544 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-10-15T22:45:11.545Z,1571179511.545 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-10-15T22:45:11.546Z,1571179511.546 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-10-15T22:45:11.553Z,1571179511.553 [SBIT](INFO): Initialize SBIT Component.
2019-10-15T22:45:11.554Z,1571179511.554 [SBIT](IMPORTANT): git: 2019-09-23_TRN-10-g363bc01
2019-10-15T22:45:11.554Z,1571179511.554 [SBIT](INFO): git hash: 363bc01ea79a8c50235a8d518caa16d007addd55
2019-10-15T22:45:11.554Z,1571179511.554 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-10-15T22:45:11.555Z,1571179511.555 [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-10-15T22:45:11.556Z,1571179511.556 [SBIT](INFO): Beginning SBIT in 38.000000 seconds.
2019-10-15T22:45:11.557Z,1571179511.557 [IBIT](INFO): Initialize IBIT Component.
2019-10-15T22:45:11.558Z,1571179511.558 [CBIT](DEBUG): Initialize CBIT Component.
2019-10-15T22:45:11.559Z,1571179511.559 [logger ThreadHandler](INFO): Handler Thread ID is 7671
2019-10-15T22:45:11.569Z,1571179511.569 [CBIT](DEBUG): Initialized mux pins.
2019-10-15T22:45:11.569Z,1571179511.569 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2019-10-15T22:45:11.569Z,1571179511.569 [CBIT](DEBUG): Initializing the watchdog timer.
2019-10-15T22:45:11.577Z,1571179511.577 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7672
2019-10-15T22:45:11.578Z,1571179511.578 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-10-15T22:45:11.589Z,1571179511.589 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7673
2019-10-15T22:45:11.593Z,1571179511.593 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-10-15T22:45:11.593Z,1571179511.593 [CBIT](DEBUG): Initializing heartbeat.
2019-10-15T22:45:11.601Z,1571179511.601 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7674
2019-10-15T22:45:11.602Z,1571179511.602 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-15T22:45:11.605Z,1571179511.605 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-15T22:45:11.606Z,1571179511.606 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7676
2019-10-15T22:45:11.608Z,1571179511.608 [WetLabsBB2FL](INFO): Powering down
2019-10-15T22:45:11.634Z,1571179511.634 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7677
2019-10-15T22:45:11.638Z,1571179511.638 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-10-15T22:45:11.638Z,1571179511.638 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-10-15T22:45:11.638Z,1571179511.638 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-10-15T22:45:11.638Z,1571179511.638 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-10-15T22:45:11.639Z,1571179511.639 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-10-15T22:45:11.639Z,1571179511.639 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-10-15T22:45:11.639Z,1571179511.639 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-10-15T22:45:11.639Z,1571179511.639 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-10-15T22:45:11.639Z,1571179511.639 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-10-15T22:45:11.640Z,1571179511.640 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-10-15T22:45:11.640Z,1571179511.640 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-10-15T22:45:11.640Z,1571179511.640 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-10-15T22:45:11.640Z,1571179511.640 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-10-15T22:45:11.640Z,1571179511.640 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-10-15T22:45:11.641Z,1571179511.641 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-10-15T22:45:11.641Z,1571179511.641 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-10-15T22:45:11.665Z,1571179511.665 [CBIT](DEBUG): Deactivating GF circuits.
2019-10-15T22:45:11.665Z,1571179511.665 [CBIT](DEBUG): Deactivating emergency mode.
2019-10-15T22:45:11.701Z,1571179511.701 [CBIT](DEBUG): Backplane powered.
2019-10-15T22:45:11.702Z,1571179511.702 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-10-15T22:45:11.711Z,1571179511.711 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-15T22:45:11.722Z,1571179511.722 [MissionManager](DEBUG):
2019-10-15T22:45:11.723Z,1571179511.723 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-10-15T22:45:11.779Z,1571179511.779 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-10-15T22:45:11.780Z,1571179511.780 [Default:A.Wait](DEBUG): Construct Wait.
2019-10-15T22:45:11.798Z,1571179511.798 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-15T22:45:11.829Z,1571179511.829 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-10-15T22:45:11.831Z,1571179511.831 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-10-15T22:45:11.853Z,1571179511.853 [Default:E.Execute](DEBUG): Construct Execute.
2019-10-15T22:45:11.856Z,1571179511.856 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2019-10-15T22:45:11.862Z,1571179511.862 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,BPC1,ESPComponent,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-10-15T22:45:11.881Z,1571179511.881 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-15T22:45:11.959Z,1571179511.959 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2019-10-15T22:45:11.959Z,1571179511.959 [DAT](INFO): Powering up
2019-10-15T22:45:11.960Z,1571179511.960 [DAT](DEBUG): Initializing DAT.
2019-10-15T22:45:11.993Z,1571179511.993 [Radio_Surface](INFO): Powering up
2019-10-15T22:45:12.033Z,1571179512.033 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2019-10-15T22:45:12.099Z,1571179512.099 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:45:12.113Z,1571179512.113 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-10-15T22:45:12.114Z,1571179512.114 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:45:12.129Z,1571179512.129 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-10-15T22:45:12.130Z,1571179512.130 [MassServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:45:12.141Z,1571179512.141 [MassServo](DEBUG): Initializing MassServo.
2019-10-15T22:45:12.142Z,1571179512.142 [RudderServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:45:12.153Z,1571179512.153 [RudderServo](DEBUG): Initializing RudderServo.
2019-10-15T22:45:12.154Z,1571179512.154 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:45:12.161Z,1571179512.161 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-10-15T22:45:12.290Z,1571179512.290 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2019-10-15T22:45:12.290Z,1571179512.290 [DropWeight] Hardware Fault, FailCount= 1
2019-10-15T22:45:12.290Z,1571179512.290 [DropWeight](ERROR): Hardware Fault
2019-10-15T22:45:12.342Z,1571179512.342 [CommandLine](FAULT): Scheduling is paused
2019-10-15T22:45:12.343Z,1571179512.343 [CBIT](INFO): Critical error at 20191015T224512
2019-10-15T22:45:12.343Z,1571179512.343 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-10-15T22:45:12.346Z,1571179512.346 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-10-15T22:45:12.346Z,1571179512.346 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-10-15T22:45:13.097Z,1571179513.097 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2019-10-15T22:45:13.169Z,1571179513.169 [ThrusterServo](FAULT): Thruster failed to initialize
2019-10-15T22:45:13.169Z,1571179513.169 [ThrusterServo] Communications Fault, FailCount= 1
2019-10-15T22:45:13.169Z,1571179513.169 [ThrusterServo](ERROR): Communications Fault
2019-10-15T22:45:13.170Z,1571179513.170 [CBIT](INFO): Critical error at 20191015T224512
2019-10-15T22:45:13.173Z,1571179513.173 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2019-10-15T22:45:13.370Z,1571179513.370 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-10-15T22:45:13.370Z,1571179513.370 [ThrusterServo](INFO): Powering down
2019-10-15T22:45:14.176Z,1571179514.176 [CBIT](INFO): Clearing failed state for component ThrusterServo
2019-10-15T22:45:14.176Z,1571179514.176 [ThrusterServo] No Fault, FailCount= 1
2019-10-15T22:45:14.493Z,1571179514.493 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:45:14.497Z,1571179514.497 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-10-15T22:45:15.521Z,1571179515.521 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:16.093Z,1571179516.093 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:16.609Z,1571179516.609 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:17.081Z,1571179517.081 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:17.537Z,1571179517.537 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:18.013Z,1571179518.013 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:18.469Z,1571179518.469 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:19.189Z,1571179519.189 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:19.837Z,1571179519.837 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:20.273Z,1571179520.273 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:20.729Z,1571179520.729 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:21.205Z,1571179521.205 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:21.633Z,1571179521.633 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:22.081Z,1571179522.081 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:22.541Z,1571179522.541 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:22.973Z,1571179522.973 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:23.401Z,1571179523.401 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:23.833Z,1571179523.833 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:24.257Z,1571179524.257 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:24.653Z,1571179524.653 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-15T22:45:24.653Z,1571179524.653 [CTD_Seabird] Communications Fault, FailCount= 1
2019-10-15T22:45:24.653Z,1571179524.653 [CTD_Seabird](ERROR): Communications Fault
2019-10-15T22:45:24.697Z,1571179524.697 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:24.809Z,1571179524.809 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-15T22:45:25.057Z,1571179525.057 [CTD_Seabird](INFO): Powering down
2019-10-15T22:45:25.137Z,1571179525.137 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:25.137Z,1571179525.137 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-10-15T22:45:25.137Z,1571179525.137 [AHRS_M2] Communications Fault, FailCount= 1
2019-10-15T22:45:25.137Z,1571179525.137 [AHRS_M2](ERROR): Communications Fault
2019-10-15T22:45:25.229Z,1571179525.229 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-10-15T22:45:25.238Z,1571179525.238 [AHRS_M2](INFO): Powering down
2019-10-15T22:45:26.125Z,1571179526.125 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-10-15T22:45:26.125Z,1571179526.125 [AHRS_M2] No Fault, FailCount= 1
2019-10-15T22:45:26.125Z,1571179526.125 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-10-15T22:45:26.125Z,1571179526.125 [CTD_Seabird] No Fault, FailCount= 1
2019-10-15T22:45:26.429Z,1571179526.429 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-15T22:45:26.431Z,1571179526.431 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-15T22:45:26.444Z,1571179526.444 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-15T22:45:27.978Z,1571179527.978 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004702
2019-10-15T22:45:29.278Z,1571179529.278 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-15T22:45:29.278Z,1571179529.278 [DVL_micro] Communications Fault, FailCount= 1
2019-10-15T22:45:29.278Z,1571179529.278 [DVL_micro](ERROR): Communications Fault
2019-10-15T22:45:29.377Z,1571179529.377 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-15T22:45:30.001Z,1571179530.001 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:30.077Z,1571179530.077 [DVL_micro](INFO): Powering down
2019-10-15T22:45:30.545Z,1571179530.545 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:30.973Z,1571179530.973 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:31.075Z,1571179531.075 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-15T22:45:31.075Z,1571179531.075 [DVL_micro] No Fault, FailCount= 1
2019-10-15T22:45:31.401Z,1571179531.401 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:31.829Z,1571179531.829 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:32.257Z,1571179532.257 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:32.673Z,1571179532.673 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:33.125Z,1571179533.125 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:33.561Z,1571179533.561 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:33.985Z,1571179533.985 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:34.437Z,1571179534.437 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:35.017Z,1571179535.017 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:35.465Z,1571179535.465 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:35.873Z,1571179535.873 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:36.281Z,1571179536.281 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:36.697Z,1571179536.697 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:37.125Z,1571179537.125 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:37.529Z,1571179537.529 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:37.953Z,1571179537.953 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:38.345Z,1571179538.345 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:38.741Z,1571179538.741 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:38.747Z,1571179538.747 [NAL9602](INFO): Powering up NAL9602
2019-10-15T22:45:39.161Z,1571179539.161 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:39.481Z,1571179539.481 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-15T22:45:39.481Z,1571179539.481 [CTD_Seabird] Communications Fault, FailCount= 2
2019-10-15T22:45:39.481Z,1571179539.481 [CTD_Seabird](ERROR): Communications Fault
2019-10-15T22:45:39.557Z,1571179539.557 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:39.557Z,1571179539.557 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-10-15T22:45:39.557Z,1571179539.557 [AHRS_M2] Communications Fault, FailCount= 2
2019-10-15T22:45:39.557Z,1571179539.557 [AHRS_M2](ERROR): Communications Fault
2019-10-15T22:45:39.646Z,1571179539.646 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-10-15T22:45:39.646Z,1571179539.646 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-15T22:45:39.655Z,1571179539.655 [AHRS_M2](INFO): Powering down
2019-10-15T22:45:39.885Z,1571179539.885 [CTD_Seabird](INFO): Powering down
2019-10-15T22:45:40.516Z,1571179540.516 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-10-15T22:45:40.516Z,1571179540.516 [AHRS_M2] No Fault, FailCount= 2
2019-10-15T22:45:40.860Z,1571179540.860 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-15T22:45:40.936Z,1571179540.936 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-10-15T22:45:40.936Z,1571179540.936 [CTD_Seabird] No Fault, FailCount= 2
2019-10-15T22:45:41.229Z,1571179541.229 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-15T22:45:41.237Z,1571179541.237 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-15T22:45:44.409Z,1571179544.409 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:44.821Z,1571179544.821 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:45.229Z,1571179545.229 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:45.661Z,1571179545.661 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:46.073Z,1571179546.073 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:46.477Z,1571179546.477 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:46.881Z,1571179546.881 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:47.301Z,1571179547.301 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:47.709Z,1571179547.709 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:48.125Z,1571179548.125 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:48.541Z,1571179548.541 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:48.945Z,1571179548.945 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:48.957Z,1571179548.957 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-15T22:45:48.957Z,1571179548.957 [DVL_micro] Communications Fault, FailCount= 2
2019-10-15T22:45:48.957Z,1571179548.957 [DVL_micro](ERROR): Communications Fault
2019-10-15T22:45:49.027Z,1571179549.027 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-15T22:45:49.361Z,1571179549.361 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:49.445Z,1571179549.445 [DVL_micro](INFO): Powering down
2019-10-15T22:45:49.865Z,1571179549.865 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:49.870Z,1571179549.870 [NAL9602](INFO): NAL9602 initialized
2019-10-15T22:45:50.285Z,1571179550.285 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:50.383Z,1571179550.383 [SBIT](IMPORTANT): Beginning Startup BIT
2019-10-15T22:45:50.395Z,1571179550.395 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-15T22:45:50.396Z,1571179550.396 [DVL_micro] No Fault, FailCount= 2
2019-10-15T22:45:50.396Z,1571179550.396 [CBIT](IMPORTANT): Beginning ground fault scan
2019-10-15T22:45:50.765Z,1571179550.765 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:50.789Z,1571179550.789 [NAL9602](DEBUG): Fix Requested
2019-10-15T22:45:51.253Z,1571179551.253 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:51.849Z,1571179551.849 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:52.393Z,1571179552.393 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:52.905Z,1571179552.905 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:53.841Z,1571179553.841 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:53.841Z,1571179553.841 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-10-15T22:45:53.841Z,1571179553.841 [AHRS_M2] Communications Fault, FailCount= 3
2019-10-15T22:45:53.841Z,1571179553.841 [AHRS_M2](ERROR): Communications Fault
2019-10-15T22:45:54.010Z,1571179554.010 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-10-15T22:45:54.020Z,1571179554.020 [AHRS_M2](INFO): Powering down
2019-10-15T22:45:54.281Z,1571179554.281 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-15T22:45:54.281Z,1571179554.281 [CTD_Seabird] Communications Fault, FailCount= 3
2019-10-15T22:45:54.281Z,1571179554.281 [CTD_Seabird](ERROR): Communications Fault
2019-10-15T22:45:54.558Z,1571179554.558 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-15T22:45:54.558Z,1571179554.558 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-10-15T22:45:54.685Z,1571179554.685 [CTD_Seabird](INFO): Powering down
2019-10-15T22:45:54.937Z,1571179554.937 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-10-15T22:45:54.937Z,1571179554.937 [AHRS_M2] No Fault, FailCount= 3
2019-10-15T22:45:55.224Z,1571179555.224 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-15T22:45:58.781Z,1571179558.781 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:59.325Z,1571179559.325 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:45:59.769Z,1571179559.769 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:46:00.197Z,1571179560.197 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:46:00.737Z,1571179560.737 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:46:01.173Z,1571179561.173 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:46:01.601Z,1571179561.601 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:46:02.221Z,1571179562.221 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:46:02.307Z,1571179562.307 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.007082
CHAN A1 (24V): 0.177781
CHAN A2 (12V): -0.004317
CHAN A3 (5V): 0.000068
CHAN B0 (3.3V): -0.000669
CHAN B1 (3.15aV): -0.001350
CHAN B2 (3.15bV): -0.000928
CHAN B3 (GND): 0.003052
OPEN: 0.004776
Full Scale Calc: 4.765 mA, -1.589 mA
2019-10-15T22:46:07.053Z,1571179567.053 [AHRS_M2](ERROR): Subscribe MagBufferActiveIndex UART error: serial timeout
2019-10-15T22:46:07.457Z,1571179567.457 [AHRS_M2](ERROR): Subscribe MagBufferActiveIndex UART error: serial timeout
2019-10-15T22:46:08.089Z,1571179568.089 [AHRS_M2](ERROR): Subscribe MagBufferActiveIndex UART error: serial timeout
2019-10-15T22:46:08.089Z,1571179568.089 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-10-15T22:46:08.089Z,1571179568.089 [AHRS_M2] Communications Fault, FailCount= 4
2019-10-15T22:46:08.089Z,1571179568.089 [AHRS_M2](ERROR): Communications Fault
2019-10-15T22:46:08.094Z,1571179568.094 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-15T22:46:08.094Z,1571179568.094 [DVL_micro] Communications Fault, FailCount= 3
2019-10-15T22:46:08.094Z,1571179568.094 [DVL_micro](ERROR): Communications Fault
2019-10-15T22:46:08.293Z,1571179568.293 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-10-15T22:46:08.294Z,1571179568.294 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-15T22:46:08.294Z,1571179568.294 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2019-10-15T22:46:08.299Z,1571179568.299 [AHRS_M2](INFO): Powering down
2019-10-15T22:46:08.453Z,1571179568.453 [DVL_micro](INFO): Powering down
2019-10-15T22:46:08.596Z,1571179568.596 [CBIT](INFO): Critical error at 20191015T224608
2019-10-15T22:46:09.245Z,1571179569.245 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-10-15T22:46:09.245Z,1571179569.245 [AHRS_M2] No Fault, FailCount= 4
2019-10-15T22:46:09.508Z,1571179569.508 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-15T22:46:12.358Z,1571179572.358 [DAT](FAULT): failed to initialize; deviceResponse_ loaded:
Teledyne Benthos DAT-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.12.21
Oct 15 2019 22:43:28
Features enabled [Bearing]
CONNECT, available: CC 12.50ms MP
Forwarding Delay UpTx time:22:43:36.5064
?
2019-10-15T22:46:12.358Z,1571179572.358 [DAT] Communications Fault, FailCount= 1
2019-10-15T22:46:12.358Z,1571179572.358 [DAT](ERROR): Communications Fault
2019-10-15T22:46:12.444Z,1571179572.444 [CBIT](ERROR): Communications Fault in component: DAT
2019-10-15T22:46:13.065Z,1571179573.065 [AHRS_M2](ERROR): Subscribe MagBufferActiveIndex UART error: serial timeout
2019-10-15T22:46:13.095Z,1571179573.095 [DAT](INFO): Powering down
2019-10-15T22:46:13.629Z,1571179573.629 [AHRS_M2](ERROR): Subscribe MagBufferActiveIndex UART error: serial timeout
2019-10-15T22:46:14.085Z,1571179574.085 [AHRS_M2](ERROR): Subscribe MagBufferActiveIndex UART error: serial timeout
2019-10-15T22:46:14.183Z,1571179574.183 [CBIT](INFO): Clearing failed state for component DAT
2019-10-15T22:46:14.183Z,1571179574.183 [DAT] No Fault, FailCount= 1
2019-10-15T22:46:14.509Z,1571179574.509 [AHRS_M2](ERROR): Subscribe MagBufferActiveIndex UART error: serial timeout
2019-10-15T22:46:14.921Z,1571179574.921 [AHRS_M2](ERROR): Subscribe MagBufferActiveIndex UART error: serial timeout
2019-10-15T22:46:15.333Z,1571179575.333 [AHRS_M2](ERROR): Subscribe MagBufferActiveIndex UART error: serial timeout
2019-10-15T22:46:15.753Z,1571179575.753 [AHRS_M2](ERROR): Subscribe MagBufferActiveIndex UART error: serial timeout
2019-10-15T22:46:16.169Z,1571179576.169 [AHRS_M2](ERROR): Subscribe MagBufferActiveIndex UART error: serial timeout
2019-10-15T22:46:16.194Z,1571179576.194 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2019-10-15T22:46:16.194Z,1571179576.194 [DAT](INFO): Powering up
2019-10-15T22:46:16.195Z,1571179576.195 [DAT](DEBUG): Initializing DAT.
2019-10-15T22:46:16.241Z,1571179576.241 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2019-10-15T22:46:16.653Z,1571179576.653 [AHRS_M2](ERROR): Subscribe MagBufferActiveIndex UART error: serial timeout
2019-10-15T22:46:17.077Z,1571179577.077 [AHRS_M2](ERROR): Subscribe MagBufferActiveIndex UART error: serial timeout
2019-10-15T22:46:17.509Z,1571179577.509 [AHRS_M2](ERROR): Subscribe MagBufferActiveIndex UART error: serial timeout
2019-10-15T22:46:17.929Z,1571179577.929 [AHRS_M2](ERROR): Subscribe MagBufferActiveIndex UART error: serial timeout
2019-10-15T22:46:18.357Z,1571179578.357 [AHRS_M2](ERROR): Subscribe MagBufferActiveIndex UART error: serial timeout
2019-10-15T22:46:18.797Z,1571179578.797 [AHRS_M2](ERROR): Subscribe MagBufferActiveIndex UART error: serial timeout
2019-10-15T22:46:19.217Z,1571179579.217 [AHRS_M2](ERROR): Subscribe MagBufferActiveIndex UART error: serial timeout
2019-10-15T22:46:19.637Z,1571179579.637 [AHRS_M2](ERROR): Subscribe MagBufferActiveIndex UART error: serial timeout
2019-10-15T22:46:20.085Z,1571179580.085 [AHRS_M2](ERROR): Subscribe MagBufferActiveIndex UART error: serial timeout
2019-10-15T22:46:20.553Z,1571179580.553 [AHRS_M2](ERROR): Subscribe MagBufferActiveIndex UART error: serial timeout
2019-10-15T22:46:20.981Z,1571179580.981 [AHRS_M2](ERROR): Subscribe MagBufferActiveIndex UART error: serial timeout
2019-10-15T22:46:21.421Z,1571179581.421 [AHRS_M2](ERROR): Subscribe MagBufferActiveIndex UART error: serial timeout
2019-10-15T22:46:21.837Z,1571179581.837 [AHRS_M2](ERROR): Subscribe MagBufferActiveIndex UART error: serial timeout
2019-10-15T22:46:22.257Z,1571179582.257 [AHRS_M2](ERROR): Subscribe MagBufferActiveIndex UART error: serial timeout
2019-10-15T22:46:22.766Z,1571179582.766 [AHRS_M2](ERROR): Subscribe MagBufferActiveIndex UART error: serial timeout
2019-10-15T22:46:22.766Z,1571179582.766 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-10-15T22:46:22.766Z,1571179582.766 [AHRS_M2] Communications Fault, FailCount= 5
2019-10-15T22:46:22.766Z,1571179582.766 [AHRS_M2](ERROR): Communications Fault
2019-10-15T22:46:22.858Z,1571179582.858 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-10-15T22:46:22.858Z,1571179582.858 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2019-10-15T22:46:22.864Z,1571179582.864 [AHRS_M2](INFO): Powering down
2019-10-15T22:46:22.987Z,1571179582.987 [CBIT](INFO): Critical error at 20191015T224622
2019-10-15T22:46:29.344Z,1571179589.344 [DAT](INFO): setting local address to 5
2019-10-15T22:46:29.750Z,1571179589.750 [DAT](INFO): set local address to 5
2019-10-15T22:46:43.999Z,1571179603.999 [SBIT](IMPORTANT): SBIT PASSED
2019-10-15T22:46:44.066Z,1571179604.066 [CommandLine](IMPORTANT): got command configSet list
2019-10-15T22:46:44.066Z,1571179604.066 [CommandLine](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2019-10-15T22:46:44.390Z,1571179604.390 [MissionManager](IMPORTANT): Started mission Startup
2019-10-15T22:46:44.390Z,1571179604.390 [Startup] Running Loop=1
2019-10-15T22:46:44.391Z,1571179604.391 [Startup](DEBUG): Aggregate::initialize Startup
2019-10-15T22:46:44.391Z,1571179604.391 [Startup:A.GoToSurface] Running Loop=1
2019-10-15T22:46:44.391Z,1571179604.391 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-15T22:46:44.391Z,1571179604.391 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-15T22:46:44.392Z,1571179604.392 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-15T22:46:44.392Z,1571179604.392 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-15T22:46:44.393Z,1571179604.393 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-15T22:46:44.393Z,1571179604.393 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-15T22:46:44.394Z,1571179604.394 [Startup:StartupSatComms] Running Loop=1
2019-10-15T22:46:44.395Z,1571179604.395 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-10-15T22:46:44.395Z,1571179604.395 [Startup:StartupSatComms:A] Running Loop=1
2019-10-15T22:46:44.798Z,1571179604.798 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-10-15T22:46:53.923Z,1571179613.923 [CommandLine](IMPORTANT): got command configSet list
2019-10-15T22:46:53.923Z,1571179613.923 [CommandLine](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2019-10-15T22:47:08.628Z,1571179628.628 [CommandLine](IMPORTANT): got command configSet DVL_micro.loadAtStartup 0.000000 bool persist
2019-10-15T22:47:08.628Z,1571179628.628 [CommandLine](IMPORTANT): configSet DVL_micro.loadAtStartup requires a restart to take effect.
2019-10-15T22:47:08.629Z,1571179628.629 [CommandLine](FAULT): Not allowing to overwrite existing Data/persisted.cfg after unclean shutdown.
2019-10-15T22:47:11.869Z,1571179631.869 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-10-15T22:47:11.869Z,1571179631.869 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-10-15T22:47:11.870Z,1571179631.870 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-15T22:47:11.880Z,1571179631.880 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-15T22:47:12.274Z,1571179632.274 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-15T22:47:12.274Z,1571179632.274 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-10-15T22:47:23.891Z,1571179643.891 [CommandLine](IMPORTANT): got command configSet DVL_micro.loadAtStartup 0.000000 bool persist
2019-10-15T22:47:23.891Z,1571179643.891 [CommandLine](IMPORTANT): configSet DVL_micro.loadAtStartup requires a restart to take effect.
2019-10-15T22:47:30.226Z,1571179650.226 [CommandLine](IMPORTANT): got command configSet list
2019-10-15T22:47:30.226Z,1571179650.226 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-10-15T22:47:30.227Z,1571179650.227 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool;
2019-10-15T22:47:44.591Z,1571179664.591 [Startup:StartupSatComms:A](INFO): Timed out from 2019-10-15T22:46:44.4Z
2019-10-15T22:47:44.591Z,1571179664.591 [Startup:StartupSatComms:A] Stopped
2019-10-15T22:47:44.591Z,1571179664.591 [Startup:StartupSatComms:B] Running Loop=1
2019-10-15T22:47:44.994Z,1571179664.994 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-10-15T22:47:51.172Z,1571179671.172 [CommandLine](IMPORTANT): got command configSet DAT.loadAtStartup 0.000000 bool persist
2019-10-15T22:47:51.172Z,1571179671.172 [CommandLine](IMPORTANT): configSet DAT.loadAtStartup requires a restart to take effect.
2019-10-15T22:47:56.495Z,1571179676.495 [CommandLine](IMPORTANT): got command configSet list
2019-10-15T22:47:56.495Z,1571179676.495 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-10-15T22:47:56.496Z,1571179676.496 [CommandLine](IMPORTANT): DAT.loadAtStartup=0 bool;
2019-10-15T22:47:56.496Z,1571179676.496 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool;
2019-10-15T22:48:03.617Z,1571179683.617 [DataOverHttps](INFO): Sending 159 bytes from file Logs/20191015T221415/Courier0000.lzma
2019-10-15T22:48:05.622Z,1571179685.622 [DataOverHttps](INFO): Moved sent file to Logs/20191015T221415/Courier0000.lzma.bak
2019-10-15T22:48:05.623Z,1571179685.623 [DataOverHttps](INFO): SBD MOMSN=11912753
2019-10-15T22:48:10.819Z,1571179690.819 [CommandLine](IMPORTANT): got command restart application
2019-10-15T22:48:11.825Z,1571179691.825 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:48:11.825Z,1571179691.825 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:48:11.873Z,1571179691.873 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2019-10-15T22:48:11.873Z,1571179691.873 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:48:11.874Z,1571179691.874 [CommandLine](INFO): Join timeout helper Thread ID is 7713
2019-10-15T22:48:11.874Z,1571179691.874 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-10-15T22:48:11.874Z,1571179691.874 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:48:11.875Z,1571179691.875 [NavChartDb](INFO): Join timeout helper Thread ID is 7714
2019-10-15T22:48:11.906Z,1571179691.906 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:48:11.906Z,1571179691.906 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:48:11.913Z,1571179691.913 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-10-15T22:48:11.913Z,1571179691.913 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:48:11.913Z,1571179691.913 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7715
2019-10-15T22:48:12.101Z,1571179692.101 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:48:12.101Z,1571179692.101 [WetLabsBB2FL](INFO): Powering down
2019-10-15T22:48:12.102Z,1571179692.102 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:48:12.113Z,1571179692.113 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-10-15T22:48:12.113Z,1571179692.113 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:48:12.113Z,1571179692.113 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7716
2019-10-15T22:48:12.229Z,1571179692.229 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:48:12.229Z,1571179692.229 [CTD_Seabird](INFO): Powering down
2019-10-15T22:48:12.241Z,1571179692.241 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:48:12.265Z,1571179692.265 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-10-15T22:48:12.265Z,1571179692.265 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:48:12.265Z,1571179692.265 [Radio_Surface](INFO): Join timeout helper Thread ID is 7717
2019-10-15T22:48:12.633Z,1571179692.633 [Radio_Surface](INFO): Powering down
2019-10-15T22:48:12.634Z,1571179692.634 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:48:12.634Z,1571179692.634 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:48:12.650Z,1571179692.650 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-10-15T22:48:12.650Z,1571179692.650 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:48:12.650Z,1571179692.650 [DataOverHttps](INFO): Join timeout helper Thread ID is 7718
2019-10-15T22:48:13.053Z,1571179693.053 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:48:13.054Z,1571179693.054 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:48:13.058Z,1571179693.058 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-10-15T22:48:13.058Z,1571179693.058 [logger ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:48:13.058Z,1571179693.058 [logger](INFO): Join timeout helper Thread ID is 7719
2019-10-15T22:48:13.093Z,1571179693.093 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:48:13.093Z,1571179693.093 [logger ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:48:13.097Z,1571179693.097 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-10-15T22:48:13.098Z,1571179693.098 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:48:13.098Z,1571179693.098 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-10-15T22:48:13.098Z,1571179693.098 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:48:13.098Z,1571179693.098 [controlThread](INFO): Join timeout helper Thread ID is 7720
2019-10-15T22:48:13.337Z,1571179693.337 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:48:13.337Z,1571179693.337 [controlThread](DEBUG): Uninitializing ControlThread
2019-10-15T22:48:13.338Z,1571179693.338 [AHRS_M2](INFO): Powering down
2019-10-15T22:48:13.339Z,1571179693.339 [DVL_micro](INFO): Powering down
2019-10-15T22:48:13.339Z,1571179693.339 [NAL9602](INFO): Powering down
2019-10-15T22:48:13.340Z,1571179693.340 [DAT](INFO): Powering down
2019-10-15T22:48:13.482Z,1571179693.482 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-10-15T22:48:13.483Z,1571179693.483 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-10-15T22:48:13.483Z,1571179693.483 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-10-15T22:48:13.484Z,1571179693.484 [MissionManager](INFO): Uninitializing Mission Default
2019-10-15T22:48:13.486Z,1571179693.486 [MissionManager](INFO): Uninitializing Mission Startup
2019-10-15T22:48:13.486Z,1571179693.486 [Startup] Stopped
2019-10-15T22:48:13.486Z,1571179693.486 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-10-15T22:48:13.486Z,1571179693.486 [Startup:A.GoToSurface] Stopped
2019-10-15T22:48:13.486Z,1571179693.486 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-10-15T22:48:13.486Z,1571179693.486 [Startup:StartupSatComms] Stopped
2019-10-15T22:48:13.487Z,1571179693.487 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-10-15T22:48:13.487Z,1571179693.487 [Startup:StartupSatComms:B] Stopped
2019-10-15T22:48:13.488Z,1571179693.488 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-10-15T22:48:13.488Z,1571179693.488 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-10-15T22:48:13.488Z,1571179693.488 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-10-15T22:48:13.489Z,1571179693.489 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-10-15T22:48:13.489Z,1571179693.489 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-10-15T22:48:13.489Z,1571179693.489 [BuoyancyServo](INFO): Powering down
2019-10-15T22:48:13.501Z,1571179693.501 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-10-15T22:48:13.501Z,1571179693.501 [ElevatorServo](INFO): Powering down
2019-10-15T22:48:13.502Z,1571179693.502 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-10-15T22:48:13.502Z,1571179693.502 [MassServo](INFO): Powering down
2019-10-15T22:48:13.503Z,1571179693.503 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-10-15T22:48:13.503Z,1571179693.503 [RudderServo](INFO): Powering down
2019-10-15T22:48:13.503Z,1571179693.503 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-10-15T22:48:13.504Z,1571179693.504 [ThrusterServo](INFO): Powering down
2019-10-15T22:48:13.504Z,1571179693.504 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-10-15T22:48:13.505Z,1571179693.505 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-10-15T22:48:13.505Z,1571179693.505 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-10-15T22:48:13.505Z,1571179693.505 [CBIT](DEBUG): Powering off loads.
2019-10-15T22:48:13.517Z,1571179693.517 [CBIT](DEBUG): Disabling WDT.
2019-10-15T22:48:13.529Z,1571179693.529 [CBIT](DEBUG): Opening all GF detection circuits.
2019-10-15T22:48:13.529Z,1571179693.529 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:48:13.607Z,1571179693.607 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:48:13.614Z,1571179693.614 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:48:13.651Z,1571179693.651 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:48:13.655Z,1571179693.655 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:48:13.688Z,1571179693.688 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:48:13.748Z,1571179693.748 [logger ThreadHandler](INFO): Thread cancelled.