2020-01-16T20:20:38.001Z,1579206038.001 [Supervisor](DEBUG): Initializing supervisor.
2020-01-16T20:20:38.004Z,1579206038.004 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-01-16T20:20:38.005Z,1579206038.005 [SyncHandler](INFO): Protected caller Thread ID is 1278
2020-01-16T20:20:38.006Z,1579206038.006 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-01-16T20:20:38.007Z,1579206038.007 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-01-16T20:20:38.007Z,1579206038.007 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1279
2020-01-16T20:20:38.010Z,1579206038.010 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-01-16T20:20:38.022Z,1579206038.022 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-01-16T20:20:38.023Z,1579206038.023 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-01-16T20:20:38.023Z,1579206038.023 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1280
2020-01-16T20:20:38.024Z,1579206038.024 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-01-16T20:20:38.025Z,1579206038.025 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-01-16T20:20:38.025Z,1579206038.025 [logger ThreadHandler](INFO): Protected caller Thread ID is 1281
2020-01-16T20:20:38.027Z,1579206038.027 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-01-16T20:20:38.027Z,1579206038.027 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-01-16T20:20:38.029Z,1579206038.029 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-01-16T20:20:38.475Z,1579206038.475 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-01-16T20:20:38.475Z,1579206038.475 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-01-16T20:20:38.573Z,1579206038.573 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-01-16T20:20:38.574Z,1579206038.574 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-01-16T20:20:38.896Z,1579206038.896 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-01-16T20:20:38.897Z,1579206038.897 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-01-16T20:20:39.036Z,1579206039.036 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-01-16T20:20:39.036Z,1579206039.036 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-01-16T20:20:39.279Z,1579206039.279 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-01-16T20:20:39.280Z,1579206039.280 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-01-16T20:20:39.809Z,1579206039.809 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-01-16T20:20:39.810Z,1579206039.810 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-01-16T20:20:40.023Z,1579206040.023 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-01-16T20:20:40.024Z,1579206040.024 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-01-16T20:20:40.168Z,1579206040.168 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-01-16T20:20:40.169Z,1579206040.169 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-01-16T20:20:40.364Z,1579206040.364 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-01-16T20:20:40.364Z,1579206040.364 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-01-16T20:20:40.460Z,1579206040.460 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-01-16T20:20:40.461Z,1579206040.461 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-01-16T20:20:40.765Z,1579206040.765 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-01-16T20:20:40.766Z,1579206040.766 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-01-16T20:20:40.846Z,1579206040.846 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-01-16T20:20:40.949Z,1579206040.949 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-01-16T20:20:40.949Z,1579206040.949 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-01-16T20:20:41.599Z,1579206041.599 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-01-16T20:20:41.600Z,1579206041.600 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-01-16T20:20:42.015Z,1579206042.015 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-01-16T20:20:42.017Z,1579206042.017 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-01-16T20:20:42.018Z,1579206042.018 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-01-16T20:20:42.233Z,1579206042.233 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-01-16T20:20:42.334Z,1579206042.334 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-01-16T20:20:42.434Z,1579206042.434 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-01-16T20:20:42.662Z,1579206042.662 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-01-16T20:20:42.663Z,1579206042.663 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-01-16T20:20:42.747Z,1579206042.747 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-01-16T20:20:42.841Z,1579206042.841 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-01-16T20:20:42.940Z,1579206042.940 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-01-16T20:20:43.023Z,1579206043.023 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-01-16T20:20:43.131Z,1579206043.131 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/workSite.cfg
2020-01-16T20:20:43.224Z,1579206043.224 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-01-16T20:20:43.412Z,1579206043.412 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-01-16T20:20:43.544Z,1579206043.544 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2020-01-16T20:20:43.544Z,1579206043.544 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-01-16T20:20:43.550Z,1579206043.550 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-01-16T20:20:43.856Z,1579206043.856 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-01-16T20:20:43.861Z,1579206043.861 [AHRS_M2](INFO): created writer for : platform_orientation
2020-01-16T20:20:43.863Z,1579206043.863 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-01-16T20:20:43.868Z,1579206043.868 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-01-16T20:20:43.868Z,1579206043.868 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-01-16T20:20:43.874Z,1579206043.874 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-01-16T20:20:43.874Z,1579206043.874 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-01-16T20:20:43.879Z,1579206043.879 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-01-16T20:20:43.948Z,1579206043.948 [AHRS_M2] Loaded
2020-01-16T20:20:43.948Z,1579206043.948 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-01-16T20:20:44.032Z,1579206044.032 [DataOverHttps] Loaded
2020-01-16T20:20:44.032Z,1579206044.032 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-01-16T20:20:44.033Z,1579206044.033 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407684E0
2020-01-16T20:20:44.033Z,1579206044.033 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1363
2020-01-16T20:20:44.056Z,1579206044.056 [DDM] Loaded
2020-01-16T20:20:44.057Z,1579206044.057 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2020-01-16T20:20:44.069Z,1579206044.069 [Depth_Keller] Loaded
2020-01-16T20:20:44.070Z,1579206044.070 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-01-16T20:20:44.074Z,1579206044.074 [DropWeight] Loaded
2020-01-16T20:20:44.074Z,1579206044.074 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-01-16T20:20:44.134Z,1579206044.134 [DUSBL_Hydroid] Loaded
2020-01-16T20:20:44.134Z,1579206044.134 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-01-16T20:20:44.179Z,1579206044.179 [Micromodem] Loaded
2020-01-16T20:20:44.180Z,1579206044.180 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-01-16T20:20:44.274Z,1579206044.274 [NAL9602] Loaded
2020-01-16T20:20:44.275Z,1579206044.275 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-01-16T20:20:44.310Z,1579206044.310 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
2020-01-16T20:20:44.311Z,1579206044.311 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
2020-01-16T20:20:44.311Z,1579206044.311 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
2020-01-16T20:20:44.312Z,1579206044.312 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
2020-01-16T20:20:44.312Z,1579206044.312 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
2020-01-16T20:20:44.313Z,1579206044.313 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
2020-01-16T20:20:44.314Z,1579206044.314 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
2020-01-16T20:20:44.314Z,1579206044.314 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
2020-01-16T20:20:44.314Z,1579206044.314 [Onboard] Loaded
2020-01-16T20:20:44.314Z,1579206044.314 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-01-16T20:20:44.320Z,1579206044.320 [PowerOnly] Loaded
2020-01-16T20:20:44.320Z,1579206044.320 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2020-01-16T20:20:44.327Z,1579206044.327 [Radio_Surface] Loaded
2020-01-16T20:20:44.327Z,1579206044.327 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-01-16T20:20:44.328Z,1579206044.328 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407984E0
2020-01-16T20:20:44.328Z,1579206044.328 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1364
2020-01-16T20:20:44.371Z,1579206044.371 [RDI_Pathfinder] Loaded
2020-01-16T20:20:44.371Z,1579206044.371 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-01-16T20:20:46.384Z,1579206046.384 [BPC1] Loaded
2020-01-16T20:20:46.384Z,1579206046.384 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-01-16T20:20:46.385Z,1579206046.385 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-01-16T20:20:46.385Z,1579206046.385 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-01-16T20:20:46.398Z,1579206046.398 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-01-16T20:20:46.398Z,1579206046.398 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-01-16T20:20:46.500Z,1579206046.500 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-01-16T20:20:46.500Z,1579206046.500 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-01-16T20:20:46.520Z,1579206046.520 [NavChart] Loaded
2020-01-16T20:20:46.520Z,1579206046.520 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-01-16T20:20:46.524Z,1579206046.524 [UniversalFixResidualReporter] Loaded
2020-01-16T20:20:46.524Z,1579206046.524 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-01-16T20:20:46.524Z,1579206046.524 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-01-16T20:20:46.525Z,1579206046.525 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-01-16T20:20:46.631Z,1579206046.631 [BuoyancyServo] Loaded
2020-01-16T20:20:46.632Z,1579206046.632 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-01-16T20:20:46.647Z,1579206046.647 [ElevatorServo] Loaded
2020-01-16T20:20:46.647Z,1579206046.647 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-01-16T20:20:46.661Z,1579206046.661 [MassServo] Loaded
2020-01-16T20:20:46.661Z,1579206046.661 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-01-16T20:20:46.676Z,1579206046.676 [RudderServo] Loaded
2020-01-16T20:20:46.676Z,1579206046.676 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-01-16T20:20:46.690Z,1579206046.690 [ThrusterServo] Loaded
2020-01-16T20:20:46.691Z,1579206046.691 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-01-16T20:20:46.691Z,1579206046.691 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-01-16T20:20:46.692Z,1579206046.692 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-01-16T20:20:46.945Z,1579206046.945 [CTD_NeilBrown] Loaded
2020-01-16T20:20:46.945Z,1579206046.945 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2020-01-16T20:20:46.946Z,1579206046.946 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408EC4E0
2020-01-16T20:20:46.946Z,1579206046.946 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1365
2020-01-16T20:20:46.960Z,1579206046.960 [PAR_Licor] Loaded
2020-01-16T20:20:46.961Z,1579206046.961 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-01-16T20:20:47.003Z,1579206047.003 [WetLabsSeaOWL_UV_A] Loaded
2020-01-16T20:20:47.003Z,1579206047.003 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-01-16T20:20:47.004Z,1579206047.004 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 4091C4E0
2020-01-16T20:20:47.005Z,1579206047.005 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1366
2020-01-16T20:20:47.005Z,1579206047.005 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-01-16T20:20:47.006Z,1579206047.006 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-01-16T20:20:47.334Z,1579206047.334 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-01-16T20:20:47.335Z,1579206047.335 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-01-16T20:20:47.375Z,1579206047.375 [DepthRateCalculator] Loaded
2020-01-16T20:20:47.375Z,1579206047.375 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-01-16T20:20:47.381Z,1579206047.381 [PitchRateCalculator] Loaded
2020-01-16T20:20:47.381Z,1579206047.381 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-01-16T20:20:47.393Z,1579206047.393 [SpeedCalculator] Loaded
2020-01-16T20:20:47.393Z,1579206047.393 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-01-16T20:20:47.414Z,1579206047.414 [TempGradientCalculator] Loaded
2020-01-16T20:20:47.414Z,1579206047.414 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-01-16T20:20:47.419Z,1579206047.419 [YawRateCalculator] Loaded
2020-01-16T20:20:47.419Z,1579206047.419 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-01-16T20:20:47.459Z,1579206047.459 [ElevatorOffsetCalculator] Loaded
2020-01-16T20:20:47.460Z,1579206047.460 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-01-16T20:20:47.460Z,1579206047.460 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-01-16T20:20:47.461Z,1579206047.461 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-01-16T20:20:47.596Z,1579206047.596 [SBIT](DEBUG): Construct Startup Built In Test.
2020-01-16T20:20:47.617Z,1579206047.617 [SBIT] Loaded
2020-01-16T20:20:47.617Z,1579206047.617 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-01-16T20:20:47.618Z,1579206047.618 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-01-16T20:20:47.630Z,1579206047.630 [IBIT] Loaded
2020-01-16T20:20:47.631Z,1579206047.631 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-01-16T20:20:47.634Z,1579206047.634 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-01-16T20:20:47.770Z,1579206047.770 [CBIT] Loaded
2020-01-16T20:20:47.770Z,1579206047.770 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-01-16T20:20:47.771Z,1579206047.771 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-01-16T20:20:47.771Z,1579206047.771 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-01-16T20:20:47.851Z,1579206047.851 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-01-16T20:20:47.851Z,1579206047.851 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-01-16T20:20:47.949Z,1579206047.949 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-01-16T20:20:47.950Z,1579206047.950 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-01-16T20:20:47.997Z,1579206047.997 [VerticalControl](DEBUG): Construct VerticalControl.
2020-01-16T20:20:48.079Z,1579206048.079 [VerticalControl] Loaded
2020-01-16T20:20:48.079Z,1579206048.079 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-01-16T20:20:48.079Z,1579206048.079 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-01-16T20:20:48.147Z,1579206048.147 [HorizontalControl] Loaded
2020-01-16T20:20:48.147Z,1579206048.147 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-01-16T20:20:48.148Z,1579206048.148 [SpeedControl](DEBUG): Construct SpeedControl.
2020-01-16T20:20:48.149Z,1579206048.149 [SpeedControl] Loaded
2020-01-16T20:20:48.150Z,1579206048.150 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-01-16T20:20:48.150Z,1579206048.150 [LoopControl](DEBUG): Construct LoopControl.
2020-01-16T20:20:48.151Z,1579206048.151 [LoopControl] Loaded
2020-01-16T20:20:48.151Z,1579206048.151 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-01-16T20:20:48.151Z,1579206048.151 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-01-16T20:20:48.152Z,1579206048.152 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-01-16T20:20:48.184Z,1579206048.184 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-01-16T20:20:48.187Z,1579206048.187 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-01-16T20:20:48.188Z,1579206048.188 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-01-16T20:20:48.195Z,1579206048.195 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-01-16T20:20:48.196Z,1579206048.196 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B024E0
2020-01-16T20:20:48.196Z,1579206048.196 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1367
2020-01-16T20:20:48.201Z,1579206048.201 [Supervisor](INFO): Main Thread ID is 802
2020-01-16T20:20:48.201Z,1579206048.201 [Supervisor](DEBUG): Running supervisor.
2020-01-16T20:20:48.201Z,1579206048.201 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1368
2020-01-16T20:20:48.204Z,1579206048.204 [controlThread ThreadHandler](INFO): Handler Thread ID is 1369
2020-01-16T20:20:48.204Z,1579206048.204 [controlThread](DEBUG): Initializing ControlThread
2020-01-16T20:20:48.213Z,1579206048.213 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-01-16T20:20:48.213Z,1579206048.213 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-01-16T20:20:48.217Z,1579206048.217 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-01-16T20:20:48.217Z,1579206048.217 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-01-16T20:20:48.218Z,1579206048.218 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-01-16T20:20:48.218Z,1579206048.218 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-01-16T20:20:48.218Z,1579206048.218 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-01-16T20:20:48.219Z,1579206048.219 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-01-16T20:20:48.219Z,1579206048.219 [SBIT](INFO): Initialize SBIT Component.
2020-01-16T20:20:48.220Z,1579206048.220 [SBIT](IMPORTANT): git: 2019-12-03-49-g04fffb6
2020-01-16T20:20:48.220Z,1579206048.220 [SBIT](INFO): git hash: 04fffb6753863654093fc991b35c0948c18f1aa1
2020-01-16T20:20:48.220Z,1579206048.220 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-01-16T20:20:48.221Z,1579206048.221 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #2 PREEMPT Wed May 15 08:34:03 PDT 2019
2020-01-16T20:20:48.223Z,1579206048.223 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2020-01-16T20:20:48.223Z,1579206048.223 [IBIT](INFO): Initialize IBIT Component.
2020-01-16T20:20:48.224Z,1579206048.224 [CBIT](DEBUG): Initialize CBIT Component.
2020-01-16T20:20:48.225Z,1579206048.225 [logger ThreadHandler](INFO): Handler Thread ID is 1370
2020-01-16T20:20:48.237Z,1579206048.237 [CBIT](DEBUG): Initialized mux pins.
2020-01-16T20:20:48.237Z,1579206048.237 [CBIT](DEBUG): Initializing the watchdog timer.
2020-01-16T20:20:48.245Z,1579206048.245 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1371
2020-01-16T20:20:48.246Z,1579206048.246 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-01-16T20:20:48.257Z,1579206048.257 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1372
2020-01-16T20:20:48.261Z,1579206048.261 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-01-16T20:20:48.261Z,1579206048.261 [CBIT](DEBUG): Initializing heartbeat.
2020-01-16T20:20:48.269Z,1579206048.269 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1373
2020-01-16T20:20:48.270Z,1579206048.270 [CTD_NeilBrown](INFO): Powering down
2020-01-16T20:20:48.297Z,1579206048.297 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1374
2020-01-16T20:20:48.298Z,1579206048.298 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-16T20:20:48.321Z,1579206048.321 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1375
2020-01-16T20:20:48.324Z,1579206048.324 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-01-16T20:20:48.325Z,1579206048.325 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-01-16T20:20:48.325Z,1579206048.325 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-01-16T20:20:48.325Z,1579206048.325 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-01-16T20:20:48.325Z,1579206048.325 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-01-16T20:20:48.325Z,1579206048.325 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-01-16T20:20:48.326Z,1579206048.326 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-01-16T20:20:48.326Z,1579206048.326 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-01-16T20:20:48.326Z,1579206048.326 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-01-16T20:20:48.326Z,1579206048.326 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-01-16T20:20:48.326Z,1579206048.326 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-01-16T20:20:48.326Z,1579206048.326 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-01-16T20:20:48.327Z,1579206048.327 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-01-16T20:20:48.327Z,1579206048.327 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-01-16T20:20:48.327Z,1579206048.327 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-01-16T20:20:48.327Z,1579206048.327 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-01-16T20:20:48.333Z,1579206048.333 [CBIT](DEBUG): Deactivating GF circuits.
2020-01-16T20:20:48.333Z,1579206048.333 [CBIT](DEBUG): Deactivating emergency mode.
2020-01-16T20:20:48.369Z,1579206048.369 [CBIT](DEBUG): Backplane powered.
2020-01-16T20:20:48.369Z,1579206048.369 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-01-16T20:20:48.371Z,1579206048.371 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-01-16T20:20:48.372Z,1579206048.372 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-01-16T20:20:48.372Z,1579206048.372 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-01-16T20:20:48.373Z,1579206048.373 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-01-16T20:20:48.383Z,1579206048.383 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-16T20:20:48.434Z,1579206048.434 [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-01-16T20:20:48.435Z,1579206048.435 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-01-16T20:20:48.502Z,1579206048.502 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-01-16T20:20:48.503Z,1579206048.503 [Default:A.Wait](DEBUG): Construct Wait.
2020-01-16T20:20:48.505Z,1579206048.505 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-16T20:20:48.544Z,1579206048.544 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-01-16T20:20:48.559Z,1579206048.559 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-01-16T20:20:48.579Z,1579206048.579 [Default:E.Execute](DEBUG): Construct Execute.
2020-01-16T20:20:48.582Z,1579206048.582 [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-01-16T20:20:48.589Z,1579206048.589 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,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-01-16T20:20:48.609Z,1579206048.609 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-16T20:20:48.641Z,1579206048.641 [DDM](INFO): Powering up
2020-01-16T20:20:48.641Z,1579206048.641 [DDM](DEBUG): Initializing DDM.
2020-01-16T20:20:48.661Z,1579206048.661 [Radio_Surface](INFO): Powering up
2020-01-16T20:20:48.668Z,1579206048.668 [DUSBL_Hydroid](INFO): Powering up
2020-01-16T20:20:48.668Z,1579206048.668 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-16T20:20:48.867Z,1579206048.867 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-01-16T20:20:48.873Z,1579206048.873 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-01-16T20:20:48.874Z,1579206048.874 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-01-16T20:20:48.882Z,1579206048.882 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-01-16T20:20:48.882Z,1579206048.882 [MassServo](DEBUG): Initializing EZServoServo.
2020-01-16T20:20:48.893Z,1579206048.893 [MassServo](DEBUG): Initializing MassServo.
2020-01-16T20:20:48.894Z,1579206048.894 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-16T20:20:48.901Z,1579206048.901 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-16T20:20:48.902Z,1579206048.902 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-01-16T20:20:48.909Z,1579206048.909 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-01-16T20:20:49.046Z,1579206049.046 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-01-16T20:20:49.046Z,1579206049.046 [DropWeight] Hardware Fault, FailCount= 1
2020-01-16T20:20:49.046Z,1579206049.046 [DropWeight](ERROR): Hardware Fault
2020-01-16T20:20:49.048Z,1579206049.048 [Micromodem](INFO): Powering up
2020-01-16T20:20:49.048Z,1579206049.048 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-16T20:20:49.112Z,1579206049.112 [CommandLine](FAULT): Scheduling is paused
2020-01-16T20:20:49.112Z,1579206049.112 [CBIT](INFO): Critical error at 20200116T202049
2020-01-16T20:20:49.113Z,1579206049.113 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-01-16T20:20:49.115Z,1579206049.115 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-01-16T20:20:49.115Z,1579206049.115 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-01-16T20:20:49.805Z,1579206049.805 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-01-16T20:20:49.805Z,1579206049.805 [RudderServo](FAULT): Rudder failed to initialize
2020-01-16T20:20:49.805Z,1579206049.805 [RudderServo] Communications Fault, FailCount= 1
2020-01-16T20:20:49.805Z,1579206049.805 [RudderServo](ERROR): Communications Fault
2020-01-16T20:20:49.914Z,1579206049.914 [CBIT](INFO): Critical error at 20200116T202049
2020-01-16T20:20:49.917Z,1579206049.917 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-01-16T20:20:50.098Z,1579206050.098 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-16T20:20:50.098Z,1579206050.098 [RudderServo](INFO): Powering down
2020-01-16T20:20:50.799Z,1579206050.799 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-16T20:20:50.918Z,1579206050.918 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-16T20:20:50.923Z,1579206050.923 [CBIT](INFO): Clearing failed state for component RudderServo
2020-01-16T20:20:50.923Z,1579206050.923 [RudderServo] No Fault, FailCount= 1
2020-01-16T20:20:52.265Z,1579206052.265 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-01-16T20:20:52.665Z,1579206052.665 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-01-16T20:20:53.321Z,1579206053.321 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-01-16T20:20:53.773Z,1579206053.773 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-01-16T20:20:53.782Z,1579206053.782 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-01-16T20:20:54.193Z,1579206054.193 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-01-16T20:20:54.665Z,1579206054.665 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-01-16T20:20:54.671Z,1579206054.671 [Micromodem](INFO): Nmea in: $CATMG,2020-01-16T20:20:53.953827Z,RTC,RTC*52

2020-01-16T20:20:54.672Z,1579206054.672 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-01-16T20:20:53.953827Z,RTC,RTC*52

2020-01-16T20:20:55.133Z,1579206055.133 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-01-16T20:20:55.140Z,1579206055.140 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-01-16T20:20:55.140Z,1579206055.140 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-01-16T20:20:55.617Z,1579206055.617 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-01-16T20:20:55.623Z,1579206055.623 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-01-16T20:20:55.623Z,1579206055.623 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-01-16T20:20:55.959Z,1579206055.959 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-01-16T20:20:55.959Z,1579206055.959 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-01-16T20:20:56.343Z,1579206056.343 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-01-16T20:20:56.343Z,1579206056.343 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-01-16T20:20:56.747Z,1579206056.747 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-01-16T20:20:56.747Z,1579206056.747 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2020-01-16T20:20:57.195Z,1579206057.195 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2020-01-16T20:20:57.195Z,1579206057.195 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-01-16T20:20:57.555Z,1579206057.555 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-01-16T20:20:57.555Z,1579206057.555 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-01-16T20:20:57.959Z,1579206057.959 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-01-16T20:20:57.960Z,1579206057.960 [Micromodem](INFO): Nmea out: $CCCLK,2020,01,16,20,20,58*4F

2020-01-16T20:20:58.363Z,1579206058.363 [Micromodem](INFO): Nmea in: $CACLK,2020,1,16,20,20,58*7D

2020-01-16T20:20:58.364Z,1579206058.364 [Micromodem](INFO): Nmea in: $CATMS,0,2020-01-16T20:20:59Z*7C

2020-01-16T20:20:58.367Z,1579206058.367 [Micromodem](INFO): Nmea in: $CATMG,2020-01-16T20:20:59.027859Z,USER_CMD,RTC*1A

2020-01-16T20:20:58.453Z,1579206058.453 [CBIT](CRITICAL): Environmental Failure. Press:14.814070 PSI. Humidity:33%. Temp:22 C. ABORTING MISSION
2020-01-16T20:20:58.859Z,1579206058.859 [CBIT](INFO): Critical error at 20200116T202058
2020-01-16T20:21:08.849Z,1579206068.849 [DDM](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0
2020-01-16T20:21:08.850Z,1579206068.850 [DDM] Communications Fault, FailCount= 1
2020-01-16T20:21:08.850Z,1579206068.850 [DDM](ERROR): Communications Fault
2020-01-16T20:21:08.854Z,1579206068.854 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0
2020-01-16T20:21:08.854Z,1579206068.854 [DUSBL_Hydroid] Communications Fault, FailCount= 1
2020-01-16T20:21:08.854Z,1579206068.854 [DUSBL_Hydroid](ERROR): Communications Fault
2020-01-16T20:21:08.923Z,1579206068.923 [CBIT](ERROR): Communications Fault in component: DDM
2020-01-16T20:21:08.923Z,1579206068.923 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2020-01-16T20:21:09.253Z,1579206069.253 [DDM](INFO): Powering down
2020-01-16T20:21:09.354Z,1579206069.354 [DUSBL_Hydroid](INFO): Powering down
2020-01-16T20:21:10.129Z,1579206070.129 [CBIT](INFO): Clearing failed state for component DDM
2020-01-16T20:21:10.129Z,1579206070.129 [DDM] No Fault, FailCount= 1
2020-01-16T20:21:10.129Z,1579206070.129 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2020-01-16T20:21:10.129Z,1579206070.129 [DUSBL_Hydroid] No Fault, FailCount= 1
2020-01-16T20:21:10.869Z,1579206070.869 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2020-01-16T20:21:10.869Z,1579206070.869 [AHRS_M2] Data Fault, FailCount= 1
2020-01-16T20:21:10.869Z,1579206070.869 [AHRS_M2](ERROR): Data Fault
2020-01-16T20:21:10.915Z,1579206070.915 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-01-16T20:21:10.915Z,1579206070.915 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-01-16T20:21:10.915Z,1579206070.915 [RDI_Pathfinder](ERROR): Communications Fault
2020-01-16T20:21:10.960Z,1579206070.960 [CBIT](ERROR): Data Fault in component: AHRS_M2
2020-01-16T20:21:10.960Z,1579206070.960 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-01-16T20:21:11.260Z,1579206071.260 [AHRS_M2](INFO): Powering down
2020-01-16T20:21:11.429Z,1579206071.429 [RDI_Pathfinder](INFO): Powering down
2020-01-16T20:21:12.125Z,1579206072.125 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-01-16T20:21:12.125Z,1579206072.125 [AHRS_M2] No Fault, FailCount= 1
2020-01-16T20:21:12.125Z,1579206072.125 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-01-16T20:21:12.125Z,1579206072.125 [RDI_Pathfinder] No Fault, FailCount= 1
2020-01-16T20:21:12.472Z,1579206072.472 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-16T20:21:12.488Z,1579206072.488 [DDM](INFO): Powering up
2020-01-16T20:21:12.488Z,1579206072.488 [DDM](DEBUG): Initializing DDM.
2020-01-16T20:21:12.510Z,1579206072.510 [DUSBL_Hydroid](INFO): Powering up
2020-01-16T20:21:12.510Z,1579206072.510 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-16T20:21:14.507Z,1579206074.507 [NAL9602](INFO): Powering up NAL9602
2020-01-16T20:21:16.029Z,1579206076.029 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:16.433Z,1579206076.433 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:16.829Z,1579206076.829 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:17.253Z,1579206077.253 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:17.685Z,1579206077.685 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:18.073Z,1579206078.073 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:18.485Z,1579206078.485 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:18.897Z,1579206078.897 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:19.309Z,1579206079.309 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:19.701Z,1579206079.701 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:20.101Z,1579206080.101 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:20.163Z,1579206080.163 [CBIT](FAULT): WATER ALARM BOW.
2020-01-16T20:21:20.169Z,1579206080.169 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 1
2020-01-16T20:21:20.529Z,1579206080.529 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:20.611Z,1579206080.611 [CBIT](FAULT): WATER ALARM BOW.
2020-01-16T20:21:20.613Z,1579206080.613 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 2
2020-01-16T20:21:20.973Z,1579206080.973 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:21.039Z,1579206081.039 [CBIT](FAULT): WATER ALARM BOW.
2020-01-16T20:21:21.040Z,1579206081.040 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 3
2020-01-16T20:21:21.393Z,1579206081.393 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:21.450Z,1579206081.450 [CBIT](FAULT): WATER ALARM BOW.
2020-01-16T20:21:21.465Z,1579206081.465 [CBIT](CRITICAL): WATER DETECTED IN PRESSURE HULL. BURNWIRE ACTIVATED
2020-01-16T20:21:21.465Z,1579206081.465 [CBIT](IMPORTANT): No ESP to power off.
2020-01-16T20:21:21.798Z,1579206081.798 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:21.875Z,1579206081.875 [CBIT](INFO): Critical error at 20200116T202121
2020-01-16T20:21:22.233Z,1579206082.233 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:22.649Z,1579206082.649 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:23.034Z,1579206083.034 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:23.441Z,1579206083.441 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:23.845Z,1579206083.845 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:24.257Z,1579206084.257 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:24.645Z,1579206084.645 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:25.061Z,1579206085.061 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:25.465Z,1579206085.465 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:25.465Z,1579206085.465 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-01-16T20:21:25.465Z,1579206085.465 [AHRS_M2] Communications Fault, FailCount= 2
2020-01-16T20:21:25.465Z,1579206085.465 [AHRS_M2](ERROR): Communications Fault
2020-01-16T20:21:25.472Z,1579206085.472 [NAL9602](INFO): NAL9602 initialized
2020-01-16T20:21:25.550Z,1579206085.550 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-01-16T20:21:25.559Z,1579206085.559 [AHRS_M2](INFO): Powering down
2020-01-16T20:21:26.481Z,1579206086.481 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-01-16T20:21:26.481Z,1579206086.481 [AHRS_M2] No Fault, FailCount= 2
2020-01-16T20:21:26.764Z,1579206086.764 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-16T20:21:28.018Z,1579206088.018 [SBIT](IMPORTANT): Beginning Startup BIT
2020-01-16T20:21:28.026Z,1579206088.026 [CBIT](IMPORTANT): Beginning ground fault scan
2020-01-16T20:21:30.093Z,1579206090.093 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:30.973Z,1579206090.973 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:31.549Z,1579206091.549 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:32.069Z,1579206092.069 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:32.741Z,1579206092.741 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:32.742Z,1579206092.742 [DDM](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0
2020-01-16T20:21:32.742Z,1579206092.742 [DDM] Communications Fault, FailCount= 2
2020-01-16T20:21:32.742Z,1579206092.742 [DDM](ERROR): Communications Fault
2020-01-16T20:21:32.746Z,1579206092.746 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0
2020-01-16T20:21:32.746Z,1579206092.746 [DUSBL_Hydroid] Communications Fault, FailCount= 2
2020-01-16T20:21:32.746Z,1579206092.746 [DUSBL_Hydroid](ERROR): Communications Fault
2020-01-16T20:21:32.940Z,1579206092.940 [CBIT](ERROR): Communications Fault in component: DDM
2020-01-16T20:21:32.941Z,1579206092.941 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2020-01-16T20:21:33.273Z,1579206093.273 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:33.273Z,1579206093.273 [DDM](INFO): Powering down
2020-01-16T20:21:33.346Z,1579206093.346 [DUSBL_Hydroid](INFO): Powering down
2020-01-16T20:21:34.089Z,1579206094.089 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:34.541Z,1579206094.541 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:34.661Z,1579206094.661 [CBIT](INFO): Clearing failed state for component DDM
2020-01-16T20:21:34.661Z,1579206094.661 [DDM] No Fault, FailCount= 2
2020-01-16T20:21:34.662Z,1579206094.662 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2020-01-16T20:21:34.662Z,1579206094.662 [DUSBL_Hydroid] No Fault, FailCount= 2
2020-01-16T20:21:34.989Z,1579206094.989 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:35.026Z,1579206095.026 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-01-16T20:21:35.026Z,1579206095.026 [RDI_Pathfinder] Communications Fault, FailCount= 2
2020-01-16T20:21:35.027Z,1579206095.027 [RDI_Pathfinder](ERROR): Communications Fault
2020-01-16T20:21:35.110Z,1579206095.110 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-01-16T20:21:35.441Z,1579206095.441 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:35.533Z,1579206095.533 [RDI_Pathfinder](INFO): Powering down
2020-01-16T20:21:36.009Z,1579206096.009 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:36.437Z,1579206096.437 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:36.437Z,1579206096.437 [DDM](INFO): Powering up
2020-01-16T20:21:36.437Z,1579206096.437 [DDM](DEBUG): Initializing DDM.
2020-01-16T20:21:36.474Z,1579206096.474 [DUSBL_Hydroid](INFO): Powering up
2020-01-16T20:21:36.474Z,1579206096.474 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-16T20:21:36.585Z,1579206096.585 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-01-16T20:21:36.586Z,1579206096.586 [RDI_Pathfinder] No Fault, FailCount= 2
2020-01-16T20:21:36.909Z,1579206096.909 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:37.453Z,1579206097.453 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:37.885Z,1579206097.885 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:38.301Z,1579206098.301 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:38.825Z,1579206098.825 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:39.269Z,1579206099.269 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:39.713Z,1579206099.713 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:39.713Z,1579206099.713 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-01-16T20:21:39.713Z,1579206099.713 [AHRS_M2] Communications Fault, FailCount= 3
2020-01-16T20:21:39.713Z,1579206099.713 [AHRS_M2](ERROR): Communications Fault
2020-01-16T20:21:39.921Z,1579206099.921 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-01-16T20:21:40.033Z,1579206100.033 [AHRS_M2](INFO): Powering down
2020-01-16T20:21:40.181Z,1579206100.181 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.010648
CHAN A1 (24V): -0.028451
CHAN A2 (12V): -0.007035
CHAN A3 (5V): -0.002160
CHAN B0 (3.3V): 0.000140
CHAN B1 (3.15aV): -0.000237
CHAN B2 (3.15bV): 0.000148
CHAN B3 (GND): 0.002177
OPEN: 0.000038
Full Scale Calc: 4.765 mA, -1.589 mA
2020-01-16T20:21:40.894Z,1579206100.894 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-01-16T20:21:40.894Z,1579206100.894 [AHRS_M2] No Fault, FailCount= 3
2020-01-16T20:21:41.232Z,1579206101.232 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-16T20:21:44.945Z,1579206104.945 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:45.437Z,1579206105.437 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:45.909Z,1579206105.909 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:46.373Z,1579206106.373 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:46.845Z,1579206106.845 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:47.313Z,1579206107.313 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:47.777Z,1579206107.777 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:48.245Z,1579206108.245 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:48.713Z,1579206108.713 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:49.313Z,1579206109.313 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:49.869Z,1579206109.869 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:50.325Z,1579206110.325 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:50.797Z,1579206110.797 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:51.241Z,1579206111.241 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:51.661Z,1579206111.661 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:52.077Z,1579206112.077 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:52.489Z,1579206112.489 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:52.917Z,1579206112.917 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:53.345Z,1579206113.345 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:53.765Z,1579206113.765 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:54.193Z,1579206114.193 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:54.625Z,1579206114.625 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:54.625Z,1579206114.625 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-01-16T20:21:54.625Z,1579206114.625 [AHRS_M2] Communications Fault, FailCount= 4
2020-01-16T20:21:54.625Z,1579206114.625 [AHRS_M2](ERROR): Communications Fault
2020-01-16T20:21:54.750Z,1579206114.750 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-01-16T20:21:54.756Z,1579206114.756 [AHRS_M2](INFO): Powering down
2020-01-16T20:21:55.670Z,1579206115.670 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-01-16T20:21:55.670Z,1579206115.670 [AHRS_M2] No Fault, FailCount= 4
2020-01-16T20:21:55.964Z,1579206115.964 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-16T20:21:56.773Z,1579206116.773 [DDM](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0
2020-01-16T20:21:56.773Z,1579206116.773 [DDM] Communications Fault, FailCount= 3
2020-01-16T20:21:56.773Z,1579206116.773 [DDM](ERROR): Communications Fault
2020-01-16T20:21:56.782Z,1579206116.782 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0
2020-01-16T20:21:56.782Z,1579206116.782 [DUSBL_Hydroid] Communications Fault, FailCount= 3
2020-01-16T20:21:56.782Z,1579206116.782 [DUSBL_Hydroid](ERROR): Communications Fault
2020-01-16T20:21:56.927Z,1579206116.927 [CBIT](ERROR): Communications Fault in component: DDM
2020-01-16T20:21:56.927Z,1579206116.927 [CBIT](CRITICAL): Communications Fault in component: DDM
2020-01-16T20:21:56.927Z,1579206116.927 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2020-01-16T20:21:57.176Z,1579206117.176 [DDM](INFO): Powering down
2020-01-16T20:21:57.258Z,1579206117.258 [DUSBL_Hydroid](INFO): Powering down
2020-01-16T20:21:57.424Z,1579206117.424 [CBIT](INFO): Critical error at 20200116T202156
2020-01-16T20:21:58.074Z,1579206118.074 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2020-01-16T20:21:58.074Z,1579206118.074 [DUSBL_Hydroid] No Fault, FailCount= 3
2020-01-16T20:21:59.521Z,1579206119.521 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:21:59.572Z,1579206119.572 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-01-16T20:21:59.572Z,1579206119.572 [RDI_Pathfinder] Communications Fault, FailCount= 3
2020-01-16T20:21:59.572Z,1579206119.572 [RDI_Pathfinder](ERROR): Communications Fault
2020-01-16T20:21:59.587Z,1579206119.587 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-01-16T20:21:59.588Z,1579206119.588 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2020-01-16T20:21:59.917Z,1579206119.917 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:22:00.029Z,1579206120.029 [RDI_Pathfinder](INFO): Powering down
2020-01-16T20:22:00.048Z,1579206120.048 [CBIT](INFO): Critical error at 20200116T202159
2020-01-16T20:22:00.373Z,1579206120.373 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:22:00.382Z,1579206120.382 [DUSBL_Hydroid](INFO): Powering up
2020-01-16T20:22:00.382Z,1579206120.382 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-16T20:22:00.777Z,1579206120.777 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:22:01.197Z,1579206121.197 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:22:01.593Z,1579206121.593 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:22:01.997Z,1579206121.997 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:22:02.401Z,1579206122.401 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:22:02.809Z,1579206122.809 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:22:03.213Z,1579206123.213 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:22:03.613Z,1579206123.613 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:22:04.017Z,1579206124.017 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:22:04.421Z,1579206124.421 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:22:04.829Z,1579206124.829 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:22:05.221Z,1579206125.221 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:22:05.629Z,1579206125.629 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:22:06.041Z,1579206126.041 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:22:06.441Z,1579206126.441 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:22:06.841Z,1579206126.841 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:22:07.249Z,1579206127.249 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:22:07.653Z,1579206127.653 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:22:08.053Z,1579206128.053 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:22:08.457Z,1579206128.457 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:22:08.861Z,1579206128.861 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2020-01-16T20:22:08.861Z,1579206128.861 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-01-16T20:22:08.861Z,1579206128.861 [AHRS_M2] Communications Fault, FailCount= 5
2020-01-16T20:22:08.861Z,1579206128.861 [AHRS_M2](ERROR): Communications Fault
2020-01-16T20:22:08.942Z,1579206128.942 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-01-16T20:22:08.943Z,1579206128.943 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2020-01-16T20:22:08.960Z,1579206128.960 [AHRS_M2](INFO): Powering down
2020-01-16T20:22:09.073Z,1579206129.073 [CBIT](INFO): Critical error at 20200116T202208
2020-01-16T20:22:18.435Z,1579206138.435 [CommandLine](IMPORTANT): got command restart application
2020-01-16T20:22:19.441Z,1579206139.441 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T20:22:19.441Z,1579206139.441 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:22:19.442Z,1579206139.442 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-01-16T20:22:19.442Z,1579206139.442 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:22:19.443Z,1579206139.443 [CommandLine](INFO): Join timeout helper Thread ID is 1406
2020-01-16T20:22:19.443Z,1579206139.443 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-01-16T20:22:19.443Z,1579206139.443 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:22:19.444Z,1579206139.444 [NavChartDb](INFO): Join timeout helper Thread ID is 1407
2020-01-16T20:22:19.716Z,1579206139.716 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T20:22:19.716Z,1579206139.716 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:22:19.729Z,1579206139.729 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-01-16T20:22:19.729Z,1579206139.729 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:22:19.729Z,1579206139.729 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1408
2020-01-16T20:22:20.101Z,1579206140.101 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T20:22:20.101Z,1579206140.101 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-16T20:22:20.102Z,1579206140.102 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:22:20.109Z,1579206140.109 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2020-01-16T20:22:20.109Z,1579206140.109 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:22:20.109Z,1579206140.109 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1409
2020-01-16T20:22:20.157Z,1579206140.157 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T20:22:20.157Z,1579206140.157 [CTD_NeilBrown](INFO): Powering down
2020-01-16T20:22:20.169Z,1579206140.169 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:22:20.169Z,1579206140.169 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-01-16T20:22:20.169Z,1579206140.169 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:22:20.170Z,1579206140.170 [Radio_Surface](INFO): Join timeout helper Thread ID is 1410
2020-01-16T20:22:20.393Z,1579206140.393 [Radio_Surface](INFO): Powering down
2020-01-16T20:22:20.394Z,1579206140.394 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T20:22:20.394Z,1579206140.394 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:22:20.410Z,1579206140.410 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-01-16T20:22:20.410Z,1579206140.410 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:22:20.410Z,1579206140.410 [DataOverHttps](INFO): Join timeout helper Thread ID is 1411
2020-01-16T20:22:20.434Z,1579206140.434 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0
2020-01-16T20:22:20.434Z,1579206140.434 [DUSBL_Hydroid] Communications Fault, FailCount= 4
2020-01-16T20:22:20.434Z,1579206140.434 [DUSBL_Hydroid](ERROR): Communications Fault
2020-01-16T20:22:20.506Z,1579206140.506 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2020-01-16T20:22:20.613Z,1579206140.613 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T20:22:20.613Z,1579206140.613 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:22:20.633Z,1579206140.633 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-01-16T20:22:20.634Z,1579206140.634 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:22:20.634Z,1579206140.634 [logger](INFO): Join timeout helper Thread ID is 1412
2020-01-16T20:22:20.649Z,1579206140.649 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T20:22:20.649Z,1579206140.649 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:22:20.654Z,1579206140.654 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-01-16T20:22:20.654Z,1579206140.654 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:22:20.654Z,1579206140.654 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-01-16T20:22:20.654Z,1579206140.654 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:22:20.654Z,1579206140.654 [controlThread](INFO): Join timeout helper Thread ID is 1413
2020-01-16T20:22:20.829Z,1579206140.829 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T20:22:20.829Z,1579206140.829 [controlThread](DEBUG): Uninitializing ControlThread
2020-01-16T20:22:20.830Z,1579206140.830 [AHRS_M2](INFO): Powering down
2020-01-16T20:22:20.830Z,1579206140.830 [DDM](INFO): Powering down
2020-01-16T20:22:20.831Z,1579206140.831 [DUSBL_Hydroid](INFO): Powering down
2020-01-16T20:22:20.901Z,1579206140.901 [Micromodem](INFO): Powering down
2020-01-16T20:22:20.997Z,1579206140.997 [NAL9602](INFO): Powering down
2020-01-16T20:22:20.999Z,1579206140.999 [RDI_Pathfinder](INFO): Powering down
2020-01-16T20:22:21.001Z,1579206141.001 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-01-16T20:22:21.002Z,1579206141.002 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-01-16T20:22:21.002Z,1579206141.002 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-01-16T20:22:21.002Z,1579206141.003 [MissionManager](INFO): Uninitializing Mission Default
2020-01-16T20:22:21.005Z,1579206141.005 [MissionManager](INFO): Uninitializing Mission Startup
2020-01-16T20:22:21.006Z,1579206141.006 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-01-16T20:22:21.006Z,1579206141.006 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-01-16T20:22:21.007Z,1579206141.007 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-01-16T20:22:21.007Z,1579206141.007 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-01-16T20:22:21.007Z,1579206141.007 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-01-16T20:22:21.007Z,1579206141.007 [BuoyancyServo](INFO): Powering down
2020-01-16T20:22:21.021Z,1579206141.021 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-01-16T20:22:21.021Z,1579206141.021 [ElevatorServo](INFO): Powering down
2020-01-16T20:22:21.022Z,1579206141.022 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-01-16T20:22:21.022Z,1579206141.022 [MassServo](INFO): Powering down
2020-01-16T20:22:21.023Z,1579206141.023 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-16T20:22:21.023Z,1579206141.023 [RudderServo](INFO): Powering down
2020-01-16T20:22:21.023Z,1579206141.023 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-01-16T20:22:21.024Z,1579206141.024 [ThrusterServo](INFO): Powering down
2020-01-16T20:22:21.024Z,1579206141.024 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-01-16T20:22:21.025Z,1579206141.025 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-01-16T20:22:21.025Z,1579206141.025 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-01-16T20:22:21.025Z,1579206141.025 [CBIT](DEBUG): Powering off loads.
2020-01-16T20:22:21.037Z,1579206141.037 [CBIT](DEBUG): Disabling WDT.
2020-01-16T20:22:21.049Z,1579206141.049 [CBIT](DEBUG): Opening all GF detection circuits.
2020-01-16T20:22:21.049Z,1579206141.049 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:22:21.091Z,1579206141.091 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:22:21.102Z,1579206141.102 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:22:21.142Z,1579206141.142 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:22:21.144Z,1579206141.144 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:22:21.202Z,1579206141.202 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:22:21.262Z,1579206141.262 [logger ThreadHandler](INFO): Thread cancelled.