2019-03-28T20:28:56.314Z,1553804936.314 [Supervisor](DEBUG): Initializing supervisor.
2019-03-28T20:28:56.317Z,1553804936.317 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-03-28T20:28:56.318Z,1553804936.318 [SyncHandler](INFO): Protected caller Thread ID is 3453
2019-03-28T20:28:56.318Z,1553804936.318 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-03-28T20:28:56.319Z,1553804936.319 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-03-28T20:28:56.320Z,1553804936.320 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3454
2019-03-28T20:28:56.323Z,1553804936.323 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-03-28T20:28:56.336Z,1553804936.336 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-03-28T20:28:56.337Z,1553804936.337 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-03-28T20:28:56.337Z,1553804936.337 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3455
2019-03-28T20:28:56.338Z,1553804936.338 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-03-28T20:28:56.339Z,1553804936.339 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-03-28T20:28:56.340Z,1553804936.340 [logger ThreadHandler](INFO): Protected caller Thread ID is 3456
2019-03-28T20:28:56.342Z,1553804936.342 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-03-28T20:28:56.342Z,1553804936.342 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-03-28T20:28:56.344Z,1553804936.344 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-03-28T20:28:56.765Z,1553804936.765 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-03-28T20:28:56.766Z,1553804936.766 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-03-28T20:28:56.862Z,1553804936.862 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-03-28T20:28:56.862Z,1553804936.862 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-03-28T20:28:57.187Z,1553804937.187 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-03-28T20:28:57.188Z,1553804937.188 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-03-28T20:28:57.328Z,1553804937.328 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-03-28T20:28:57.329Z,1553804937.329 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-03-28T20:28:57.519Z,1553804937.519 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-03-28T20:28:57.520Z,1553804937.520 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-03-28T20:28:58.057Z,1553804938.057 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-03-28T20:28:58.057Z,1553804938.057 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-03-28T20:28:58.268Z,1553804938.268 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-03-28T20:28:58.269Z,1553804938.269 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-03-28T20:28:58.412Z,1553804938.412 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-03-28T20:28:58.413Z,1553804938.413 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-03-28T20:28:58.606Z,1553804938.606 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-03-28T20:28:58.607Z,1553804938.607 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-03-28T20:28:58.701Z,1553804938.701 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-03-28T20:28:58.701Z,1553804938.701 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-03-28T20:28:59.019Z,1553804939.019 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-03-28T20:28:59.020Z,1553804939.020 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-03-28T20:28:59.099Z,1553804939.099 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-03-28T20:28:59.201Z,1553804939.201 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-03-28T20:28:59.201Z,1553804939.201 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-03-28T20:28:59.699Z,1553804939.699 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-03-28T20:28:59.699Z,1553804939.699 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-03-28T20:29:00.091Z,1553804940.091 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-03-28T20:29:00.093Z,1553804940.093 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-03-28T20:29:00.094Z,1553804940.094 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-03-28T20:29:00.300Z,1553804940.300 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-03-28T20:29:00.400Z,1553804940.400 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-03-28T20:29:00.498Z,1553804940.498 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-03-28T20:29:00.726Z,1553804940.726 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-03-28T20:29:00.727Z,1553804940.727 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-03-28T20:29:00.810Z,1553804940.810 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-03-28T20:29:00.902Z,1553804940.902 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-03-28T20:29:00.998Z,1553804940.998 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-03-28T20:29:01.099Z,1553804941.099 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-03-28T20:29:01.323Z,1553804941.323 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-03-28T20:29:01.578Z,1553804941.578 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-03-28T20:29:02.056Z,1553804942.056 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2019-03-28T20:29:02.057Z,1553804942.057 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2019-03-28T20:29:02.058Z,1553804942.058 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-03-28T20:29:02.424Z,1553804942.424 [AHRS_M2] Loaded
2019-03-28T20:29:02.425Z,1553804942.425 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-03-28T20:29:02.499Z,1553804942.499 [DataOverHttps] Loaded
2019-03-28T20:29:02.500Z,1553804942.500 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-03-28T20:29:02.501Z,1553804942.501 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4075B4E0
2019-03-28T20:29:02.501Z,1553804942.501 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3536
2019-03-28T20:29:02.515Z,1553804942.515 [Depth_Keller] Loaded
2019-03-28T20:29:02.515Z,1553804942.515 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-03-28T20:29:02.520Z,1553804942.520 [DropWeight] Loaded
2019-03-28T20:29:02.520Z,1553804942.520 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-03-28T20:29:02.572Z,1553804942.572 [DUSBL_Hydroid] Loaded
2019-03-28T20:29:02.573Z,1553804942.573 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-03-28T20:29:02.612Z,1553804942.612 [Micromodem] Loaded
2019-03-28T20:29:02.613Z,1553804942.613 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-03-28T20:29:02.710Z,1553804942.710 [NAL9602] Loaded
2019-03-28T20:29:02.711Z,1553804942.711 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-03-28T20:29:02.726Z,1553804942.726 [Onboard] Loaded
2019-03-28T20:29:02.727Z,1553804942.727 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-03-28T20:29:02.732Z,1553804942.732 [PowerOnly] Loaded
2019-03-28T20:29:02.733Z,1553804942.733 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2019-03-28T20:29:02.739Z,1553804942.739 [Radio_Surface] Loaded
2019-03-28T20:29:02.739Z,1553804942.739 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-03-28T20:29:02.740Z,1553804942.740 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4078B4E0
2019-03-28T20:29:02.741Z,1553804942.741 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3537
2019-03-28T20:29:02.800Z,1553804942.800 [RDI_Pathfinder] Loaded
2019-03-28T20:29:02.800Z,1553804942.800 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-03-28T20:29:04.290Z,1553804944.290 [BPC1] Loaded
2019-03-28T20:29:04.290Z,1553804944.290 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-03-28T20:29:04.291Z,1553804944.291 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-03-28T20:29:04.291Z,1553804944.291 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-03-28T20:29:04.305Z,1553804944.305 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-03-28T20:29:04.306Z,1553804944.306 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-03-28T20:29:04.413Z,1553804944.413 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-03-28T20:29:04.414Z,1553804944.414 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-03-28T20:29:04.472Z,1553804944.472 [DeadReckonUsingSpeedCalculator] Loaded
2019-03-28T20:29:04.473Z,1553804944.473 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-03-28T20:29:04.532Z,1553804944.532 [DeadReckonWithRespectToSeafloor] Loaded
2019-03-28T20:29:04.532Z,1553804944.532 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-03-28T20:29:04.552Z,1553804944.552 [NavChart] Loaded
2019-03-28T20:29:04.553Z,1553804944.553 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-03-28T20:29:04.557Z,1553804944.557 [UniversalFixResidualReporter] Loaded
2019-03-28T20:29:04.557Z,1553804944.557 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-03-28T20:29:04.557Z,1553804944.557 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-03-28T20:29:04.558Z,1553804944.558 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-03-28T20:29:04.662Z,1553804944.662 [BuoyancyServo] Loaded
2019-03-28T20:29:04.662Z,1553804944.662 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-03-28T20:29:04.677Z,1553804944.677 [ElevatorServo] Loaded
2019-03-28T20:29:04.677Z,1553804944.677 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-03-28T20:29:04.692Z,1553804944.692 [MassServo] Loaded
2019-03-28T20:29:04.692Z,1553804944.692 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-03-28T20:29:04.708Z,1553804944.708 [RudderServo] Loaded
2019-03-28T20:29:04.708Z,1553804944.708 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-03-28T20:29:04.723Z,1553804944.723 [ThrusterServo] Loaded
2019-03-28T20:29:04.723Z,1553804944.723 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-03-28T20:29:04.723Z,1553804944.723 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-03-28T20:29:04.724Z,1553804944.724 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-03-28T20:29:04.983Z,1553804944.983 [CTD_NeilBrown] Loaded
2019-03-28T20:29:04.983Z,1553804944.983 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-03-28T20:29:04.984Z,1553804944.984 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408D04E0
2019-03-28T20:29:04.985Z,1553804944.985 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 3538
2019-03-28T20:29:05.029Z,1553804945.029 [WetLabsSeaOWL_UV_A] Loaded
2019-03-28T20:29:05.029Z,1553804945.029 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-03-28T20:29:05.030Z,1553804945.030 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409004E0
2019-03-28T20:29:05.031Z,1553804945.031 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 3539
2019-03-28T20:29:05.031Z,1553804945.031 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-03-28T20:29:05.032Z,1553804945.032 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-03-28T20:29:05.312Z,1553804945.312 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-03-28T20:29:05.312Z,1553804945.312 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-03-28T20:29:05.355Z,1553804945.355 [DepthRateCalculator] Loaded
2019-03-28T20:29:05.355Z,1553804945.355 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-03-28T20:29:05.361Z,1553804945.361 [PitchRateCalculator] Loaded
2019-03-28T20:29:05.361Z,1553804945.361 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-03-28T20:29:05.373Z,1553804945.373 [SpeedCalculator] Loaded
2019-03-28T20:29:05.374Z,1553804945.374 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-03-28T20:29:05.394Z,1553804945.394 [TempGradientCalculator] Loaded
2019-03-28T20:29:05.395Z,1553804945.395 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-03-28T20:29:05.400Z,1553804945.400 [YawRateCalculator] Loaded
2019-03-28T20:29:05.400Z,1553804945.400 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-03-28T20:29:05.441Z,1553804945.441 [ElevatorOffsetCalculator] Loaded
2019-03-28T20:29:05.442Z,1553804945.442 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-03-28T20:29:05.442Z,1553804945.442 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-03-28T20:29:05.443Z,1553804945.443 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-03-28T20:29:05.579Z,1553804945.579 [SBIT](DEBUG): Construct Startup Built In Test.
2019-03-28T20:29:05.601Z,1553804945.601 [SBIT] Loaded
2019-03-28T20:29:05.601Z,1553804945.601 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-03-28T20:29:05.602Z,1553804945.602 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-03-28T20:29:05.614Z,1553804945.614 [IBIT] Loaded
2019-03-28T20:29:05.614Z,1553804945.614 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-03-28T20:29:05.617Z,1553804945.617 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-03-28T20:29:05.756Z,1553804945.756 [CBIT] Loaded
2019-03-28T20:29:05.756Z,1553804945.756 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-03-28T20:29:05.757Z,1553804945.757 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-03-28T20:29:05.757Z,1553804945.757 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-03-28T20:29:05.832Z,1553804945.832 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-03-28T20:29:05.833Z,1553804945.833 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-03-28T20:29:05.934Z,1553804945.934 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-03-28T20:29:05.935Z,1553804945.935 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-03-28T20:29:06.004Z,1553804946.004 [VerticalControl](DEBUG): Construct VerticalControl.
2019-03-28T20:29:06.087Z,1553804946.087 [VerticalControl] Loaded
2019-03-28T20:29:06.088Z,1553804946.088 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-03-28T20:29:06.088Z,1553804946.088 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-03-28T20:29:06.145Z,1553804946.145 [HorizontalControl] Loaded
2019-03-28T20:29:06.145Z,1553804946.145 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-03-28T20:29:06.146Z,1553804946.146 [SpeedControl](DEBUG): Construct SpeedControl.
2019-03-28T20:29:06.148Z,1553804946.148 [SpeedControl] Loaded
2019-03-28T20:29:06.148Z,1553804946.148 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-03-28T20:29:06.149Z,1553804946.149 [LoopControl](DEBUG): Construct LoopControl.
2019-03-28T20:29:06.149Z,1553804946.149 [LoopControl] Loaded
2019-03-28T20:29:06.150Z,1553804946.150 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-03-28T20:29:06.150Z,1553804946.150 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-03-28T20:29:06.150Z,1553804946.150 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-03-28T20:29:06.234Z,1553804946.234 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-03-28T20:29:06.237Z,1553804946.237 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-03-28T20:29:06.238Z,1553804946.238 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-03-28T20:29:06.245Z,1553804946.245 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-03-28T20:29:06.246Z,1553804946.246 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AD04E0
2019-03-28T20:29:06.247Z,1553804946.247 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3540
2019-03-28T20:29:06.252Z,1553804946.252 [Supervisor](INFO): Main Thread ID is 3452
2019-03-28T20:29:06.252Z,1553804946.252 [Supervisor](DEBUG): Running supervisor.
2019-03-28T20:29:06.252Z,1553804946.252 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3541
2019-03-28T20:29:06.255Z,1553804946.255 [controlThread ThreadHandler](INFO): Handler Thread ID is 3542
2019-03-28T20:29:06.255Z,1553804946.255 [controlThread](DEBUG): Initializing ControlThread
2019-03-28T20:29:06.261Z,1553804946.261 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-28T20:29:06.262Z,1553804946.262 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-03-28T20:29:06.262Z,1553804946.262 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-03-28T20:29:06.263Z,1553804946.263 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-03-28T20:29:06.264Z,1553804946.264 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-03-28T20:29:06.264Z,1553804946.264 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-03-28T20:29:06.264Z,1553804946.264 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-03-28T20:29:06.265Z,1553804946.265 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-03-28T20:29:06.265Z,1553804946.265 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-03-28T20:29:06.266Z,1553804946.266 [SBIT](INFO): Initialize SBIT Component.
2019-03-28T20:29:06.266Z,1553804946.266 [SBIT](IMPORTANT): git: 2019-03-18-30-g7da7159
2019-03-28T20:29:06.266Z,1553804946.266 [SBIT](INFO): git hash: 7da7159d5431eac8e9f43cbd3a8b52ad9cf33c07
2019-03-28T20:29:06.276Z,1553804946.276 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-03-28T20:29:06.279Z,1553804946.279 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2019-03-28T20:29:06.280Z,1553804946.280 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-03-28T20:29:06.280Z,1553804946.280 [IBIT](INFO): Initialize IBIT Component.
2019-03-28T20:29:06.281Z,1553804946.281 [CBIT](DEBUG): Initialize CBIT Component.
2019-03-28T20:29:06.285Z,1553804946.285 [logger ThreadHandler](INFO): Handler Thread ID is 3543
2019-03-28T20:29:06.294Z,1553804946.294 [CBIT](DEBUG): Initialized mux pins.
2019-03-28T20:29:06.294Z,1553804946.294 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2019-03-28T20:29:06.294Z,1553804946.294 [CBIT](DEBUG): Initializing the watchdog timer.
2019-03-28T20:29:06.302Z,1553804946.302 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3544
2019-03-28T20:29:06.303Z,1553804946.303 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-03-28T20:29:06.314Z,1553804946.314 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3545
2019-03-28T20:29:06.318Z,1553804946.318 [CBIT](CRITICAL): Watchdog Timer failed to initialize.
2019-03-28T20:29:06.318Z,1553804946.318 [CBIT] Hardware Fault, FailCount= 1
2019-03-28T20:29:06.318Z,1553804946.318 [CBIT](ERROR): Hardware Fault
2019-03-28T20:29:06.318Z,1553804946.318 [CBIT](DEBUG): Initializing heartbeat.
2019-03-28T20:29:06.330Z,1553804946.330 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 3546
2019-03-28T20:29:06.331Z,1553804946.331 [CTD_NeilBrown](INFO): Powering down
2019-03-28T20:29:06.366Z,1553804946.366 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 3547
2019-03-28T20:29:06.367Z,1553804946.367 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-03-28T20:29:06.390Z,1553804946.390 [CBIT](DEBUG): Deactivating GF circuits.
2019-03-28T20:29:06.390Z,1553804946.390 [CBIT](DEBUG): Deactivating emergency mode.
2019-03-28T20:29:06.398Z,1553804946.398 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3548
2019-03-28T20:29:06.402Z,1553804946.402 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-03-28T20:29:06.402Z,1553804946.402 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-03-28T20:29:06.402Z,1553804946.402 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-03-28T20:29:06.403Z,1553804946.403 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-03-28T20:29:06.403Z,1553804946.403 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-03-28T20:29:06.403Z,1553804946.403 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-03-28T20:29:06.403Z,1553804946.403 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-03-28T20:29:06.403Z,1553804946.403 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-03-28T20:29:06.403Z,1553804946.403 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-03-28T20:29:06.404Z,1553804946.404 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-03-28T20:29:06.404Z,1553804946.404 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-03-28T20:29:06.404Z,1553804946.404 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-03-28T20:29:06.404Z,1553804946.404 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-03-28T20:29:06.404Z,1553804946.404 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-03-28T20:29:06.405Z,1553804946.405 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-03-28T20:29:06.405Z,1553804946.405 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-03-28T20:29:06.426Z,1553804946.426 [CBIT](DEBUG): Backplane powered.
2019-03-28T20:29:06.426Z,1553804946.426 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-03-28T20:29:06.428Z,1553804946.428 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-03-28T20:29:06.429Z,1553804946.429 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-03-28T20:29:06.429Z,1553804946.429 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-03-28T20:29:06.430Z,1553804946.430 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-03-28T20:29:06.443Z,1553804946.443 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-28T20:29:06.486Z,1553804946.486 [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>


2019-03-28T20:29:06.487Z,1553804946.487 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-03-28T20:29:06.551Z,1553804946.551 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-03-28T20:29:06.552Z,1553804946.552 [Default:A.Wait](DEBUG): Construct Wait.
2019-03-28T20:29:06.562Z,1553804946.562 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-28T20:29:06.589Z,1553804946.589 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-03-28T20:29:06.591Z,1553804946.591 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-03-28T20:29:06.606Z,1553804946.606 [Default:E.Execute](DEBUG): Construct Execute.
2019-03-28T20:29:06.609Z,1553804946.609 [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>


2019-03-28T20:29:06.643Z,1553804946.643 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-03-28T20:29:06.666Z,1553804946.666 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-03-28T20:29:06.701Z,1553804946.701 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-03-28T20:29:06.701Z,1553804946.701 [DUSBL_Hydroid](INFO): Powering up
2019-03-28T20:29:06.702Z,1553804946.702 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-03-28T20:29:06.718Z,1553804946.718 [Radio_Surface](INFO): Powering up
2019-03-28T20:29:06.778Z,1553804946.778 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-28T20:29:06.823Z,1553804946.823 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-03-28T20:29:06.830Z,1553804946.830 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-03-28T20:29:06.831Z,1553804946.831 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-03-28T20:29:06.838Z,1553804946.838 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-03-28T20:29:06.839Z,1553804946.839 [MassServo](DEBUG): Initializing EZServoServo.
2019-03-28T20:29:06.850Z,1553804946.850 [MassServo](DEBUG): Initializing MassServo.
2019-03-28T20:29:06.851Z,1553804946.851 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-28T20:29:06.858Z,1553804946.858 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-28T20:29:06.859Z,1553804946.859 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-03-28T20:29:06.866Z,1553804946.866 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-03-28T20:29:06.867Z,1553804946.867 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-03-28T20:29:06.867Z,1553804946.867 [CBIT](DEBUG): Powering off loads.
2019-03-28T20:29:06.882Z,1553804946.882 [CBIT](DEBUG): Disabling WDT.
2019-03-28T20:29:06.894Z,1553804946.894 [CBIT](DEBUG): Opening all GF detection circuits.
2019-03-28T20:29:07.068Z,1553804947.068 [Micromodem](INFO): Powering up
2019-03-28T20:29:07.068Z,1553804947.068 [Micromodem](DEBUG): Initializing Micromodem.
2019-03-28T20:29:07.106Z,1553804947.106 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-28T20:29:07.798Z,1553804947.798 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-03-28T20:29:07.798Z,1553804947.798 [RudderServo](FAULT): Rudder failed to initialize
2019-03-28T20:29:07.798Z,1553804947.798 [RudderServo] Communications Fault, FailCount= 1
2019-03-28T20:29:07.798Z,1553804947.798 [RudderServo](ERROR): Communications Fault
2019-03-28T20:29:08.122Z,1553804948.122 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2019-03-28T20:29:08.446Z,1553804948.446 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout
2019-03-28T20:29:08.446Z,1553804948.446 [ThrusterServo](FAULT): Thruster failed to initialize
2019-03-28T20:29:08.446Z,1553804948.446 [ThrusterServo] Communications Fault, FailCount= 1
2019-03-28T20:29:08.446Z,1553804948.446 [ThrusterServo](ERROR): Communications Fault
2019-03-28T20:29:08.814Z,1553804948.814 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2019-03-28T20:29:08.814Z,1553804948.814 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2019-03-28T20:29:08.814Z,1553804948.814 [BuoyancyServo] Communications Fault, FailCount= 1
2019-03-28T20:29:08.814Z,1553804948.814 [BuoyancyServo](ERROR): Communications Fault
2019-03-28T20:29:09.126Z,1553804949.126 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2019-03-28T20:29:09.126Z,1553804949.126 [ElevatorServo](FAULT): Elevator failed to initialize
2019-03-28T20:29:09.126Z,1553804949.126 [ElevatorServo] Communications Fault, FailCount= 1
2019-03-28T20:29:09.126Z,1553804949.126 [ElevatorServo](ERROR): Communications Fault
2019-03-28T20:29:09.127Z,1553804949.127 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-28T20:29:09.127Z,1553804949.127 [RudderServo](INFO): Powering down
2019-03-28T20:29:09.210Z,1553804949.210 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-03-28T20:29:09.210Z,1553804949.210 [ThrusterServo](INFO): Powering down
2019-03-28T20:29:09.346Z,1553804949.346 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-03-28T20:29:09.346Z,1553804949.346 [ElevatorServo](INFO): Powering down
2019-03-28T20:29:09.702Z,1553804949.702 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-28T20:29:09.823Z,1553804949.823 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-28T20:29:10.150Z,1553804950.150 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-03-28T20:29:10.271Z,1553804950.271 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-03-28T20:29:10.574Z,1553804950.574 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-03-28T20:29:10.574Z,1553804950.574 [MassServo] Communications Fault, FailCount= 1
2019-03-28T20:29:10.574Z,1553804950.574 [MassServo](ERROR): Communications Fault
2019-03-28T20:29:10.674Z,1553804950.674 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout
2019-03-28T20:29:10.705Z,1553804950.705 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-03-28T20:29:10.705Z,1553804950.705 [MassServo](INFO): Powering down
2019-03-28T20:29:11.038Z,1553804951.038 [MassServo](FAULT): LCB fault:  Current Limiter Activated.
2019-03-28T20:29:11.038Z,1553804951.038 [MassServo] Hardware Fault, FailCount= 1
2019-03-28T20:29:11.038Z,1553804951.038 [MassServo](ERROR): Hardware Fault
2019-03-28T20:29:11.482Z,1553804951.482 [AHRS_M2](ERROR): Set Trigger UART error: serial timeout
2019-03-28T20:29:11.514Z,1553804951.514 [MassServo](DEBUG): Initializing EZServoServo.
2019-03-28T20:29:11.635Z,1553804951.635 [MassServo](DEBUG): Initializing MassServo.
2019-03-28T20:29:11.820Z,1553804951.820 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2019-03-28T20:29:12.306Z,1553804952.306 [AHRS_M2](ERROR): Clear channel UART error: serial timeout
2019-03-28T20:29:12.694Z,1553804952.694 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2019-03-28T20:29:13.906Z,1553804953.906 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2019-03-28T20:29:14.310Z,1553804954.310 [AHRS_M2](ERROR): Subscribe Magnetometer UART error: serial timeout
2019-03-28T20:29:16.322Z,1553804956.322 [AHRS_M2](ERROR): Subscribe Temperature UART error: serial timeout
2019-03-28T20:29:23.191Z,1553804963.191 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2019-03-28T20:29:26.828Z,1553804966.828 [DUSBL_Hydroid](FAULT): failed to initialize; deviceResponse_ loaded: , available: 0
2019-03-28T20:29:26.828Z,1553804966.828 [DUSBL_Hydroid] Communications Fault, FailCount= 1
2019-03-28T20:29:26.828Z,1553804966.828 [DUSBL_Hydroid](ERROR): Communications Fault
2019-03-28T20:29:27.159Z,1553804967.159 [DUSBL_Hydroid](INFO): Powering down
2019-03-28T20:29:27.654Z,1553804967.654 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-03-28T20:29:27.654Z,1553804967.654 [AHRS_M2] Data Fault, FailCount= 1
2019-03-28T20:29:27.654Z,1553804967.654 [AHRS_M2](ERROR): Data Fault
2019-03-28T20:29:27.659Z,1553804967.659 [DUSBL_Hydroid](FAULT): LCB fault:  Hardware Overcurrent Shutdown. Current Limiter Activated.
2019-03-28T20:29:27.659Z,1553804967.659 [DUSBL_Hydroid] Hardware Fault, FailCount= 1
2019-03-28T20:29:27.659Z,1553804967.659 [DUSBL_Hydroid](ERROR): Hardware Fault
2019-03-28T20:29:27.949Z,1553804967.949 [AHRS_M2](INFO): Powering down
2019-03-28T20:29:28.353Z,1553804968.353 [AHRS_M2](FAULT): LCB fault:  Hardware Overcurrent Shutdown. Current Limiter Activated.
2019-03-28T20:29:28.353Z,1553804968.353 [AHRS_M2] Hardware Fault, FailCount= 1
2019-03-28T20:29:28.354Z,1553804968.354 [AHRS_M2](ERROR): Hardware Fault
2019-03-28T20:29:29.179Z,1553804969.179 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-03-28T20:29:29.179Z,1553804969.179 [RDI_Pathfinder] Communications Fault, FailCount= 1
2019-03-28T20:29:29.179Z,1553804969.179 [RDI_Pathfinder](ERROR): Communications Fault
2019-03-28T20:29:29.654Z,1553804969.654 [RDI_Pathfinder](INFO): Powering down
2019-03-28T20:29:32.800Z,1553804972.800 [NAL9602](INFO): Powering up NAL9602
2019-03-28T20:29:34.415Z,1553804974.415 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2019-03-28T20:29:34.849Z,1553804974.849 [SBIT](IMPORTANT): Beginning Startup BIT
2019-03-28T20:29:45.731Z,1553804985.731 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2019-03-28T20:29:48.183Z,1553804988.183 [SBIT](ERROR): Could not read elevatorAngleReader_.
2019-03-28T20:29:48.183Z,1553804988.183 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-03-28T20:29:48.183Z,1553804988.183 [SBIT](ERROR): Could not read massPosReader_.
2019-03-28T20:29:53.407Z,1553804993.407 [NAL9602](ERROR): NAL9602 initialization error.
2019-03-28T20:29:53.408Z,1553804993.408 [NAL9602] Communications Fault, FailCount= 1
2019-03-28T20:29:53.408Z,1553804993.408 [NAL9602](ERROR): Communications Fault
2019-03-28T20:29:53.811Z,1553804993.811 [NAL9602](INFO): Powering down
2019-03-28T20:29:57.044Z,1553804997.044 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2019-03-28T20:30:08.359Z,1553805008.359 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2019-03-28T20:30:08.562Z,1553805008.562 [CommandLine](IMPORTANT): got command failComponent
2019-03-28T20:30:08.563Z,1553805008.563 [CommandLine](IMPORTANT): Failed components:
2019-03-28T20:30:08.563Z,1553805008.563 [CommandLine](IMPORTANT): AHRS_M2: Hardware Fault
2019-03-28T20:30:08.563Z,1553805008.563 [CommandLine](IMPORTANT): DUSBL_Hydroid: Hardware Fault
2019-03-28T20:30:08.563Z,1553805008.563 [CommandLine](IMPORTANT): NAL9602: Communications Fault
2019-03-28T20:30:08.564Z,1553805008.564 [CommandLine](IMPORTANT): RDI_Pathfinder: Communications Fault
2019-03-28T20:30:08.564Z,1553805008.564 [CommandLine](IMPORTANT): BuoyancyServo: Communications Fault
2019-03-28T20:30:08.564Z,1553805008.564 [CommandLine](IMPORTANT): ElevatorServo: Communications Fault
2019-03-28T20:30:08.564Z,1553805008.564 [CommandLine](IMPORTANT): MassServo: Hardware Fault
2019-03-28T20:30:08.565Z,1553805008.565 [CommandLine](IMPORTANT): RudderServo: Communications Fault
2019-03-28T20:30:08.565Z,1553805008.565 [CommandLine](IMPORTANT): ThrusterServo: Communications Fault
2019-03-28T20:30:08.565Z,1553805008.565 [CommandLine](IMPORTANT): CBIT: Hardware Fault
2019-03-28T20:30:10.379Z,1553805010.379 [CommandLine](IMPORTANT): got command restart application
2019-03-28T20:30:11.382Z,1553805011.382 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-03-28T20:30:11.382Z,1553805011.382 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-28T20:30:11.382Z,1553805011.382 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-28T20:30:11.570Z,1553805011.570 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-03-28T20:30:11.570Z,1553805011.570 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-28T20:30:11.571Z,1553805011.571 [CommandLine](INFO): Join timeout helper Thread ID is 3561
2019-03-28T20:30:11.571Z,1553805011.571 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-03-28T20:30:11.571Z,1553805011.571 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-28T20:30:11.572Z,1553805011.572 [NavChartDb](INFO): Join timeout helper Thread ID is 3562
2019-03-28T20:30:11.754Z,1553805011.754 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-28T20:30:11.754Z,1553805011.754 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-28T20:30:11.774Z,1553805011.774 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-03-28T20:30:11.774Z,1553805011.774 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-28T20:30:11.774Z,1553805011.774 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 3563
2019-03-28T20:30:11.862Z,1553805011.862 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-28T20:30:11.862Z,1553805011.862 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-03-28T20:30:11.863Z,1553805011.863 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-28T20:30:11.874Z,1553805011.874 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-03-28T20:30:11.874Z,1553805011.874 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-28T20:30:11.874Z,1553805011.874 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 3564
2019-03-28T20:30:12.070Z,1553805012.070 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-28T20:30:12.070Z,1553805012.070 [CTD_NeilBrown](INFO): Powering down
2019-03-28T20:30:12.082Z,1553805012.082 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-28T20:30:12.094Z,1553805012.094 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-03-28T20:30:12.094Z,1553805012.094 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-28T20:30:12.094Z,1553805012.094 [Radio_Surface](INFO): Join timeout helper Thread ID is 3565
2019-03-28T20:30:12.166Z,1553805012.166 [Radio_Surface](INFO): Powering down
2019-03-28T20:30:12.167Z,1553805012.167 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-28T20:30:12.167Z,1553805012.167 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-28T20:30:12.179Z,1553805012.179 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-03-28T20:30:12.179Z,1553805012.179 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-28T20:30:12.179Z,1553805012.179 [DataOverHttps](INFO): Join timeout helper Thread ID is 3566
2019-03-28T20:30:12.558Z,1553805012.558 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-28T20:30:12.558Z,1553805012.558 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-28T20:30:12.562Z,1553805012.562 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-03-28T20:30:12.563Z,1553805012.563 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-28T20:30:12.563Z,1553805012.563 [logger](INFO): Join timeout helper Thread ID is 3567
2019-03-28T20:30:12.642Z,1553805012.642 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-28T20:30:12.642Z,1553805012.642 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-28T20:30:12.662Z,1553805012.662 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-03-28T20:30:12.663Z,1553805012.663 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-28T20:30:12.663Z,1553805012.663 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-03-28T20:30:12.663Z,1553805012.663 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-28T20:30:12.663Z,1553805012.663 [controlThread](INFO): Join timeout helper Thread ID is 3568
2019-03-28T20:30:12.794Z,1553805012.794 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-28T20:30:12.794Z,1553805012.794 [controlThread](DEBUG): Uninitializing ControlThread
2019-03-28T20:30:12.794Z,1553805012.794 [AHRS_M2](INFO): Powering down
2019-03-28T20:30:12.796Z,1553805012.796 [DUSBL_Hydroid](INFO): Powering down
2019-03-28T20:30:12.796Z,1553805012.796 [Micromodem](INFO): Powering down
2019-03-28T20:30:12.910Z,1553805012.910 [NAL9602](INFO): Powering down
2019-03-28T20:30:12.911Z,1553805012.911 [RDI_Pathfinder](INFO): Powering down
2019-03-28T20:30:12.913Z,1553805012.913 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-03-28T20:30:12.914Z,1553805012.914 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-03-28T20:30:12.915Z,1553805012.915 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-03-28T20:30:12.915Z,1553805012.915 [MissionManager](INFO): Uninitializing Mission Default
2019-03-28T20:30:12.918Z,1553805012.918 [MissionManager](INFO): Uninitializing Mission Startup
2019-03-28T20:30:12.919Z,1553805012.919 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-03-28T20:30:12.919Z,1553805012.919 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-03-28T20:30:12.920Z,1553805012.920 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-03-28T20:30:12.920Z,1553805012.920 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-03-28T20:30:12.920Z,1553805012.920 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-03-28T20:30:12.920Z,1553805012.920 [BuoyancyServo](INFO): Powering down
2019-03-28T20:30:13.074Z,1553805013.074 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-03-28T20:30:13.074Z,1553805013.074 [ElevatorServo](INFO): Powering down
2019-03-28T20:30:13.075Z,1553805013.075 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-03-28T20:30:13.075Z,1553805013.075 [MassServo](INFO): Powering down
2019-03-28T20:30:13.076Z,1553805013.076 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-28T20:30:13.076Z,1553805013.076 [RudderServo](INFO): Powering down
2019-03-28T20:30:13.077Z,1553805013.077 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-03-28T20:30:13.077Z,1553805013.077 [ThrusterServo](INFO): Powering down
2019-03-28T20:30:13.078Z,1553805013.078 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-03-28T20:30:13.078Z,1553805013.078 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-03-28T20:30:13.078Z,1553805013.078 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-03-28T20:30:13.078Z,1553805013.078 [CBIT](DEBUG): Powering off loads.
2019-03-28T20:30:13.090Z,1553805013.090 [CBIT](DEBUG): Disabling WDT.
2019-03-28T20:30:13.102Z,1553805013.102 [CBIT](DEBUG): Opening all GF detection circuits.
2019-03-28T20:30:13.103Z,1553805013.103 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-28T20:30:13.146Z,1553805013.146 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-28T20:30:13.155Z,1553805013.155 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-28T20:30:13.551Z,1553805013.551 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-28T20:30:13.553Z,1553805013.553 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-28T20:30:13.608Z,1553805013.608 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-28T20:30:13.665Z,1553805013.665 [logger ThreadHandler](INFO): Thread cancelled.