2018-11-13T20:52:28.114Z,1542142348.114 [Supervisor](DEBUG): Initializing supervisor.
2018-11-13T20:52:28.118Z,1542142348.118 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-11-13T20:52:28.118Z,1542142348.118 [SyncHandler](INFO): Protected caller Thread ID is 6461
2018-11-13T20:52:28.119Z,1542142348.119 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-11-13T20:52:28.120Z,1542142348.120 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-11-13T20:52:28.120Z,1542142348.120 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6462
2018-11-13T20:52:28.123Z,1542142348.123 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-11-13T20:52:28.135Z,1542142348.135 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-11-13T20:52:28.136Z,1542142348.136 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-11-13T20:52:28.137Z,1542142348.137 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6463
2018-11-13T20:52:28.138Z,1542142348.138 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-11-13T20:52:28.138Z,1542142348.138 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-11-13T20:52:28.139Z,1542142348.139 [logger ThreadHandler](INFO): Protected caller Thread ID is 6464
2018-11-13T20:52:28.141Z,1542142348.141 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-11-13T20:52:28.141Z,1542142348.141 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-11-13T20:52:28.143Z,1542142348.143 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-11-13T20:52:28.468Z,1542142348.468 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-11-13T20:52:28.468Z,1542142348.468 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-11-13T20:52:28.920Z,1542142348.920 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-11-13T20:52:28.921Z,1542142348.921 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-11-13T20:52:29.269Z,1542142349.269 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-11-13T20:52:29.270Z,1542142349.270 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-11-13T20:52:29.467Z,1542142349.467 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-11-13T20:52:29.468Z,1542142349.468 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-11-13T20:52:29.949Z,1542142349.949 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-11-13T20:52:29.949Z,1542142349.949 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-11-13T20:52:30.488Z,1542142350.488 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-11-13T20:52:30.489Z,1542142350.489 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-11-13T20:52:30.592Z,1542142350.592 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-11-13T20:52:30.593Z,1542142350.593 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-11-13T20:52:30.791Z,1542142350.791 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-11-13T20:52:30.791Z,1542142350.791 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-11-13T20:52:30.935Z,1542142350.935 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-11-13T20:52:30.936Z,1542142350.936 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-11-13T20:52:31.015Z,1542142351.015 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-11-13T20:52:31.234Z,1542142351.234 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-11-13T20:52:31.234Z,1542142351.234 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-11-13T20:52:31.334Z,1542142351.334 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-11-13T20:52:31.334Z,1542142351.334 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-11-13T20:52:31.430Z,1542142351.430 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-11-13T20:52:31.431Z,1542142351.431 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-11-13T20:52:31.581Z,1542142351.581 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-11-13T20:52:31.582Z,1542142351.582 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-11-13T20:52:31.899Z,1542142351.899 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-11-13T20:52:31.901Z,1542142351.901 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2018-11-13T20:52:31.902Z,1542142351.902 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2018-11-13T20:52:32.034Z,1542142352.034 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2018-11-13T20:52:32.280Z,1542142352.280 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-11-13T20:52:32.281Z,1542142352.281 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2018-11-13T20:52:32.488Z,1542142352.488 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2018-11-13T20:52:32.775Z,1542142352.775 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2018-11-13T20:52:32.895Z,1542142352.895 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2018-11-13T20:52:33.094Z,1542142353.094 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2018-11-13T20:52:33.178Z,1542142353.178 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2018-11-13T20:52:33.275Z,1542142353.275 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2018-11-13T20:52:33.368Z,1542142353.368 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2018-11-13T20:52:33.450Z,1542142353.450 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2018-11-13T20:52:33.559Z,1542142353.559 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2018-11-13T20:52:33.559Z,1542142353.559 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-11-13T20:52:33.570Z,1542142353.570 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-11-13T20:52:33.709Z,1542142353.709 [SBIT](DEBUG): Construct Startup Built In Test.
2018-11-13T20:52:33.741Z,1542142353.741 [SBIT] Loaded
2018-11-13T20:52:33.741Z,1542142353.741 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-11-13T20:52:33.742Z,1542142353.742 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-11-13T20:52:33.770Z,1542142353.770 [IBIT] Loaded
2018-11-13T20:52:33.771Z,1542142353.771 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-11-13T20:52:33.774Z,1542142353.774 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-11-13T20:52:33.934Z,1542142353.934 [CBIT] Loaded
2018-11-13T20:52:33.935Z,1542142353.935 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-11-13T20:52:33.935Z,1542142353.935 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-11-13T20:52:33.936Z,1542142353.936 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-11-13T20:52:34.060Z,1542142354.060 [BuoyancyServo] Loaded
2018-11-13T20:52:34.060Z,1542142354.060 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-11-13T20:52:34.075Z,1542142354.075 [ElevatorServo] Loaded
2018-11-13T20:52:34.076Z,1542142354.076 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-11-13T20:52:34.090Z,1542142354.090 [MassServo] Loaded
2018-11-13T20:52:34.091Z,1542142354.091 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-11-13T20:52:34.106Z,1542142354.106 [RudderServo] Loaded
2018-11-13T20:52:34.106Z,1542142354.106 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-11-13T20:52:34.121Z,1542142354.121 [ThrusterServo] Loaded
2018-11-13T20:52:34.121Z,1542142354.121 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-11-13T20:52:34.122Z,1542142354.122 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-11-13T20:52:34.122Z,1542142354.122 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-11-13T20:52:34.227Z,1542142354.227 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-11-13T20:52:34.227Z,1542142354.227 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-11-13T20:52:34.283Z,1542142354.283 [DeadReckonUsingSpeedCalculator] Loaded
2018-11-13T20:52:34.284Z,1542142354.284 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-11-13T20:52:34.300Z,1542142354.300 [NavChart] Loaded
2018-11-13T20:52:34.301Z,1542142354.301 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-11-13T20:52:34.305Z,1542142354.305 [UniversalFixResidualReporter] Loaded
2018-11-13T20:52:34.305Z,1542142354.305 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-11-13T20:52:34.305Z,1542142354.305 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-11-13T20:52:34.306Z,1542142354.306 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-11-13T20:52:34.320Z,1542142354.320 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-11-13T20:52:34.321Z,1542142354.321 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-11-13T20:52:34.583Z,1542142354.583 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-11-13T20:52:34.583Z,1542142354.583 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-11-13T20:52:34.599Z,1542142354.599 [Module Loader](ERROR): Cannot load library: Modules/Sensor.so: undefined symbol: _ZN16CalibrateSparton14CreateBehaviorERK3StrPK6Module
2018-11-13T20:52:34.600Z,1542142354.600 [Module Loader](ERROR): Could not load the module at Modules/Sensor.so
2018-11-13T20:52:34.600Z,1542142354.600 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-11-13T20:52:34.674Z,1542142354.674 [VerticalControl](DEBUG): Construct VerticalControl.
2018-11-13T20:52:34.764Z,1542142354.764 [VerticalControl] Loaded
2018-11-13T20:52:34.764Z,1542142354.764 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-11-13T20:52:34.765Z,1542142354.765 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-11-13T20:52:34.824Z,1542142354.824 [HorizontalControl] Loaded
2018-11-13T20:52:34.824Z,1542142354.824 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-11-13T20:52:34.825Z,1542142354.825 [SpeedControl](DEBUG): Construct SpeedControl.
2018-11-13T20:52:34.828Z,1542142354.828 [SpeedControl] Loaded
2018-11-13T20:52:34.828Z,1542142354.828 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-11-13T20:52:34.829Z,1542142354.829 [LoopControl](DEBUG): Construct LoopControl.
2018-11-13T20:52:34.838Z,1542142354.838 [LoopControl] Loaded
2018-11-13T20:52:34.839Z,1542142354.839 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-11-13T20:52:34.840Z,1542142354.840 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-11-13T20:52:34.840Z,1542142354.840 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-11-13T20:52:34.950Z,1542142354.950 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-11-13T20:52:34.950Z,1542142354.950 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-11-13T20:52:34.971Z,1542142354.971 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-11-13T20:52:34.972Z,1542142354.972 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-11-13T20:52:35.043Z,1542142355.043 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-11-13T20:52:35.044Z,1542142355.044 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-11-13T20:52:35.088Z,1542142355.088 [DepthRateCalculator] Loaded
2018-11-13T20:52:35.089Z,1542142355.089 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-11-13T20:52:35.094Z,1542142355.094 [PitchRateCalculator] Loaded
2018-11-13T20:52:35.095Z,1542142355.095 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-11-13T20:52:35.107Z,1542142355.107 [SpeedCalculator] Loaded
2018-11-13T20:52:35.107Z,1542142355.107 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-11-13T20:52:35.129Z,1542142355.129 [TempGradientCalculator] Loaded
2018-11-13T20:52:35.130Z,1542142355.130 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-11-13T20:52:35.135Z,1542142355.135 [YawRateCalculator] Loaded
2018-11-13T20:52:35.135Z,1542142355.135 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-11-13T20:52:35.165Z,1542142355.165 [ElevatorOffsetCalculator] Loaded
2018-11-13T20:52:35.166Z,1542142355.166 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-11-13T20:52:35.166Z,1542142355.166 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-11-13T20:52:35.167Z,1542142355.167 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-11-13T20:52:35.193Z,1542142355.193 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-11-13T20:52:35.196Z,1542142355.196 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-11-13T20:52:35.198Z,1542142355.198 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-11-13T20:52:35.204Z,1542142355.204 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-11-13T20:52:35.205Z,1542142355.205 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 406F84E0
2018-11-13T20:52:35.206Z,1542142355.206 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6544
2018-11-13T20:52:35.211Z,1542142355.211 [Supervisor](INFO): Main Thread ID is 6460
2018-11-13T20:52:35.211Z,1542142355.211 [Supervisor](DEBUG): Running supervisor.
2018-11-13T20:52:35.211Z,1542142355.211 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6545
2018-11-13T20:52:35.214Z,1542142355.214 [controlThread ThreadHandler](INFO): Handler Thread ID is 6546
2018-11-13T20:52:35.214Z,1542142355.214 [controlThread](DEBUG): Initializing ControlThread
2018-11-13T20:52:35.215Z,1542142355.215 [SBIT](INFO): Initialize SBIT Component.
2018-11-13T20:52:35.216Z,1542142355.216 [SBIT](IMPORTANT): git: 2018-11-05
2018-11-13T20:52:35.216Z,1542142355.216 [SBIT](INFO): git hash: 17f8e4440ddc7f0910aa911cb821ed1a114d9cee
2018-11-13T20:52:35.216Z,1542142355.216 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-11-13T20:52:35.217Z,1542142355.217 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-11-13T20:52:35.218Z,1542142355.218 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2018-11-13T20:52:35.218Z,1542142355.218 [IBIT](INFO): Initialize IBIT Component.
2018-11-13T20:52:35.219Z,1542142355.219 [CBIT](DEBUG): Initialize CBIT Component.
2018-11-13T20:52:35.219Z,1542142355.219 [CBIT](DEBUG): Initializing mux pins.
2018-11-13T20:52:35.219Z,1542142355.219 [CBIT](DEBUG): Initializing the watchdog timer.
2018-11-13T20:52:35.220Z,1542142355.220 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-11-13T20:52:35.221Z,1542142355.221 [CBIT](INFO): Reporting lpc3250 register adresses:
LPC3Reg::TimClkCtrl_ 0x400200bc
LPC3Reg::WDTimCtrl_: 0x4041e004
LPC3Reg::WDTimMCtrl_: 0x4041e00c
LPC3Reg::WDTimEMR_: 0x4041e014
2018-11-13T20:52:35.221Z,1542142355.221 [CBIT](DEBUG): Initializing heartbeat.
2018-11-13T20:52:35.222Z,1542142355.222 [logger ThreadHandler](INFO): Handler Thread ID is 6547
2018-11-13T20:52:35.249Z,1542142355.249 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6548
2018-11-13T20:52:35.252Z,1542142355.252 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-11-13T20:52:35.253Z,1542142355.253 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-11-13T20:52:35.253Z,1542142355.253 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-11-13T20:52:35.253Z,1542142355.253 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-11-13T20:52:35.253Z,1542142355.253 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-11-13T20:52:35.253Z,1542142355.253 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-11-13T20:52:35.254Z,1542142355.254 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-11-13T20:52:35.254Z,1542142355.254 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-11-13T20:52:35.254Z,1542142355.254 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-11-13T20:52:35.254Z,1542142355.254 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-11-13T20:52:35.254Z,1542142355.254 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-11-13T20:52:35.255Z,1542142355.255 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-11-13T20:52:35.255Z,1542142355.255 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-11-13T20:52:35.255Z,1542142355.255 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-11-13T20:52:35.255Z,1542142355.255 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-11-13T20:52:35.255Z,1542142355.255 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-11-13T20:52:35.293Z,1542142355.293 [CBIT](DEBUG): Deactivating GF circuits.
2018-11-13T20:52:35.293Z,1542142355.293 [CBIT](DEBUG): Deactivating emergency mode.
2018-11-13T20:52:35.330Z,1542142355.330 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-11-13T20:52:35.331Z,1542142355.331 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-11-13T20:52:35.331Z,1542142355.331 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-11-13T20:52:35.331Z,1542142355.331 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-13T20:52:35.332Z,1542142355.332 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-13T20:52:35.332Z,1542142355.332 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-13T20:52:35.333Z,1542142355.333 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-11-13T20:52:35.333Z,1542142355.333 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-11-13T20:52:35.333Z,1542142355.333 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-11-13T20:52:35.335Z,1542142355.335 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-11-13T20:52:35.336Z,1542142355.336 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-11-13T20:52:35.336Z,1542142355.336 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-11-13T20:52:35.336Z,1542142355.336 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-11-13T20:52:35.337Z,1542142355.337 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-11-13T20:52:35.337Z,1542142355.337 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-11-13T20:52:35.338Z,1542142355.338 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-11-13T20:52:35.338Z,1542142355.338 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-11-13T20:52:35.338Z,1542142355.338 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-11-13T20:52:35.340Z,1542142355.340 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-11-13T20:52:35.351Z,1542142355.351 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-11-13T20:52:35.395Z,1542142355.395 [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>


2018-11-13T20:52:35.395Z,1542142355.395 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-11-13T20:52:35.471Z,1542142355.471 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-11-13T20:52:35.472Z,1542142355.472 [Default:A.Wait](DEBUG): Construct Wait.
2018-11-13T20:52:35.490Z,1542142355.490 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-11-13T20:52:35.529Z,1542142355.529 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-11-13T20:52:35.532Z,1542142355.532 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-11-13T20:52:35.552Z,1542142355.552 [Default:E.Execute](DEBUG): Construct Execute.
2018-11-13T20:52:35.567Z,1542142355.567 [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>


2018-11-13T20:52:35.576Z,1542142355.576 [controlThread](DEBUG): Component order: CycleStarter,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,
2018-11-13T20:52:35.600Z,1542142355.600 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:35.842Z,1542142355.842 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-11-13T20:52:35.849Z,1542142355.849 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-11-13T20:52:35.856Z,1542142355.856 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-11-13T20:52:35.865Z,1542142355.865 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-11-13T20:52:35.879Z,1542142355.879 [MassServo](DEBUG): Initializing EZServoServo.
2018-11-13T20:52:35.885Z,1542142355.885 [MassServo](DEBUG): Initializing MassServo.
2018-11-13T20:52:35.899Z,1542142355.899 [RudderServo](DEBUG): Initializing EZServoServo.
2018-11-13T20:52:35.905Z,1542142355.905 [RudderServo](DEBUG): Initializing RudderServo.
2018-11-13T20:52:35.921Z,1542142355.921 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-11-13T20:52:35.929Z,1542142355.929 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-11-13T20:52:36.071Z,1542142356.071 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:36.418Z,1542142356.418 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:36.818Z,1542142356.818 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:37.218Z,1542142357.218 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:37.618Z,1542142357.618 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:38.018Z,1542142358.018 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:38.418Z,1542142358.418 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:38.818Z,1542142358.818 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:39.218Z,1542142359.218 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:39.618Z,1542142359.618 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:40.018Z,1542142360.018 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:40.418Z,1542142360.418 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:40.818Z,1542142360.818 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:41.218Z,1542142361.218 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:41.618Z,1542142361.618 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:42.018Z,1542142362.018 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:42.418Z,1542142362.418 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:42.818Z,1542142362.818 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:43.218Z,1542142363.218 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:43.618Z,1542142363.618 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:44.018Z,1542142364.018 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:44.418Z,1542142364.418 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:44.818Z,1542142364.818 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:45.218Z,1542142365.218 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:45.258Z,1542142365.258 [CBIT](CRITICAL): Environmental Failure. Press:0.000000 PSI. Humidity:2139208%. Temp:3383368 C. ABORTING MISSION
2018-11-13T20:52:45.258Z,1542142365.258 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals
2018-11-13T20:52:45.618Z,1542142365.618 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:45.656Z,1542142365.656 [CommandLine](FAULT): Scheduling is paused
2018-11-13T20:52:45.657Z,1542142365.657 [CBIT](INFO): Critical error at 20181113T205245
2018-11-13T20:52:46.018Z,1542142366.018 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:46.418Z,1542142366.418 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:46.818Z,1542142366.818 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:47.218Z,1542142367.218 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:47.618Z,1542142367.618 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:48.018Z,1542142368.018 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:48.418Z,1542142368.418 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:48.818Z,1542142368.818 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:49.218Z,1542142369.218 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:49.618Z,1542142369.618 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:50.018Z,1542142370.018 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:50.418Z,1542142370.418 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:50.818Z,1542142370.818 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:51.218Z,1542142371.218 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:51.618Z,1542142371.618 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:52.018Z,1542142372.018 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:52.418Z,1542142372.418 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:52.818Z,1542142372.818 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:53.218Z,1542142373.218 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:53.618Z,1542142373.618 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:54.018Z,1542142374.018 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:54.418Z,1542142374.418 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:54.818Z,1542142374.818 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:55.218Z,1542142375.218 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:55.618Z,1542142375.618 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:56.018Z,1542142376.018 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:56.418Z,1542142376.418 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:56.818Z,1542142376.818 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:57.218Z,1542142377.218 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:57.618Z,1542142377.618 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:58.017Z,1542142378.017 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:58.417Z,1542142378.417 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:58.621Z,1542142378.621 [CommandLine](IMPORTANT): got command quit
2018-11-13T20:52:58.817Z,1542142378.817 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:59.217Z,1542142379.217 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:59.617Z,1542142379.617 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:52:59.627Z,1542142379.627 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-13T20:52:59.628Z,1542142379.628 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-13T20:52:59.801Z,1542142379.801 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-11-13T20:52:59.801Z,1542142379.801 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-13T20:52:59.802Z,1542142379.802 [CommandLine](INFO): Join timeout helper Thread ID is 6554
2018-11-13T20:52:59.813Z,1542142379.813 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-11-13T20:52:59.813Z,1542142379.813 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-13T20:52:59.813Z,1542142379.813 [NavChartDb](INFO): Join timeout helper Thread ID is 6555
2018-11-13T20:53:00.017Z,1542142380.017 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-13T20:53:00.159Z,1542142380.159 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-13T20:53:00.160Z,1542142380.160 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-13T20:53:00.165Z,1542142380.165 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-11-13T20:53:00.165Z,1542142380.165 [logger ThreadHandler](INFO): Thread cancelled.
2018-11-13T20:53:00.165Z,1542142380.165 [logger](INFO): Join timeout helper Thread ID is 6556
2018-11-13T20:53:00.189Z,1542142380.189 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-13T20:53:00.189Z,1542142380.189 [logger ThreadHandler](INFO): Thread cancelled.
2018-11-13T20:53:00.205Z,1542142380.205 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-11-13T20:53:00.205Z,1542142380.205 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-13T20:53:00.205Z,1542142380.205 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-11-13T20:53:00.205Z,1542142380.205 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-11-13T20:53:00.205Z,1542142380.205 [controlThread](INFO): Join timeout helper Thread ID is 6557
2018-11-13T20:53:00.413Z,1542142380.413 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-13T20:53:00.413Z,1542142380.413 [controlThread](DEBUG): Uninitializing ControlThread
2018-11-13T20:53:00.414Z,1542142380.414 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-11-13T20:53:00.414Z,1542142380.414 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-11-13T20:53:00.415Z,1542142380.415 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-11-13T20:53:00.416Z,1542142380.416 [MissionManager](INFO): Uninitializing Mission Default
2018-11-13T20:53:00.418Z,1542142380.418 [MissionManager](INFO): Uninitializing Mission Startup
2018-11-13T20:53:00.419Z,1542142380.419 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-11-13T20:53:00.420Z,1542142380.420 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-11-13T20:53:00.420Z,1542142380.420 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-11-13T20:53:00.420Z,1542142380.420 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-11-13T20:53:00.420Z,1542142380.420 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-11-13T20:53:00.421Z,1542142380.421 [BuoyancyServo](INFO): Powering down
2018-11-13T20:53:00.433Z,1542142380.433 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-11-13T20:53:00.433Z,1542142380.433 [ElevatorServo](INFO): Powering down
2018-11-13T20:53:00.452Z,1542142380.452 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-11-13T20:53:00.452Z,1542142380.452 [MassServo](INFO): Powering down
2018-11-13T20:53:00.453Z,1542142380.453 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-11-13T20:53:00.453Z,1542142380.453 [RudderServo](INFO): Powering down
2018-11-13T20:53:00.454Z,1542142380.454 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-11-13T20:53:00.454Z,1542142380.454 [ThrusterServo](INFO): Powering down
2018-11-13T20:53:00.455Z,1542142380.455 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-11-13T20:53:00.455Z,1542142380.455 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-11-13T20:53:00.455Z,1542142380.455 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-11-13T20:53:00.455Z,1542142380.455 [CBIT](DEBUG): Powering off loads.
2018-11-13T20:53:00.456Z,1542142380.456 [CBIT](DEBUG): Disabling WDT.
2018-11-13T20:53:00.456Z,1542142380.456 [CBIT](DEBUG): Opening all GF detection circuits.
2018-11-13T20:53:00.456Z,1542142380.456 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-11-13T20:53:00.645Z,1542142380.645 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-13T20:53:00.694Z,1542142380.694 [logger ThreadHandler](INFO): Thread cancelled.