2020-01-16T20:22:22.143Z,1579206142.143 [Supervisor](DEBUG): Initializing supervisor.
2020-01-16T20:22:22.146Z,1579206142.146 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-01-16T20:22:22.146Z,1579206142.146 [SyncHandler](INFO): Protected caller Thread ID is 1414
2020-01-16T20:22:22.147Z,1579206142.147 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-01-16T20:22:22.148Z,1579206142.148 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-01-16T20:22:22.149Z,1579206142.149 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1415
2020-01-16T20:22:22.151Z,1579206142.151 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-01-16T20:22:22.164Z,1579206142.164 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-01-16T20:22:22.165Z,1579206142.165 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-01-16T20:22:22.165Z,1579206142.165 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1416
2020-01-16T20:22:22.166Z,1579206142.166 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-01-16T20:22:22.167Z,1579206142.167 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-01-16T20:22:22.167Z,1579206142.167 [logger ThreadHandler](INFO): Protected caller Thread ID is 1417
2020-01-16T20:22:22.169Z,1579206142.169 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-01-16T20:22:22.169Z,1579206142.169 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-01-16T20:22:22.171Z,1579206142.171 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-01-16T20:22:22.599Z,1579206142.599 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-01-16T20:22:22.600Z,1579206142.600 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-01-16T20:22:22.696Z,1579206142.696 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-01-16T20:22:22.697Z,1579206142.697 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-01-16T20:22:23.016Z,1579206143.016 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-01-16T20:22:23.016Z,1579206143.016 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-01-16T20:22:23.152Z,1579206143.152 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-01-16T20:22:23.153Z,1579206143.153 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-01-16T20:22:23.342Z,1579206143.342 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-01-16T20:22:23.342Z,1579206143.342 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-01-16T20:22:23.826Z,1579206143.826 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-01-16T20:22:23.826Z,1579206143.826 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-01-16T20:22:24.280Z,1579206144.280 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-01-16T20:22:24.280Z,1579206144.280 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-01-16T20:22:24.423Z,1579206144.423 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-01-16T20:22:24.423Z,1579206144.423 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-01-16T20:22:24.611Z,1579206144.611 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-01-16T20:22:24.611Z,1579206144.611 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-01-16T20:22:24.706Z,1579206144.706 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-01-16T20:22:24.706Z,1579206144.706 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-01-16T20:22:25.002Z,1579206145.002 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-01-16T20:22:25.002Z,1579206145.002 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-01-16T20:22:25.082Z,1579206145.082 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-01-16T20:22:25.185Z,1579206145.185 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-01-16T20:22:25.185Z,1579206145.185 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-01-16T20:22:25.812Z,1579206145.812 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-01-16T20:22:25.812Z,1579206145.812 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-01-16T20:22:26.213Z,1579206146.213 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-01-16T20:22:26.215Z,1579206146.215 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-01-16T20:22:26.216Z,1579206146.216 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-01-16T20:22:26.425Z,1579206146.425 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-01-16T20:22:26.525Z,1579206146.525 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-01-16T20:22:26.623Z,1579206146.623 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-01-16T20:22:26.847Z,1579206146.847 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-01-16T20:22:26.848Z,1579206146.848 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-01-16T20:22:26.932Z,1579206146.932 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-01-16T20:22:27.024Z,1579206147.024 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-01-16T20:22:27.121Z,1579206147.121 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-01-16T20:22:27.204Z,1579206147.204 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-01-16T20:22:27.310Z,1579206147.310 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/workSite.cfg
2020-01-16T20:22:27.402Z,1579206147.402 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-01-16T20:22:27.583Z,1579206147.583 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-01-16T20:22:27.712Z,1579206147.712 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2020-01-16T20:22:27.713Z,1579206147.713 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-01-16T20:22:27.719Z,1579206147.719 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-01-16T20:22:28.016Z,1579206148.016 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-01-16T20:22:28.021Z,1579206148.021 [AHRS_M2](INFO): created writer for : platform_orientation
2020-01-16T20:22:28.023Z,1579206148.023 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-01-16T20:22:28.027Z,1579206148.027 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-01-16T20:22:28.028Z,1579206148.028 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-01-16T20:22:28.033Z,1579206148.033 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-01-16T20:22:28.033Z,1579206148.033 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-01-16T20:22:28.038Z,1579206148.038 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-01-16T20:22:28.104Z,1579206148.104 [AHRS_M2] Loaded
2020-01-16T20:22:28.105Z,1579206148.105 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-01-16T20:22:28.192Z,1579206148.192 [DataOverHttps] Loaded
2020-01-16T20:22:28.192Z,1579206148.192 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-01-16T20:22:28.193Z,1579206148.193 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407684E0
2020-01-16T20:22:28.193Z,1579206148.193 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1499
2020-01-16T20:22:28.216Z,1579206148.216 [DDM] Loaded
2020-01-16T20:22:28.216Z,1579206148.216 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2020-01-16T20:22:28.228Z,1579206148.228 [Depth_Keller] Loaded
2020-01-16T20:22:28.229Z,1579206148.229 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-01-16T20:22:28.233Z,1579206148.233 [DropWeight] Loaded
2020-01-16T20:22:28.233Z,1579206148.233 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-01-16T20:22:28.291Z,1579206148.291 [DUSBL_Hydroid] Loaded
2020-01-16T20:22:28.291Z,1579206148.291 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-01-16T20:22:28.335Z,1579206148.335 [Micromodem] Loaded
2020-01-16T20:22:28.335Z,1579206148.335 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-01-16T20:22:28.426Z,1579206148.426 [NAL9602] Loaded
2020-01-16T20:22:28.427Z,1579206148.427 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-01-16T20:22:28.461Z,1579206148.461 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
2020-01-16T20:22:28.462Z,1579206148.462 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
2020-01-16T20:22:28.462Z,1579206148.462 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
2020-01-16T20:22:28.463Z,1579206148.463 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
2020-01-16T20:22:28.463Z,1579206148.463 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
2020-01-16T20:22:28.464Z,1579206148.464 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
2020-01-16T20:22:28.464Z,1579206148.464 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
2020-01-16T20:22:28.465Z,1579206148.465 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
2020-01-16T20:22:28.465Z,1579206148.465 [Onboard] Loaded
2020-01-16T20:22:28.465Z,1579206148.465 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-01-16T20:22:28.471Z,1579206148.471 [PowerOnly] Loaded
2020-01-16T20:22:28.471Z,1579206148.471 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2020-01-16T20:22:28.477Z,1579206148.477 [Radio_Surface] Loaded
2020-01-16T20:22:28.477Z,1579206148.477 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-01-16T20:22:28.478Z,1579206148.478 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407984E0
2020-01-16T20:22:28.478Z,1579206148.478 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1500
2020-01-16T20:22:28.519Z,1579206148.519 [RDI_Pathfinder] Loaded
2020-01-16T20:22:28.519Z,1579206148.519 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-01-16T20:22:30.560Z,1579206150.560 [BPC1] Loaded
2020-01-16T20:22:30.561Z,1579206150.561 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-01-16T20:22:30.561Z,1579206150.561 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-01-16T20:22:30.561Z,1579206150.561 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-01-16T20:22:30.574Z,1579206150.574 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-01-16T20:22:30.574Z,1579206150.574 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-01-16T20:22:30.673Z,1579206150.673 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-01-16T20:22:30.673Z,1579206150.673 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-01-16T20:22:30.692Z,1579206150.692 [NavChart] Loaded
2020-01-16T20:22:30.692Z,1579206150.692 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-01-16T20:22:30.696Z,1579206150.696 [UniversalFixResidualReporter] Loaded
2020-01-16T20:22:30.696Z,1579206150.696 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-01-16T20:22:30.697Z,1579206150.697 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-01-16T20:22:30.697Z,1579206150.697 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-01-16T20:22:30.799Z,1579206150.799 [BuoyancyServo] Loaded
2020-01-16T20:22:30.800Z,1579206150.800 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-01-16T20:22:30.814Z,1579206150.814 [ElevatorServo] Loaded
2020-01-16T20:22:30.814Z,1579206150.814 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-01-16T20:22:30.828Z,1579206150.828 [MassServo] Loaded
2020-01-16T20:22:30.829Z,1579206150.829 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-01-16T20:22:30.843Z,1579206150.843 [RudderServo] Loaded
2020-01-16T20:22:30.843Z,1579206150.843 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-01-16T20:22:30.857Z,1579206150.857 [ThrusterServo] Loaded
2020-01-16T20:22:30.858Z,1579206150.858 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-01-16T20:22:30.858Z,1579206150.858 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-01-16T20:22:30.859Z,1579206150.859 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-01-16T20:22:31.104Z,1579206151.104 [CTD_NeilBrown] Loaded
2020-01-16T20:22:31.104Z,1579206151.104 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2020-01-16T20:22:31.105Z,1579206151.105 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408EC4E0
2020-01-16T20:22:31.106Z,1579206151.106 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1501
2020-01-16T20:22:31.119Z,1579206151.119 [PAR_Licor] Loaded
2020-01-16T20:22:31.120Z,1579206151.120 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-01-16T20:22:31.161Z,1579206151.161 [WetLabsSeaOWL_UV_A] Loaded
2020-01-16T20:22:31.161Z,1579206151.161 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-01-16T20:22:31.162Z,1579206151.162 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 4091C4E0
2020-01-16T20:22:31.162Z,1579206151.162 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1502
2020-01-16T20:22:31.163Z,1579206151.163 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-01-16T20:22:31.163Z,1579206151.163 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-01-16T20:22:31.477Z,1579206151.477 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-01-16T20:22:31.477Z,1579206151.477 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-01-16T20:22:31.517Z,1579206151.517 [DepthRateCalculator] Loaded
2020-01-16T20:22:31.517Z,1579206151.517 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-01-16T20:22:31.522Z,1579206151.522 [PitchRateCalculator] Loaded
2020-01-16T20:22:31.523Z,1579206151.523 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-01-16T20:22:31.534Z,1579206151.534 [SpeedCalculator] Loaded
2020-01-16T20:22:31.534Z,1579206151.534 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-01-16T20:22:31.554Z,1579206151.554 [TempGradientCalculator] Loaded
2020-01-16T20:22:31.554Z,1579206151.554 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-01-16T20:22:31.559Z,1579206151.559 [YawRateCalculator] Loaded
2020-01-16T20:22:31.560Z,1579206151.560 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-01-16T20:22:31.598Z,1579206151.598 [ElevatorOffsetCalculator] Loaded
2020-01-16T20:22:31.599Z,1579206151.599 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-01-16T20:22:31.599Z,1579206151.599 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-01-16T20:22:31.600Z,1579206151.600 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-01-16T20:22:31.729Z,1579206151.729 [SBIT](DEBUG): Construct Startup Built In Test.
2020-01-16T20:22:31.750Z,1579206151.750 [SBIT] Loaded
2020-01-16T20:22:31.750Z,1579206151.750 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-01-16T20:22:31.751Z,1579206151.751 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-01-16T20:22:31.763Z,1579206151.763 [IBIT] Loaded
2020-01-16T20:22:31.763Z,1579206151.763 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-01-16T20:22:31.766Z,1579206151.766 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-01-16T20:22:31.898Z,1579206151.898 [CBIT] Loaded
2020-01-16T20:22:31.899Z,1579206151.899 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-01-16T20:22:31.899Z,1579206151.899 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-01-16T20:22:31.899Z,1579206151.899 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-01-16T20:22:31.977Z,1579206151.977 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-01-16T20:22:31.977Z,1579206151.977 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-01-16T20:22:32.071Z,1579206152.071 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-01-16T20:22:32.071Z,1579206152.071 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-01-16T20:22:32.117Z,1579206152.117 [VerticalControl](DEBUG): Construct VerticalControl.
2020-01-16T20:22:32.196Z,1579206152.196 [VerticalControl] Loaded
2020-01-16T20:22:32.196Z,1579206152.196 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-01-16T20:22:32.197Z,1579206152.197 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-01-16T20:22:32.262Z,1579206152.262 [HorizontalControl] Loaded
2020-01-16T20:22:32.262Z,1579206152.262 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-01-16T20:22:32.263Z,1579206152.263 [SpeedControl](DEBUG): Construct SpeedControl.
2020-01-16T20:22:32.264Z,1579206152.264 [SpeedControl] Loaded
2020-01-16T20:22:32.264Z,1579206152.264 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-01-16T20:22:32.265Z,1579206152.265 [LoopControl](DEBUG): Construct LoopControl.
2020-01-16T20:22:32.266Z,1579206152.266 [LoopControl] Loaded
2020-01-16T20:22:32.266Z,1579206152.266 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-01-16T20:22:32.266Z,1579206152.266 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-01-16T20:22:32.267Z,1579206152.267 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-01-16T20:22:32.298Z,1579206152.298 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-01-16T20:22:32.301Z,1579206152.301 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-01-16T20:22:32.302Z,1579206152.302 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-01-16T20:22:32.308Z,1579206152.308 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-01-16T20:22:32.309Z,1579206152.309 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B024E0
2020-01-16T20:22:32.310Z,1579206152.310 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1503
2020-01-16T20:22:32.314Z,1579206152.314 [Supervisor](INFO): Main Thread ID is 802
2020-01-16T20:22:32.314Z,1579206152.314 [Supervisor](DEBUG): Running supervisor.
2020-01-16T20:22:32.315Z,1579206152.315 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1504
2020-01-16T20:22:32.317Z,1579206152.317 [controlThread ThreadHandler](INFO): Handler Thread ID is 1505
2020-01-16T20:22:32.318Z,1579206152.318 [controlThread](DEBUG): Initializing ControlThread
2020-01-16T20:22:32.324Z,1579206152.324 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-01-16T20:22:32.324Z,1579206152.324 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-01-16T20:22:32.329Z,1579206152.329 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-01-16T20:22:32.329Z,1579206152.329 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-01-16T20:22:32.330Z,1579206152.330 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-01-16T20:22:32.330Z,1579206152.330 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-01-16T20:22:32.330Z,1579206152.330 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-01-16T20:22:32.331Z,1579206152.331 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-01-16T20:22:32.331Z,1579206152.331 [SBIT](INFO): Initialize SBIT Component.
2020-01-16T20:22:32.332Z,1579206152.332 [SBIT](IMPORTANT): git: 2019-12-03-49-g04fffb6
2020-01-16T20:22:32.332Z,1579206152.332 [SBIT](INFO): git hash: 04fffb6753863654093fc991b35c0948c18f1aa1
2020-01-16T20:22:32.332Z,1579206152.332 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-01-16T20:22:32.333Z,1579206152.333 [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:22:32.334Z,1579206152.334 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2020-01-16T20:22:32.335Z,1579206152.335 [IBIT](INFO): Initialize IBIT Component.
2020-01-16T20:22:32.336Z,1579206152.336 [CBIT](DEBUG): Initialize CBIT Component.
2020-01-16T20:22:32.337Z,1579206152.337 [logger ThreadHandler](INFO): Handler Thread ID is 1506
2020-01-16T20:22:32.349Z,1579206152.349 [CBIT](DEBUG): Initialized mux pins.
2020-01-16T20:22:32.349Z,1579206152.349 [CBIT](DEBUG): Initializing the watchdog timer.
2020-01-16T20:22:32.357Z,1579206152.357 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1507
2020-01-16T20:22:32.358Z,1579206152.358 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-01-16T20:22:32.369Z,1579206152.369 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1508
2020-01-16T20:22:32.373Z,1579206152.373 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-01-16T20:22:32.373Z,1579206152.373 [CBIT](DEBUG): Initializing heartbeat.
2020-01-16T20:22:32.381Z,1579206152.381 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1509
2020-01-16T20:22:32.382Z,1579206152.382 [CTD_NeilBrown](INFO): Powering down
2020-01-16T20:22:32.409Z,1579206152.409 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1510
2020-01-16T20:22:32.410Z,1579206152.410 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-16T20:22:32.433Z,1579206152.433 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1511
2020-01-16T20:22:32.436Z,1579206152.436 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-01-16T20:22:32.437Z,1579206152.437 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-01-16T20:22:32.437Z,1579206152.437 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-01-16T20:22:32.437Z,1579206152.437 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-01-16T20:22:32.437Z,1579206152.437 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-01-16T20:22:32.437Z,1579206152.437 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-01-16T20:22:32.438Z,1579206152.438 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-01-16T20:22:32.438Z,1579206152.438 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-01-16T20:22:32.438Z,1579206152.438 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-01-16T20:22:32.438Z,1579206152.438 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-01-16T20:22:32.438Z,1579206152.438 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-01-16T20:22:32.438Z,1579206152.438 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-01-16T20:22:32.439Z,1579206152.439 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-01-16T20:22:32.439Z,1579206152.439 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-01-16T20:22:32.439Z,1579206152.439 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-01-16T20:22:32.439Z,1579206152.439 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-01-16T20:22:32.445Z,1579206152.445 [CBIT](DEBUG): Deactivating GF circuits.
2020-01-16T20:22:32.445Z,1579206152.445 [CBIT](DEBUG): Deactivating emergency mode.
2020-01-16T20:22:32.481Z,1579206152.481 [CBIT](DEBUG): Backplane powered.
2020-01-16T20:22:32.481Z,1579206152.481 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-01-16T20:22:32.483Z,1579206152.483 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-01-16T20:22:32.484Z,1579206152.484 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-01-16T20:22:32.484Z,1579206152.484 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-01-16T20:22:32.485Z,1579206152.485 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-01-16T20:22:32.495Z,1579206152.495 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-16T20:22:32.538Z,1579206152.538 [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:22:32.539Z,1579206152.539 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-01-16T20:22:32.602Z,1579206152.602 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-01-16T20:22:32.603Z,1579206152.603 [Default:A.Wait](DEBUG): Construct Wait.
2020-01-16T20:22:32.605Z,1579206152.605 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-16T20:22:32.681Z,1579206152.681 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-01-16T20:22:32.684Z,1579206152.684 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-01-16T20:22:32.702Z,1579206152.702 [Default:E.Execute](DEBUG): Construct Execute.
2020-01-16T20:22:32.720Z,1579206152.720 [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:22:32.725Z,1579206152.725 [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:22:32.745Z,1579206152.745 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-16T20:22:32.773Z,1579206152.773 [Radio_Surface](INFO): Powering up
2020-01-16T20:22:32.792Z,1579206152.792 [DDM](INFO): Powering up
2020-01-16T20:22:32.797Z,1579206152.797 [DDM](DEBUG): Initializing DDM.
2020-01-16T20:22:32.827Z,1579206152.827 [DUSBL_Hydroid](INFO): Powering up
2020-01-16T20:22:32.827Z,1579206152.827 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-16T20:22:33.011Z,1579206153.011 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-01-16T20:22:33.017Z,1579206153.017 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-01-16T20:22:33.018Z,1579206153.018 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-01-16T20:22:33.025Z,1579206153.025 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-01-16T20:22:33.026Z,1579206153.026 [MassServo](DEBUG): Initializing EZServoServo.
2020-01-16T20:22:33.033Z,1579206153.033 [MassServo](DEBUG): Initializing MassServo.
2020-01-16T20:22:33.034Z,1579206153.034 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-16T20:22:33.041Z,1579206153.041 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-16T20:22:33.042Z,1579206153.042 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-01-16T20:22:33.049Z,1579206153.049 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-01-16T20:22:33.154Z,1579206153.154 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-01-16T20:22:33.154Z,1579206153.154 [DropWeight] Hardware Fault, FailCount= 1
2020-01-16T20:22:33.154Z,1579206153.154 [DropWeight](ERROR): Hardware Fault
2020-01-16T20:22:33.156Z,1579206153.156 [Micromodem](INFO): Powering up
2020-01-16T20:22:33.156Z,1579206153.156 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-16T20:22:33.220Z,1579206153.220 [CommandLine](FAULT): Scheduling is paused
2020-01-16T20:22:33.221Z,1579206153.221 [CBIT](INFO): Critical error at 20200116T202233
2020-01-16T20:22:33.221Z,1579206153.221 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-01-16T20:22:33.223Z,1579206153.223 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-01-16T20:22:33.224Z,1579206153.224 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-01-16T20:22:33.945Z,1579206153.945 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-01-16T20:22:33.945Z,1579206153.945 [RudderServo](FAULT): Rudder failed to initialize
2020-01-16T20:22:33.945Z,1579206153.945 [RudderServo] Communications Fault, FailCount= 1
2020-01-16T20:22:33.945Z,1579206153.945 [RudderServo](ERROR): Communications Fault
2020-01-16T20:22:34.054Z,1579206154.054 [CBIT](INFO): Critical error at 20200116T202233
2020-01-16T20:22:34.056Z,1579206154.056 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-01-16T20:22:34.222Z,1579206154.222 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-16T20:22:34.222Z,1579206154.222 [RudderServo](INFO): Powering down
2020-01-16T20:22:34.917Z,1579206154.917 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-16T20:22:35.038Z,1579206155.038 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-16T20:22:35.042Z,1579206155.042 [CBIT](INFO): Clearing failed state for component RudderServo
2020-01-16T20:22:35.042Z,1579206155.042 [RudderServo] No Fault, FailCount= 1
2020-01-16T20:22:36.405Z,1579206156.405 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-01-16T20:22:36.797Z,1579206156.797 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-01-16T20:22:37.281Z,1579206157.281 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-01-16T20:22:37.849Z,1579206157.849 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-01-16T20:22:37.857Z,1579206157.857 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-01-16T20:22:38.281Z,1579206158.281 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-01-16T20:22:38.749Z,1579206158.749 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-01-16T20:22:38.755Z,1579206158.755 [Micromodem](INFO): Nmea in: $CATMG,2020-01-16T20:22:38.061652Z,RTC,RTC*59

2020-01-16T20:22:38.756Z,1579206158.756 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-01-16T20:22:38.061652Z,RTC,RTC*59

2020-01-16T20:22:39.015Z,1579206159.015 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-01-16T20:22:39.015Z,1579206159.015 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-01-16T20:22:39.461Z,1579206159.461 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:39.467Z,1579206159.467 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

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

2020-01-16T20:22:39.865Z,1579206159.865 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:39.871Z,1579206159.871 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

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

2020-01-16T20:22:40.269Z,1579206160.269 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:40.275Z,1579206160.275 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-01-16T20:22:40.275Z,1579206160.275 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-01-16T20:22:40.889Z,1579206160.889 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:40.895Z,1579206160.895 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-01-16T20:22:40.895Z,1579206160.895 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2020-01-16T20:22:41.301Z,1579206161.301 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:41.307Z,1579206161.307 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2020-01-16T20:22:41.308Z,1579206161.308 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-01-16T20:22:41.781Z,1579206161.781 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:41.787Z,1579206161.787 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-01-16T20:22:41.787Z,1579206161.787 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-01-16T20:22:42.197Z,1579206162.197 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:42.203Z,1579206162.203 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-01-16T20:22:42.204Z,1579206162.204 [Micromodem](INFO): Nmea out: $CCCLK,2020,01,16,20,22,43*47

2020-01-16T20:22:42.613Z,1579206162.613 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:42.619Z,1579206162.619 [Micromodem](INFO): Nmea in: $CACLK,2020,1,16,20,22,43*75

2020-01-16T20:22:42.706Z,1579206162.706 [CBIT](CRITICAL): Environmental Failure. Press:14.719749 PSI. Humidity:32%. Temp:23 C. ABORTING MISSION
2020-01-16T20:22:43.025Z,1579206163.025 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:43.037Z,1579206163.037 [Micromodem](INFO): Nmea in: $CATMS,0,2020-01-16T20:22:44Z*72

2020-01-16T20:22:43.119Z,1579206163.119 [CBIT](INFO): Critical error at 20200116T202242
2020-01-16T20:22:43.449Z,1579206163.449 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:43.456Z,1579206163.456 [Micromodem](INFO): Nmea in: $CATMG,2020-01-16T20:22:44.025974Z,USER_CMD,RTC*18

2020-01-16T20:22:43.897Z,1579206163.897 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:44.309Z,1579206164.309 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:44.753Z,1579206164.753 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:45.285Z,1579206165.285 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:45.701Z,1579206165.701 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:45.701Z,1579206165.701 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-01-16T20:22:45.701Z,1579206165.701 [AHRS_M2] Communications Fault, FailCount= 1
2020-01-16T20:22:45.701Z,1579206165.701 [AHRS_M2](ERROR): Communications Fault
2020-01-16T20:22:45.829Z,1579206165.829 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-01-16T20:22:45.834Z,1579206165.834 [AHRS_M2](INFO): Powering down
2020-01-16T20:22:46.781Z,1579206166.781 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-01-16T20:22:46.781Z,1579206166.781 [AHRS_M2] No Fault, FailCount= 1
2020-01-16T20:22:47.047Z,1579206167.047 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-16T20:22:50.589Z,1579206170.589 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:51.017Z,1579206171.017 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:51.441Z,1579206171.441 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:51.873Z,1579206171.873 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:52.412Z,1579206172.412 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:52.813Z,1579206172.813 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:52.814Z,1579206172.814 [DDM](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0
2020-01-16T20:22:52.814Z,1579206172.814 [DDM] Communications Fault, FailCount= 1
2020-01-16T20:22:52.814Z,1579206172.814 [DDM](ERROR): Communications Fault
2020-01-16T20:22:52.892Z,1579206172.892 [CBIT](ERROR): Communications Fault in component: DDM
2020-01-16T20:22:53.245Z,1579206173.245 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:53.245Z,1579206173.245 [DDM](INFO): Powering down
2020-01-16T20:22:53.339Z,1579206173.339 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0
2020-01-16T20:22:53.339Z,1579206173.339 [DUSBL_Hydroid] Communications Fault, FailCount= 1
2020-01-16T20:22:53.339Z,1579206173.339 [DUSBL_Hydroid](ERROR): Communications Fault
2020-01-16T20:22:53.401Z,1579206173.401 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2020-01-16T20:22:53.737Z,1579206173.737 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:53.742Z,1579206173.742 [DUSBL_Hydroid](INFO): Powering down
2020-01-16T20:22:54.237Z,1579206174.237 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:54.303Z,1579206174.303 [CBIT](INFO): Clearing failed state for component DDM
2020-01-16T20:22:54.303Z,1579206174.303 [DDM] No Fault, FailCount= 1
2020-01-16T20:22:54.649Z,1579206174.649 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:54.709Z,1579206174.709 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2020-01-16T20:22:54.709Z,1579206174.709 [DUSBL_Hydroid] No Fault, FailCount= 1
2020-01-16T20:22:55.057Z,1579206175.057 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:55.457Z,1579206175.457 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:55.487Z,1579206175.487 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-01-16T20:22:55.487Z,1579206175.487 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-01-16T20:22:55.487Z,1579206175.487 [RDI_Pathfinder](ERROR): Communications Fault
2020-01-16T20:22:55.543Z,1579206175.543 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-01-16T20:22:55.869Z,1579206175.869 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:55.973Z,1579206175.973 [RDI_Pathfinder](INFO): Powering down
2020-01-16T20:22:56.357Z,1579206176.357 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:56.357Z,1579206176.357 [DDM](INFO): Powering up
2020-01-16T20:22:56.357Z,1579206176.357 [DDM](DEBUG): Initializing DDM.
2020-01-16T20:22:56.761Z,1579206176.761 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:56.829Z,1579206176.829 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-01-16T20:22:56.829Z,1579206176.829 [RDI_Pathfinder] No Fault, FailCount= 1
2020-01-16T20:22:57.169Z,1579206177.169 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:57.174Z,1579206177.174 [DUSBL_Hydroid](INFO): Powering up
2020-01-16T20:22:57.174Z,1579206177.174 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-16T20:22:57.601Z,1579206177.601 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:58.009Z,1579206178.009 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:58.409Z,1579206178.409 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:58.821Z,1579206178.821 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:58.827Z,1579206178.827 [NAL9602](INFO): Powering up NAL9602
2020-01-16T20:22:59.233Z,1579206179.233 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:22:59.633Z,1579206179.633 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:00.033Z,1579206180.033 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:00.033Z,1579206180.033 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-01-16T20:23:00.033Z,1579206180.033 [AHRS_M2] Communications Fault, FailCount= 2
2020-01-16T20:23:00.033Z,1579206180.033 [AHRS_M2](ERROR): Communications Fault
2020-01-16T20:23:00.117Z,1579206180.117 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-01-16T20:23:00.126Z,1579206180.126 [AHRS_M2](INFO): Powering down
2020-01-16T20:23:00.992Z,1579206180.992 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-01-16T20:23:00.992Z,1579206180.992 [AHRS_M2] No Fault, FailCount= 2
2020-01-16T20:23:01.332Z,1579206181.332 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-16T20:23:03.024Z,1579206183.024 [CBIT](FAULT): WATER ALARM STERN.
2020-01-16T20:23:03.024Z,1579206183.024 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 1
2020-01-16T20:23:03.423Z,1579206183.423 [CBIT](FAULT): WATER ALARM STERN.
2020-01-16T20:23:03.423Z,1579206183.423 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 2
2020-01-16T20:23:03.808Z,1579206183.808 [CBIT](FAULT): WATER ALARM STERN.
2020-01-16T20:23:03.813Z,1579206183.813 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 3
2020-01-16T20:23:04.226Z,1579206184.226 [CBIT](FAULT): WATER ALARM STERN.
2020-01-16T20:23:04.241Z,1579206184.241 [CBIT](CRITICAL): WATER DETECTED IN PRESSURE HULL. BURNWIRE ACTIVATED
2020-01-16T20:23:04.241Z,1579206184.241 [CBIT](IMPORTANT): No ESP to power off.
2020-01-16T20:23:04.889Z,1579206184.889 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:04.968Z,1579206184.968 [CBIT](INFO): Critical error at 20200116T202304
2020-01-16T20:23:05.313Z,1579206185.313 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:05.709Z,1579206185.709 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:06.113Z,1579206186.113 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:06.509Z,1579206186.509 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:06.905Z,1579206186.905 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:07.317Z,1579206187.317 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:07.737Z,1579206187.737 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:08.145Z,1579206188.145 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:08.545Z,1579206188.545 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:08.965Z,1579206188.965 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:09.365Z,1579206189.365 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:09.781Z,1579206189.781 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:09.788Z,1579206189.788 [NAL9602](INFO): NAL9602 initialized
2020-01-16T20:23:10.193Z,1579206190.193 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:10.585Z,1579206190.585 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:11.029Z,1579206191.029 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:11.445Z,1579206191.445 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:11.861Z,1579206191.861 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:11.944Z,1579206191.944 [SBIT](IMPORTANT): Beginning Startup BIT
2020-01-16T20:23:11.952Z,1579206191.952 [CBIT](IMPORTANT): Beginning ground fault scan
2020-01-16T20:23:12.325Z,1579206192.325 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:12.761Z,1579206192.761 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:13.209Z,1579206193.209 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:13.701Z,1579206193.701 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:14.321Z,1579206194.321 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:14.321Z,1579206194.321 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-01-16T20:23:14.321Z,1579206194.321 [AHRS_M2] Communications Fault, FailCount= 3
2020-01-16T20:23:14.321Z,1579206194.321 [AHRS_M2](ERROR): Communications Fault
2020-01-16T20:23:14.874Z,1579206194.874 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-01-16T20:23:14.891Z,1579206194.891 [AHRS_M2](INFO): Powering down
2020-01-16T20:23:15.957Z,1579206195.957 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-01-16T20:23:15.957Z,1579206195.957 [AHRS_M2] No Fault, FailCount= 3
2020-01-16T20:23:16.101Z,1579206196.101 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-16T20:23:16.505Z,1579206196.505 [DDM](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0
2020-01-16T20:23:16.505Z,1579206196.505 [DDM] Communications Fault, FailCount= 2
2020-01-16T20:23:16.505Z,1579206196.505 [DDM](ERROR): Communications Fault
2020-01-16T20:23:16.689Z,1579206196.689 [CBIT](ERROR): Communications Fault in component: DDM
2020-01-16T20:23:16.909Z,1579206196.909 [DDM](INFO): Powering down
2020-01-16T20:23:17.318Z,1579206197.318 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0
2020-01-16T20:23:17.318Z,1579206197.318 [DUSBL_Hydroid] Communications Fault, FailCount= 2
2020-01-16T20:23:17.318Z,1579206197.318 [DUSBL_Hydroid](ERROR): Communications Fault
2020-01-16T20:23:17.422Z,1579206197.422 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2020-01-16T20:23:17.722Z,1579206197.722 [DUSBL_Hydroid](INFO): Powering down
2020-01-16T20:23:17.906Z,1579206197.906 [CBIT](INFO): Clearing failed state for component DDM
2020-01-16T20:23:17.906Z,1579206197.906 [DDM] No Fault, FailCount= 2
2020-01-16T20:23:18.634Z,1579206198.634 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2020-01-16T20:23:18.634Z,1579206198.634 [DUSBL_Hydroid] No Fault, FailCount= 2
2020-01-16T20:23:19.657Z,1579206199.657 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:19.691Z,1579206199.691 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-01-16T20:23:19.691Z,1579206199.691 [RDI_Pathfinder] Communications Fault, FailCount= 2
2020-01-16T20:23:19.691Z,1579206199.691 [RDI_Pathfinder](ERROR): Communications Fault
2020-01-16T20:23:19.786Z,1579206199.786 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-01-16T20:23:20.117Z,1579206200.117 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:20.117Z,1579206200.117 [DDM](INFO): Powering up
2020-01-16T20:23:20.117Z,1579206200.117 [DDM](DEBUG): Initializing DDM.
2020-01-16T20:23:20.241Z,1579206200.241 [RDI_Pathfinder](INFO): Powering down
2020-01-16T20:23:20.737Z,1579206200.737 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:20.742Z,1579206200.742 [DUSBL_Hydroid](INFO): Powering up
2020-01-16T20:23:20.742Z,1579206200.742 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-16T20:23:21.181Z,1579206201.181 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:21.270Z,1579206201.270 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-01-16T20:23:21.270Z,1579206201.270 [RDI_Pathfinder] No Fault, FailCount= 2
2020-01-16T20:23:21.593Z,1579206201.593 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:22.129Z,1579206202.129 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:22.557Z,1579206202.557 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:22.985Z,1579206202.985 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:23.505Z,1579206203.505 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:23.633Z,1579206203.633 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.010852
CHAN A1 (24V): -0.028611
CHAN A2 (12V): -0.007354
CHAN A3 (5V): -0.001731
CHAN B0 (3.3V): 0.000550
CHAN B1 (3.15aV): -0.000069
CHAN B2 (3.15bV): 0.000014
CHAN B3 (GND): 0.002057
OPEN: -0.000123
Full Scale Calc: 4.765 mA, -1.589 mA
2020-01-16T20:23:23.957Z,1579206203.957 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:24.469Z,1579206204.469 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:24.893Z,1579206204.893 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:25.293Z,1579206205.293 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:25.705Z,1579206205.705 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:26.113Z,1579206206.113 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:26.521Z,1579206206.521 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:26.941Z,1579206206.941 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:27.829Z,1579206207.829 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:28.309Z,1579206208.309 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:28.809Z,1579206208.809 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:29.289Z,1579206209.289 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:29.289Z,1579206209.289 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-01-16T20:23:29.289Z,1579206209.289 [AHRS_M2] Communications Fault, FailCount= 4
2020-01-16T20:23:29.289Z,1579206209.289 [AHRS_M2](ERROR): Communications Fault
2020-01-16T20:23:29.449Z,1579206209.449 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-01-16T20:23:29.455Z,1579206209.455 [AHRS_M2](INFO): Powering down
2020-01-16T20:23:30.389Z,1579206210.389 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-01-16T20:23:30.389Z,1579206210.389 [AHRS_M2] No Fault, FailCount= 4
2020-01-16T20:23:30.665Z,1579206210.665 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-16T20:23:34.221Z,1579206214.221 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:34.689Z,1579206214.689 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:35.133Z,1579206215.133 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:35.577Z,1579206215.577 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:35.993Z,1579206215.993 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:36.409Z,1579206216.409 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:36.837Z,1579206216.837 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:37.257Z,1579206217.257 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:37.681Z,1579206217.681 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:38.105Z,1579206218.105 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:38.507Z,1579206218.507 [CommandLine](IMPORTANT): got command burn off
2020-01-16T20:23:38.507Z,1579206218.507 [CommandLine](IMPORTANT): Deactivating dropweight wire
2020-01-16T20:23:38.517Z,1579206218.517 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:38.961Z,1579206218.961 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:39.401Z,1579206219.401 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:39.849Z,1579206219.849 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:40.285Z,1579206220.285 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:40.286Z,1579206220.286 [DDM](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0
2020-01-16T20:23:40.286Z,1579206220.286 [DDM] Communications Fault, FailCount= 3
2020-01-16T20:23:40.286Z,1579206220.286 [DDM](ERROR): Communications Fault
2020-01-16T20:23:40.409Z,1579206220.409 [CBIT](ERROR): Communications Fault in component: DDM
2020-01-16T20:23:40.410Z,1579206220.410 [CBIT](CRITICAL): Communications Fault in component: DDM
2020-01-16T20:23:40.741Z,1579206220.741 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:40.741Z,1579206220.741 [DDM](INFO): Powering down
2020-01-16T20:23:40.815Z,1579206220.815 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0
2020-01-16T20:23:40.815Z,1579206220.815 [DUSBL_Hydroid] Communications Fault, FailCount= 3
2020-01-16T20:23:40.815Z,1579206220.815 [DUSBL_Hydroid](ERROR): Communications Fault
2020-01-16T20:23:40.892Z,1579206220.892 [CBIT](INFO): Critical error at 20200116T202340
2020-01-16T20:23:40.894Z,1579206220.894 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2020-01-16T20:23:41.229Z,1579206221.229 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:41.242Z,1579206221.242 [DUSBL_Hydroid](INFO): Powering down
2020-01-16T20:23:41.729Z,1579206221.729 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:42.149Z,1579206222.149 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:42.313Z,1579206222.313 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2020-01-16T20:23:42.314Z,1579206222.314 [DUSBL_Hydroid] No Fault, FailCount= 3
2020-01-16T20:23:42.645Z,1579206222.645 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:43.045Z,1579206223.045 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:43.449Z,1579206223.449 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:43.857Z,1579206223.857 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2020-01-16T20:23:43.857Z,1579206223.857 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-01-16T20:23:43.857Z,1579206223.857 [AHRS_M2] Communications Fault, FailCount= 5
2020-01-16T20:23:43.857Z,1579206223.857 [AHRS_M2](ERROR): Communications Fault
2020-01-16T20:23:43.945Z,1579206223.945 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-01-16T20:23:43.945Z,1579206223.945 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2020-01-16T20:23:43.955Z,1579206223.955 [AHRS_M2](INFO): Powering down
2020-01-16T20:23:44.084Z,1579206224.084 [CBIT](INFO): Critical error at 20200116T202343
2020-01-16T20:23:44.359Z,1579206224.359 [DUSBL_Hydroid](INFO): Powering up
2020-01-16T20:23:44.360Z,1579206224.360 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-16T20:23:44.400Z,1579206224.400 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2020-01-16T20:23:44.400Z,1579206224.400 [RDI_Pathfinder] Communications Fault, FailCount= 3
2020-01-16T20:23:44.400Z,1579206224.400 [RDI_Pathfinder](ERROR): Communications Fault
2020-01-16T20:23:44.459Z,1579206224.459 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-01-16T20:23:44.459Z,1579206224.459 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2020-01-16T20:23:44.861Z,1579206224.861 [RDI_Pathfinder](INFO): Powering down
2020-01-16T20:23:44.899Z,1579206224.899 [CBIT](INFO): Critical error at 20200116T202344
2020-01-16T20:23:45.354Z,1579206225.354 [CommandLine](IMPORTANT): got command restart application
2020-01-16T20:23:46.361Z,1579206226.361 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T20:23:46.361Z,1579206226.361 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:23:46.445Z,1579206226.445 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-01-16T20:23:46.445Z,1579206226.445 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:23:46.446Z,1579206226.446 [CommandLine](INFO): Join timeout helper Thread ID is 1539
2020-01-16T20:23:46.453Z,1579206226.453 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-01-16T20:23:46.453Z,1579206226.453 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:23:46.453Z,1579206226.453 [NavChartDb](INFO): Join timeout helper Thread ID is 1540
2020-01-16T20:23:46.653Z,1579206226.653 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T20:23:46.653Z,1579206226.653 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:23:46.661Z,1579206226.661 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-01-16T20:23:46.661Z,1579206226.661 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:23:46.661Z,1579206226.661 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1541
2020-01-16T20:23:46.782Z,1579206226.782 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T20:23:46.782Z,1579206226.782 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-16T20:23:46.783Z,1579206226.783 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:23:46.783Z,1579206226.783 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2020-01-16T20:23:46.783Z,1579206226.783 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:23:46.784Z,1579206226.784 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1542
2020-01-16T20:23:46.913Z,1579206226.913 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T20:23:46.913Z,1579206226.913 [CTD_NeilBrown](INFO): Powering down
2020-01-16T20:23:46.925Z,1579206226.925 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:23:46.941Z,1579206226.941 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-01-16T20:23:46.941Z,1579206226.941 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:23:46.941Z,1579206226.941 [Radio_Surface](INFO): Join timeout helper Thread ID is 1543
2020-01-16T20:23:47.133Z,1579206227.133 [Radio_Surface](INFO): Powering down
2020-01-16T20:23:47.134Z,1579206227.134 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T20:23:47.134Z,1579206227.134 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:23:47.146Z,1579206227.146 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-01-16T20:23:47.146Z,1579206227.146 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:23:47.146Z,1579206227.146 [DataOverHttps](INFO): Join timeout helper Thread ID is 1544
2020-01-16T20:23:47.669Z,1579206227.669 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T20:23:47.669Z,1579206227.669 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:23:47.674Z,1579206227.674 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-01-16T20:23:47.674Z,1579206227.674 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:23:47.674Z,1579206227.674 [logger](INFO): Join timeout helper Thread ID is 1545
2020-01-16T20:23:47.693Z,1579206227.693 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T20:23:47.693Z,1579206227.693 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:23:47.713Z,1579206227.713 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-01-16T20:23:47.714Z,1579206227.714 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:23:47.714Z,1579206227.714 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-01-16T20:23:47.714Z,1579206227.714 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:23:47.714Z,1579206227.714 [controlThread](INFO): Join timeout helper Thread ID is 1546
2020-01-16T20:23:47.985Z,1579206227.985 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-16T20:23:47.985Z,1579206227.985 [controlThread](DEBUG): Uninitializing ControlThread
2020-01-16T20:23:47.985Z,1579206227.985 [AHRS_M2](INFO): Powering down
2020-01-16T20:23:47.986Z,1579206227.986 [DDM](INFO): Powering down
2020-01-16T20:23:47.987Z,1579206227.987 [DUSBL_Hydroid](INFO): Powering down
2020-01-16T20:23:48.057Z,1579206228.057 [Micromodem](INFO): Powering down
2020-01-16T20:23:48.153Z,1579206228.153 [NAL9602](INFO): Powering down
2020-01-16T20:23:48.155Z,1579206228.155 [RDI_Pathfinder](INFO): Powering down
2020-01-16T20:23:48.156Z,1579206228.156 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-01-16T20:23:48.157Z,1579206228.157 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-01-16T20:23:48.158Z,1579206228.158 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-01-16T20:23:48.158Z,1579206228.158 [MissionManager](INFO): Uninitializing Mission Default
2020-01-16T20:23:48.161Z,1579206228.161 [MissionManager](INFO): Uninitializing Mission Startup
2020-01-16T20:23:48.162Z,1579206228.162 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-01-16T20:23:48.162Z,1579206228.162 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-01-16T20:23:48.162Z,1579206228.162 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-01-16T20:23:48.163Z,1579206228.163 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-01-16T20:23:48.163Z,1579206228.163 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-01-16T20:23:48.163Z,1579206228.163 [BuoyancyServo](INFO): Powering down
2020-01-16T20:23:48.177Z,1579206228.177 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-01-16T20:23:48.177Z,1579206228.177 [ElevatorServo](INFO): Powering down
2020-01-16T20:23:48.178Z,1579206228.178 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-01-16T20:23:48.178Z,1579206228.178 [MassServo](INFO): Powering down
2020-01-16T20:23:48.179Z,1579206228.179 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-16T20:23:48.179Z,1579206228.179 [RudderServo](INFO): Powering down
2020-01-16T20:23:48.179Z,1579206228.179 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-01-16T20:23:48.180Z,1579206228.180 [ThrusterServo](INFO): Powering down
2020-01-16T20:23:48.180Z,1579206228.180 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-01-16T20:23:48.181Z,1579206228.181 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-01-16T20:23:48.181Z,1579206228.181 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-01-16T20:23:48.181Z,1579206228.181 [CBIT](DEBUG): Powering off loads.
2020-01-16T20:23:48.193Z,1579206228.193 [CBIT](DEBUG): Disabling WDT.
2020-01-16T20:23:48.205Z,1579206228.205 [CBIT](DEBUG): Opening all GF detection circuits.
2020-01-16T20:23:48.206Z,1579206228.206 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:23:48.250Z,1579206228.250 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:23:48.260Z,1579206228.260 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:23:48.299Z,1579206228.299 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:23:48.302Z,1579206228.302 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:23:48.359Z,1579206228.359 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-16T20:23:48.418Z,1579206228.418 [logger ThreadHandler](INFO): Thread cancelled.