2020-08-13T22:00:47.413Z,1597356047.413 [Supervisor](DEBUG): Initializing supervisor.
2020-08-13T22:00:47.416Z,1597356047.416 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-08-13T22:00:47.416Z,1597356047.416 [SyncHandler](INFO): Protected caller Thread ID is 2975
2020-08-13T22:00:47.416Z,1597356047.416 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-08-13T22:00:47.417Z,1597356047.417 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-08-13T22:00:47.418Z,1597356047.418 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2976
2020-08-13T22:00:47.420Z,1597356047.420 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-08-13T22:00:47.433Z,1597356047.433 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-08-13T22:00:47.434Z,1597356047.434 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-08-13T22:00:47.434Z,1597356047.434 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2977
2020-08-13T22:00:47.435Z,1597356047.435 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-08-13T22:00:47.436Z,1597356047.436 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-08-13T22:00:47.436Z,1597356047.436 [logger ThreadHandler](INFO): Protected caller Thread ID is 2978
2020-08-13T22:00:47.438Z,1597356047.438 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-08-13T22:00:47.438Z,1597356047.438 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-08-13T22:00:47.440Z,1597356047.440 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-08-13T22:00:47.534Z,1597356047.534 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-08-13T22:00:47.534Z,1597356047.534 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-08-13T22:00:48.171Z,1597356048.171 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-08-13T22:00:48.171Z,1597356048.171 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-08-13T22:00:48.367Z,1597356048.367 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-08-13T22:00:48.367Z,1597356048.367 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-08-13T22:00:48.464Z,1597356048.464 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-08-13T22:00:48.465Z,1597356048.465 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-08-13T22:00:48.566Z,1597356048.566 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-08-13T22:00:48.566Z,1597356048.566 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-08-13T22:00:48.645Z,1597356048.645 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-08-13T22:00:48.782Z,1597356048.782 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-08-13T22:00:48.783Z,1597356048.783 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-08-13T22:00:49.078Z,1597356049.078 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-08-13T22:00:49.078Z,1597356049.078 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-08-13T22:00:49.552Z,1597356049.552 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-08-13T22:00:49.553Z,1597356049.553 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-08-13T22:00:49.696Z,1597356049.696 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-08-13T22:00:49.696Z,1597356049.696 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-08-13T22:00:49.970Z,1597356049.970 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-08-13T22:00:49.971Z,1597356049.971 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-08-13T22:00:50.462Z,1597356050.462 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-08-13T22:00:50.463Z,1597356050.463 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-08-13T22:00:50.681Z,1597356050.681 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-08-13T22:00:50.682Z,1597356050.682 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-08-13T22:00:50.886Z,1597356050.886 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-08-13T22:00:50.887Z,1597356050.887 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-08-13T22:00:51.297Z,1597356051.297 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-08-13T22:00:51.298Z,1597356051.298 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-08-13T22:00:51.634Z,1597356051.634 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-08-13T22:00:51.636Z,1597356051.636 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2020-08-13T22:00:51.637Z,1597356051.637 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2020-08-13T22:00:51.719Z,1597356051.719 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2020-08-13T22:00:51.878Z,1597356051.878 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2020-08-13T22:00:51.984Z,1597356051.984 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2020-08-13T22:00:52.068Z,1597356052.068 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2020-08-13T22:00:52.197Z,1597356052.197 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2020-08-13T22:00:52.448Z,1597356052.448 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2020-08-13T22:00:52.872Z,1597356052.872 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-08-13T22:00:52.873Z,1597356052.873 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2020-08-13T22:00:53.050Z,1597356053.050 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2020-08-13T22:00:53.213Z,1597356053.213 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2020-08-13T22:00:53.342Z,1597356053.342 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2020-08-13T22:00:53.440Z,1597356053.440 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2020-08-13T22:00:53.440Z,1597356053.440 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-08-13T22:00:53.450Z,1597356053.450 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-08-13T22:00:53.525Z,1597356053.525 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-08-13T22:00:53.525Z,1597356053.525 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-08-13T22:00:53.572Z,1597356053.572 [VerticalControl](DEBUG): Construct VerticalControl.
2020-08-13T22:00:53.680Z,1597356053.680 [VerticalControl] Loaded
2020-08-13T22:00:53.680Z,1597356053.680 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-08-13T22:00:53.681Z,1597356053.681 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-08-13T22:00:53.758Z,1597356053.758 [HorizontalControl] Loaded
2020-08-13T22:00:53.758Z,1597356053.758 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-08-13T22:00:53.759Z,1597356053.759 [SpeedControl](DEBUG): Construct SpeedControl.
2020-08-13T22:00:53.764Z,1597356053.764 [SpeedControl] Loaded
2020-08-13T22:00:53.764Z,1597356053.764 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-08-13T22:00:53.765Z,1597356053.765 [LoopControl](DEBUG): Construct LoopControl.
2020-08-13T22:00:53.765Z,1597356053.765 [LoopControl] Loaded
2020-08-13T22:00:53.766Z,1597356053.766 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-08-13T22:00:53.766Z,1597356053.766 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-08-13T22:00:53.767Z,1597356053.767 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-08-13T22:00:53.806Z,1597356053.806 [DepthRateCalculator] Loaded
2020-08-13T22:00:53.807Z,1597356053.807 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-08-13T22:00:53.812Z,1597356053.812 [PitchRateCalculator] Loaded
2020-08-13T22:00:53.812Z,1597356053.812 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-08-13T22:00:53.827Z,1597356053.827 [SpeedCalculator] Loaded
2020-08-13T22:00:53.827Z,1597356053.827 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-08-13T22:00:53.847Z,1597356053.847 [TempGradientCalculator] Loaded
2020-08-13T22:00:53.848Z,1597356053.848 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-08-13T22:00:53.853Z,1597356053.853 [YawRateCalculator] Loaded
2020-08-13T22:00:53.853Z,1597356053.853 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-08-13T22:00:53.881Z,1597356053.881 [ElevatorOffsetCalculator] Loaded
2020-08-13T22:00:53.881Z,1597356053.881 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-08-13T22:00:53.882Z,1597356053.882 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-08-13T22:00:53.882Z,1597356053.882 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-08-13T22:00:53.908Z,1597356053.908 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-08-13T22:00:53.908Z,1597356053.908 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-08-13T22:00:53.988Z,1597356053.988 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-08-13T22:00:53.988Z,1597356053.988 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-08-13T22:00:54.268Z,1597356054.268 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-08-13T22:00:54.269Z,1597356054.269 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-08-13T22:00:54.363Z,1597356054.363 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-08-13T22:00:54.364Z,1597356054.364 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-08-13T22:00:54.643Z,1597356054.643 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-08-13T22:00:54.648Z,1597356054.648 [AHRS_M2](INFO): created writer for : platform_orientation
2020-08-13T22:00:54.649Z,1597356054.649 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-08-13T22:00:54.654Z,1597356054.654 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-08-13T22:00:54.655Z,1597356054.655 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-08-13T22:00:54.660Z,1597356054.660 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-08-13T22:00:54.660Z,1597356054.660 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-08-13T22:00:54.665Z,1597356054.665 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-08-13T22:00:54.735Z,1597356054.735 [AHRS_M2] Loaded
2020-08-13T22:00:54.735Z,1597356054.735 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-08-13T22:00:54.814Z,1597356054.814 [DataOverHttps] Loaded
2020-08-13T22:00:54.814Z,1597356054.814 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-08-13T22:00:54.815Z,1597356054.815 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409054E0
2020-08-13T22:00:54.816Z,1597356054.816 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3061
2020-08-13T22:00:54.828Z,1597356054.828 [Depth_Keller] Loaded
2020-08-13T22:00:54.829Z,1597356054.829 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-08-13T22:00:54.923Z,1597356054.923 [NAL9602] Loaded
2020-08-13T22:00:54.923Z,1597356054.923 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-08-13T22:00:54.969Z,1597356054.969 [Onboard] Loaded
2020-08-13T22:00:54.970Z,1597356054.970 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-08-13T22:00:54.971Z,1597356054.971 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409354E0
2020-08-13T22:00:54.971Z,1597356054.971 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3062
2020-08-13T22:00:54.974Z,1597356054.974 [Radio_Surface] Loaded
2020-08-13T22:00:54.974Z,1597356054.974 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-08-13T22:00:54.975Z,1597356054.975 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409654E0
2020-08-13T22:00:54.976Z,1597356054.976 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3063
2020-08-13T22:00:55.104Z,1597356055.104 [DAT] Loaded
2020-08-13T22:00:55.105Z,1597356055.105 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-08-13T22:00:56.559Z,1597356056.559 [BPC1] Loaded
2020-08-13T22:00:56.559Z,1597356056.559 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-08-13T22:00:56.559Z,1597356056.559 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-08-13T22:00:56.560Z,1597356056.560 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-08-13T22:00:56.674Z,1597356056.674 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-08-13T22:00:56.674Z,1597356056.674 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-08-13T22:00:56.694Z,1597356056.694 [NavChart] Loaded
2020-08-13T22:00:56.694Z,1597356056.694 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-08-13T22:00:56.698Z,1597356056.698 [UniversalFixResidualReporter] Loaded
2020-08-13T22:00:56.698Z,1597356056.698 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-08-13T22:00:56.699Z,1597356056.699 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-08-13T22:00:56.699Z,1597356056.699 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-08-13T22:00:56.831Z,1597356056.831 [SBIT](DEBUG): Construct Startup Built In Test.
2020-08-13T22:00:56.842Z,1597356056.842 [SBIT] Loaded
2020-08-13T22:00:56.842Z,1597356056.842 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-08-13T22:00:56.843Z,1597356056.843 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-08-13T22:00:56.855Z,1597356056.855 [IBIT] Loaded
2020-08-13T22:00:56.855Z,1597356056.855 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-08-13T22:00:56.858Z,1597356056.858 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-08-13T22:00:56.992Z,1597356056.992 [CBIT] Loaded
2020-08-13T22:00:56.992Z,1597356056.992 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-08-13T22:00:56.993Z,1597356056.993 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-08-13T22:00:56.993Z,1597356056.993 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-08-13T22:00:57.094Z,1597356057.094 [BuoyancyServo] Loaded
2020-08-13T22:00:57.094Z,1597356057.094 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-08-13T22:00:57.105Z,1597356057.105 [ElevatorServo] Loaded
2020-08-13T22:00:57.105Z,1597356057.105 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-08-13T22:00:57.116Z,1597356057.116 [MassServo] Loaded
2020-08-13T22:00:57.116Z,1597356057.116 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-08-13T22:00:57.127Z,1597356057.127 [RudderServo] Loaded
2020-08-13T22:00:57.128Z,1597356057.128 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-08-13T22:00:57.138Z,1597356057.138 [ThrusterServo] Loaded
2020-08-13T22:00:57.138Z,1597356057.138 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-08-13T22:00:57.139Z,1597356057.139 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-08-13T22:00:57.139Z,1597356057.139 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-08-13T22:00:57.157Z,1597356057.157 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-08-13T22:00:57.158Z,1597356057.158 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-08-13T22:00:57.372Z,1597356057.372 [BackSeatDriver] Loaded
2020-08-13T22:00:57.373Z,1597356057.373 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread.
2020-08-13T22:00:57.374Z,1597356057.374 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40AFB4E0
2020-08-13T22:00:57.374Z,1597356057.374 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 3064
2020-08-13T22:00:57.383Z,1597356057.383 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-08-13T22:00:57.388Z,1597356057.388 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-08-13T22:00:57.389Z,1597356057.389 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-08-13T22:00:57.394Z,1597356057.394 [CTD_Seabird](INFO): created writer for : depth
2020-08-13T22:00:57.394Z,1597356057.394 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-08-13T22:00:57.399Z,1597356057.399 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-08-13T22:00:57.400Z,1597356057.400 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-08-13T22:00:57.405Z,1597356057.405 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-08-13T22:00:57.405Z,1597356057.405 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-08-13T22:00:57.410Z,1597356057.410 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-08-13T22:00:57.411Z,1597356057.411 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-08-13T22:00:57.416Z,1597356057.416 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-08-13T22:00:57.416Z,1597356057.416 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-08-13T22:00:57.421Z,1597356057.421 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-08-13T22:00:57.448Z,1597356057.448 [CTD_Seabird] Loaded
2020-08-13T22:00:57.448Z,1597356057.448 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-08-13T22:00:57.449Z,1597356057.449 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B2B4E0
2020-08-13T22:00:57.449Z,1597356057.449 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3065
2020-08-13T22:00:57.456Z,1597356057.456 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-08-13T22:00:57.456Z,1597356057.456 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-08-13T22:00:57.460Z,1597356057.460 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-08-13T22:00:57.460Z,1597356057.460 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-08-13T22:00:57.464Z,1597356057.464 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-08-13T22:00:57.465Z,1597356057.465 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-08-13T22:00:57.469Z,1597356057.469 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-08-13T22:00:57.469Z,1597356057.469 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-08-13T22:00:57.473Z,1597356057.473 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-08-13T22:00:57.473Z,1597356057.473 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-08-13T22:00:57.477Z,1597356057.477 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-08-13T22:00:57.478Z,1597356057.478 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-08-13T22:00:57.482Z,1597356057.482 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-08-13T22:00:57.482Z,1597356057.482 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-08-13T22:00:57.486Z,1597356057.486 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-08-13T22:00:57.490Z,1597356057.490 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-08-13T22:00:57.491Z,1597356057.491 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-08-13T22:00:57.491Z,1597356057.491 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-08-13T22:00:57.495Z,1597356057.495 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-08-13T22:00:57.495Z,1597356057.495 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-08-13T22:00:57.499Z,1597356057.499 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-08-13T22:00:57.499Z,1597356057.499 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-08-13T22:00:57.504Z,1597356057.504 [WetLabsBB2FL] Loaded
2020-08-13T22:00:57.504Z,1597356057.504 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-08-13T22:00:57.505Z,1597356057.505 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B5B4E0
2020-08-13T22:00:57.505Z,1597356057.505 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3066
2020-08-13T22:00:57.519Z,1597356057.519 [WetLabsUBAT..SerialDriver](INFO): Created logger
2020-08-13T22:00:57.520Z,1597356057.520 [WetLabsUBAT..SerialDriver](INFO): publishing on LCM channel WetLabsUBAT
2020-08-13T22:00:57.520Z,1597356057.520 [WetLabsUBAT..StreamSerialDriver](INFO): Created logger
2020-08-13T22:00:57.523Z,1597356057.523 [WetLabsUBAT](CRITICAL): No configuration setting for: WetLabsUBAT.optionalArgs
2020-08-13T22:00:57.525Z,1597356057.525 [WetLabsUBAT](CRITICAL): No configuration setting for: WetLabsUBAT.emulateHardware
2020-08-13T22:00:57.526Z,1597356057.526 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: UBAT0051
2020-08-13T22:00:57.526Z,1597356057.526 [WetLabsUBAT](CRITICAL): Error loading configuration parameters during WetLabsUBAT::start()
2020-08-13T22:00:57.527Z,1597356057.527 [WetLabsUBAT] Data Fault, FailCount= 1
2020-08-13T22:00:57.527Z,1597356057.527 [WetLabsUBAT](ERROR): Data Fault
2020-08-13T22:00:57.527Z,1597356057.527 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.biolum_potential
2020-08-13T22:00:57.527Z,1597356057.527 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: biolum_potential
2020-08-13T22:00:57.531Z,1597356057.531 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.flow_rate
2020-08-13T22:00:57.531Z,1597356057.531 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: flow_rate
2020-08-13T22:00:57.536Z,1597356057.536 [WetLabsUBAT] Loaded
2020-08-13T22:00:57.537Z,1597356057.537 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2020-08-13T22:00:57.538Z,1597356057.538 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40B8B4E0
2020-08-13T22:00:57.538Z,1597356057.538 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 3067
2020-08-13T22:00:57.539Z,1597356057.539 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-08-13T22:00:57.585Z,1597356057.585 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-08-13T22:00:57.586Z,1597356057.586 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-08-13T22:00:57.593Z,1597356057.593 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-08-13T22:00:57.594Z,1597356057.594 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BBB4E0
2020-08-13T22:00:57.594Z,1597356057.594 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3068
2020-08-13T22:00:57.599Z,1597356057.599 [Supervisor](INFO): Main Thread ID is 2974
2020-08-13T22:00:57.599Z,1597356057.599 [Supervisor](DEBUG): Running supervisor.
2020-08-13T22:00:57.600Z,1597356057.600 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3069
2020-08-13T22:00:57.602Z,1597356057.602 [controlThread ThreadHandler](INFO): Handler Thread ID is 3070
2020-08-13T22:00:57.603Z,1597356057.603 [controlThread](DEBUG): Initializing ControlThread
2020-08-13T22:00:57.604Z,1597356057.604 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-08-13T22:00:57.605Z,1597356057.605 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-08-13T22:00:57.606Z,1597356057.606 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-08-13T22:00:57.607Z,1597356057.607 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-08-13T22:00:57.607Z,1597356057.607 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-08-13T22:00:57.607Z,1597356057.607 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-08-13T22:00:57.608Z,1597356057.608 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-08-13T22:00:57.608Z,1597356057.608 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-08-13T22:00:57.609Z,1597356057.609 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-08-13T22:00:57.609Z,1597356057.609 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-08-13T22:00:57.612Z,1597356057.612 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-08-13T22:00:57.613Z,1597356057.613 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-08-13T22:00:57.613Z,1597356057.613 [SBIT](INFO): Initialize SBIT Component.
2020-08-13T22:00:57.614Z,1597356057.614 [SBIT](IMPORTANT): git: 2020-06-09-114-g1451e00
2020-08-13T22:00:57.614Z,1597356057.614 [SBIT](INFO): git hash: 1451e003b16ed6818131e4c85b4832e6473e2549
2020-08-13T22:00:57.614Z,1597356057.614 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-08-13T22:00:57.615Z,1597356057.615 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Thu Aug 6 12:04:53 PDT 2020
2020-08-13T22:00:57.616Z,1597356057.616 [SBIT](INFO): Beginning SBIT in 76.000000 seconds.
2020-08-13T22:00:57.617Z,1597356057.617 [IBIT](INFO): Initialize IBIT Component.
2020-08-13T22:00:57.618Z,1597356057.618 [CBIT](DEBUG): Initialize CBIT Component.
2020-08-13T22:00:57.631Z,1597356057.631 [logger ThreadHandler](INFO): Handler Thread ID is 3071
2020-08-13T22:00:57.631Z,1597356057.631 [CBIT](DEBUG): Initialized mux pins.
2020-08-13T22:00:57.631Z,1597356057.631 [CBIT](DEBUG): Initializing the watchdog timer.
2020-08-13T22:00:57.644Z,1597356057.644 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3072
2020-08-13T22:00:57.645Z,1597356057.645 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-08-13T22:00:57.652Z,1597356057.652 [Onboard ThreadHandler](INFO): Handler Thread ID is 3073
2020-08-13T22:00:57.656Z,1597356057.656 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-08-13T22:00:57.657Z,1597356057.657 [CBIT](DEBUG): Initializing heartbeat.
2020-08-13T22:00:57.679Z,1597356057.679 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 3075
2020-08-13T22:00:57.684Z,1597356057.684 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3076
2020-08-13T22:00:57.685Z,1597356057.685 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-08-13T22:00:57.687Z,1597356057.687 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3078
2020-08-13T22:00:57.688Z,1597356057.688 [WetLabsBB2FL](INFO): Powering down
2020-08-13T22:00:57.708Z,1597356057.708 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 3079
2020-08-13T22:00:57.712Z,1597356057.712 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3080
2020-08-13T22:00:57.715Z,1597356057.715 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-08-13T22:00:57.715Z,1597356057.715 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-08-13T22:00:57.715Z,1597356057.715 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-08-13T22:00:57.715Z,1597356057.715 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-08-13T22:00:57.716Z,1597356057.716 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-08-13T22:00:57.716Z,1597356057.716 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-08-13T22:00:57.716Z,1597356057.716 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-08-13T22:00:57.716Z,1597356057.716 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-08-13T22:00:57.716Z,1597356057.716 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-08-13T22:00:57.716Z,1597356057.716 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-08-13T22:00:57.717Z,1597356057.717 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-08-13T22:00:57.717Z,1597356057.717 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-08-13T22:00:57.717Z,1597356057.717 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-08-13T22:00:57.717Z,1597356057.717 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-08-13T22:00:57.717Z,1597356057.717 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-08-13T22:00:57.717Z,1597356057.717 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-08-13T22:00:57.731Z,1597356057.731 [CBIT](DEBUG): Deactivating GF circuits.
2020-08-13T22:00:57.731Z,1597356057.731 [CBIT](DEBUG): Deactivating emergency mode.
2020-08-13T22:00:57.735Z,1597356057.735 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3074
2020-08-13T22:00:57.767Z,1597356057.767 [CBIT](DEBUG): Backplane powered.
2020-08-13T22:00:57.769Z,1597356057.769 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-08-13T22:00:57.778Z,1597356057.778 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-08-13T22:00:57.820Z,1597356057.820 [MissionManager](DEBUG):
2020-08-13T22:00:57.821Z,1597356057.821 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-08-13T22:00:57.932Z,1597356057.932 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-08-13T22:00:57.933Z,1597356057.933 [Default:A.Wait](DEBUG): Construct Wait.
2020-08-13T22:00:57.934Z,1597356057.934 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-08-13T22:00:57.957Z,1597356057.957 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-08-13T22:00:57.979Z,1597356057.979 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-08-13T22:00:57.985Z,1597356057.985 [Default:E.Execute](DEBUG): Construct Execute.
2020-08-13T22:00:58.000Z,1597356058.000 [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
2020-08-13T22:00:58.004Z,1597356058.004 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,NAL9602,DAT,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-08-13T22:00:58.023Z,1597356058.023 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-08-13T22:00:58.139Z,1597356058.139 [Radio_Surface](INFO): Powering up
2020-08-13T22:00:58.181Z,1597356058.181 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-08-13T22:00:58.181Z,1597356058.181 [DAT](INFO): Powering up
2020-08-13T22:00:58.182Z,1597356058.182 [DAT](DEBUG): Initializing DAT.
2020-08-13T22:00:58.199Z,1597356058.199 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-08-13T22:00:58.289Z,1597356058.289 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-08-13T22:00:58.299Z,1597356058.299 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-08-13T22:00:58.301Z,1597356058.301 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-08-13T22:00:58.307Z,1597356058.307 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-08-13T22:00:58.308Z,1597356058.308 [MassServo](DEBUG): Initializing EZServoServo.
2020-08-13T22:00:58.315Z,1597356058.315 [MassServo](DEBUG): Initializing MassServo.
2020-08-13T22:00:58.316Z,1597356058.316 [RudderServo](DEBUG): Initializing EZServoServo.
2020-08-13T22:00:58.324Z,1597356058.324 [RudderServo](DEBUG): Initializing RudderServo.
2020-08-13T22:00:58.325Z,1597356058.325 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-08-13T22:00:58.339Z,1597356058.339 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-08-13T22:00:58.342Z,1597356058.342 [CommandLine](FAULT): Scheduling is paused
2020-08-13T22:00:58.342Z,1597356058.342 [CBIT](INFO): Critical error at 20200813T220057
2020-08-13T22:00:58.342Z,1597356058.342 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-08-13T22:00:58.346Z,1597356058.346 [CBIT](ERROR): Data Fault in component: WetLabsUBAT
2020-08-13T22:00:58.347Z,1597356058.347 [CBIT](INFO): Clearing failed state for component WetLabsUBAT
2020-08-13T22:00:58.347Z,1597356058.347 [WetLabsUBAT] No Fault, FailCount= 1
2020-08-13T22:00:58.516Z,1597356058.516 [WetLabsUBAT](INFO): Initializing
2020-08-13T22:00:58.518Z,1597356058.518 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: nan n/a
2020-08-13T22:00:58.518Z,1597356058.518 [WetLabsUBAT](INFO): Powering up
2020-08-13T22:00:58.518Z,1597356058.518 [WetLabsUBAT](INFO): Checking LCM
2020-08-13T22:00:58.518Z,1597356058.518 [WetLabsUBAT](INFO): LCM OK
2020-08-13T22:00:58.520Z,1597356058.520 [WetLabsUBAT](ERROR): Error configuring driver
2020-08-13T22:00:58.520Z,1597356058.520 [WetLabsUBAT] Software Fault, FailCount= 2
2020-08-13T22:00:58.520Z,1597356058.520 [WetLabsUBAT](ERROR): Software Fault
2020-08-13T22:00:58.923Z,1597356058.923 [WetLabsUBAT](INFO): Powering down
2020-08-13T22:00:59.131Z,1597356059.131 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-08-13T22:00:59.131Z,1597356059.131 [RudderServo](FAULT): Rudder failed to initialize
2020-08-13T22:00:59.131Z,1597356059.131 [RudderServo] Communications Fault, FailCount= 1
2020-08-13T22:00:59.131Z,1597356059.131 [RudderServo](ERROR): Communications Fault
2020-08-13T22:00:59.290Z,1597356059.290 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-08-13T22:00:59.291Z,1597356059.291 [CBIT](ERROR): Software Fault in component: WetLabsUBAT
2020-08-13T22:00:59.452Z,1597356059.452 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-08-13T22:00:59.452Z,1597356059.452 [RudderServo](INFO): Powering down
2020-08-13T22:00:59.751Z,1597356059.751 [CBIT](INFO): Clearing failed state for component WetLabsUBAT
2020-08-13T22:00:59.751Z,1597356059.751 [WetLabsUBAT] No Fault, FailCount= 2
2020-08-13T22:01:00.119Z,1597356060.119 [RudderServo](DEBUG): Initializing EZServoServo.
2020-08-13T22:01:00.151Z,1597356060.151 [WetLabsUBAT](INFO): Initializing
2020-08-13T22:01:00.153Z,1597356060.153 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: nan n/a
2020-08-13T22:01:00.153Z,1597356060.153 [WetLabsUBAT](INFO): Powering up
2020-08-13T22:01:00.154Z,1597356060.154 [WetLabsUBAT](INFO): Checking LCM
2020-08-13T22:01:00.154Z,1597356060.154 [WetLabsUBAT](INFO): LCM OK
2020-08-13T22:01:00.156Z,1597356060.156 [WetLabsUBAT](ERROR): Error configuring driver
2020-08-13T22:01:00.156Z,1597356060.156 [WetLabsUBAT] Software Fault, FailCount= 3
2020-08-13T22:01:00.156Z,1597356060.156 [WetLabsUBAT](ERROR): Software Fault
2020-08-13T22:01:00.236Z,1597356060.236 [RudderServo](DEBUG): Initializing RudderServo.
2020-08-13T22:01:00.240Z,1597356060.240 [CBIT](INFO): Clearing failed state for component RudderServo
2020-08-13T22:01:00.240Z,1597356060.240 [RudderServo] No Fault, FailCount= 1
2020-08-13T22:01:00.240Z,1597356060.240 [CBIT](ERROR): Software Fault in component: WetLabsUBAT
2020-08-13T22:01:00.559Z,1597356060.559 [WetLabsUBAT](INFO): Powering down
2020-08-13T22:01:01.639Z,1597356061.639 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:01.666Z,1597356061.666 [CBIT](INFO): Clearing failed state for component WetLabsUBAT
2020-08-13T22:01:01.666Z,1597356061.666 [WetLabsUBAT] No Fault, FailCount= 3
2020-08-13T22:01:01.771Z,1597356061.771 [WetLabsUBAT](INFO): Initializing
2020-08-13T22:01:01.773Z,1597356061.773 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: nan n/a
2020-08-13T22:01:01.773Z,1597356061.773 [WetLabsUBAT](INFO): Powering up
2020-08-13T22:01:01.773Z,1597356061.773 [WetLabsUBAT](INFO): Checking LCM
2020-08-13T22:01:01.774Z,1597356061.774 [WetLabsUBAT](INFO): LCM OK
2020-08-13T22:01:01.776Z,1597356061.776 [WetLabsUBAT](ERROR): Error configuring driver
2020-08-13T22:01:01.776Z,1597356061.776 [WetLabsUBAT] Software Fault, FailCount= 4
2020-08-13T22:01:01.776Z,1597356061.776 [WetLabsUBAT](ERROR): Software Fault
2020-08-13T22:01:02.043Z,1597356062.043 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:02.136Z,1597356062.136 [CBIT](ERROR): Software Fault in component: WetLabsUBAT
2020-08-13T22:01:02.181Z,1597356062.181 [WetLabsUBAT](INFO): Powering down
2020-08-13T22:01:02.463Z,1597356062.463 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:02.992Z,1597356062.992 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:03.104Z,1597356063.104 [CBIT](INFO): Clearing failed state for component WetLabsUBAT
2020-08-13T22:01:03.104Z,1597356063.104 [WetLabsUBAT] No Fault, FailCount= 4
2020-08-13T22:01:03.395Z,1597356063.395 [WetLabsUBAT](INFO): Initializing
2020-08-13T22:01:03.397Z,1597356063.397 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: nan n/a
2020-08-13T22:01:03.397Z,1597356063.397 [WetLabsUBAT](INFO): Powering up
2020-08-13T22:01:03.398Z,1597356063.398 [WetLabsUBAT](INFO): Checking LCM
2020-08-13T22:01:03.398Z,1597356063.398 [WetLabsUBAT](INFO): LCM OK
2020-08-13T22:01:03.400Z,1597356063.400 [WetLabsUBAT](ERROR): Error configuring driver
2020-08-13T22:01:03.400Z,1597356063.400 [WetLabsUBAT] Software Fault, FailCount= 5
2020-08-13T22:01:03.400Z,1597356063.400 [WetLabsUBAT](ERROR): Software Fault
2020-08-13T22:01:03.435Z,1597356063.435 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:03.539Z,1597356063.539 [CBIT](ERROR): Software Fault in component: WetLabsUBAT
2020-08-13T22:01:03.540Z,1597356063.540 [CBIT](CRITICAL): Software Fault in component: WetLabsUBAT
2020-08-13T22:01:03.803Z,1597356063.803 [WetLabsUBAT](INFO): Powering down
2020-08-13T22:01:03.871Z,1597356063.871 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:03.981Z,1597356063.981 [CBIT](INFO): Critical error at 20200813T220103
2020-08-13T22:01:04.315Z,1597356064.315 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:04.799Z,1597356064.799 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:05.227Z,1597356065.227 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:05.627Z,1597356065.627 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:05.720Z,1597356065.720 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2020-08-13T22:01:06.115Z,1597356066.115 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:06.523Z,1597356066.523 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:06.963Z,1597356066.963 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:07.399Z,1597356067.399 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:07.811Z,1597356067.811 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:07.900Z,1597356067.900 [CBIT](CRITICAL): Environmental Failure. Press:14.397555 PSI. Humidity:57%. Temp:24 C. ABORTING MISSION
2020-08-13T22:01:08.231Z,1597356068.231 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:08.313Z,1597356068.313 [CBIT](INFO): Critical error at 20200813T220107
2020-08-13T22:01:08.647Z,1597356068.647 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:09.083Z,1597356069.083 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:09.483Z,1597356069.483 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:09.638Z,1597356069.638 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.036135
2020-08-13T22:01:09.911Z,1597356069.911 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:10.335Z,1597356070.335 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:10.775Z,1597356070.775 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:10.782Z,1597356070.782 [DAT](INFO): commRate: 800
2020-08-13T22:01:10.783Z,1597356070.783 [DAT](INFO): commRate: 800
2020-08-13T22:01:11.195Z,1597356071.195 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:11.195Z,1597356071.195 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-08-13T22:01:11.195Z,1597356071.195 [AHRS_M2] Communications Fault, FailCount= 1
2020-08-13T22:01:11.195Z,1597356071.195 [AHRS_M2](ERROR): Communications Fault
2020-08-13T22:01:11.201Z,1597356071.201 [DAT](INFO): entering command mode
2020-08-13T22:01:11.288Z,1597356071.288 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-08-13T22:01:11.293Z,1597356071.293 [AHRS_M2](INFO): Powering down
2020-08-13T22:01:11.441Z,1597356071.441 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:11.701Z,1597356071.701 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:12.105Z,1597356072.105 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:12.175Z,1597356072.175 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-08-13T22:01:12.175Z,1597356072.175 [AHRS_M2] No Fault, FailCount= 1
2020-08-13T22:01:12.503Z,1597356072.503 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-08-13T22:01:12.525Z,1597356072.525 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:12.912Z,1597356072.912 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:13.317Z,1597356073.317 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:13.721Z,1597356073.721 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:14.125Z,1597356074.125 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:14.529Z,1597356074.529 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:14.932Z,1597356074.932 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:15.336Z,1597356075.336 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:16.047Z,1597356076.047 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:16.053Z,1597356076.053 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:16.499Z,1597356076.499 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:16.505Z,1597356076.505 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:16.903Z,1597356076.903 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:16.909Z,1597356076.909 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:17.339Z,1597356077.339 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:17.345Z,1597356077.345 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:17.767Z,1597356077.767 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:17.773Z,1597356077.773 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:18.195Z,1597356078.195 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:18.201Z,1597356078.201 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:18.599Z,1597356078.599 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:18.605Z,1597356078.605 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:19.007Z,1597356079.007 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:19.013Z,1597356079.013 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:19.423Z,1597356079.423 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:19.429Z,1597356079.429 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:19.851Z,1597356079.851 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:19.857Z,1597356079.857 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:20.271Z,1597356080.271 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:20.277Z,1597356080.277 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:20.598Z,1597356080.598 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 40.000000 millimeter
2020-08-13T22:01:20.599Z,1597356080.599 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread.
2020-08-13T22:01:20.688Z,1597356080.688 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:20.693Z,1597356080.693 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:20.783Z,1597356080.783 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,NAL9602,DAT,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-08-13T22:01:21.111Z,1597356081.111 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:21.113Z,1597356081.113 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:21.515Z,1597356081.515 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:21.521Z,1597356081.521 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:21.931Z,1597356081.931 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:21.937Z,1597356081.937 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:22.332Z,1597356082.332 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:22.337Z,1597356082.337 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:22.812Z,1597356082.812 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:22.821Z,1597356082.821 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:23.219Z,1597356083.219 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:23.225Z,1597356083.225 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:23.667Z,1597356083.667 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:23.673Z,1597356083.673 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:24.067Z,1597356084.067 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:24.073Z,1597356084.073 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:24.487Z,1597356084.487 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:24.493Z,1597356084.493 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:24.911Z,1597356084.911 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:24.917Z,1597356084.917 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:25.331Z,1597356085.331 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:25.337Z,1597356085.337 [NAL9602](INFO): Powering up NAL9602
2020-08-13T22:01:25.338Z,1597356085.338 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:25.743Z,1597356085.743 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:25.743Z,1597356085.743 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-08-13T22:01:25.743Z,1597356085.743 [AHRS_M2] Communications Fault, FailCount= 2
2020-08-13T22:01:25.744Z,1597356085.744 [AHRS_M2](ERROR): Communications Fault
2020-08-13T22:01:25.749Z,1597356085.749 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:25.835Z,1597356085.835 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-08-13T22:01:25.847Z,1597356085.847 [AHRS_M2](INFO): Powering down
2020-08-13T22:01:25.936Z,1597356085.936 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:26.249Z,1597356086.249 [DAT](DEBUG): checking for command mode acknowledgment
2020-08-13T22:01:26.249Z,1597356086.249 [DAT](FAULT): failed to enter command mode
2020-08-13T22:01:26.653Z,1597356086.653 [DAT](INFO): entering command mode
2020-08-13T22:01:26.724Z,1597356086.724 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-08-13T22:01:26.724Z,1597356086.724 [AHRS_M2] No Fault, FailCount= 2
2020-08-13T22:01:27.051Z,1597356087.051 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-08-13T22:01:27.066Z,1597356087.066 [DAT](INFO): setting verbose to 3
2020-08-13T22:01:27.463Z,1597356087.463 [DAT](INFO): set verbose to 3
2020-08-13T22:01:27.463Z,1597356087.463 [DAT](INFO): setting DatVerbose to 27440
2020-08-13T22:01:27.866Z,1597356087.866 [DAT](INFO): set DatVerbose to 27440
2020-08-13T22:01:27.866Z,1597356087.866 [DAT](INFO): setting transmit power to 8
2020-08-13T22:01:28.270Z,1597356088.270 [DAT](INFO): set transmit power to 8
2020-08-13T22:01:28.270Z,1597356088.270 [DAT](INFO): setting local address to 9
2020-08-13T22:01:28.674Z,1597356088.674 [DAT](INFO): set local address to 9
2020-08-13T22:01:30.607Z,1597356090.607 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:31.075Z,1597356091.075 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:31.499Z,1597356091.499 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:31.923Z,1597356091.923 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:32.351Z,1597356092.351 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:32.764Z,1597356092.764 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:33.203Z,1597356093.203 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:33.623Z,1597356093.623 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:34.031Z,1597356094.031 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:34.459Z,1597356094.459 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:34.915Z,1597356094.915 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:35.328Z,1597356095.328 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:35.763Z,1597356095.763 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:36.179Z,1597356096.179 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:36.611Z,1597356096.611 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:36.617Z,1597356096.617 [NAL9602](INFO): NAL9602 initialized
2020-08-13T22:01:37.071Z,1597356097.071 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:37.527Z,1597356097.527 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:37.991Z,1597356097.991 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:38.419Z,1597356098.419 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:38.867Z,1597356098.867 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:39.307Z,1597356099.307 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:39.727Z,1597356099.727 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:40.175Z,1597356100.175 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:40.175Z,1597356100.175 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-08-13T22:01:40.175Z,1597356100.175 [AHRS_M2] Communications Fault, FailCount= 3
2020-08-13T22:01:40.175Z,1597356100.175 [AHRS_M2](ERROR): Communications Fault
2020-08-13T22:01:40.292Z,1597356100.292 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-08-13T22:01:40.297Z,1597356100.297 [AHRS_M2](INFO): Powering down
2020-08-13T22:01:41.175Z,1597356101.175 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-08-13T22:01:41.176Z,1597356101.175 [AHRS_M2] No Fault, FailCount= 3
2020-08-13T22:01:41.507Z,1597356101.507 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-08-13T22:01:45.063Z,1597356105.063 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:45.503Z,1597356105.503 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:45.939Z,1597356105.939 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:46.359Z,1597356106.359 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:46.783Z,1597356106.783 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:47.223Z,1597356107.223 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:47.631Z,1597356107.631 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:48.087Z,1597356108.087 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:48.503Z,1597356108.503 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:48.935Z,1597356108.935 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:49.367Z,1597356109.367 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:49.799Z,1597356109.799 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:50.247Z,1597356110.247 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:50.683Z,1597356110.683 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:51.135Z,1597356111.135 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:51.575Z,1597356111.575 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:52.012Z,1597356112.012 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:52.455Z,1597356112.455 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:52.907Z,1597356112.907 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:53.307Z,1597356113.307 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:53.715Z,1597356113.715 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:54.119Z,1597356114.119 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:54.531Z,1597356114.531 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:54.531Z,1597356114.531 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-08-13T22:01:54.531Z,1597356114.531 [AHRS_M2] Communications Fault, FailCount= 4
2020-08-13T22:01:54.531Z,1597356114.531 [AHRS_M2](ERROR): Communications Fault
2020-08-13T22:01:54.716Z,1597356114.716 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-08-13T22:01:54.721Z,1597356114.721 [AHRS_M2](INFO): Powering down
2020-08-13T22:01:55.587Z,1597356115.587 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-08-13T22:01:55.588Z,1597356115.588 [AHRS_M2] No Fault, FailCount= 4
2020-08-13T22:01:55.931Z,1597356115.931 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-08-13T22:01:59.487Z,1597356119.487 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:01:59.919Z,1597356119.919 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:02:00.335Z,1597356120.335 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:02:00.751Z,1597356120.751 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:02:01.167Z,1597356121.167 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:02:01.575Z,1597356121.575 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:02:01.983Z,1597356121.983 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:02:02.387Z,1597356122.387 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:02:02.787Z,1597356122.787 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:02:03.195Z,1597356123.195 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:02:03.599Z,1597356123.599 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:02:03.995Z,1597356123.995 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:02:04.399Z,1597356124.399 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:02:04.803Z,1597356124.803 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:02:05.227Z,1597356125.227 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:02:05.691Z,1597356125.691 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:02:06.147Z,1597356126.147 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:02:06.583Z,1597356126.583 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:02:06.995Z,1597356126.995 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:02:07.399Z,1597356127.399 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:02:07.803Z,1597356127.803 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:02:08.199Z,1597356128.199 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:02:08.611Z,1597356128.611 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:02:09.015Z,1597356129.015 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-13T22:02:09.015Z,1597356129.015 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-08-13T22:02:09.015Z,1597356129.015 [AHRS_M2] Communications Fault, FailCount= 5
2020-08-13T22:02:09.015Z,1597356129.015 [AHRS_M2](ERROR): Communications Fault
2020-08-13T22:02:09.075Z,1597356129.075 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-08-13T22:02:09.075Z,1597356129.075 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2020-08-13T22:02:09.095Z,1597356129.095 [AHRS_M2](INFO): Powering down
2020-08-13T22:02:09.210Z,1597356129.210 [CBIT](INFO): Critical error at 20200813T220209
2020-08-13T22:02:14.037Z,1597356134.037 [SBIT](IMPORTANT): Beginning Startup BIT
2020-08-13T22:02:14.041Z,1597356134.041 [CBIT](IMPORTANT): Beginning ground fault scan
2020-08-13T22:02:16.458Z,1597356136.458 [CommandLine](IMPORTANT): got command quit
2020-08-13T22:02:17.467Z,1597356137.467 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-13T22:02:17.468Z,1597356137.468 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:17.563Z,1597356137.563 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2020-08-13T22:02:17.563Z,1597356137.563 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:17.564Z,1597356137.564 [CommandLine](INFO): Join timeout helper Thread ID is 3095
2020-08-13T22:02:17.564Z,1597356137.564 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-08-13T22:02:17.564Z,1597356137.564 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:17.565Z,1597356137.565 [NavChartDb](INFO): Join timeout helper Thread ID is 3096
2020-08-13T22:02:17.643Z,1597356137.643 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-13T22:02:17.644Z,1597356137.644 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:17.647Z,1597356137.647 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2020-08-13T22:02:17.647Z,1597356137.647 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:17.648Z,1597356137.648 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 3097
2020-08-13T22:02:17.772Z,1597356137.772 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-13T22:02:17.773Z,1597356137.773 [WetLabsUBAT](INFO): Powering down
2020-08-13T22:02:17.775Z,1597356137.775 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:17.778Z,1597356137.778 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-08-13T22:02:17.778Z,1597356137.778 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:17.780Z,1597356137.780 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3098
2020-08-13T22:02:17.848Z,1597356137.848 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-13T22:02:17.848Z,1597356137.848 [WetLabsBB2FL](INFO): Powering down
2020-08-13T22:02:17.849Z,1597356137.849 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:17.859Z,1597356137.859 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-08-13T22:02:17.859Z,1597356137.859 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:17.860Z,1597356137.860 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3099
2020-08-13T22:02:18.035Z,1597356138.035 [CTD_Seabird](INFO): Powering down
2020-08-13T22:02:18.048Z,1597356138.048 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-13T22:02:18.049Z,1597356138.049 [CTD_Seabird](INFO): Powering down
2020-08-13T22:02:18.063Z,1597356138.063 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:18.073Z,1597356138.073 [ComponentRegistry](INFO): Shutting down BackSeatDriver ThreadHandler
2020-08-13T22:02:18.073Z,1597356138.073 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:18.073Z,1597356138.073 [BackSeatDriver](INFO): Join timeout helper Thread ID is 3100
2020-08-13T22:02:18.095Z,1597356138.095 [BackSeatDriver ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-13T22:02:18.096Z,1597356138.096 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:18.112Z,1597356138.112 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-08-13T22:02:18.112Z,1597356138.112 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:18.112Z,1597356138.112 [Radio_Surface](INFO): Join timeout helper Thread ID is 3101
2020-08-13T22:02:18.163Z,1597356138.163 [Radio_Surface](INFO): Powering down
2020-08-13T22:02:18.164Z,1597356138.164 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-13T22:02:18.165Z,1597356138.165 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:18.180Z,1597356138.180 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-08-13T22:02:18.180Z,1597356138.180 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:18.180Z,1597356138.180 [Onboard](INFO): Join timeout helper Thread ID is 3102
2020-08-13T22:02:18.995Z,1597356138.995 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-08-13T22:02:22.719Z,1597356142.719 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-13T22:02:22.720Z,1597356142.720 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:22.732Z,1597356142.732 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-08-13T22:02:22.732Z,1597356142.732 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:22.732Z,1597356142.732 [DataOverHttps](INFO): Join timeout helper Thread ID is 3103
2020-08-13T22:02:22.924Z,1597356142.924 [CBIT](FAULT): Humidity exceeds 50% of running average: Humidity:2285320 %. Average:256954.796875
2020-08-13T22:02:23.011Z,1597356143.011 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-13T22:02:23.012Z,1597356143.012 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:23.020Z,1597356143.020 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-08-13T22:02:23.020Z,1597356143.020 [logger ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:23.020Z,1597356143.020 [logger](INFO): Join timeout helper Thread ID is 3104
2020-08-13T22:02:23.031Z,1597356143.031 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-13T22:02:23.032Z,1597356143.032 [logger ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:23.040Z,1597356143.040 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-08-13T22:02:23.040Z,1597356143.040 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:23.040Z,1597356143.040 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-08-13T22:02:23.040Z,1597356143.040 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:23.041Z,1597356143.041 [controlThread](INFO): Join timeout helper Thread ID is 3105
2020-08-13T22:02:23.231Z,1597356143.231 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-13T22:02:23.232Z,1597356143.232 [controlThread](DEBUG): Uninitializing ControlThread
2020-08-13T22:02:23.232Z,1597356143.232 [AHRS_M2](INFO): Powering down
2020-08-13T22:02:23.233Z,1597356143.233 [NAL9602](INFO): Powering down
2020-08-13T22:02:23.234Z,1597356143.234 [DAT](INFO): Powering down
2020-08-13T22:02:23.352Z,1597356143.352 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-08-13T22:02:23.353Z,1597356143.353 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-08-13T22:02:23.354Z,1597356143.354 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-08-13T22:02:23.354Z,1597356143.354 [MissionManager](INFO): Uninitializing Mission Default
2020-08-13T22:02:23.356Z,1597356143.356 [MissionManager](INFO): Uninitializing Mission Startup
2020-08-13T22:02:23.358Z,1597356143.358 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-08-13T22:02:23.358Z,1597356143.358 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-08-13T22:02:23.358Z,1597356143.358 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-08-13T22:02:23.358Z,1597356143.358 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-08-13T22:02:23.359Z,1597356143.359 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-08-13T22:02:23.359Z,1597356143.359 [BuoyancyServo](INFO): Powering down
2020-08-13T22:02:23.371Z,1597356143.371 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-08-13T22:02:23.371Z,1597356143.371 [ElevatorServo](INFO): Powering down
2020-08-13T22:02:23.372Z,1597356143.372 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-08-13T22:02:23.372Z,1597356143.372 [MassServo](INFO): Powering down
2020-08-13T22:02:23.373Z,1597356143.373 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-08-13T22:02:23.373Z,1597356143.373 [RudderServo](INFO): Powering down
2020-08-13T22:02:23.374Z,1597356143.374 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-08-13T22:02:23.374Z,1597356143.374 [ThrusterServo](INFO): Powering down
2020-08-13T22:02:23.375Z,1597356143.375 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-08-13T22:02:23.375Z,1597356143.375 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-08-13T22:02:23.376Z,1597356143.376 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-08-13T22:02:23.376Z,1597356143.376 [CBIT](DEBUG): Powering off loads.
2020-08-13T22:02:23.387Z,1597356143.387 [CBIT](DEBUG): Disabling WDT.
2020-08-13T22:02:23.399Z,1597356143.399 [CBIT](DEBUG): Opening all GF detection circuits.
2020-08-13T22:02:23.400Z,1597356143.400 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:23.502Z,1597356143.502 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:23.504Z,1597356143.504 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:23.509Z,1597356143.509 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:23.685Z,1597356143.685 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:23.686Z,1597356143.686 [WetLabsUBAT](INFO): WetLabs destructor: _dataLog is not open
2020-08-13T22:02:23.686Z,1597356143.686 [WetLabsUBAT](INFO): WetLabs destructor: done
2020-08-13T22:02:23.689Z,1597356143.689 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:23.692Z,1597356143.692 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:23.694Z,1597356143.694 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:23.704Z,1597356143.704 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-08-13T22:02:23.766Z,1597356143.766 [logger ThreadHandler](INFO): Thread cancelled.