2019-01-08T18:43:29.306Z,1546973009.306 [Supervisor](DEBUG): Initializing supervisor.
2019-01-08T18:43:29.309Z,1546973009.309 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-01-08T18:43:29.310Z,1546973009.310 [SyncHandler](INFO): Protected caller Thread ID is 1754
2019-01-08T18:43:29.311Z,1546973009.311 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-01-08T18:43:29.312Z,1546973009.312 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-01-08T18:43:29.312Z,1546973009.312 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1755
2019-01-08T18:43:29.315Z,1546973009.315 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-01-08T18:43:29.328Z,1546973009.328 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-01-08T18:43:29.329Z,1546973009.329 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-01-08T18:43:29.329Z,1546973009.329 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1756
2019-01-08T18:43:29.330Z,1546973009.330 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-01-08T18:43:29.331Z,1546973009.331 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-01-08T18:43:29.331Z,1546973009.331 [logger ThreadHandler](INFO): Protected caller Thread ID is 1757
2019-01-08T18:43:29.333Z,1546973009.333 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-01-08T18:43:29.334Z,1546973009.334 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-01-08T18:43:29.335Z,1546973009.335 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-01-08T18:43:29.659Z,1546973009.659 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-01-08T18:43:29.659Z,1546973009.659 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-01-08T18:43:30.109Z,1546973010.109 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-01-08T18:43:30.109Z,1546973010.109 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-01-08T18:43:30.455Z,1546973010.455 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-01-08T18:43:30.456Z,1546973010.456 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-01-08T18:43:30.652Z,1546973010.652 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-01-08T18:43:30.652Z,1546973010.652 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-01-08T18:43:31.339Z,1546973011.339 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-01-08T18:43:31.340Z,1546973011.340 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-01-08T18:43:31.821Z,1546973011.821 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-01-08T18:43:31.822Z,1546973011.822 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-01-08T18:43:31.926Z,1546973011.926 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-01-08T18:43:31.926Z,1546973011.926 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-01-08T18:43:32.126Z,1546973012.126 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-01-08T18:43:32.126Z,1546973012.126 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-01-08T18:43:32.270Z,1546973012.270 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-01-08T18:43:32.271Z,1546973012.271 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-01-08T18:43:32.351Z,1546973012.351 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-01-08T18:43:32.570Z,1546973012.570 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-01-08T18:43:32.570Z,1546973012.570 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-01-08T18:43:32.670Z,1546973012.670 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-01-08T18:43:32.671Z,1546973012.671 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-01-08T18:43:32.767Z,1546973012.767 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-01-08T18:43:32.768Z,1546973012.768 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-01-08T18:43:32.917Z,1546973012.917 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-01-08T18:43:32.918Z,1546973012.918 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-01-08T18:43:33.236Z,1546973013.236 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-01-08T18:43:33.238Z,1546973013.238 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-01-08T18:43:33.239Z,1546973013.239 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-01-08T18:43:33.371Z,1546973013.371 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-01-08T18:43:33.596Z,1546973013.596 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-01-08T18:43:33.597Z,1546973013.597 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-01-08T18:43:33.770Z,1546973013.770 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-01-08T18:43:33.870Z,1546973013.870 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-01-08T18:43:33.969Z,1546973013.969 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-01-08T18:43:34.164Z,1546973014.164 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-01-08T18:43:34.249Z,1546973014.249 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-01-08T18:43:34.347Z,1546973014.347 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-01-08T18:43:34.441Z,1546973014.441 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-01-08T18:43:34.524Z,1546973014.524 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-01-08T18:43:34.634Z,1546973014.634 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2019-01-08T18:43:34.634Z,1546973014.634 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-01-08T18:43:34.638Z,1546973014.638 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-01-08T18:43:34.769Z,1546973014.769 [SBIT](DEBUG): Construct Startup Built In Test.
2019-01-08T18:43:34.800Z,1546973014.800 [SBIT] Loaded
2019-01-08T18:43:34.800Z,1546973014.800 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-01-08T18:43:34.801Z,1546973014.801 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-01-08T18:43:34.829Z,1546973014.829 [IBIT] Loaded
2019-01-08T18:43:34.829Z,1546973014.829 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-01-08T18:43:34.832Z,1546973014.832 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-01-08T18:43:34.991Z,1546973014.991 [CBIT] Loaded
2019-01-08T18:43:34.991Z,1546973014.991 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-01-08T18:43:34.992Z,1546973014.992 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-01-08T18:43:34.992Z,1546973014.992 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-01-08T18:43:35.108Z,1546973015.108 [BuoyancyServo] Loaded
2019-01-08T18:43:35.108Z,1546973015.108 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-01-08T18:43:35.123Z,1546973015.123 [ElevatorServo] Loaded
2019-01-08T18:43:35.123Z,1546973015.123 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-01-08T18:43:35.138Z,1546973015.138 [MassServo] Loaded
2019-01-08T18:43:35.138Z,1546973015.138 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-01-08T18:43:35.154Z,1546973015.154 [RudderServo] Loaded
2019-01-08T18:43:35.154Z,1546973015.154 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-01-08T18:43:35.169Z,1546973015.169 [ThrusterServo] Loaded
2019-01-08T18:43:35.170Z,1546973015.170 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-01-08T18:43:35.170Z,1546973015.170 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-01-08T18:43:35.171Z,1546973015.171 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-01-08T18:43:35.273Z,1546973015.273 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-01-08T18:43:35.273Z,1546973015.273 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-01-08T18:43:35.328Z,1546973015.328 [DeadReckonUsingSpeedCalculator] Loaded
2019-01-08T18:43:35.328Z,1546973015.328 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-01-08T18:43:35.345Z,1546973015.345 [NavChart] Loaded
2019-01-08T18:43:35.345Z,1546973015.345 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-01-08T18:43:35.349Z,1546973015.349 [UniversalFixResidualReporter] Loaded
2019-01-08T18:43:35.350Z,1546973015.350 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-01-08T18:43:35.350Z,1546973015.350 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-01-08T18:43:35.351Z,1546973015.351 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-01-08T18:43:35.364Z,1546973015.364 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-01-08T18:43:35.365Z,1546973015.365 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-01-08T18:43:35.610Z,1546973015.610 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-01-08T18:43:35.611Z,1546973015.611 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-01-08T18:43:35.914Z,1546973015.914 [AHRS_M2] Loaded
2019-01-08T18:43:35.915Z,1546973015.915 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-01-08T18:43:35.987Z,1546973015.987 [DataOverHttps] Loaded
2019-01-08T18:43:35.987Z,1546973015.987 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2019-01-08T18:43:36.001Z,1546973016.001 [Depth_Keller] Loaded
2019-01-08T18:43:36.001Z,1546973016.001 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-01-08T18:43:36.006Z,1546973016.006 [DropWeight] Loaded
2019-01-08T18:43:36.006Z,1546973016.006 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-01-08T18:43:36.098Z,1546973016.098 [NAL9602] Loaded
2019-01-08T18:43:36.099Z,1546973016.099 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-01-08T18:43:36.104Z,1546973016.104 [Onboard] Loaded
2019-01-08T18:43:36.104Z,1546973016.104 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-01-08T18:43:36.111Z,1546973016.111 [Radio_Surface] Loaded
2019-01-08T18:43:36.111Z,1546973016.111 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-01-08T18:43:36.112Z,1546973016.112 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408364E0
2019-01-08T18:43:36.113Z,1546973016.113 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1836
2019-01-08T18:43:36.141Z,1546973016.141 [RDI_Pathfinder] Loaded
2019-01-08T18:43:36.142Z,1546973016.142 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-01-08T18:43:36.164Z,1546973016.164 [RDI_PathfinderUp] Loaded
2019-01-08T18:43:36.164Z,1546973016.164 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2019-01-08T18:43:36.171Z,1546973016.171 [SCPI] Loaded
2019-01-08T18:43:36.171Z,1546973016.171 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2019-01-08T18:43:36.172Z,1546973016.172 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-01-08T18:43:36.172Z,1546973016.172 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-01-08T18:43:36.241Z,1546973016.241 [VerticalControl](DEBUG): Construct VerticalControl.
2019-01-08T18:43:36.332Z,1546973016.332 [VerticalControl] Loaded
2019-01-08T18:43:36.332Z,1546973016.332 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-01-08T18:43:36.333Z,1546973016.333 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-01-08T18:43:36.392Z,1546973016.392 [HorizontalControl] Loaded
2019-01-08T18:43:36.393Z,1546973016.393 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-01-08T18:43:36.393Z,1546973016.393 [SpeedControl](DEBUG): Construct SpeedControl.
2019-01-08T18:43:36.395Z,1546973016.395 [SpeedControl] Loaded
2019-01-08T18:43:36.396Z,1546973016.396 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-01-08T18:43:36.396Z,1546973016.396 [LoopControl](DEBUG): Construct LoopControl.
2019-01-08T18:43:36.397Z,1546973016.397 [LoopControl] Loaded
2019-01-08T18:43:36.397Z,1546973016.397 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-01-08T18:43:36.398Z,1546973016.398 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-01-08T18:43:36.398Z,1546973016.398 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-01-08T18:43:36.640Z,1546973016.640 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-01-08T18:43:36.641Z,1546973016.641 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-01-08T18:43:36.963Z,1546973016.963 [Aanderaa_O2] Loaded
2019-01-08T18:43:36.964Z,1546973016.964 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-01-08T18:43:37.053Z,1546973017.053 [CTD_NeilBrown] Loaded
2019-01-08T18:43:37.053Z,1546973017.053 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-01-08T18:43:37.054Z,1546973017.054 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409374E0
2019-01-08T18:43:37.055Z,1546973017.055 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1837
2019-01-08T18:43:37.101Z,1546973017.101 [WetLabsSeaOWL_UV_A] Loaded
2019-01-08T18:43:37.101Z,1546973017.101 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-01-08T18:43:37.102Z,1546973017.102 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409674E0
2019-01-08T18:43:37.102Z,1546973017.102 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1838
2019-01-08T18:43:37.103Z,1546973017.103 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-01-08T18:43:37.103Z,1546973017.103 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-01-08T18:43:37.346Z,1546973017.346 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-01-08T18:43:37.346Z,1546973017.346 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-01-08T18:43:37.390Z,1546973017.390 [DepthRateCalculator] Loaded
2019-01-08T18:43:37.390Z,1546973017.390 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-01-08T18:43:37.395Z,1546973017.395 [PitchRateCalculator] Loaded
2019-01-08T18:43:37.396Z,1546973017.396 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-01-08T18:43:37.408Z,1546973017.408 [SpeedCalculator] Loaded
2019-01-08T18:43:37.408Z,1546973017.408 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-01-08T18:43:37.430Z,1546973017.430 [TempGradientCalculator] Loaded
2019-01-08T18:43:37.430Z,1546973017.430 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-01-08T18:43:37.436Z,1546973017.436 [YawRateCalculator] Loaded
2019-01-08T18:43:37.436Z,1546973017.436 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-01-08T18:43:37.467Z,1546973017.467 [ElevatorOffsetCalculator] Loaded
2019-01-08T18:43:37.467Z,1546973017.467 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-01-08T18:43:37.468Z,1546973017.468 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-01-08T18:43:37.468Z,1546973017.468 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-01-08T18:43:37.493Z,1546973017.493 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-01-08T18:43:37.497Z,1546973017.497 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-01-08T18:43:37.498Z,1546973017.498 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-01-08T18:43:37.504Z,1546973017.504 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-01-08T18:43:37.505Z,1546973017.505 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A174E0
2019-01-08T18:43:37.506Z,1546973017.506 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1839
2019-01-08T18:43:37.511Z,1546973017.511 [Supervisor](INFO): Main Thread ID is 996
2019-01-08T18:43:37.511Z,1546973017.511 [Supervisor](DEBUG): Running supervisor.
2019-01-08T18:43:37.511Z,1546973017.511 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1840
2019-01-08T18:43:37.514Z,1546973017.514 [controlThread ThreadHandler](INFO): Handler Thread ID is 1841
2019-01-08T18:43:37.514Z,1546973017.514 [controlThread](DEBUG): Initializing ControlThread
2019-01-08T18:43:37.515Z,1546973017.515 [SBIT](INFO): Initialize SBIT Component.
2019-01-08T18:43:37.515Z,1546973017.515 [SBIT](IMPORTANT): git: 2018-11-05-2-g6a71760
2019-01-08T18:43:37.516Z,1546973017.516 [SBIT](INFO): git hash: 6a71760e9f7f683776be04bba2e56bc8c8a5e6e9
2019-01-08T18:43:37.516Z,1546973017.516 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-01-08T18:43:37.516Z,1546973017.516 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2019-01-08T18:43:37.517Z,1546973017.517 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-01-08T18:43:37.518Z,1546973017.518 [IBIT](INFO): Initialize IBIT Component.
2019-01-08T18:43:37.519Z,1546973017.519 [CBIT](DEBUG): Initialize CBIT Component.
2019-01-08T18:43:37.519Z,1546973017.519 [CBIT](CRITICAL): Watchdog Timer failed to initialize.
2019-01-08T18:43:37.519Z,1546973017.519 [CBIT] Hardware Fault, FailCount= 1
2019-01-08T18:43:37.519Z,1546973017.519 [CBIT](ERROR): Hardware Fault
2019-01-08T18:43:37.520Z,1546973017.520 [logger ThreadHandler](INFO): Handler Thread ID is 1842
2019-01-08T18:43:37.537Z,1546973017.537 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1843
2019-01-08T18:43:37.543Z,1546973017.543 [Radio_Surface](INFO): Powering up
2019-01-08T18:43:37.549Z,1546973017.549 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1844
2019-01-08T18:43:37.550Z,1546973017.550 [CTD_NeilBrown](INFO): Powering down
2019-01-08T18:43:37.582Z,1546973017.582 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1845
2019-01-08T18:43:37.582Z,1546973017.582 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-01-08T18:43:37.613Z,1546973017.613 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1846
2019-01-08T18:43:37.617Z,1546973017.617 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-01-08T18:43:37.617Z,1546973017.617 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-01-08T18:43:37.617Z,1546973017.617 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-01-08T18:43:37.617Z,1546973017.617 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-01-08T18:43:37.617Z,1546973017.617 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-01-08T18:43:37.618Z,1546973017.618 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-01-08T18:43:37.618Z,1546973017.618 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-01-08T18:43:37.618Z,1546973017.618 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-01-08T18:43:37.618Z,1546973017.618 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-01-08T18:43:37.618Z,1546973017.618 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-01-08T18:43:37.619Z,1546973017.619 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-01-08T18:43:37.619Z,1546973017.619 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-01-08T18:43:37.619Z,1546973017.619 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-01-08T18:43:37.619Z,1546973017.619 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-01-08T18:43:37.619Z,1546973017.619 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-01-08T18:43:37.620Z,1546973017.620 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-01-08T18:43:37.626Z,1546973017.626 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2019-01-08T18:43:37.627Z,1546973017.627 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2019-01-08T18:43:37.627Z,1546973017.627 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2019-01-08T18:43:37.628Z,1546973017.628 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-01-08T18:43:37.628Z,1546973017.628 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2019-01-08T18:43:37.628Z,1546973017.628 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2019-01-08T18:43:37.629Z,1546973017.629 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-01-08T18:43:37.629Z,1546973017.629 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-01-08T18:43:37.635Z,1546973017.635 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-01-08T18:43:37.638Z,1546973017.638 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-01-08T18:43:37.638Z,1546973017.638 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-01-08T18:43:37.639Z,1546973017.639 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-01-08T18:43:37.639Z,1546973017.639 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-01-08T18:43:37.640Z,1546973017.640 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-01-08T18:43:37.640Z,1546973017.640 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-01-08T18:43:37.641Z,1546973017.641 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-01-08T18:43:37.641Z,1546973017.641 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-01-08T18:43:37.642Z,1546973017.642 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-01-08T18:43:37.643Z,1546973017.643 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-01-08T18:43:37.675Z,1546973017.675 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-01-08T18:43:37.729Z,1546973017.729 [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-01-08T18:43:37.730Z,1546973017.730 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-01-08T18:43:37.808Z,1546973017.808 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-01-08T18:43:37.809Z,1546973017.809 [Default:A.Wait](DEBUG): Construct Wait.
2019-01-08T18:43:37.811Z,1546973017.811 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-01-08T18:43:37.855Z,1546973017.855 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-01-08T18:43:37.858Z,1546973017.858 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-01-08T18:43:37.879Z,1546973017.879 [Default:E.Execute](DEBUG): Construct Execute.
2019-01-08T18:43:37.902Z,1546973017.902 [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-01-08T18:43:37.911Z,1546973017.911 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,RDI_PathfinderUp,SCPI,Aanderaa_O2,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-01-08T18:43:37.945Z,1546973017.945 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-01-08T18:43:37.992Z,1546973017.992 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-01-08T18:43:38.023Z,1546973018.023 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-01-08T18:43:38.201Z,1546973018.201 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-01-08T18:43:38.433Z,1546973018.433 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-01-08T18:43:38.441Z,1546973018.441 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-01-08T18:43:38.455Z,1546973018.455 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-01-08T18:43:38.461Z,1546973018.461 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-01-08T18:43:38.475Z,1546973018.475 [MassServo](DEBUG): Initializing EZServoServo.
2019-01-08T18:43:38.481Z,1546973018.481 [MassServo](DEBUG): Initializing MassServo.
2019-01-08T18:43:38.487Z,1546973018.487 [RudderServo](DEBUG): Initializing EZServoServo.
2019-01-08T18:43:38.493Z,1546973018.493 [RudderServo](DEBUG): Initializing RudderServo.
2019-01-08T18:43:38.507Z,1546973018.507 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-01-08T18:43:38.513Z,1546973018.513 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-01-08T18:43:38.550Z,1546973018.550 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-01-08T18:43:38.661Z,1546973018.661 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-01-08T18:43:38.662Z,1546973018.662 [DropWeight] Hardware Fault, FailCount= 1
2019-01-08T18:43:38.662Z,1546973018.662 [DropWeight](ERROR): Hardware Fault
2019-01-08T18:43:38.761Z,1546973018.761 [RDI_PathfinderUp](INFO): Powering down
2019-01-08T18:43:38.813Z,1546973018.813 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-01-08T18:43:38.997Z,1546973018.997 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-01-08T18:43:39.333Z,1546973019.333 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2019-01-08T18:43:39.333Z,1546973019.333 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2019-01-08T18:43:39.333Z,1546973019.333 [BuoyancyServo] Communications Fault, FailCount= 1
2019-01-08T18:43:39.333Z,1546973019.333 [BuoyancyServo](ERROR): Communications Fault
2019-01-08T18:43:39.645Z,1546973019.645 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2019-01-08T18:43:39.645Z,1546973019.645 [ElevatorServo](FAULT): Elevator failed to initialize
2019-01-08T18:43:39.645Z,1546973019.645 [ElevatorServo] Communications Fault, FailCount= 1
2019-01-08T18:43:39.645Z,1546973019.645 [ElevatorServo](ERROR): Communications Fault
2019-01-08T18:43:39.949Z,1546973019.949 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-01-08T18:43:39.949Z,1546973019.949 [RudderServo](FAULT): Rudder failed to initialize
2019-01-08T18:43:39.949Z,1546973019.949 [RudderServo] Communications Fault, FailCount= 1
2019-01-08T18:43:39.949Z,1546973019.949 [RudderServo](ERROR): Communications Fault
2019-01-08T18:43:40.273Z,1546973020.273 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2019-01-08T18:43:40.597Z,1546973020.597 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout
2019-01-08T18:43:40.597Z,1546973020.597 [ThrusterServo](FAULT): Thruster failed to initialize
2019-01-08T18:43:40.597Z,1546973020.597 [ThrusterServo] Communications Fault, FailCount= 1
2019-01-08T18:43:40.597Z,1546973020.597 [ThrusterServo](ERROR): Communications Fault
2019-01-08T18:43:40.656Z,1546973020.656 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-01-08T18:43:40.657Z,1546973020.657 [ElevatorServo](INFO): Powering down
2019-01-08T18:43:41.045Z,1546973021.045 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-01-08T18:43:41.045Z,1546973021.045 [MassServo] Communications Fault, FailCount= 1
2019-01-08T18:43:41.045Z,1546973021.045 [MassServo](ERROR): Communications Fault
2019-01-08T18:43:41.046Z,1546973021.046 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-01-08T18:43:41.046Z,1546973021.046 [RudderServo](INFO): Powering down
2019-01-08T18:43:41.074Z,1546973021.074 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-01-08T18:43:41.074Z,1546973021.074 [ThrusterServo](INFO): Powering down
2019-01-08T18:43:41.457Z,1546973021.457 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:43:41.499Z,1546973021.499 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-01-08T18:43:41.499Z,1546973021.499 [MassServo](INFO): Powering down
2019-01-08T18:43:41.909Z,1546973021.909 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:43:41.959Z,1546973021.959 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-01-08T18:43:42.078Z,1546973022.078 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-01-08T18:43:42.079Z,1546973022.079 [MassServo](FAULT): LCB fault:  Current Limiter Activated.
2019-01-08T18:43:42.079Z,1546973022.079 [MassServo] Hardware Fault, FailCount= 1
2019-01-08T18:43:42.079Z,1546973022.079 [MassServo](ERROR): Hardware Fault
2019-01-08T18:43:42.080Z,1546973022.080 [RudderServo](DEBUG): Initializing EZServoServo.
2019-01-08T18:43:42.198Z,1546973022.198 [RudderServo](DEBUG): Initializing RudderServo.
2019-01-08T18:43:42.649Z,1546973022.649 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:43:42.701Z,1546973022.701 [MassServo](DEBUG): Initializing EZServoServo.
2019-01-08T18:43:42.822Z,1546973022.822 [MassServo](DEBUG): Initializing MassServo.
2019-01-08T18:43:43.153Z,1546973023.153 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:43:43.553Z,1546973023.553 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:43:43.942Z,1546973023.942 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:43:44.353Z,1546973024.353 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:43:44.749Z,1546973024.749 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:43:45.153Z,1546973025.153 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:43:45.565Z,1546973025.565 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:43:45.953Z,1546973025.953 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:43:46.353Z,1546973026.353 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:43:46.757Z,1546973026.757 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:43:47.153Z,1546973027.153 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:43:47.553Z,1546973027.553 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:43:47.953Z,1546973027.953 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:43:48.353Z,1546973028.353 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-01-08T18:43:48.353Z,1546973028.353 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-01-08T18:43:48.353Z,1546973028.353 [AHRS_M2] Communications Fault, FailCount= 1
2019-01-08T18:43:48.353Z,1546973028.353 [AHRS_M2](ERROR): Communications Fault
2019-01-08T18:43:48.373Z,1546973028.373 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-01-08T18:43:48.373Z,1546973028.374 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-01-08T18:43:48.374Z,1546973028.374 [Aanderaa_O2](ERROR): Communications Fault
2019-01-08T18:43:48.430Z,1546973028.430 [AHRS_M2](INFO): Powering down
2019-01-08T18:43:48.685Z,1546973028.685 [Aanderaa_O2](INFO): Powering down
2019-01-08T18:43:48.848Z,1546973028.848 [Aanderaa_O2](FAULT): LCB fault:  Hardware Overcurrent Shutdown. Current Limiter Activated.
2019-01-08T18:43:48.848Z,1546973028.848 [Aanderaa_O2] Hardware Fault, FailCount= 1
2019-01-08T18:43:48.848Z,1546973028.848 [Aanderaa_O2](ERROR): Hardware Fault
2019-01-08T18:44:00.449Z,1546973040.449 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-01-08T18:44:00.449Z,1546973040.449 [RDI_Pathfinder] Communications Fault, FailCount= 1
2019-01-08T18:44:00.449Z,1546973040.449 [RDI_Pathfinder](ERROR): Communications Fault
2019-01-08T18:44:00.925Z,1546973040.925 [RDI_Pathfinder](INFO): Powering down
2019-01-08T18:44:06.067Z,1546973046.067 [SBIT](IMPORTANT): Beginning Startup BIT
2019-01-08T18:44:06.434Z,1546973046.434 [NAL9602](INFO): Powering up NAL9602
2019-01-08T18:44:19.291Z,1546973059.291 [SBIT](ERROR): Could not read elevatorAngleReader_.
2019-01-08T18:44:19.291Z,1546973059.291 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-01-08T18:44:19.292Z,1546973059.292 [SBIT](ERROR): Could not read massPosReader_.
2019-01-08T18:44:27.632Z,1546973067.632 [NAL9602](ERROR): NAL9602 initialization error.
2019-01-08T18:44:27.632Z,1546973067.632 [NAL9602] Communications Fault, FailCount= 1
2019-01-08T18:44:27.632Z,1546973067.632 [NAL9602](ERROR): Communications Fault
2019-01-08T18:44:28.034Z,1546973068.034 [NAL9602](INFO): Powering down
2019-01-08T18:44:46.067Z,1546973086.067 [SBIT](ERROR): Could not read elevatorAngleReader_.
2019-01-08T18:44:46.067Z,1546973086.067 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-01-08T18:44:46.067Z,1546973086.067 [SBIT](ERROR): Could not read massPosReader_.
2019-01-08T18:44:46.067Z,1546973086.067 [SBIT](FAULT): Control surface position failure.
2019-01-08T18:44:59.678Z,1546973099.678 [SBIT](ERROR): Could not read elevatorAngleReader_.
2019-01-08T18:44:59.678Z,1546973099.678 [SBIT](ERROR): Could not read rudderAngleReader_.
2019-01-08T18:44:59.678Z,1546973099.678 [SBIT](ERROR): Could not read massPosReader_.
2019-01-08T18:44:59.679Z,1546973099.679 [SBIT](FAULT): Control surface position failure.
2019-01-08T18:45:00.067Z,1546973100.067 [SBIT](CRITICAL): SBIT FAILED
2019-01-08T18:45:00.110Z,1546973100.110 [CommandLine](IMPORTANT): got command configSet list
2019-01-08T18:45:00.110Z,1546973100.110 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-01-08T18:45:00.111Z,1546973100.111 [CommandLine](IMPORTANT): 

2019-01-08T18:45:00.111Z,1546973100.111 [CommandLine](IMPORTANT): BPC1.loadAtStartup=0 bool;

2019-01-08T18:45:00.111Z,1546973100.111 [CommandLine](IMPORTANT): CBIT.gf24Offset=170 microampere;

2019-01-08T18:45:00.454Z,1546973100.454 [MissionManager](IMPORTANT): Started mission Startup
2019-01-08T18:45:00.455Z,1546973100.455 [Startup] Running Loop=1
2019-01-08T18:45:00.455Z,1546973100.455 [Startup](DEBUG): Aggregate::initialize Startup
2019-01-08T18:45:00.455Z,1546973100.455 [Startup:A.GoToSurface] Running Loop=1
2019-01-08T18:45:00.455Z,1546973100.455 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-01-08T18:45:00.456Z,1546973100.456 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-01-08T18:45:00.456Z,1546973100.456 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-01-08T18:45:00.456Z,1546973100.456 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-01-08T18:45:00.457Z,1546973100.457 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-01-08T18:45:00.457Z,1546973100.457 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-01-08T18:45:00.463Z,1546973100.463 [Startup:StartupSatComms] Running Loop=1
2019-01-08T18:45:00.463Z,1546973100.463 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-01-08T18:45:00.463Z,1546973100.463 [Startup:StartupSatComms:A] Running Loop=1
2019-01-08T18:45:00.863Z,1546973100.863 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-01-08T18:45:37.652Z,1546973137.652 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-01-08T18:45:37.653Z,1546973137.653 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-01-08T18:45:37.653Z,1546973137.653 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-01-08T18:45:37.654Z,1546973137.654 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-01-08T18:45:37.655Z,1546973137.655 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-01-08T18:45:37.655Z,1546973137.655 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-01-08T18:46:00.855Z,1546973160.855 [Startup:StartupSatComms:A](INFO): Timed out from 2019-01-08T18:45:00.5Z
2019-01-08T18:46:00.856Z,1546973160.856 [Startup:StartupSatComms:A] Stopped
2019-01-08T18:46:00.856Z,1546973160.856 [Startup:StartupSatComms:B] Running Loop=1
2019-01-08T18:46:01.261Z,1546973161.261 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-01-08T18:47:01.275Z,1546973221.275 [Startup:StartupSatComms:B](INFO): Timed out from 2019-01-08T18:46:00.9Z
2019-01-08T18:47:01.275Z,1546973221.275 [Startup:StartupSatComms:B] Stopped
2019-01-08T18:47:01.275Z,1546973221.275 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-01-08T18:47:01.275Z,1546973221.275 [Startup:StartupSatComms] Stopped
2019-01-08T18:47:01.275Z,1546973221.275 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-01-08T18:47:01.276Z,1546973221.276 [Startup](INFO): Completed Startup
2019-01-08T18:47:01.276Z,1546973221.276 [MissionManager](INFO): Startup is completed.
2019-01-08T18:47:01.276Z,1546973221.276 [MissionManager](INFO): Uninitializing Mission Startup
2019-01-08T18:47:01.276Z,1546973221.276 [Startup] Stopped
2019-01-08T18:47:01.277Z,1546973221.277 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-01-08T18:47:01.277Z,1546973221.277 [Startup:A.GoToSurface] Stopped
2019-01-08T18:47:01.277Z,1546973221.277 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-01-08T18:47:01.654Z,1546973221.654 [MissionManager](IMPORTANT): Started mission Default
2019-01-08T18:47:01.654Z,1546973221.654 [Default] Running Loop=1
2019-01-08T18:47:01.654Z,1546973221.654 [Default](DEBUG): Aggregate::initialize Default
2019-01-08T18:47:01.654Z,1546973221.654 [Default:B.GoToSurface] Running Loop=1
2019-01-08T18:47:01.654Z,1546973221.654 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-01-08T18:47:01.655Z,1546973221.655 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-01-08T18:47:01.655Z,1546973221.655 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-01-08T18:47:01.655Z,1546973221.655 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-01-08T18:47:01.655Z,1546973221.655 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-01-08T18:47:01.656Z,1546973221.656 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-01-08T18:47:01.656Z,1546973221.656 [Default:A.Wait] Running Loop=1
20