2020-11-18T21:57:38.850Z,1605736658.850 [Supervisor](DEBUG): Initializing supervisor.
2020-11-18T21:57:38.854Z,1605736658.854 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-11-18T21:57:38.854Z,1605736658.854 [SyncHandler](INFO): Protected caller Thread ID is 1429
2020-11-18T21:57:38.854Z,1605736658.854 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-11-18T21:57:38.855Z,1605736658.855 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-11-18T21:57:38.856Z,1605736658.856 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1430
2020-11-18T21:57:38.859Z,1605736658.859 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-11-18T21:57:38.877Z,1605736658.877 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-11-18T21:57:38.878Z,1605736658.878 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-11-18T21:57:38.878Z,1605736658.878 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1431
2020-11-18T21:57:38.879Z,1605736658.879 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-11-18T21:57:38.879Z,1605736658.879 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-11-18T21:57:38.880Z,1605736658.880 [logger ThreadHandler](INFO): Protected caller Thread ID is 1432
2020-11-18T21:57:38.882Z,1605736658.882 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-11-18T21:57:38.883Z,1605736658.883 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-11-18T21:57:38.884Z,1605736658.884 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-11-18T21:57:39.454Z,1605736659.454 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-11-18T21:57:39.455Z,1605736659.455 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-11-18T21:57:39.592Z,1605736659.592 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-11-18T21:57:39.593Z,1605736659.593 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-11-18T21:57:39.673Z,1605736659.673 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-11-18T21:57:39.868Z,1605736659.868 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-11-18T21:57:39.868Z,1605736659.868 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-11-18T21:57:39.964Z,1605736659.964 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-11-18T21:57:39.964Z,1605736659.964 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-11-18T21:57:40.058Z,1605736660.058 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-11-18T21:57:40.059Z,1605736660.059 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-11-18T21:57:40.432Z,1605736660.432 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-11-18T21:57:40.432Z,1605736660.432 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-11-18T21:57:40.536Z,1605736660.536 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-11-18T21:57:40.536Z,1605736660.536 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-11-18T21:57:41.020Z,1605736661.020 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-11-18T21:57:41.021Z,1605736661.021 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-11-18T21:57:41.202Z,1605736661.202 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-11-18T21:57:41.202Z,1605736661.202 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-11-18T21:57:41.496Z,1605736661.496 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-11-18T21:57:41.496Z,1605736661.496 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-11-18T21:57:41.705Z,1605736661.705 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-11-18T21:57:41.705Z,1605736661.705 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-11-18T21:57:41.843Z,1605736661.843 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-11-18T21:57:41.844Z,1605736661.844 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-11-18T21:57:42.884Z,1605736662.884 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-11-18T21:57:42.885Z,1605736662.885 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-11-18T21:57:43.204Z,1605736663.204 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-11-18T21:57:43.204Z,1605736663.204 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-11-18T21:57:43.403Z,1605736663.403 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-11-18T21:57:43.405Z,1605736663.405 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2020-11-18T21:57:43.406Z,1605736663.406 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2020-11-18T21:57:43.590Z,1605736663.590 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2020-11-18T21:57:43.671Z,1605736663.671 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2020-11-18T21:57:43.815Z,1605736663.815 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2020-11-18T21:57:43.898Z,1605736663.898 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2020-11-18T21:57:44.144Z,1605736664.144 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-11-18T21:57:44.144Z,1605736664.144 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2020-11-18T21:57:44.246Z,1605736664.246 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2020-11-18T21:57:44.350Z,1605736664.350 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2020-11-18T21:57:44.442Z,1605736664.442 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2020-11-18T21:57:44.581Z,1605736664.581 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2020-11-18T21:57:44.676Z,1605736664.676 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2020-11-18T21:57:44.775Z,1605736664.775 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2020-11-18T21:57:44.776Z,1605736664.776 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2020-11-18T21:57:44.777Z,1605736664.777 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-11-18T21:57:44.807Z,1605736664.807 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-11-18T21:57:44.807Z,1605736664.807 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-11-18T21:57:44.903Z,1605736664.903 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-11-18T21:57:44.904Z,1605736664.904 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-11-18T21:57:44.965Z,1605736664.965 [VerticalControl](DEBUG): Construct VerticalControl.
2020-11-18T21:57:45.023Z,1605736665.023 [VerticalControl] Loaded
2020-11-18T21:57:45.023Z,1605736665.023 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-11-18T21:57:45.024Z,1605736665.024 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-11-18T21:57:45.060Z,1605736665.060 [HorizontalControl] Loaded
2020-11-18T21:57:45.060Z,1605736665.060 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-11-18T21:57:45.061Z,1605736665.061 [SpeedControl](DEBUG): Construct SpeedControl.
2020-11-18T21:57:45.064Z,1605736665.064 [SpeedControl] Loaded
2020-11-18T21:57:45.064Z,1605736665.064 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-11-18T21:57:45.065Z,1605736665.065 [LoopControl](DEBUG): Construct LoopControl.
2020-11-18T21:57:45.065Z,1605736665.065 [LoopControl] Loaded
2020-11-18T21:57:45.065Z,1605736665.065 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-11-18T21:57:45.066Z,1605736665.066 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-11-18T21:57:45.066Z,1605736665.066 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-11-18T21:57:45.195Z,1605736665.195 [BuoyancyServo] Loaded
2020-11-18T21:57:45.196Z,1605736665.196 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-11-18T21:57:45.212Z,1605736665.212 [ElevatorServo] Loaded
2020-11-18T21:57:45.212Z,1605736665.212 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-11-18T21:57:45.227Z,1605736665.227 [MassServo] Loaded
2020-11-18T21:57:45.227Z,1605736665.227 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-11-18T21:57:45.242Z,1605736665.242 [RudderServo] Loaded
2020-11-18T21:57:45.242Z,1605736665.242 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-11-18T21:57:45.257Z,1605736665.257 [ThrusterServo] Loaded
2020-11-18T21:57:45.257Z,1605736665.257 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-11-18T21:57:45.258Z,1605736665.258 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-11-18T21:57:45.258Z,1605736665.258 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-11-18T21:57:45.308Z,1605736665.308 [DepthRateCalculator] Loaded
2020-11-18T21:57:45.309Z,1605736665.309 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-11-18T21:57:45.311Z,1605736665.311 [PitchRateCalculator] Loaded
2020-11-18T21:57:45.312Z,1605736665.312 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-11-18T21:57:45.319Z,1605736665.319 [SpeedCalculator] Loaded
2020-11-18T21:57:45.319Z,1605736665.319 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-11-18T21:57:45.330Z,1605736665.330 [TempGradientCalculator] Loaded
2020-11-18T21:57:45.331Z,1605736665.331 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-11-18T21:57:45.333Z,1605736665.333 [YawRateCalculator] Loaded
2020-11-18T21:57:45.334Z,1605736665.334 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-11-18T21:57:45.348Z,1605736665.348 [ElevatorOffsetCalculator] Loaded
2020-11-18T21:57:45.348Z,1605736665.348 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-11-18T21:57:45.349Z,1605736665.349 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-11-18T21:57:45.349Z,1605736665.349 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-11-18T21:57:45.445Z,1605736665.445 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-11-18T21:57:45.445Z,1605736665.445 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-11-18T21:57:45.456Z,1605736665.456 [NavChart] Loaded
2020-11-18T21:57:45.456Z,1605736665.456 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-11-18T21:57:45.460Z,1605736665.460 [UniversalFixResidualReporter] Loaded
2020-11-18T21:57:45.460Z,1605736665.460 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-11-18T21:57:45.461Z,1605736665.461 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-11-18T21:57:45.461Z,1605736665.461 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-11-18T21:57:45.992Z,1605736665.992 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-11-18T21:57:45.995Z,1605736665.995 [AHRS_M2](INFO): created writer for : platform_orientation
2020-11-18T21:57:45.997Z,1605736665.997 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-11-18T21:57:45.000Z,1605736666.000 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-11-18T21:57:46.000Z,1605736666.000 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-11-18T21:57:46.003Z,1605736666.003 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-11-18T21:57:46.004Z,1605736666.004 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-11-18T21:57:46.007Z,1605736666.007 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-11-18T21:57:46.149Z,1605736666.149 [AHRS_M2] Loaded
2020-11-18T21:57:46.149Z,1605736666.149 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-11-18T21:57:46.295Z,1605736666.295 [DataOverHttps] Loaded
2020-11-18T21:57:46.295Z,1605736666.295 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-11-18T21:57:46.301Z,1605736666.301 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408504E0
2020-11-18T21:57:46.302Z,1605736666.302 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1515
2020-11-18T21:57:46.318Z,1605736666.318 [Depth_Keller] Loaded
2020-11-18T21:57:46.318Z,1605736666.318 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-11-18T21:57:46.321Z,1605736666.321 [DropWeight] Loaded
2020-11-18T21:57:46.321Z,1605736666.321 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-11-18T21:57:46.352Z,1605736666.352 [DVL_micro] Loaded
2020-11-18T21:57:46.352Z,1605736666.352 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2020-11-18T21:57:46.405Z,1605736666.405 [NAL9602] Loaded
2020-11-18T21:57:46.406Z,1605736666.406 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-11-18T21:57:46.431Z,1605736666.431 [Onboard] Loaded
2020-11-18T21:57:46.432Z,1605736666.432 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-11-18T21:57:46.432Z,1605736666.432 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408804E0
2020-11-18T21:57:46.433Z,1605736666.433 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1516
2020-11-18T21:57:46.443Z,1605736666.443 [Radio_Surface] Loaded
2020-11-18T21:57:46.443Z,1605736666.443 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-11-18T21:57:46.444Z,1605736666.444 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408B04E0
2020-11-18T21:57:46.445Z,1605736666.445 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1517
2020-11-18T21:57:46.519Z,1605736666.519 [DAT] Loaded
2020-11-18T21:57:46.519Z,1605736666.519 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-11-18T21:57:47.304Z,1605736667.304 [BPC1] Loaded
2020-11-18T21:57:47.304Z,1605736667.304 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-11-18T21:57:47.305Z,1605736667.305 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-11-18T21:57:47.305Z,1605736667.305 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-11-18T21:57:47.431Z,1605736667.431 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-11-18T21:57:47.431Z,1605736667.431 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-11-18T21:57:47.556Z,1605736667.556 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-11-18T21:57:47.557Z,1605736667.557 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-11-18T21:57:47.597Z,1605736667.597 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-11-18T21:57:47.597Z,1605736667.597 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-11-18T21:57:47.881Z,1605736667.881 [Aanderaa_O2] Loaded
2020-11-18T21:57:47.882Z,1605736667.882 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2020-11-18T21:57:47.900Z,1605736667.900 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-11-18T21:57:47.903Z,1605736667.903 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-11-18T21:57:47.903Z,1605736667.903 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-11-18T21:57:47.906Z,1605736667.906 [CTD_Seabird](INFO): created writer for : depth
2020-11-18T21:57:47.906Z,1605736667.906 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-11-18T21:57:47.909Z,1605736667.909 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-11-18T21:57:47.910Z,1605736667.910 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-11-18T21:57:47.912Z,1605736667.912 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-11-18T21:57:47.913Z,1605736667.913 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-11-18T21:57:47.916Z,1605736667.916 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-11-18T21:57:47.916Z,1605736667.916 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-11-18T21:57:47.919Z,1605736667.919 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-11-18T21:57:47.920Z,1605736667.920 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-11-18T21:57:47.923Z,1605736667.923 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-11-18T21:57:47.936Z,1605736667.936 [CTD_Seabird] Loaded
2020-11-18T21:57:47.936Z,1605736667.936 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-11-18T21:57:47.945Z,1605736667.945 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A584E0
2020-11-18T21:57:47.953Z,1605736667.953 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1518
2020-11-18T21:57:47.983Z,1605736667.983 [ESPComponent] Loaded
2020-11-18T21:57:47.983Z,1605736667.983 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2020-11-18T21:57:47.999Z,1605736667.999 [PAR_Licor] Loaded
2020-11-18T21:57:47.000Z,1605736668.000 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-11-18T21:57:48.012Z,1605736668.012 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-11-18T21:57:48.012Z,1605736668.012 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-11-18T21:57:48.014Z,1605736668.014 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-11-18T21:57:48.014Z,1605736668.014 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-11-18T21:57:48.016Z,1605736668.016 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-11-18T21:57:48.016Z,1605736668.016 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-11-18T21:57:48.018Z,1605736668.018 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-11-18T21:57:48.018Z,1605736668.018 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-11-18T21:57:48.020Z,1605736668.020 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-11-18T21:57:48.020Z,1605736668.020 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-11-18T21:57:48.025Z,1605736668.025 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-11-18T21:57:48.025Z,1605736668.025 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-11-18T21:57:48.033Z,1605736668.033 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-11-18T21:57:48.033Z,1605736668.033 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-11-18T21:57:48.035Z,1605736668.035 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-11-18T21:57:48.038Z,1605736668.038 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-11-18T21:57:48.038Z,1605736668.038 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-11-18T21:57:48.038Z,1605736668.038 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-11-18T21:57:48.040Z,1605736668.040 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-11-18T21:57:48.040Z,1605736668.040 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-11-18T21:57:48.069Z,1605736668.069 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-11-18T21:57:48.069Z,1605736668.069 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-11-18T21:57:48.071Z,1605736668.071 [WetLabsBB2FL] Loaded
2020-11-18T21:57:48.071Z,1605736668.071 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-11-18T21:57:48.072Z,1605736668.072 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A884E0
2020-11-18T21:57:48.073Z,1605736668.073 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1519
2020-11-18T21:57:48.090Z,1605736668.090 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-11-18T21:57:48.090Z,1605736668.090 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-11-18T21:57:48.642Z,1605736668.642 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-11-18T21:57:48.642Z,1605736668.642 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-11-18T21:57:48.797Z,1605736668.797 [SBIT](DEBUG): Construct Startup Built In Test.
2020-11-18T21:57:48.805Z,1605736668.805 [SBIT] Loaded
2020-11-18T21:57:48.806Z,1605736668.806 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-11-18T21:57:48.806Z,1605736668.806 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-11-18T21:57:48.818Z,1605736668.818 [IBIT] Loaded
2020-11-18T21:57:48.819Z,1605736668.819 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-11-18T21:57:48.822Z,1605736668.822 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-11-18T21:57:48.917Z,1605736668.917 [CBIT] Loaded
2020-11-18T21:57:48.917Z,1605736668.917 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-11-18T21:57:48.918Z,1605736668.918 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-11-18T21:57:48.922Z,1605736668.922 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-11-18T21:57:48.923Z,1605736668.923 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-11-18T21:57:48.931Z,1605736668.931 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-11-18T21:57:48.938Z,1605736668.938 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B664E0
2020-11-18T21:57:48.938Z,1605736668.938 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1520
2020-11-18T21:57:48.943Z,1605736668.943 [Supervisor](INFO): Main Thread ID is 1428
2020-11-18T21:57:48.943Z,1605736668.943 [Supervisor](DEBUG): Running supervisor.
2020-11-18T21:57:48.944Z,1605736668.944 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1521
2020-11-18T21:57:48.946Z,1605736668.946 [controlThread ThreadHandler](INFO): Handler Thread ID is 1522
2020-11-18T21:57:48.947Z,1605736668.947 [controlThread](DEBUG): Initializing ControlThread
2020-11-18T21:57:48.948Z,1605736668.948 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-11-18T21:57:48.949Z,1605736668.949 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-11-18T21:57:48.950Z,1605736668.950 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-11-18T21:57:48.951Z,1605736668.951 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-11-18T21:57:48.952Z,1605736668.952 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-11-18T21:57:48.952Z,1605736668.952 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-11-18T21:57:48.953Z,1605736668.953 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-11-18T21:57:48.953Z,1605736668.953 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-11-18T21:57:48.954Z,1605736668.954 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-11-18T21:57:48.954Z,1605736668.954 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-11-18T21:57:48.955Z,1605736668.955 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-11-18T21:57:48.955Z,1605736668.955 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-11-18T21:57:48.961Z,1605736668.961 [SBIT](INFO): Initialize SBIT Component.
2020-11-18T21:57:48.962Z,1605736668.962 [SBIT](IMPORTANT): git: 2020-10-07_A-8-g2edcf16
2020-11-18T21:57:48.962Z,1605736668.962 [SBIT](INFO): git hash: 2edcf162c99e1cdac7c4e1eb7e8495acc35f264a
2020-11-18T21:57:48.962Z,1605736668.962 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-11-18T21:57:48.963Z,1605736668.963 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Tue May 26 08:17:09 PDT 2020
2020-11-18T21:57:48.964Z,1605736668.964 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2020-11-18T21:57:48.965Z,1605736668.965 [IBIT](INFO): Initialize IBIT Component.
2020-11-18T21:57:48.966Z,1605736668.966 [CBIT](DEBUG): Initialize CBIT Component.
2020-11-18T21:57:48.967Z,1605736668.967 [logger ThreadHandler](INFO): Handler Thread ID is 1523
2020-11-18T21:57:48.977Z,1605736668.977 [CBIT](DEBUG): Initialized mux pins.
2020-11-18T21:57:48.977Z,1605736668.977 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2020-11-18T21:57:48.977Z,1605736668.977 [CBIT](DEBUG): Initializing the watchdog timer.
2020-11-18T21:57:48.985Z,1605736668.985 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1524
2020-11-18T21:57:48.986Z,1605736668.986 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-11-18T21:57:48.997Z,1605736668.997 [Onboard ThreadHandler](INFO): Handler Thread ID is 1525
2020-11-18T21:57:49.002Z,1605736669.002 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2020-11-18T21:57:49.002Z,1605736669.002 [CBIT](DEBUG): Initializing heartbeat.
2020-11-18T21:57:49.027Z,1605736669.027 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1526
2020-11-18T21:57:49.033Z,1605736669.033 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1527
2020-11-18T21:57:49.034Z,1605736669.034 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-11-18T21:57:49.039Z,1605736669.039 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1529
2020-11-18T21:57:49.040Z,1605736669.040 [WetLabsBB2FL](INFO): Powering down
2020-11-18T21:57:49.073Z,1605736669.073 [CBIT](DEBUG): Deactivating GF circuits.
2020-11-18T21:57:49.073Z,1605736669.073 [CBIT](DEBUG): Deactivating emergency mode.
2020-11-18T21:57:49.109Z,1605736669.109 [CBIT](DEBUG): Backplane powered.
2020-11-18T21:57:49.155Z,1605736669.155 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-11-18T21:57:49.157Z,1605736669.157 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1530
2020-11-18T21:57:49.175Z,1605736669.175 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-11-18T21:57:49.283Z,1605736669.283 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2020-11-18T21:57:49.284Z,1605736669.284 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-11-18T21:57:49.303Z,1605736669.303 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-11-18T21:57:49.303Z,1605736669.303 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-11-18T21:57:49.303Z,1605736669.303 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-11-18T21:57:49.304Z,1605736669.304 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-11-18T21:57:49.304Z,1605736669.304 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-11-18T21:57:49.304Z,1605736669.304 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-11-18T21:57:49.304Z,1605736669.304 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-11-18T21:57:49.304Z,1605736669.304 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-11-18T21:57:49.304Z,1605736669.304 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-11-18T21:57:49.304Z,1605736669.304 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-11-18T21:57:49.305Z,1605736669.305 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-11-18T21:57:49.305Z,1605736669.305 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-11-18T21:57:49.305Z,1605736669.305 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-11-18T21:57:49.305Z,1605736669.305 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-11-18T21:57:49.305Z,1605736669.305 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-11-18T21:57:49.306Z,1605736669.306 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-11-18T21:57:49.409Z,1605736669.409 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-11-18T21:57:49.410Z,1605736669.410 [Default:A.Wait](DEBUG): Construct Wait.
2020-11-18T21:57:49.412Z,1605736669.412 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-11-18T21:57:49.429Z,1605736669.429 [Radio_Surface](INFO): Powering up
2020-11-18T21:57:49.473Z,1605736669.473 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-11-18T21:57:49.475Z,1605736669.475 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-11-18T21:57:49.507Z,1605736669.507 [Default:E.Execute](DEBUG): Construct Execute.
2020-11-18T21:57:49.559Z,1605736669.559 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2020-11-18T21:57:49.564Z,1605736669.564 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,DAT,BPC1,Aanderaa_O2,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,
2020-11-18T21:57:49.601Z,1605736669.601 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-11-18T21:57:49.730Z,1605736669.730 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar
2020-11-18T21:57:49.741Z,1605736669.741 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-11-18T21:57:49.741Z,1605736669.741 [DAT](INFO): Powering up
2020-11-18T21:57:49.742Z,1605736669.742 [DAT](DEBUG): Initializing DAT.
2020-11-18T21:57:49.770Z,1605736669.770 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-11-18T21:57:49.856Z,1605736669.856 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-11-18T21:57:49.932Z,1605736669.932 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-11-18T21:57:49.937Z,1605736669.937 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-11-18T21:57:49.939Z,1605736669.939 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-11-18T21:57:49.945Z,1605736669.945 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-11-18T21:57:49.946Z,1605736669.946 [MassServo](DEBUG): Initializing EZServoServo.
2020-11-18T21:57:49.953Z,1605736669.953 [MassServo](DEBUG): Initializing MassServo.
2020-11-18T21:57:49.954Z,1605736669.954 [RudderServo](DEBUG): Initializing EZServoServo.
2020-11-18T21:57:49.961Z,1605736669.961 [RudderServo](DEBUG): Initializing RudderServo.
2020-11-18T21:57:49.962Z,1605736669.962 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-11-18T21:57:49.969Z,1605736669.969 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-11-18T21:57:49.972Z,1605736669.972 [CommandLine](FAULT): Scheduling is paused
2020-11-18T21:57:49.972Z,1605736669.972 [CBIT](INFO): Critical error at 20201118T215749
2020-11-18T21:57:49.972Z,1605736669.972 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-11-18T21:57:50.187Z,1605736670.187 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-11-18T21:57:50.187Z,1605736670.187 [DropWeight] Hardware Fault, FailCount= 1
2020-11-18T21:57:50.187Z,1605736670.187 [DropWeight](ERROR): Hardware Fault
2020-11-18T21:57:50.239Z,1605736670.239 [CBIT](INFO): Critical error at 20201118T215750
2020-11-18T21:57:50.242Z,1605736670.242 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-11-18T21:57:50.242Z,1605736670.242 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-11-18T21:57:50.403Z,1605736670.403 [CBIT](INFO): Critical error at 20201118T215750
2020-11-18T21:57:53.133Z,1605736673.133 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-11-18T21:57:53.549Z,1605736673.549 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-11-18T21:57:54.153Z,1605736674.153 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-11-18T21:57:54.765Z,1605736674.765 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-11-18T21:57:55.373Z,1605736675.373 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-11-18T21:57:55.777Z,1605736675.777 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-11-18T21:57:56.289Z,1605736676.289 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-11-18T21:57:56.701Z,1605736676.701 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-11-18T21:57:57.110Z,1605736677.110 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-11-18T21:57:57.505Z,1605736677.505 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-11-18T21:57:57.705Z,1605736677.705 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-11-18T21:57:58.309Z,1605736678.309 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-11-18T21:57:58.793Z,1605736678.793 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-11-18T21:57:59.209Z,1605736679.209 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-11-18T21:57:59.597Z,1605736679.597 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-11-18T21:57:59.989Z,1605736679.989 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-11-18T21:57:59.995Z,1605736679.995 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2020-11-18T21:57:59.995Z,1605736679.995 [Aanderaa_O2] Communications Fault, FailCount= 1
2020-11-18T21:57:59.995Z,1605736679.995 [Aanderaa_O2](ERROR): Communications Fault
2020-11-18T21:58:00.050Z,1605736680.050 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2020-11-18T21:58:00.401Z,1605736680.401 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-11-18T21:58:00.529Z,1605736680.529 [Aanderaa_O2](INFO): Powering down
2020-11-18T21:58:00.941Z,1605736680.941 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-11-18T21:58:01.361Z,1605736681.361 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-11-18T21:58:01.409Z,1605736681.409 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2020-11-18T21:58:01.409Z,1605736681.409 [Aanderaa_O2] No Fault, FailCount= 1
2020-11-18T21:58:01.745Z,1605736681.745 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-11-18T21:58:02.157Z,1605736682.157 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-11-18T21:58:02.561Z,1605736682.561 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-11-18T21:58:02.561Z,1605736682.561 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-11-18T21:58:02.561Z,1605736682.561 [AHRS_M2] Communications Fault, FailCount= 1
2020-11-18T21:58:02.561Z,1605736682.561 [AHRS_M2](ERROR): Communications Fault
2020-11-18T21:58:02.641Z,1605736682.641 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-11-18T21:58:02.659Z,1605736682.659 [AHRS_M2](INFO): Powering down
2020-11-18T21:58:02.679Z,1605736682.679 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004840
2020-11-18T21:58:03.480Z,1605736683.480 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-11-18T21:58:03.481Z,1605736683.481 [AHRS_M2] No Fault, FailCount= 1
2020-11-18T21:58:03.857Z,1605736683.857 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-11-18T21:58:07.425Z,1605736687.425 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-11-18T21:58:07.430Z,1605736687.430 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-11-18T21:58:07.430Z,1605736687.430 [DVL_micro] Communications Fault, FailCount= 1
2020-11-18T21:58:07.430Z,1605736687.430 [DVL_micro](ERROR): Communications Fault
2020-11-18T21:58:07.451Z,1605736687.451 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-11-18T21:58:07.813Z,1605736687.813 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-11-18T21:58:07.885Z,1605736687.885 [DVL_micro](INFO): Powering down
2020-11-18T21:58:08.273Z,1605736688.273 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-11-18T21:58:08.657Z,1605736688.657 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-11-18T21:58:08.705Z,1605736688.705 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-11-18T21:58:08.705Z,1605736688.705 [DVL_micro] No Fault, FailCount= 1
2020-11-18T21:58:09.073Z,1605736689.073 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-11-18T21:58:09.469Z,1605736689.469 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-11-18T21:58:09.527Z,1605736689.527 [SBIT](IMPORTANT): Beginning Startup BIT
2020-11-18T21:58:09.531Z,1605736689.531 [CBIT](IMPORTANT): Beginning ground fault scan
2020-11-18T21:58:10.273Z,1605736690.273 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:10.701Z,1605736690.701 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:11.225Z,1605736691.225 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:11.729Z,1605736691.729 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:12.341Z,1605736692.341 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:12.347Z,1605736692.347 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2020-11-18T21:58:12.347Z,1605736692.347 [Aanderaa_O2] Communications Fault, FailCount= 2
2020-11-18T21:58:12.347Z,1605736692.347 [Aanderaa_O2](ERROR): Communications Fault
2020-11-18T21:58:12.494Z,1605736692.494 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2020-11-18T21:58:12.817Z,1605736692.817 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:12.941Z,1605736692.941 [Aanderaa_O2](INFO): Powering down
2020-11-18T21:58:13.569Z,1605736693.569 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:14.022Z,1605736694.022 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:14.193Z,1605736694.193 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2020-11-18T21:58:14.194Z,1605736694.194 [Aanderaa_O2] No Fault, FailCount= 2
2020-11-18T21:58:14.537Z,1605736694.537 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:15.173Z,1605736695.173 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:15.609Z,1605736695.609 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:16.009Z,1605736696.009 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:16.413Z,1605736696.413 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:16.421Z,1605736696.421 [NAL9602](INFO): Powering up NAL9602
2020-11-18T21:58:16.925Z,1605736696.925 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:16.925Z,1605736696.925 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-11-18T21:58:16.925Z,1605736696.925 [AHRS_M2] Communications Fault, FailCount= 2
2020-11-18T21:58:16.925Z,1605736696.925 [AHRS_M2](ERROR): Communications Fault
2020-11-18T21:58:16.994Z,1605736696.994 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-11-18T21:58:17.008Z,1605736697.008 [AHRS_M2](INFO): Powering down
2020-11-18T21:58:17.886Z,1605736697.886 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-11-18T21:58:17.886Z,1605736697.886 [AHRS_M2] No Fault, FailCount= 2
2020-11-18T21:58:18.209Z,1605736698.209 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-11-18T21:58:20.690Z,1605736700.690 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.024172
CHAN A1 (24V): -0.007549
CHAN A2 (12V): -0.004669
CHAN A3 (5V): 0.000619
CHAN B0 (3.3V): -0.000954
CHAN B1 (3.15aV): -0.000841
CHAN B2 (3.15bV): -0.001653
CHAN B3 (GND): 0.002633
OPEN: 0.004620
Full Scale Calc: 4.765 mA, -1.589 mA
2020-11-18T21:58:21.753Z,1605736701.753 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:22.173Z,1605736702.173 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:22.573Z,1605736702.573 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:22.977Z,1605736702.977 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:23.381Z,1605736703.381 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:23.789Z,1605736703.789 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:24.209Z,1605736704.209 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:24.613Z,1605736704.613 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:24.619Z,1605736704.619 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2020-11-18T21:58:24.619Z,1605736704.619 [Aanderaa_O2] Communications Fault, FailCount= 3
2020-11-18T21:58:24.619Z,1605736704.619 [Aanderaa_O2](ERROR): Communications Fault
2020-11-18T21:58:24.845Z,1605736704.845 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2020-11-18T21:58:24.846Z,1605736704.846 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2020-11-18T21:58:25.177Z,1605736705.177 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:25.301Z,1605736705.301 [Aanderaa_O2](INFO): Powering down
2020-11-18T21:58:25.761Z,1605736705.761 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:26.201Z,1605736706.201 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:26.653Z,1605736706.653 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:26.658Z,1605736706.658 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-11-18T21:58:26.658Z,1605736706.658 [DVL_micro] Communications Fault, FailCount= 2
2020-11-18T21:58:26.658Z,1605736706.658 [DVL_micro](ERROR): Communications Fault
2020-11-18T21:58:26.802Z,1605736706.802 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-11-18T21:58:27.137Z,1605736707.137 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:27.209Z,1605736707.209 [DVL_micro](INFO): Powering down
2020-11-18T21:58:27.669Z,1605736707.669 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:28.121Z,1605736708.121 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:28.258Z,1605736708.258 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-11-18T21:58:28.258Z,1605736708.258 [DVL_micro] No Fault, FailCount= 2
2020-11-18T21:58:28.585Z,1605736708.585 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:29.049Z,1605736709.049 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:29.513Z,1605736709.513 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:29.985Z,1605736709.985 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:30.529Z,1605736710.529 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:31.189Z,1605736711.189 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:31.189Z,1605736711.189 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-11-18T21:58:31.189Z,1605736711.189 [AHRS_M2] Communications Fault, FailCount= 3
2020-11-18T21:58:31.189Z,1605736711.189 [AHRS_M2](ERROR): Communications Fault
2020-11-18T21:58:31.306Z,1605736711.306 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-11-18T21:58:31.311Z,1605736711.311 [AHRS_M2](INFO): Powering down
2020-11-18T21:58:32.186Z,1605736712.186 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-11-18T21:58:32.186Z,1605736712.186 [AHRS_M2] No Fault, FailCount= 3
2020-11-18T21:58:32.521Z,1605736712.521 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-11-18T21:58:36.077Z,1605736716.077 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:36.481Z,1605736716.481 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:36.897Z,1605736716.897 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:37.313Z,1605736717.313 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:37.319Z,1605736717.319 [NAL9602](ERROR): NAL9602 initialization error.
2020-11-18T21:58:37.319Z,1605736717.319 [NAL9602] Communications Fault, FailCount= 1
2020-11-18T21:58:37.319Z,1605736717.319 [NAL9602](ERROR): Communications Fault
2020-11-18T21:58:37.422Z,1605736717.422 [CBIT](ERROR): Communications Fault in component: NAL9602
2020-11-18T21:58:37.753Z,1605736717.753 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:37.758Z,1605736717.758 [NAL9602](INFO): Powering down
2020-11-18T21:58:38.157Z,1605736718.157 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:38.569Z,1605736718.569 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:38.658Z,1605736718.658 [CBIT](INFO): Clearing failed state for component NAL9602
2020-11-18T21:58:38.658Z,1605736718.658 [NAL9602] No Fault, FailCount= 1
2020-11-18T21:58:38.989Z,1605736718.989 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:39.405Z,1605736719.405 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:39.821Z,1605736719.821 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:40.225Z,1605736720.225 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:40.637Z,1605736720.637 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:41.057Z,1605736721.057 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:41.525Z,1605736721.525 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:41.929Z,1605736721.929 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:42.333Z,1605736722.333 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:42.737Z,1605736722.737 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:43.141Z,1605736723.141 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:43.545Z,1605736723.545 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:43.949Z,1605736723.949 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:44.353Z,1605736724.353 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:44.757Z,1605736724.757 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:45.161Z,1605736725.161 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:45.565Z,1605736725.565 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:45.565Z,1605736725.565 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-11-18T21:58:45.565Z,1605736725.565 [AHRS_M2] Communications Fault, FailCount= 4
2020-11-18T21:58:45.565Z,1605736725.565 [AHRS_M2](ERROR): Communications Fault
2020-11-18T21:58:45.610Z,1605736725.610 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-11-18T21:58:45.645Z,1605736725.645 [AHRS_M2](INFO): Powering down
2020-11-18T21:58:46.463Z,1605736726.463 [DVL_micro](FAULT): Failed to initialize within timeout.
2020-11-18T21:58:46.463Z,1605736726.463 [DVL_micro] Communications Fault, FailCount= 3
2020-11-18T21:58:46.463Z,1605736726.463 [DVL_micro](ERROR): Communications Fault
2020-11-18T21:58:46.479Z,1605736726.478 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-11-18T21:58:46.479Z,1605736726.479 [AHRS_M2] No Fault, FailCount= 4
2020-11-18T21:58:46.479Z,1605736726.479 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-11-18T21:58:46.479Z,1605736726.479 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2020-11-18T21:58:46.857Z,1605736726.857 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-11-18T21:58:46.941Z,1605736726.941 [DVL_micro](INFO): Powering down
2020-11-18T21:58:46.955Z,1605736726.955 [CBIT](INFO): Critical error at 20201118T215846
2020-11-18T21:58:50.413Z,1605736730.413 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:50.422Z,1605736730.422 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2020-11-18T21:58:50.422Z,1605736730.422 [DAT] Communications Fault, FailCount= 1
2020-11-18T21:58:50.423Z,1605736730.423 [DAT](ERROR): Communications Fault
2020-11-18T21:58:50.439Z,1605736730.439 [CBIT](ERROR): Communications Fault in component: DAT
2020-11-18T21:58:50.821Z,1605736730.821 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:50.837Z,1605736730.837 [DAT](INFO): Powering down
2020-11-18T21:58:51.329Z,1605736731.329 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:51.745Z,1605736731.745 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:51.931Z,1605736731.931 [CBIT](INFO): Clearing failed state for component DAT
2020-11-18T21:58:51.931Z,1605736731.931 [DAT] No Fault, FailCount= 1
2020-11-18T21:58:52.253Z,1605736732.253 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:52.729Z,1605736732.729 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:53.153Z,1605736733.153 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:53.605Z,1605736733.605 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:54.045Z,1605736734.045 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:54.084Z,1605736734.084 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-11-18T21:58:54.084Z,1605736734.084 [DAT](INFO): Powering up
2020-11-18T21:58:54.084Z,1605736734.084 [DAT](DEBUG): Initializing DAT.
2020-11-18T21:58:54.129Z,1605736734.129 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-11-18T21:58:54.609Z,1605736734.609 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:55.121Z,1605736735.121 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:55.689Z,1605736735.689 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:56.185Z,1605736736.185 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:56.593Z,1605736736.593 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:57.009Z,1605736737.009 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:57.421Z,1605736737.421 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:57.833Z,1605736737.833 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:58.249Z,1605736738.249 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:58.637Z,1605736738.637 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:59.045Z,1605736739.045 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:59.469Z,1605736739.469 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:59.873Z,1605736739.873 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-11-18T21:58:59.873Z,1605736739.873 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-11-18T21:58:59.873Z,1605736739.873 [AHRS_M2] Communications Fault, FailCount= 5
2020-11-18T21:58:59.873Z,1605736739.873 [AHRS_M2](ERROR): Communications Fault
2020-11-18T21:58:59.949Z,1605736739.949 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-11-18T21:58:59.950Z,1605736739.950 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2020-11-18T21:58:59.955Z,1605736739.955 [AHRS_M2](INFO): Powering down
2020-11-18T21:59:00.072Z,1605736740.072 [CBIT](INFO): Critical error at 20201118T215859
2020-11-18T21:59:03.223Z,1605736743.223 [SBIT](IMPORTANT): SBIT PASSED
2020-11-18T21:59:03.224Z,1605736743.224 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2020-11-18T21:59:03.629Z,1605736743.629 [MissionManager](IMPORTANT): Started mission Startup
2020-11-18T21:59:03.630Z,1605736743.630 [Startup] Running Loop=1
2020-11-18T21:59:03.630Z,1605736743.630 [Startup](DEBUG): Aggregate::initialize Startup
2020-11-18T21:59:03.630Z,1605736743.630 [Startup:A.GoToSurface] Running Loop=1
2020-11-18T21:59:03.630Z,1605736743.630 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-11-18T21:59:03.631Z,1605736743.631 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-11-18T21:59:03.631Z,1605736743.631 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-11-18T21:59:03.631Z,1605736743.631 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-11-18T21:59:03.632Z,1605736743.632 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-11-18T21:59:03.632Z,1605736743.632 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-11-18T21:59:03.633Z,1605736743.633 [Startup:StartupSatComms] Running Loop=1
2020-11-18T21:59:03.634Z,1605736743.634 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-11-18T21:59:03.634Z,1605736743.634 [Startup:StartupSatComms:A] Running Loop=1
2020-11-18T21:59:04.030Z,1605736744.030 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-11-18T21:59:06.833Z,1605736746.833 [DAT](INFO): commRate: 800
2020-11-18T21:59:08.042Z,1605736748.042 [NAL9602](INFO): Powering up NAL9602
2020-11-18T21:59:08.847Z,1605736748.847 [DAT](INFO): entering command mode
2020-11-18T21:59:09.255Z,1605736749.255 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-18T21:59:09.654Z,1605736749.654 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-18T21:59:10.060Z,1605736750.060 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-18T21:59:10.466Z,1605736750.466 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-18T21:59:10.866Z,1605736750.866 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-18T21:59:11.270Z,1605736751.270 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-18T21:59:11.676Z,1605736751.676 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-18T21:59:12.082Z,1605736752.082 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-18T21:59:12.482Z,1605736752.482 [DAT](DEBUG): checking for command mode acknowledgment
2020-11-18T21:59:12.891Z,1605736752.891 [DAT](INFO): setting verbose to 3
2020-11-18T21:59:13.290Z,1605736753.290 [DAT](INFO): set verbose to 3
2020-11-18T21:59:13.290Z,1605736753.290 [DAT](INFO): setting DatVerbose to 27440
2020-11-18T21:59:13.704Z,1605736753.704 [DAT](INFO): Ping request sent.
2020-11-18T21:59:13.705Z,1605736753.705 [DAT](INFO): set DatVerbose to 27440
2020-11-18T21:59:13.705Z,1605736753.705 [DAT](INFO): setting transmit power to 8
2020-11-18T21:59:14.100Z,1605736754.100 [DAT](INFO): set transmit power to 8
2020-11-18T21:59:14.100Z,1605736754.100 [DAT](INFO): setting local address to 5
2020-11-18T21:59:14.504Z,1605736754.504 [DAT](INFO): set local address to 5
2020-11-18T21:59:18.950Z,1605736758.950 [NAL9602](INFO): NAL9602 initialized
2020-11-18T21:59:22.487Z,1605736762.487 [CommandLine](IMPORTANT): got command quit
2020-11-18T21:59:23.493Z,1605736763.493 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-18T21:59:23.494Z,1605736763.494 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-11-18T21:59:23.501Z,1605736763.501 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-11-18T21:59:23.501Z,1605736763.501 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-11-18T21:59:23.502Z,1605736763.502 [CommandLine](INFO): Join timeout helper Thread ID is 1559
2020-11-18T21:59:23.502Z,1605736763.502 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-11-18T21:59:23.502Z,1605736763.502 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-11-18T21:59:23.503Z,1605736763.503 [NavChartDb](INFO): Join timeout helper Thread ID is 1560
2020-11-18T21:59:23.869Z,1605736763.869 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-18T21:59:23.869Z,1605736763.869 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-11-18T21:59:23.881Z,1605736763.881 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-11-18T21:59:23.881Z,1605736763.881 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-11-18T21:59:23.881Z,1605736763.881 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1561
2020-11-18T21:59:24.345Z,1605736764.345 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-18T21:59:24.346Z,1605736764.346 [WetLabsBB2FL](INFO): Powering down
2020-11-18T21:59:24.346Z,1605736764.346 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-11-18T21:59:24.361Z,1605736764.361 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-11-18T21:59:24.361Z,1605736764.361 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-11-18T21:59:24.361Z,1605736764.361 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1562
2020-11-18T21:59:24.441Z,1605736764.441 [CTD_Seabird](INFO): Powering down
2020-11-18T21:59:24.453Z,1605736764.453 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-18T21:59:24.453Z,1605736764.453 [CTD_Seabird](INFO): Powering down
2020-11-18T21:59:24.465Z,1605736764.465 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-11-18T21:59:24.482Z,1605736764.482 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-11-18T21:59:24.482Z,1605736764.482 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-11-18T21:59:24.483Z,1605736764.483 [Radio_Surface](INFO): Join timeout helper Thread ID is 1563
2020-11-18T21:59:24.837Z,1605736764.837 [Radio_Surface](INFO): Powering down
2020-11-18T21:59:24.838Z,1605736764.838 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-18T21:59:24.839Z,1605736764.839 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-11-18T21:59:24.842Z,1605736764.842 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-11-18T21:59:24.842Z,1605736764.842 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-11-18T21:59:24.842Z,1605736764.842 [Onboard](INFO): Join timeout helper Thread ID is 1564
2020-11-18T21:59:25.566Z,1605736765.566 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-11-18T21:59:29.077Z,1605736769.077 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-18T21:59:29.077Z,1605736769.077 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-11-18T21:59:29.086Z,1605736769.086 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-11-18T21:59:29.086Z,1605736769.086 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-11-18T21:59:29.086Z,1605736769.086 [DataOverHttps](INFO): Join timeout helper Thread ID is 1565
2020-11-18T21:59:29.581Z,1605736769.581 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-18T21:59:29.581Z,1605736769.581 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-11-18T21:59:29.590Z,1605736769.590 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-11-18T21:59:29.590Z,1605736769.590 [logger ThreadHandler](INFO): Thread cancelled.
2020-11-18T21:59:29.590Z,1605736769.590 [logger](INFO): Join timeout helper Thread ID is 1566
2020-11-18T21:59:29.629Z,1605736769.629 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-18T21:59:29.629Z,1605736769.629 [logger ThreadHandler](INFO): Thread cancelled.
2020-11-18T21:59:29.650Z,1605736769.650 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-11-18T21:59:29.650Z,1605736769.650 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-11-18T21:59:29.650Z,1605736769.650 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-11-18T21:59:29.650Z,1605736769.650 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-11-18T21:59:29.650Z,1605736769.650 [controlThread](INFO): Join timeout helper Thread ID is 1567
2020-11-18T21:59:29.845Z,1605736769.845 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-18T21:59:29.845Z,1605736769.845 [controlThread](DEBUG): Uninitializing ControlThread
2020-11-18T21:59:29.846Z,1605736769.846 [AHRS_M2](INFO): Powering down
2020-11-18T21:59:29.847Z,1605736769.847 [DVL_micro](INFO): Powering down
2020-11-18T21:59:29.847Z,1605736769.847 [NAL9602](INFO): Powering down
2020-11-18T21:59:29.848Z,1605736769.848 [DAT](INFO): Powering down
2020-11-18T21:59:29.970Z,1605736769.970 [Aanderaa_O2](INFO): Powering down
2020-11-18T21:59:29.972Z,1605736769.972 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-11-18T21:59:29.974Z,1605736769.974 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-11-18T21:59:29.974Z,1605736769.974 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-11-18T21:59:29.974Z,1605736769.974 [MissionManager](INFO): Uninitializing Mission Default
2020-11-18T21:59:29.977Z,1605736769.977 [MissionManager](INFO): Uninitializing Mission Startup
2020-11-18T21:59:29.977Z,1605736769.977 [Startup] Stopped
2020-11-18T21:59:29.977Z,1605736769.977 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-11-18T21:59:29.977Z,1605736769.977 [Startup:A.GoToSurface] Stopped
2020-11-18T21:59:29.977Z,1605736769.977 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-11-18T21:59:29.977Z,1605736769.977 [Startup:StartupSatComms] Stopped
2020-11-18T21:59:29.977Z,1605736769.977 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-11-18T21:59:29.977Z,1605736769.977 [Startup:StartupSatComms:A] Stopped
2020-11-18T21:59:29.979Z,1605736769.979 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-11-18T21:59:29.979Z,1605736769.979 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-11-18T21:59:29.979Z,1605736769.979 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-11-18T21:59:29.979Z,1605736769.979 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-11-18T21:59:29.980Z,1605736769.980 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-11-18T21:59:29.980Z,1605736769.980 [BuoyancyServo](INFO): Powering down
2020-11-18T21:59:29.993Z,1605736769.993 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-11-18T21:59:29.993Z,1605736769.993 [ElevatorServo](INFO): Powering down
2020-11-18T21:59:29.994Z,1605736769.994 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-11-18T21:59:29.994Z,1605736769.994 [MassServo](INFO): Powering down
2020-11-18T21:59:29.995Z,1605736769.995 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-11-18T21:59:29.995Z,1605736769.995 [RudderServo](INFO): Powering down
2020-11-18T21:59:29.996Z,1605736769.996 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-11-18T21:59:29.996Z,1605736769.996 [ThrusterServo](INFO): Powering down
2020-11-18T21:59:29.997Z,1605736769.997 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-11-18T21:59:29.997Z,1605736769.997 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-11-18T21:59:29.997Z,1605736769.997 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-11-18T21:59:29.997Z,1605736769.997 [CBIT](DEBUG): Powering off loads.
2020-11-18T21:59:30.009Z,1605736770.009 [CBIT](DEBUG): Disabling WDT.
2020-11-18T21:59:30.021Z,1605736770.021 [CBIT](DEBUG): Opening all GF detection circuits.
2020-11-18T21:59:30.022Z,1605736770.022 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-11-18T21:59:30.113Z,1605736770.113 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-11-18T21:59:30.115Z,1605736770.115 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-11-18T21:59:30.122Z,1605736770.122 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-11-18T21:59:30.162Z,1605736770.162 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-11-18T21:59:30.168Z,1605736770.168 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-11-18T21:59:30.202Z,1605736770.202 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-11-18T21:59:30.263Z,1605736770.263 [logger ThreadHandler](INFO): Thread cancelled.