2018-12-18T22:40:56.693Z,1545172856.693 [Supervisor](DEBUG): Initializing supervisor.
2018-12-18T22:40:56.699Z,1545172856.699 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-12-18T22:40:56.700Z,1545172856.700 [SyncHandler](INFO): Protected caller Thread ID is 804
2018-12-18T22:40:56.701Z,1545172856.701 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-12-18T22:40:56.703Z,1545172856.703 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-12-18T22:40:56.704Z,1545172856.704 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 805
2018-12-18T22:40:56.710Z,1545172856.710 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-12-18T22:40:56.723Z,1545172856.723 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-12-18T22:40:56.724Z,1545172856.724 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-12-18T22:40:56.725Z,1545172856.725 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 806
2018-12-18T22:40:56.727Z,1545172856.727 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-12-18T22:40:56.729Z,1545172856.729 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-12-18T22:40:56.730Z,1545172856.730 [logger ThreadHandler](INFO): Protected caller Thread ID is 807
2018-12-18T22:40:56.734Z,1545172856.734 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-12-18T22:40:56.735Z,1545172856.735 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-12-18T22:40:56.740Z,1545172856.740 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-12-18T22:40:56.847Z,1545172856.847 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-12-18T22:40:56.849Z,1545172856.849 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-12-18T22:40:56.956Z,1545172856.956 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-12-18T22:40:56.958Z,1545172856.958 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-12-18T22:40:57.181Z,1545172857.181 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-12-18T22:40:57.183Z,1545172857.183 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-12-18T22:40:57.577Z,1545172857.577 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-12-18T22:40:57.578Z,1545172857.578 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-12-18T22:40:57.935Z,1545172857.935 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-12-18T22:40:57.936Z,1545172857.936 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-12-18T22:40:58.461Z,1545172858.461 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-12-18T22:40:58.463Z,1545172858.463 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-12-18T22:40:58.976Z,1545172858.976 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-12-18T22:40:58.977Z,1545172858.977 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-12-18T22:40:59.509Z,1545172859.509 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-12-18T22:40:59.511Z,1545172859.511 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-12-18T22:40:59.624Z,1545172859.624 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-12-18T22:40:59.974Z,1545172859.974 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-12-18T22:40:59.976Z,1545172859.976 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-12-18T22:41:00.149Z,1545172860.149 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-12-18T22:41:00.150Z,1545172860.150 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-12-18T22:41:00.408Z,1545172860.408 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-12-18T22:41:00.408Z,1545172860.408 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-12-18T22:41:00.632Z,1545172860.632 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-12-18T22:41:00.632Z,1545172860.632 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-12-18T22:41:00.751Z,1545172860.751 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-12-18T22:41:00.752Z,1545172860.752 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-12-18T22:41:00.910Z,1545172860.910 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-12-18T22:41:00.912Z,1545172860.912 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-12-18T22:41:00.914Z,1545172860.914 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-12-18T22:41:01.188Z,1545172861.188 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-12-18T22:41:01.190Z,1545172861.190 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-12-18T22:41:01.308Z,1545172861.308 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-12-18T22:41:01.486Z,1545172861.486 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-12-18T22:41:01.599Z,1545172861.599 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-12-18T22:41:01.692Z,1545172861.692 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-12-18T22:41:01.861Z,1545172861.861 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-12-18T22:41:02.087Z,1545172862.087 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-12-18T22:41:02.195Z,1545172862.195 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-12-18T22:41:02.297Z,1545172862.297 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-12-18T22:41:02.403Z,1545172862.403 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-12-18T22:41:02.488Z,1545172862.488 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-12-18T22:41:02.498Z,1545172862.498 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-12-18T22:41:02.640Z,1545172862.640 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-12-18T22:41:02.640Z,1545172862.640 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-12-18T22:41:02.665Z,1545172862.665 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-12-18T22:41:02.665Z,1545172862.665 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-12-18T22:41:02.726Z,1545172862.726 [DepthRateCalculator] Loaded
2018-12-18T22:41:02.726Z,1545172862.726 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-12-18T22:41:02.737Z,1545172862.737 [PitchRateCalculator] Loaded
2018-12-18T22:41:02.738Z,1545172862.738 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-12-18T22:41:02.763Z,1545172862.763 [SpeedCalculator] Loaded
2018-12-18T22:41:02.763Z,1545172862.763 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-12-18T22:41:02.786Z,1545172862.786 [TempGradientCalculator] Loaded
2018-12-18T22:41:02.786Z,1545172862.786 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-12-18T22:41:02.792Z,1545172862.792 [YawRateCalculator] Loaded
2018-12-18T22:41:02.792Z,1545172862.792 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-12-18T22:41:02.834Z,1545172862.834 [ElevatorOffsetCalculator] Loaded
2018-12-18T22:41:02.834Z,1545172862.834 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-12-18T22:41:02.834Z,1545172862.834 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-12-18T22:41:02.835Z,1545172862.835 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-12-18T22:41:02.882Z,1545172862.882 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-12-18T22:41:02.883Z,1545172862.883 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-12-18T22:41:03.094Z,1545172863.094 [Module Loader](ERROR): Cannot load library: libboost_system.so.1.63.0: cannot open shared object file: No such file or directory
2018-12-18T22:41:03.094Z,1545172863.094 [Module Loader](ERROR): Could not load the module at Modules/Sensor.so
2018-12-18T22:41:03.096Z,1545172863.096 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-12-18T22:41:03.266Z,1545172863.266 [SBIT](DEBUG): Construct Startup Built In Test.
2018-12-18T22:41:03.296Z,1545172863.296 [SBIT] Loaded
2018-12-18T22:41:03.296Z,1545172863.296 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-12-18T22:41:03.297Z,1545172863.297 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-12-18T22:41:03.347Z,1545172863.347 [IBIT] Loaded
2018-12-18T22:41:03.347Z,1545172863.347 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-12-18T22:41:03.350Z,1545172863.350 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-12-18T22:41:03.789Z,1545172863.789 [CBIT] Loaded
2018-12-18T22:41:03.789Z,1545172863.789 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-12-18T22:41:03.790Z,1545172863.790 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-12-18T22:41:03.790Z,1545172863.790 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-12-18T22:41:04.121Z,1545172864.121 [CTD_Seabird] Loaded
2018-12-18T22:41:04.121Z,1545172864.121 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-12-18T22:41:04.122Z,1545172864.122 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4064D4E0
2018-12-18T22:41:04.123Z,1545172864.123 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 886
2018-12-18T22:41:04.140Z,1545172864.140 [ESPComponent] Loaded
2018-12-18T22:41:04.140Z,1545172864.140 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-12-18T22:41:04.153Z,1545172864.153 [PAR_Licor] Loaded
2018-12-18T22:41:04.154Z,1545172864.154 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-12-18T22:41:04.202Z,1545172864.202 [WetLabsBB2FL] Loaded
2018-12-18T22:41:04.202Z,1545172864.202 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-12-18T22:41:04.203Z,1545172864.203 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4067D4E0
2018-12-18T22:41:04.203Z,1545172864.203 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 887
2018-12-18T22:41:04.204Z,1545172864.204 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-12-18T22:41:04.205Z,1545172864.205 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-12-18T22:41:04.487Z,1545172864.487 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-12-18T22:41:04.488Z,1545172864.488 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-12-18T22:41:04.574Z,1545172864.574 [VerticalControl](DEBUG): Construct VerticalControl.
2018-12-18T22:41:04.666Z,1545172864.666 [VerticalControl] Loaded
2018-12-18T22:41:04.666Z,1545172864.666 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-12-18T22:41:04.667Z,1545172864.667 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-12-18T22:41:04.726Z,1545172864.726 [HorizontalControl] Loaded
2018-12-18T22:41:04.726Z,1545172864.726 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-12-18T22:41:04.726Z,1545172864.726 [SpeedControl](DEBUG): Construct SpeedControl.
2018-12-18T22:41:04.732Z,1545172864.732 [SpeedControl] Loaded
2018-12-18T22:41:04.732Z,1545172864.732 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-12-18T22:41:04.733Z,1545172864.733 [LoopControl](DEBUG): Construct LoopControl.
2018-12-18T22:41:04.733Z,1545172864.733 [LoopControl] Loaded
2018-12-18T22:41:04.733Z,1545172864.733 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-12-18T22:41:04.734Z,1545172864.734 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-12-18T22:41:04.735Z,1545172864.735 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-12-18T22:41:04.837Z,1545172864.837 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-12-18T22:41:04.838Z,1545172864.838 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-12-18T22:41:04.955Z,1545172864.955 [BuoyancyServo] Loaded
2018-12-18T22:41:04.956Z,1545172864.956 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-12-18T22:41:04.967Z,1545172864.967 [ElevatorServo] Loaded
2018-12-18T22:41:04.967Z,1545172864.967 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-12-18T22:41:04.978Z,1545172864.978 [MassServo] Loaded
2018-12-18T22:41:04.978Z,1545172864.978 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-12-18T22:41:04.990Z,1545172864.990 [RudderServo] Loaded
2018-12-18T22:41:04.990Z,1545172864.990 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-12-18T22:41:05.000Z,1545172865.000 [ThrusterServo] Loaded
2018-12-18T22:41:05.000Z,1545172865.000 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-12-18T22:41:05.001Z,1545172865.001 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-12-18T22:41:05.002Z,1545172865.002 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-12-18T22:41:05.282Z,1545172865.282 [DeadReckonUsingSpeedCalculator] Loaded
2018-12-18T22:41:05.283Z,1545172865.283 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-12-18T22:41:05.322Z,1545172865.322 [NavChart] Loaded
2018-12-18T22:41:05.323Z,1545172865.323 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-12-18T22:41:05.326Z,1545172865.326 [UniversalFixResidualReporter] Loaded
2018-12-18T22:41:05.327Z,1545172865.327 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-12-18T22:41:05.327Z,1545172865.327 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-12-18T22:41:05.331Z,1545172865.331 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-12-18T22:41:05.331Z,1545172865.331 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-12-18T22:41:05.338Z,1545172865.338 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-12-18T22:41:05.350Z,1545172865.350 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407D74E0
2018-12-18T22:41:05.350Z,1545172865.350 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 888
2018-12-18T22:41:05.561Z,1545172865.561 [Supervisor](INFO): Main Thread ID is 800
2018-12-18T22:41:05.561Z,1545172865.561 [Supervisor](DEBUG): Running supervisor.
2018-12-18T22:41:05.561Z,1545172865.561 [CommandLine ThreadHandler](INFO): Handler Thread ID is 889
2018-12-18T22:41:05.564Z,1545172865.564 [controlThread ThreadHandler](INFO): Handler Thread ID is 890
2018-12-18T22:41:05.564Z,1545172865.564 [controlThread](DEBUG): Initializing ControlThread
2018-12-18T22:41:05.565Z,1545172865.565 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-12-18T22:41:05.566Z,1545172865.566 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-12-18T22:41:05.566Z,1545172865.566 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-12-18T22:41:05.566Z,1545172865.566 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-12-18T22:41:05.567Z,1545172865.567 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-12-18T22:41:05.567Z,1545172865.567 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-12-18T22:41:05.568Z,1545172865.568 [SBIT](INFO): Initialize SBIT Component.
2018-12-18T22:41:05.568Z,1545172865.568 [SBIT](IMPORTANT): git: 2018-12-13
2018-12-18T22:41:05.568Z,1545172865.568 [SBIT](INFO): git hash: 8200dc43e6ef01082c531ff0fab84136a371dc2d
2018-12-18T22:41:05.569Z,1545172865.569 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-12-18T22:41:05.570Z,1545172865.570 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #7 PREEMPT Mon Dec 17 14:09:40 PST 2018
2018-12-18T22:41:05.571Z,1545172865.571 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2018-12-18T22:41:05.571Z,1545172865.571 [IBIT](INFO): Initialize IBIT Component.
2018-12-18T22:41:05.572Z,1545172865.572 [CBIT](DEBUG): Initialize CBIT Component.
2018-12-18T22:41:05.573Z,1545172865.573 [logger ThreadHandler](INFO): Handler Thread ID is 891
2018-12-18T22:41:05.585Z,1545172865.585 [CBIT](DEBUG): Initialized mux pins.
2018-12-18T22:41:05.585Z,1545172865.585 [CBIT](DEBUG): Initializing the watchdog timer.
2018-12-18T22:41:05.594Z,1545172865.594 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 892
2018-12-18T22:41:05.594Z,1545172865.594 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-18T22:41:05.598Z,1545172865.598 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-18T22:41:05.599Z,1545172865.599 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 894
2018-12-18T22:41:05.600Z,1545172865.600 [WetLabsBB2FL](INFO): Powering down
2018-12-18T22:41:05.613Z,1545172865.613 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-12-18T22:41:05.613Z,1545172865.613 [CBIT](DEBUG): Initializing heartbeat.
2018-12-18T22:41:05.662Z,1545172865.662 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 895
2018-12-18T22:41:05.669Z,1545172865.669 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-12-18T22:41:05.669Z,1545172865.669 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-12-18T22:41:05.670Z,1545172865.670 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-12-18T22:41:05.670Z,1545172865.670 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-12-18T22:41:05.670Z,1545172865.670 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-12-18T22:41:05.670Z,1545172865.670 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-12-18T22:41:05.670Z,1545172865.670 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-12-18T22:41:05.670Z,1545172865.670 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-12-18T22:41:05.671Z,1545172865.671 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-12-18T22:41:05.671Z,1545172865.671 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-12-18T22:41:05.671Z,1545172865.671 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-12-18T22:41:05.671Z,1545172865.671 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-12-18T22:41:05.671Z,1545172865.671 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-12-18T22:41:05.671Z,1545172865.671 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-12-18T22:41:05.672Z,1545172865.672 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-12-18T22:41:05.672Z,1545172865.672 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-12-18T22:41:05.685Z,1545172865.685 [CBIT](DEBUG): Deactivating GF circuits.
2018-12-18T22:41:05.685Z,1545172865.685 [CBIT](DEBUG): Deactivating emergency mode.
2018-12-18T22:41:05.726Z,1545172865.726 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-12-18T22:41:05.727Z,1545172865.727 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-12-18T22:41:05.728Z,1545172865.728 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-12-18T22:41:05.728Z,1545172865.728 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-12-18T22:41:05.730Z,1545172865.730 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-12-18T22:41:05.731Z,1545172865.731 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-12-18T22:41:05.731Z,1545172865.731 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-12-18T22:41:05.732Z,1545172865.732 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-12-18T22:41:05.732Z,1545172865.732 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-12-18T22:41:05.733Z,1545172865.733 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-12-18T22:41:05.786Z,1545172865.786 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-12-18T22:41:05.850Z,1545172865.850 [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-12-18T22:41:05.850Z,1545172865.850 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-12-18T22:41:05.985Z,1545172865.985 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-12-18T22:41:05.986Z,1545172865.986 [Default:A.Wait](DEBUG): Construct Wait.
2018-12-18T22:41:05.988Z,1545172865.988 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-12-18T22:41:06.031Z,1545172866.031 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-12-18T22:41:06.058Z,1545172866.058 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-12-18T22:41:06.088Z,1545172866.088 [Default:E.Execute](DEBUG): Construct Execute.
2018-12-18T22:41:06.091Z,1545172866.091 [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-12-18T22:41:06.100Z,1545172866.100 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-12-18T22:41:06.162Z,1545172866.162 [ESPComponent](INFO): powering down ESP
2018-12-18T22:41:06.582Z,1545172866.582 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:06.990Z,1545172866.990 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-12-18T22:41:07.001Z,1545172867.001 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-12-18T22:41:07.027Z,1545172867.027 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-12-18T22:41:07.037Z,1545172867.037 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-12-18T22:41:07.055Z,1545172867.055 [MassServo](DEBUG): Initializing EZServoServo.
2018-12-18T22:41:07.066Z,1545172867.066 [MassServo](DEBUG): Initializing MassServo.
2018-12-18T22:41:07.091Z,1545172867.091 [RudderServo](DEBUG): Initializing EZServoServo.
2018-12-18T22:41:07.101Z,1545172867.101 [RudderServo](DEBUG): Initializing RudderServo.
2018-12-18T22:41:07.107Z,1545172867.107 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-12-18T22:41:07.125Z,1545172867.125 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-12-18T22:41:07.383Z,1545172867.383 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:07.654Z,1545172867.654 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:08.001Z,1545172868.001 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-12-18T22:41:08.001Z,1545172868.001 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-12-18T22:41:08.002Z,1545172868.002 [BuoyancyServo] Communications Fault, FailCount= 1
2018-12-18T22:41:08.002Z,1545172868.002 [BuoyancyServo](ERROR): Communications Fault
2018-12-18T22:41:08.221Z,1545172868.221 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-12-18T22:41:08.246Z,1545172868.246 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:08.638Z,1545172868.638 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:09.038Z,1545172869.038 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:09.438Z,1545172869.438 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:09.838Z,1545172869.838 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:10.238Z,1545172870.238 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:10.286Z,1545172870.286 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-12-18T22:41:10.286Z,1545172870.286 [BuoyancyServo] No Fault, FailCount= 1
2018-12-18T22:41:10.638Z,1545172870.638 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:11.050Z,1545172871.050 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:11.438Z,1545172871.438 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:11.838Z,1545172871.838 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:12.238Z,1545172872.238 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:12.262Z,1545172872.262 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-12-18T22:41:12.382Z,1545172872.382 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-12-18T22:41:12.638Z,1545172872.638 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:13.037Z,1545172873.037 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:13.438Z,1545172873.438 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:13.837Z,1545172873.837 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:14.237Z,1545172874.237 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:14.637Z,1545172874.637 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:15.037Z,1545172875.037 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:15.437Z,1545172875.437 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:15.837Z,1545172875.837 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:15.878Z,1545172875.878 [CBIT](CRITICAL): Environmental Failure. Press:0.000000 PSI. Humidity:2143304%. Temp:3538824 C. ABORTING MISSION
2018-12-18T22:41:15.878Z,1545172875.878 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals
2018-12-18T22:41:16.238Z,1545172876.238 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:16.276Z,1545172876.276 [CommandLine](FAULT): Scheduling is paused
2018-12-18T22:41:16.276Z,1545172876.276 [CBIT](INFO): Critical error at 20181218T224115
2018-12-18T22:41:16.638Z,1545172876.638 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:17.038Z,1545172877.038 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:17.438Z,1545172877.438 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:17.838Z,1545172877.838 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:18.238Z,1545172878.238 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:18.638Z,1545172878.638 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:19.037Z,1545172879.037 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:19.438Z,1545172879.438 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:19.657Z,1545172879.657 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-18T22:41:19.657Z,1545172879.657 [CTD_Seabird] Communications Fault, FailCount= 1
2018-12-18T22:41:19.657Z,1545172879.657 [CTD_Seabird](ERROR): Communications Fault
2018-12-18T22:41:19.838Z,1545172879.838 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:19.874Z,1545172879.874 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-18T22:41:20.061Z,1545172880.061 [CTD_Seabird](INFO): Powering down
2018-12-18T22:41:20.237Z,1545172880.237 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:20.637Z,1545172880.637 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:21.037Z,1545172881.037 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:21.437Z,1545172881.437 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:21.837Z,1545172881.837 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:22.238Z,1545172882.238 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:22.274Z,1545172882.274 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-18T22:41:22.274Z,1545172882.274 [CTD_Seabird] No Fault, FailCount= 1
2018-12-18T22:41:22.637Z,1545172882.637 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:23.038Z,1545172883.038 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:23.049Z,1545172883.049 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-18T22:41:23.058Z,1545172883.058 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-18T22:41:23.438Z,1545172883.438 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:23.837Z,1545172883.837 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:24.238Z,1545172884.238 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:24.638Z,1545172884.638 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:25.038Z,1545172885.037 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:25.438Z,1545172885.438 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:25.837Z,1545172885.837 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:26.262Z,1545172886.262 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:26.638Z,1545172886.638 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:27.038Z,1545172887.038 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:27.438Z,1545172887.438 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:27.840Z,1545172887.840 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:28.238Z,1545172888.238 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:28.638Z,1545172888.638 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:29.038Z,1545172889.038 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:29.438Z,1545172889.438 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:29.838Z,1545172889.838 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:30.237Z,1545172890.237 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:30.247Z,1545172890.247 [SBIT](IMPORTANT): Beginning Startup BIT
2018-12-18T22:41:30.251Z,1545172890.251 [CBIT](IMPORTANT): Beginning ground fault scan
2018-12-18T22:41:30.637Z,1545172890.637 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:31.037Z,1545172891.037 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:31.437Z,1545172891.437 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:31.838Z,1545172891.838 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:32.237Z,1545172892.237 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:32.702Z,1545172892.702 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:33.101Z,1545172893.101 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:33.501Z,1545172893.501 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:33.902Z,1545172893.902 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:34.306Z,1545172894.306 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:34.702Z,1545172894.702 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:35.106Z,1545172895.106 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:35.502Z,1545172895.502 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:35.902Z,1545172895.902 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:36.302Z,1545172896.302 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:36.702Z,1545172896.702 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:37.113Z,1545172897.113 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-18T22:41:37.113Z,1545172897.113 [CTD_Seabird] Communications Fault, FailCount= 2
2018-12-18T22:41:37.113Z,1545172897.113 [CTD_Seabird](ERROR): Communications Fault
2018-12-18T22:41:37.114Z,1545172897.114 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:37.175Z,1545172897.175 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-18T22:41:37.502Z,1545172897.502 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:37.517Z,1545172897.517 [CTD_Seabird](INFO): Powering down
2018-12-18T22:41:37.902Z,1545172897.902 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:38.302Z,1545172898.302 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:38.702Z,1545172898.702 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:39.110Z,1545172899.110 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:39.502Z,1545172899.502 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:39.550Z,1545172899.550 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2018-12-18T22:41:39.551Z,1545172899.551 [CTD_Seabird] No Fault, FailCount= 2
2018-12-18T22:41:39.902Z,1545172899.902 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:40.302Z,1545172900.302 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:40.466Z,1545172900.466 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2018-12-18T22:41:40.474Z,1545172900.474 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2018-12-18T22:41:40.702Z,1545172900.702 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:41.102Z,1545172901.102 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:41.182Z,1545172901.182 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005618
CHAN A1 (24V): -0.009924
CHAN A2 (12V): -0.004732
CHAN A3 (5V): -0.002483
CHAN B0 (3.3V): -0.001566
CHAN B1 (3.15aV): -0.000988
CHAN B2 (3.15bV): -0.001208
CHAN B3 (GND): 0.000252
OPEN: 0.003625
Full Scale Calc: 4.765 mA, -1.589 mA
2018-12-18T22:41:41.502Z,1545172901.502 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:41.901Z,1545172901.901 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:42.302Z,1545172902.302 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:42.702Z,1545172902.702 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:43.126Z,1545172903.126 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:43.501Z,1545172903.501 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:43.902Z,1545172903.902 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:44.302Z,1545172904.302 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:44.701Z,1545172904.701 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:45.102Z,1545172905.102 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:45.501Z,1545172905.501 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:46.045Z,1545172906.045 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:46.442Z,1545172906.442 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:46.834Z,1545172906.834 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:47.233Z,1545172907.233 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:47.646Z,1545172907.646 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:48.034Z,1545172908.034 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:48.433Z,1545172908.433 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:48.833Z,1545172908.833 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:49.233Z,1545172909.233 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:49.633Z,1545172909.633 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:50.034Z,1545172910.034 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:50.433Z,1545172910.433 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:50.833Z,1545172910.833 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:51.238Z,1545172911.238 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:51.638Z,1545172911.638 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:52.034Z,1545172912.034 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:52.434Z,1545172912.434 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:52.834Z,1545172912.834 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:53.234Z,1545172913.234 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:53.634Z,1545172913.634 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:54.034Z,1545172914.034 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:54.434Z,1545172914.434 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:54.521Z,1545172914.521 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2018-12-18T22:41:54.521Z,1545172914.521 [CTD_Seabird] Communications Fault, FailCount= 3
2018-12-18T22:41:54.521Z,1545172914.521 [CTD_Seabird](ERROR): Communications Fault
2018-12-18T22:41:54.834Z,1545172914.834 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:54.887Z,1545172914.887 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2018-12-18T22:41:54.887Z,1545172914.887 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2018-12-18T22:41:54.925Z,1545172914.925 [CTD_Seabird](INFO): Powering down
2018-12-18T22:41:55.233Z,1545172915.233 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:55.634Z,1545172915.634 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:56.034Z,1545172916.034 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:56.434Z,1545172916.434 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:56.834Z,1545172916.834 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:57.234Z,1545172917.234 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:57.634Z,1545172917.634 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:58.034Z,1545172918.034 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:58.434Z,1545172918.434 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:58.834Z,1545172918.834 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:59.233Z,1545172919.233 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:41:59.634Z,1545172919.634 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:42:00.034Z,1545172920.034 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:42:00.433Z,1545172920.433 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:42:00.834Z,1545172920.834 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:42:01.234Z,1545172921.234 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:42:01.634Z,1545172921.634 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:42:02.034Z,1545172922.033 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:42:02.434Z,1545172922.434 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:42:02.834Z,1545172922.834 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:42:03.234Z,1545172923.234 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:42:03.578Z,1545172923.578 [CommandLine](IMPORTANT): got command quit
2018-12-18T22:42:03.634Z,1545172923.634 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:42:04.034Z,1545172924.034 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:42:04.434Z,1545172924.434 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:42:04.592Z,1545172924.592 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-18T22:42:04.592Z,1545172924.592 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-12-18T22:42:04.758Z,1545172924.758 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-12-18T22:42:04.758Z,1545172924.758 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-12-18T22:42:04.759Z,1545172924.759 [CommandLine](INFO): Join timeout helper Thread ID is 911
2018-12-18T22:42:04.759Z,1545172924.759 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-12-18T22:42:04.760Z,1545172924.760 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-12-18T22:42:04.760Z,1545172924.760 [NavChartDb](INFO): Join timeout helper Thread ID is 912
2018-12-18T22:42:04.830Z,1545172924.830 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:42:04.881Z,1545172924.881 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-18T22:42:04.882Z,1545172924.882 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-12-18T22:42:04.885Z,1545172924.885 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-12-18T22:42:04.885Z,1545172924.885 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-12-18T22:42:04.886Z,1545172924.886 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 913
2018-12-18T22:42:05.185Z,1545172925.185 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-18T22:42:05.186Z,1545172925.186 [WetLabsBB2FL](INFO): Powering down
2018-12-18T22:42:05.186Z,1545172925.186 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-12-18T22:42:05.205Z,1545172925.205 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-12-18T22:42:05.205Z,1545172925.205 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-12-18T22:42:05.206Z,1545172925.206 [CTD_Seabird](INFO): Join timeout helper Thread ID is 914
2018-12-18T22:42:05.234Z,1545172925.234 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-12-18T22:42:05.549Z,1545172925.549 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-18T22:42:05.549Z,1545172925.549 [CTD_Seabird](INFO): Powering down
2018-12-18T22:42:05.561Z,1545172925.561 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-12-18T22:42:05.565Z,1545172925.565 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-12-18T22:42:05.565Z,1545172925.565 [logger ThreadHandler](INFO): Thread cancelled.
2018-12-18T22:42:05.566Z,1545172925.566 [logger](INFO): Join timeout helper Thread ID is 915
2018-12-18T22:42:05.597Z,1545172925.597 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-18T22:42:05.598Z,1545172925.598 [logger ThreadHandler](INFO): Thread cancelled.
2018-12-18T22:42:05.606Z,1545172925.606 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-12-18T22:42:05.606Z,1545172925.606 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-12-18T22:42:05.606Z,1545172925.606 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-12-18T22:42:05.606Z,1545172925.606 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-12-18T22:42:05.607Z,1545172925.607 [controlThread](INFO): Join timeout helper Thread ID is 916
2018-12-18T22:42:05.621Z,1545172925.621 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-12-18T22:42:05.622Z,1545172925.622 [controlThread](DEBUG): Uninitializing ControlThread
2018-12-18T22:42:05.623Z,1545172925.623 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2018-12-18T22:42:05.624Z,1545172925.624 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-12-18T22:42:05.624Z,1545172925.624 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-12-18T22:42:05.624Z,1545172925.624 [MissionManager](INFO): Uninitializing Mission Default
2018-12-18T22:42:05.627Z,1545172925.627 [MissionManager](INFO): Uninitializing Mission Startup
2018-12-18T22:42:05.628Z,1545172925.628 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-12-18T22:42:05.628Z,1545172925.628 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-12-18T22:42:05.629Z,1545172925.629 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-12-18T22:42:05.629Z,1545172925.629 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-12-18T22:42:05.629Z,1545172925.629 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-12-18T22:42:05.629Z,1545172925.629 [BuoyancyServo](INFO): Powering down
2018-12-18T22:42:05.641Z,1545172925.641 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-12-18T22:42:05.642Z,1545172925.642 [ElevatorServo](INFO): Powering down
2018-12-18T22:42:05.642Z,1545172925.642 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-12-18T22:42:05.642Z,1545172925.642 [MassServo](INFO): Powering down
2018-12-18T22:42:05.643Z,1545172925.643 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-12-18T22:42:05.643Z,1545172925.643 [RudderServo](INFO): Powering down
2018-12-18T22:42:05.644Z,1545172925.644 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-12-18T22:42:05.644Z,1545172925.644 [ThrusterServo](INFO): Powering down
2018-12-18T22:42:05.645Z,1545172925.645 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-12-18T22:42:05.645Z,1545172925.645 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-12-18T22:42:05.645Z,1545172925.645 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-12-18T22:42:05.646Z,1545172925.646 [CBIT](DEBUG): Powering off loads.
2018-12-18T22:42:05.657Z,1545172925.657 [CBIT](DEBUG): Disabling WDT.
2018-12-18T22:42:05.669Z,1545172925.669 [CBIT](DEBUG): Opening all GF detection circuits.
2018-12-18T22:42:05.670Z,1545172925.670 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-12-18T22:42:05.717Z,1545172925.717 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-12-18T22:42:05.722Z,1545172925.722 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-12-18T22:42:05.766Z,1545172925.766 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-12-18T22:42:05.819Z,1545172925.819 [logger ThreadHandler](INFO): Thread cancelled.