2018-09-19T20:14:15.114Z,1537388055.114 [Supervisor](DEBUG): Initializing supervisor.
2018-09-19T20:14:15.134Z,1537388055.134 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-09-19T20:14:15.134Z,1537388055.134 [SyncHandler](INFO): Protected caller Thread ID is 1636
2018-09-19T20:14:15.135Z,1537388055.135 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-09-19T20:14:15.142Z,1537388055.142 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-09-19T20:14:15.143Z,1537388055.143 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1637
2018-09-19T20:14:15.145Z,1537388055.145 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-09-19T20:14:15.180Z,1537388055.180 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-09-19T20:14:15.181Z,1537388055.181 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-09-19T20:14:15.181Z,1537388055.181 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1638
2018-09-19T20:14:15.182Z,1537388055.182 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-09-19T20:14:15.183Z,1537388055.183 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-09-19T20:14:15.183Z,1537388055.183 [logger ThreadHandler](INFO): Protected caller Thread ID is 1639
2018-09-19T20:14:15.185Z,1537388055.185 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-09-19T20:14:15.186Z,1537388055.186 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-09-19T20:14:15.187Z,1537388055.187 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-09-19T20:14:15.375Z,1537388055.375 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-09-19T20:14:15.376Z,1537388055.376 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-09-19T20:14:15.520Z,1537388055.520 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-09-19T20:14:15.520Z,1537388055.520 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-09-19T20:14:15.867Z,1537388055.867 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-09-19T20:14:15.868Z,1537388055.868 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-09-19T20:14:16.006Z,1537388056.006 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-09-19T20:14:16.007Z,1537388056.007 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-09-19T20:14:16.088Z,1537388056.088 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-09-19T20:14:16.290Z,1537388056.290 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-09-19T20:14:16.291Z,1537388056.291 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-09-19T20:14:16.390Z,1537388056.390 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-09-19T20:14:16.390Z,1537388056.390 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-09-19T20:14:16.732Z,1537388056.732 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-09-19T20:14:16.733Z,1537388056.733 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-09-19T20:14:17.189Z,1537388057.189 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-09-19T20:14:17.190Z,1537388057.190 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-09-19T20:14:17.504Z,1537388057.504 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-09-19T20:14:17.504Z,1537388057.504 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-09-19T20:14:18.076Z,1537388058.076 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-09-19T20:14:18.076Z,1537388058.076 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-09-19T20:14:18.284Z,1537388058.284 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-09-19T20:14:18.284Z,1537388058.284 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-09-19T20:14:18.383Z,1537388058.383 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-09-19T20:14:18.384Z,1537388058.384 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-09-19T20:14:18.877Z,1537388058.877 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-09-19T20:14:18.878Z,1537388058.878 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-09-19T20:14:19.035Z,1537388059.035 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-09-19T20:14:19.037Z,1537388059.037 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2018-09-19T20:14:19.037Z,1537388059.037 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2018-09-19T20:14:19.508Z,1537388059.508 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-09-19T20:14:19.509Z,1537388059.509 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2018-09-19T20:14:19.608Z,1537388059.608 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2018-09-19T20:14:19.703Z,1537388059.703 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2018-09-19T20:14:19.806Z,1537388059.806 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2018-09-19T20:14:19.893Z,1537388059.893 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2018-09-19T20:14:20.051Z,1537388060.051 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2018-09-19T20:14:20.391Z,1537388060.391 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2018-09-19T20:14:20.502Z,1537388060.502 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2018-09-19T20:14:20.588Z,1537388060.588 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2018-09-19T20:14:20.672Z,1537388060.672 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2018-09-19T20:14:20.866Z,1537388060.866 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2018-09-19T20:14:20.866Z,1537388060.866 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-09-19T20:14:20.870Z,1537388060.870 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-09-19T20:14:20.997Z,1537388060.997 [SBIT](DEBUG): Construct Startup Built In Test.
2018-09-19T20:14:21.027Z,1537388061.027 [SBIT] Loaded
2018-09-19T20:14:21.028Z,1537388061.028 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-09-19T20:14:21.028Z,1537388061.028 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-09-19T20:14:21.056Z,1537388061.056 [IBIT] Loaded
2018-09-19T20:14:21.056Z,1537388061.056 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-09-19T20:14:21.059Z,1537388061.059 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-09-19T20:14:21.214Z,1537388061.214 [CBIT] Loaded
2018-09-19T20:14:21.214Z,1537388061.214 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-09-19T20:14:21.215Z,1537388061.215 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-09-19T20:14:21.215Z,1537388061.215 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-09-19T20:14:21.283Z,1537388061.283 [VerticalControl](DEBUG): Construct VerticalControl.
2018-09-19T20:14:21.383Z,1537388061.383 [VerticalControl] Loaded
2018-09-19T20:14:21.384Z,1537388061.384 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-09-19T20:14:21.384Z,1537388061.384 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-09-19T20:14:21.445Z,1537388061.445 [HorizontalControl] Loaded
2018-09-19T20:14:21.445Z,1537388061.445 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-09-19T20:14:21.446Z,1537388061.446 [SpeedControl](DEBUG): Construct SpeedControl.
2018-09-19T20:14:21.452Z,1537388061.452 [SpeedControl] Loaded
2018-09-19T20:14:21.452Z,1537388061.452 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-09-19T20:14:21.453Z,1537388061.453 [LoopControl](DEBUG): Construct LoopControl.
2018-09-19T20:14:21.453Z,1537388061.453 [LoopControl] Loaded
2018-09-19T20:14:21.453Z,1537388061.453 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-09-19T20:14:21.454Z,1537388061.454 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-09-19T20:14:21.455Z,1537388061.455 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-09-19T20:14:21.495Z,1537388061.495 [DepthRateCalculator] Loaded
2018-09-19T20:14:21.495Z,1537388061.495 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-09-19T20:14:21.501Z,1537388061.501 [PitchRateCalculator] Loaded
2018-09-19T20:14:21.501Z,1537388061.501 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-09-19T20:14:21.517Z,1537388061.517 [SpeedCalculator] Loaded
2018-09-19T20:14:21.517Z,1537388061.517 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-09-19T20:14:21.538Z,1537388061.538 [TempGradientCalculator] Loaded
2018-09-19T20:14:21.538Z,1537388061.538 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-09-19T20:14:21.543Z,1537388061.543 [YawRateCalculator] Loaded
2018-09-19T20:14:21.544Z,1537388061.544 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-09-19T20:14:21.573Z,1537388061.573 [ElevatorOffsetCalculator] Loaded
2018-09-19T20:14:21.573Z,1537388061.573 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-09-19T20:14:21.574Z,1537388061.574 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-09-19T20:14:21.574Z,1537388061.574 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-09-19T20:14:21.638Z,1537388061.638 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-09-19T20:14:21.638Z,1537388061.638 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-09-19T20:14:21.878Z,1537388061.878 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-09-19T20:14:21.879Z,1537388061.879 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-09-19T20:14:21.974Z,1537388061.974 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-09-19T20:14:21.975Z,1537388061.975 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-09-19T20:14:22.029Z,1537388062.029 [DeadReckonUsingSpeedCalculator] Loaded
2018-09-19T20:14:22.030Z,1537388062.030 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-09-19T20:14:22.046Z,1537388062.046 [NavChart] Loaded
2018-09-19T20:14:22.046Z,1537388062.046 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-09-19T20:14:22.050Z,1537388062.050 [UniversalFixResidualReporter] Loaded
2018-09-19T20:14:22.051Z,1537388062.051 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-09-19T20:14:22.051Z,1537388062.051 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-09-19T20:14:22.052Z,1537388062.052 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-09-19T20:14:22.065Z,1537388062.065 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-09-19T20:14:22.065Z,1537388062.065 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-09-19T20:14:22.217Z,1537388062.217 [Aanderaa_O2] Loaded
2018-09-19T20:14:22.218Z,1537388062.218 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-09-19T20:14:22.263Z,1537388062.263 [WetLabsSeaOWL_UV_A] Loaded
2018-09-19T20:14:22.264Z,1537388062.264 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2018-09-19T20:14:22.265Z,1537388062.265 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 406D54E0
2018-09-19T20:14:22.265Z,1537388062.265 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1718
2018-09-19T20:14:22.266Z,1537388062.266 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-09-19T20:14:22.266Z,1537388062.266 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-09-19T20:14:22.563Z,1537388062.563 [AHRS_M2] Loaded
2018-09-19T20:14:22.563Z,1537388062.563 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2018-09-19T20:14:22.632Z,1537388062.632 [DataOverHttps] Loaded
2018-09-19T20:14:22.633Z,1537388062.633 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-09-19T20:14:22.646Z,1537388062.646 [Depth_Keller] Loaded
2018-09-19T20:14:22.647Z,1537388062.647 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-09-19T20:14:22.652Z,1537388062.652 [DropWeight] Loaded
2018-09-19T20:14:22.652Z,1537388062.652 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-09-19T20:14:22.743Z,1537388062.743 [NAL9602] Loaded
2018-09-19T20:14:22.744Z,1537388062.744 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-09-19T20:14:22.749Z,1537388062.749 [Onboard] Loaded
2018-09-19T20:14:22.749Z,1537388062.749 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-09-19T20:14:22.756Z,1537388062.756 [Radio_Surface] Loaded
2018-09-19T20:14:22.756Z,1537388062.756 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-09-19T20:14:22.757Z,1537388062.757 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409364E0
2018-09-19T20:14:22.758Z,1537388062.758 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1719
2018-09-19T20:14:22.787Z,1537388062.787 [RDI_Pathfinder] Loaded
2018-09-19T20:14:22.787Z,1537388062.787 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2018-09-19T20:14:22.809Z,1537388062.809 [RDI_PathfinderUp] Loaded
2018-09-19T20:14:22.809Z,1537388062.809 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2018-09-19T20:14:22.816Z,1537388062.816 [SCPI] Loaded
2018-09-19T20:14:22.816Z,1537388062.816 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2018-09-19T20:14:24.627Z,1537388064.627 [BPC1] Loaded
2018-09-19T20:14:24.627Z,1537388064.627 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-09-19T20:14:24.628Z,1537388064.628 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-09-19T20:14:24.628Z,1537388064.628 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-09-19T20:14:24.727Z,1537388064.727 [BuoyancyServo] Loaded
2018-09-19T20:14:24.727Z,1537388064.727 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-09-19T20:14:24.739Z,1537388064.739 [ElevatorServo] Loaded
2018-09-19T20:14:24.740Z,1537388064.740 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-09-19T20:14:24.751Z,1537388064.751 [MassServo] Loaded
2018-09-19T20:14:24.751Z,1537388064.751 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-09-19T20:14:24.762Z,1537388064.762 [RudderServo] Loaded
2018-09-19T20:14:24.763Z,1537388064.763 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-09-19T20:14:24.773Z,1537388064.773 [ThrusterServo] Loaded
2018-09-19T20:14:24.774Z,1537388064.774 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-09-19T20:14:24.774Z,1537388064.774 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-09-19T20:14:24.775Z,1537388064.775 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-09-19T20:14:24.873Z,1537388064.873 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-09-19T20:14:24.874Z,1537388064.874 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-09-19T20:14:24.898Z,1537388064.898 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-09-19T20:14:24.901Z,1537388064.901 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-09-19T20:14:24.902Z,1537388064.902 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-09-19T20:14:24.909Z,1537388064.909 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-09-19T20:14:24.910Z,1537388064.910 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409DF4E0
2018-09-19T20:14:24.910Z,1537388064.910 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1720
2018-09-19T20:14:24.915Z,1537388064.915 [Supervisor](INFO): Main Thread ID is 800
2018-09-19T20:14:24.915Z,1537388064.915 [Supervisor](DEBUG): Running supervisor.
2018-09-19T20:14:24.916Z,1537388064.916 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1721
2018-09-19T20:14:24.919Z,1537388064.919 [controlThread ThreadHandler](INFO): Handler Thread ID is 1722
2018-09-19T20:14:24.919Z,1537388064.919 [controlThread](DEBUG): Initializing ControlThread
2018-09-19T20:14:24.920Z,1537388064.920 [SBIT](INFO): Initialize SBIT Component.
2018-09-19T20:14:24.921Z,1537388064.921 [SBIT](IMPORTANT): git: 2018-09-06-21-g021ff34
2018-09-19T20:14:24.921Z,1537388064.921 [SBIT](INFO): git hash: 021ff3480491a97d99bb6df627398514e33207a2
2018-09-19T20:14:24.921Z,1537388064.921 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-09-19T20:14:24.922Z,1537388064.922 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-09-19T20:14:24.923Z,1537388064.923 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2018-09-19T20:14:24.923Z,1537388064.923 [IBIT](INFO): Initialize IBIT Component.
2018-09-19T20:14:24.924Z,1537388064.924 [CBIT](DEBUG): Initialize CBIT Component.
2018-09-19T20:14:24.925Z,1537388064.925 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-09-19T20:14:24.925Z,1537388064.925 [logger ThreadHandler](INFO): Handler Thread ID is 1723
2018-09-19T20:14:24.951Z,1537388064.951 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1724
2018-09-19T20:14:24.952Z,1537388064.952 [WetLabsSeaOWL_UV_A](INFO): Powering down
2018-09-19T20:14:24.991Z,1537388064.991 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1725
2018-09-19T20:14:24.997Z,1537388064.997 [Radio_Surface](INFO): Powering up
2018-09-19T20:14:25.003Z,1537388065.003 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1726
2018-09-19T20:14:25.006Z,1537388065.006 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-09-19T20:14:25.006Z,1537388065.006 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-09-19T20:14:25.007Z,1537388065.007 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-09-19T20:14:25.007Z,1537388065.007 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-09-19T20:14:25.007Z,1537388065.007 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-09-19T20:14:25.007Z,1537388065.007 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-09-19T20:14:25.007Z,1537388065.007 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-09-19T20:14:25.007Z,1537388065.007 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-09-19T20:14:25.008Z,1537388065.008 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-09-19T20:14:25.008Z,1537388065.008 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-09-19T20:14:25.008Z,1537388065.008 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-09-19T20:14:25.008Z,1537388065.008 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-09-19T20:14:25.008Z,1537388065.008 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-09-19T20:14:25.009Z,1537388065.009 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-09-19T20:14:25.009Z,1537388065.009 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-09-19T20:14:25.009Z,1537388065.009 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-09-19T20:14:25.031Z,1537388065.031 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-09-19T20:14:25.032Z,1537388065.032 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-09-19T20:14:25.033Z,1537388065.033 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-09-19T20:14:25.034Z,1537388065.034 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-09-19T20:14:25.034Z,1537388065.034 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-09-19T20:14:25.034Z,1537388065.034 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-09-19T20:14:25.035Z,1537388065.035 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-09-19T20:14:25.035Z,1537388065.035 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-09-19T20:14:25.036Z,1537388065.036 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-09-19T20:14:25.036Z,1537388065.036 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-09-19T20:14:25.037Z,1537388065.037 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-09-19T20:14:25.037Z,1537388065.037 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-09-19T20:14:25.038Z,1537388065.038 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-09-19T20:14:25.038Z,1537388065.038 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-19T20:14:25.039Z,1537388065.039 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-19T20:14:25.039Z,1537388065.039 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-19T20:14:25.040Z,1537388065.040 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-09-19T20:14:25.040Z,1537388065.040 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-09-19T20:14:25.047Z,1537388065.047 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-09-19T20:14:25.059Z,1537388065.059 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-09-19T20:14:25.095Z,1537388065.095 [MissionManager](DEBUG):
2018-09-19T20:14:25.096Z,1537388065.096 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-09-19T20:14:25.183Z,1537388065.183 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-09-19T20:14:25.184Z,1537388065.184 [Default:A.Wait](DEBUG): Construct Wait.
2018-09-19T20:14:25.186Z,1537388065.186 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-09-19T20:14:25.209Z,1537388065.209 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-09-19T20:14:25.211Z,1537388065.211 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-09-19T20:14:25.217Z,1537388065.217 [Default:E.Execute](DEBUG): Construct Execute.
2018-09-19T20:14:25.233Z,1537388065.233 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2018-09-19T20:14:25.238Z,1537388065.238 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,RDI_PathfinderUp,SCPI,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-09-19T20:14:25.273Z,1537388065.273 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-09-19T20:14:25.325Z,1537388065.325 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-09-19T20:14:25.349Z,1537388065.349 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2018-09-19T20:14:25.547Z,1537388065.547 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-09-19T20:14:25.631Z,1537388065.631 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T20:14:25.651Z,1537388065.651 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T20:14:25.784Z,1537388065.784 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-19T20:14:25.791Z,1537388065.791 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-19T20:14:25.813Z,1537388065.813 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-09-19T20:14:25.819Z,1537388065.819 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-09-19T20:14:25.833Z,1537388065.833 [MassServo](DEBUG): Initializing EZServoServo.
2018-09-19T20:14:25.839Z,1537388065.839 [MassServo](DEBUG): Initializing MassServo.
2018-09-19T20:14:25.852Z,1537388065.852 [RudderServo](DEBUG): Initializing EZServoServo.
2018-09-19T20:14:25.859Z,1537388065.859 [RudderServo](DEBUG): Initializing RudderServo.
2018-09-19T20:14:25.864Z,1537388065.864 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-09-19T20:14:25.871Z,1537388065.871 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-09-19T20:14:26.035Z,1537388066.035 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2018-09-19T20:14:26.035Z,1537388066.035 [DropWeight] Hardware Fault, FailCount= 1
2018-09-19T20:14:26.035Z,1537388066.035 [DropWeight](ERROR): Hardware Fault
2018-09-19T20:14:26.126Z,1537388066.126 [RDI_PathfinderUp](INFO): Powering down
2018-09-19T20:14:26.188Z,1537388066.188 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-09-19T20:14:26.190Z,1537388066.190 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T20:14:26.192Z,1537388066.192 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T20:14:26.221Z,1537388066.221 [CommandLine](FAULT): Scheduling is paused
2018-09-19T20:14:26.222Z,1537388066.222 [CBIT](INFO): Critical error at 20180919T201426
2018-09-19T20:14:26.222Z,1537388066.222 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-09-19T20:14:26.240Z,1537388066.240 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-09-19T20:14:26.241Z,1537388066.241 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-09-19T20:14:26.375Z,1537388066.375 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-09-19T20:14:26.377Z,1537388066.377 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T20:14:26.379Z,1537388066.379 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T20:14:26.710Z,1537388066.710 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-09-19T20:14:26.711Z,1537388066.711 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-09-19T20:14:26.711Z,1537388066.711 [BuoyancyServo] Communications Fault, FailCount= 1
2018-09-19T20:14:26.711Z,1537388066.711 [BuoyancyServo](ERROR): Communications Fault
2018-09-19T20:14:26.920Z,1537388066.920 [CBIT](INFO): Critical error at 20180919T201426
2018-09-19T20:14:26.923Z,1537388066.923 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-09-19T20:14:26.961Z,1537388066.961 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T20:14:26.978Z,1537388066.978 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T20:14:26.985Z,1537388066.985 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-19T20:14:26.986Z,1537388066.986 [BuoyancyServo](INFO): Powering down
2018-09-19T20:14:27.358Z,1537388067.358 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T20:14:27.359Z,1537388067.359 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T20:14:27.762Z,1537388067.762 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T20:14:27.763Z,1537388067.763 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T20:14:28.158Z,1537388068.158 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T20:14:28.159Z,1537388068.159 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T20:14:28.650Z,1537388068.650 [Aanderaa_O2](INFO): Powering down
2018-09-19T20:14:28.758Z,1537388068.758 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T20:14:28.767Z,1537388068.767 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T20:14:28.994Z,1537388068.994 [AHRS_M2](ERROR): Read RFS packet UART error: serial timeout
2018-09-19T20:14:29.038Z,1537388069.038 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T20:14:29.039Z,1537388069.039 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-09-19T20:14:29.096Z,1537388069.096 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-09-19T20:14:29.096Z,1537388069.096 [BuoyancyServo] No Fault, FailCount= 1
2018-09-19T20:14:29.373Z,1537388069.373 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-09-19T20:14:29.491Z,1537388069.491 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-09-19T20:14:38.967Z,1537388078.967 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2018-09-19T20:14:49.393Z,1537388089.393 [SBIT](IMPORTANT): Beginning Startup BIT
2018-09-19T20:14:49.403Z,1537388089.403 [CBIT](IMPORTANT): Beginning ground fault scan
2018-09-19T20:14:50.960Z,1537388090.960 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2018-09-19T20:14:50.961Z,1537388090.961 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,18091913141709,35.0, -0.1, 0.0,1448:BI,-32768,-32768,-32768,-32768,V
2018-09-19T20:14:53.052Z,1537388093.052 [NAL9602](INFO): Powering up NAL9602
2018-09-19T20:15:00.368Z,1537388100.368 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.008880
CHAN A1 (24V): -0.012478
CHAN A2 (12V): -0.007332
CHAN A3 (5V): -0.002249
CHAN B0 (3.3V): 0.000120
CHAN B1 (3.15aV): -0.000178
CHAN B2 (3.15bV): 0.000213
CHAN B3 (GND): 0.002359
OPEN: 0.004865
Full Scale Calc: 4.765 mA, -1.589 mA
2018-09-19T20:15:04.252Z,1537388104.252 [NAL9602](INFO): NAL9602 initialized
2018-09-19T20:15:32.863Z,1537388132.863 [CommandLine](IMPORTANT): got command failComponent
2018-09-19T20:15:32.863Z,1537388132.863 [CommandLine](IMPORTANT): Failed components:
2018-09-19T20:15:32.864Z,1537388132.864 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2018-09-19T20:15:39.162Z,1537388139.162 [BPC1](INFO): BPC1B: Serial:304J:0 serial_:357
2018-09-19T20:15:39.163Z,1537388139.163 [BPC1](INFO): BPC1B: Serial:304J:1 serial_:309
2018-09-19T20:15:39.163Z,1537388139.163 [BPC1](INFO): BPC1B: Serial:304J:2 serial_:329
2018-09-19T20:15:39.164Z,1537388139.164 [BPC1](INFO): BPC1B: Serial:304J:3 serial_:326
2018-09-19T20:15:39.165Z,1537388139.165 [BPC1](INFO): BPC1B: Serial:304J:4 serial_:317
2018-09-19T20:15:39.165Z,1537388139.165 [BPC1](INFO): BPC1B: Serial:304J:5 serial_:333
2018-09-19T20:15:39.166Z,1537388139.166 [BPC1](INFO): BPC1B: Serial:304J:6 serial_:342
2018-09-19T20:15:39.166Z,1537388139.166 [BPC1](INFO): BPC1B: Serial:304J:7 serial_:307
2018-09-19T20:15:39.167Z,1537388139.167 [BPC1](INFO): BPC1B: Serial:304J:8 serial_:305
2018-09-19T20:15:39.168Z,1537388139.168 [BPC1](INFO): BPC1B: Serial:304J:9 serial_:325
2018-09-19T20:15:39.168Z,1537388139.168 [BPC1](INFO): BPC1B: Serial:304J:10 serial_:330
2018-09-19T20:15:39.169Z,1537388139.169 [BPC1](INFO): BPC1B: Serial:304J:11 serial_:316
2018-09-19T20:15:39.169Z,1537388139.169 [BPC1](INFO): BPC1B: Serial:304J:12 serial_:315
2018-09-19T20:15:39.170Z,1537388139.170 [BPC1](INFO): BPC1B: Serial:304J:13 serial_:306
2018-09-19T20:15:39.171Z,1537388139.171 [BPC1](INFO): BPC1B: Serial:304J:14 serial_:308
2018-09-19T20:15:39.171Z,1537388139.171 [BPC1](INFO): BPC1B: Serial:304J:15 serial_:339
2018-09-19T20:15:39.172Z,1537388139.172 [BPC1](INFO): BPC1B: Serial:304J:16 serial_:331
2018-09-19T20:15:39.172Z,1537388139.172 [BPC1](INFO): BPC1B: Serial:304J:17 serial_:314
2018-09-19T20:15:39.173Z,1537388139.173 [BPC1](INFO): BPC1B: Serial:304J:18 serial_:310
2018-09-19T20:15:39.173Z,1537388139.173 [BPC1](INFO): BPC1B: Serial:304J:19 serial_:335
2018-09-19T20:15:39.174Z,1537388139.174 [BPC1](INFO): BPC1B: Serial:304J:20 serial_:334
2018-09-19T20:15:39.175Z,1537388139.175 [BPC1](INFO): BPC1B: Serial:304J:21 serial_:319
2018-09-19T20:15:39.175Z,1537388139.175 [BPC1](INFO): BPC1B: Serial:304J:22 serial_:338
2018-09-19T20:15:39.176Z,1537388139.176 [BPC1](INFO): BPC1B: Serial:304J:23 serial_:332
2018-09-19T20:15:39.176Z,1537388139.176 [BPC1](INFO): BPC1B: Serial:304J:24 serial_:363
2018-09-19T20:15:39.177Z,1537388139.177 [BPC1](INFO): BPC1B: Serial:304J:25 serial_:321
2018-09-19T20:15:39.177Z,1537388139.177 [BPC1](INFO): BPC1B: Serial:304J:26 serial_:313
2018-09-19T20:15:39.178Z,1537388139.178 [BPC1](INFO): BPC1B: Serial:304J:27 serial_:311
2018-09-19T20:15:39.179Z,1537388139.179 [BPC1](INFO): BPC1B: Serial:304J:28 serial_:322
2018-09-19T20:15:39.179Z,1537388139.179 [BPC1](INFO): BPC1B: Serial:304J:29 serial_:318
2018-09-19T20:15:39.180Z,1537388139.180 [BPC1](INFO): BPC1B: Serial:304J:30 serial_:327
2018-09-19T20:15:39.180Z,1537388139.180 [BPC1](ERROR): BPC1B: No match for stick with serial number:0130 in the onboard configuration file.
2018-09-19T20:15:39.466Z,1537388139.466 [BPC1](INFO): BPC1A: Serial:305J:0 serial_:320
2018-09-19T20:15:39.467Z,1537388139.467 [BPC1](INFO): BPC1A: Serial:305J:1 serial_:355
2018-09-19T20:15:39.467Z,1537388139.467 [BPC1](INFO): BPC1A: Serial:305J:2 serial_:343
2018-09-19T20:15:39.468Z,1537388139.468 [BPC1](INFO): BPC1A: Serial:305J:3 serial_:345
2018-09-19T20:15:39.468Z,1537388139.468 [BPC1](INFO): BPC1A: Serial:305J:4 serial_:366
2018-09-19T20:15:39.469Z,1537388139.469 [BPC1](INFO): BPC1A: Serial:305J:5 serial_:352
2018-09-19T20:15:39.469Z,1537388139.469 [BPC1](INFO): BPC1A: Serial:305J:6 serial_:348
2018-09-19T20:15:39.470Z,1537388139.470 [BPC1](INFO): BPC1A: Serial:305J:7 serial_:365
2018-09-19T20:15:39.471Z,1537388139.471 [BPC1](INFO): BPC1A: Serial:305J:8 serial_:301
2018-09-19T20:15:39.471Z,1537388139.471 [BPC1](INFO): BPC1A: Serial:305J:9 serial_:350
2018-09-19T20:15:39.472Z,1537388139.472 [BPC1](INFO): BPC1A: Serial:305J:10 serial_:356
2018-09-19T20:15:39.472Z,1537388139.472 [BPC1](INFO): BPC1A: Serial:305J:11 serial_:347
2018-09-19T20:15:39.473Z,1537388139.473 [BPC1](INFO): BPC1A: Serial:305J:12 serial_:370
2018-09-19T20:15:39.474Z,1537388139.474 [BPC1](INFO): BPC1A: Serial:305J:13 serial_:358
2018-09-19T20:15:39.482Z,1537388139.482 [BPC1](INFO): BPC1A: Serial:305J:14 serial_:368
2018-09-19T20:15:39.483Z,1537388139.483 [BPC1](INFO): BPC1A: Serial:305J:15 serial_:328
2018-09-19T20:15:39.484Z,1537388139.484 [BPC1](INFO): BPC1A: Serial:305J:16 serial_:304
2018-09-19T20:15:39.484Z,1537388139.484 [BPC1](INFO): BPC1A: Serial:305J:17 serial_:361
2018-09-19T20:15:39.485Z,1537388139.485 [BPC1](INFO): BPC1A: Serial:305J:18 serial_:344
2018-09-19T20:15:39.485Z,1537388139.485 [BPC1](INFO): BPC1A: Serial:305J:19 serial_:354
2018-09-19T20:15:39.486Z,1537388139.486 [BPC1](INFO): BPC1A: Serial:305J:20 serial_:336
2018-09-19T20:15:39.499Z,1537388139.499 [BPC1](INFO): BPC1A: Serial:305J:21 serial_:351
2018-09-19T20:15:39.499Z,1537388139.499 [BPC1](INFO): BPC1A: Serial:305J:22 serial_:369
2018-09-19T20:15:39.500Z,1537388139.500 [BPC1](INFO): BPC1A: Serial:305J:23 serial_:323
2018-09-19T20:15:39.500Z,1537388139.500 [BPC1](INFO): BPC1A: Serial:305J:24 serial_:324
2018-09-19T20:15:39.501Z,1537388139.501 [BPC1](INFO): BPC1A: Serial:305J:25 serial_:362
2018-09-19T20:15:39.501Z,1537388139.501 [BPC1](INFO): BPC1A: Serial:305J:26 serial_:341
2018-09-19T20:15:39.502Z,1537388139.502 [BPC1](INFO): BPC1A: Serial:305J:27 serial_:337
2018-09-19T20:15:39.515Z,1537388139.515 [BPC1](INFO): BPC1A: Serial:305J:28 serial_:340
2018-09-19T20:15:39.515Z,1537388139.515 [BPC1](INFO): BPC1A: Serial:305J:29 serial_:353
2018-09-19T20:15:39.516Z,1537388139.516 [BPC1](INFO): BPC1A: Serial:305J:30 serial_:312
2018-09-19T20:15:39.516Z,1537388139.516 [BPC1](ERROR): BPC1A: No match for stick with serial number:0131 in the onboard configuration file.
2018-09-19T20:15:39.530Z,1537388139.530 [BPC1](FAULT): Failed to parse data from all battery packs.
2018-09-19T20:15:39.531Z,1537388139.531 [BPC1] Data Fault, FailCount= 1
2018-09-19T20:15:39.531Z,1537388139.531 [BPC1](ERROR): Data Fault
2018-09-19T20:15:39.617Z,1537388139.617 [CBIT](ERROR): Data Fault in component: BPC1
2018-09-19T20:15:43.494Z,1537388143.494 [SBIT](IMPORTANT): SBIT PASSED
2018-09-19T20:15:43.547Z,1537388143.547 [CommandLine](IMPORTANT): got command configSet list
2018-09-19T20:15:43.547Z,1537388143.547 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-09-19T20:15:43.548Z,1537388143.548 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=160 cubic_centimeter;
2018-09-19T20:15:43.548Z,1537388143.548 [CommandLine](IMPORTANT): VerticalControl.massDefault=-1 millimeter;
2018-09-19T20:15:43.901Z,1537388143.901 [MissionManager](IMPORTANT): Started mission Startup
2018-09-19T20:15:43.901Z,1537388143.901 [Startup] Running Loop=1
2018-09-19T20:15:43.901Z,1537388143.901 [Startup](DEBUG): Aggregate::initialize Startup
2018-09-19T20:15:43.901Z,1537388143.901 [Startup:A.GoToSurface] Running Loop=1
2018-09-19T20:15:43.901Z,1537388143.901 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-19T20:15:43.902Z,1537388143.902 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-09-19T20:15:43.903Z,1537388143.903 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-09-19T20:15:43.903Z,1537388143.903 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-09-19T20:15:43.904Z,1537388143.904 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-19T20:15:43.904Z,1537388143.904 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-19T20:15:43.911Z,1537388143.911 [Startup:StartupSatComms] Running Loop=1
2018-09-19T20:15:43.911Z,1537388143.911 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-09-19T20:15:43.911Z,1537388143.911 [Startup:StartupSatComms:A] Running Loop=1
2018-09-19T20:15:44.292Z,1537388144.292 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-09-19T20:16:44.279Z,1537388204.279 [Startup:StartupSatComms:A](INFO): Timed out from 2018-09-19T20:15:43.9Z
2018-09-19T20:16:44.279Z,1537388204.279 [Startup:StartupSatComms:A] Stopped
2018-09-19T20:16:44.279Z,1537388204.279 [Startup:StartupSatComms:B] Running Loop=1
2018-09-19T20:16:44.712Z,1537388204.712 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-09-19T20:17:25.076Z,1537388245.076 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-19T20:17:25.076Z,1537388245.076 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2018-09-19T20:17:25.077Z,1537388245.077 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-09-19T20:17:25.083Z,1537388245.083 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-19T20:17:25.083Z,1537388245.083 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-09-19T20:17:25.083Z,1537388245.083 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-19T20:17:25.109Z,1537388245.109 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-09-19T20:17:25.109Z,1537388245.109 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-19T20:17:25.496Z,1537388245.496 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-09-19T20:17:25.496Z,1537388245.496 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2018-09-19T20:17:25.496Z,1537388245.496 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-19T20:17:25.496Z,1537388245.496 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-09-19T20:17:25.882Z,1537388245.882 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-09-19T20:17:25.883Z,1537388245.883 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-09-19T20:17:25.883Z,1537388245.883 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-09-19T20:17:25.883Z,1537388245.883 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-19T20:17:25.884Z,1537388245.884 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-19T20:17:25.884Z,1537388245.884 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-19T20:17:30.777Z,1537388250.777 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004332
2018-09-19T20:17:34.905Z,1537388254.905 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20180919T201049/Courier0000.lzma
2018-09-19T20:17:35.669Z,1537388255.669 [DataOverHttps](INFO): Moved sent file to Logs/20180919T201049/Courier0000.lzma.bak
2018-09-19T20:17:35.669Z,1537388255.669 [DataOverHttps](INFO): SBD MOMSN=8539225
2018-09-19T20:17:44.608Z,1537388264.608 [Startup:StartupSatComms:B](INFO): Timed out from 2018-09-19T20:16:44.3Z
2018-09-19T20:17:44.608Z,1537388264.608 [Startup:StartupSatComms:B] Stopped
2018-09-19T20:17:44.608Z,1537388264.608 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-09-19T20:17:44.608Z,1537388264.608 [Startup:StartupSatComms] Stopped
2018-09-19T20:17:44.608Z,1537388264.608 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-09-19T20:17:44.609Z,1537388264.609 [Startup](INFO): Completed Startup
2018-09-19T20:17:44.610Z,1537388264.610 [MissionManager](INFO): Startup is completed.
2018-09-19T20:17:44.610Z,1537388264.610 [MissionManager](INFO): Uninitializing Mission Startup
2018-09-19T20:17:44.610Z,1537388264.610 [Startup] Stopped
2018-09-19T20:17:44.611Z,1537388264.611 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-09-19T20:17:44.611Z,1537388264.611 [Startup:A.GoToSurface] Stopped
2018-09-19T20:17:44.611Z,1537388264.611 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-19T20:17:44.685Z,1537388264.685 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180919T201049/Courier0004.lzma
2018-09-19T20:17:44.787Z,1537388264.787 [MissionManager](IMPORTANT): Started mission Default
2018-09-19T20:17:44.787Z,1537388264.787 [Default] Running Loop=1
2018-09-19T20:17:44.787Z,1537388264.787 [Default](DEBUG): Aggregate::initialize Default
2018-09-19T20:17:44.787Z,1537388264.787 [Default:B.GoToSurface] Running Loop=1
2018-09-19T20:17:44.787Z,1537388264.787 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-09-19T20:17:44.788Z,1537388264.788 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-09-19T20:17:44.788Z,1537388264.788 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-09-19T20:17:44.788Z,1537388264.788 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-09-19T20:17:44.789Z,1537388264.789 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-09-19T20:17:44.789Z,1537388264.789 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-09-19T20:17:44.789Z,1537388264.789 [Default:A.Wait] Running Loop=1
2018-09-19T20:17:44.790Z,1537388264.790 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-09-19T20:17:45.473Z,1537388265.473 [DataOverHttps](INFO): Moved sent file to Logs/20180919T201049/Courier0004.lzma.bak
2018-09-19T20:17:45.473Z,1537388265.473 [DataOverHttps](INFO): SBD MOMSN=8539231
2018-09-19T20:17:58.012Z,1537388278.012 [Default:A.Wait](INFO): Done Waiting.
2018-09-19T20:17:58.012Z,1537388278.012 [Default:A.Wait] Stopped
2018-09-19T20:17:58.012Z,1537388278.012 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-09-19T20:17:58.388Z,1537388278.388 [Default:CheckIn] Running Loop=1
2018-09-19T20:17:58.388Z,1537388278.388 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-09-19T20:17:58.388Z,1537388278.388 [Default:CheckIn:Read_GPS] Running Loop=1
2018-09-19T20:17:58.807Z,1537388278.807 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-09-19T20:20:07.740Z,1537388407.740 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-09-19T20:20:26.168Z,1537388426.168 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-19T20:20:26.168Z,1537388426.168 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2018-09-19T20:20:26.168Z,1537388426.168 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-09-19T20:20:26.186Z,1537388426.186 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-19T20:20:26.187Z,1537388426.187 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-09-19T20:20:26.187Z,1537388426.187 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-19T20:20:26.212Z,1537388426.212 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-09-19T20:20:26.212Z,1537388426.212 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-19T20:20:26.599Z,1537388426.599 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-09-19T20:20:26.599Z,1537388426.599 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2018-09-19T20:20:26.600Z,1537388426.600 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-19T20:20:26.600Z,1537388426.600 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-09-19T20:20:27.005Z,1537388427.005 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-09-19T20:20:27.006Z,1537388427.006 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-09-19T20:20:27.006Z,1537388427.006 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-09-19T20:20:27.007Z,1537388427.007 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-19T20:20:27.007Z,1537388427.007 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-19T20:20:27.007Z,1537388427.007 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-19T20:21:04.660Z,1537388464.660 [RDI_Pathfinder](ERROR): only read 1 of 1 data item for altitude
2018-09-19T20:21:04.661Z,1537388464.661 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G0ffd, 15.967, -0.005, 0.000
2018-09-19T20:22:58.461Z,1537388578.461 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-09-19T20:17:58.4Z
2018-09-19T20:22:58.462Z,1537388578.462 [Default:CheckIn:Read_GPS] Stopped
2018-09-19T20:22:58.462Z,1537388578.462 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-09-19T20:22:58.892Z,1537388578.892 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-09-19T20:23:02.943Z,1537388582.943 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20180919T201414/Courier0000.lzma
2018-09-19T20:23:03.737Z,1537388583.737 [DataOverHttps](INFO): Moved sent file to Logs/20180919T201414/Courier0000.lzma.bak
2018-09-19T20:23:03.737Z,1537388583.737 [DataOverHttps](INFO): SBD MOMSN=8539634
2018-09-19T20:23:12.702Z,1537388592.702 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180919T201414/Courier0004.lzma
2018-09-19T20:23:13.481Z,1537388593.481 [DataOverHttps](INFO): Moved sent file to Logs/20180919T201414/Courier0004.lzma.bak
2018-09-19T20:23:13.481Z,1537388593.481 [DataOverHttps](INFO): SBD MOMSN=8539639
2018-09-19T20:23:22.473Z,1537388602.473 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20180919T195624/Express0008.lzma
2018-09-19T20:23:23.221Z,1537388603.221 [DataOverHttps](INFO): Moved sent file to Logs/20180919T195624/Express0008.lzma.bak
2018-09-19T20:23:23.221Z,1537388603.221 [DataOverHttps](INFO): SBD MOMSN=8539641
2018-09-19T20:23:27.248Z,1537388607.248 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-19T20:23:27.248Z,1537388607.248 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2018-09-19T20:23:27.248Z,1537388607.248 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-09-19T20:23:27.250Z,1537388607.250 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-19T20:23:27.250Z,1537388607.250 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-09-19T20:23:27.250Z,1537388607.250 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-19T20:23:27.263Z,1537388607.263 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-09-19T20:23:27.263Z,1537388607.263 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-19T20:23:27.408Z,1537388607.408 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-09-19T20:23:27.408Z,1537388607.408 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2018-09-19T20:23:27.409Z,1537388607.409 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-19T20:23:27.409Z,1537388607.409 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-09-19T20:23:27.746Z,1537388607.746 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-09-19T20:23:27.746Z,1537388607.746 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-09-19T20:23:27.747Z,1537388607.747 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-09-19T20:23:27.747Z,1537388607.747 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-19T20:23:27.748Z,1537388607.748 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-19T20:23:27.748Z,1537388607.748 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-19T20:23:32.182Z,1537388612.182 [DataOverHttps](INFO): Sending 744 bytes from file Logs/20180919T201049/Express0001.lzma
2018-09-19T20:23:32.937Z,1537388612.937 [DataOverHttps](INFO): Moved sent file to Logs/20180919T201049/Express0001.lzma.bak
2018-09-19T20:23:32.937Z,1537388612.937 [DataOverHttps](INFO): SBD MOMSN=8539645
2018-09-19T20:23:41.861Z,1537388621.861 [DataOverHttps](INFO): Sending 78 bytes from file Logs/20180919T201049/Express0005.lzma
2018-09-19T20:23:42.658Z,1537388622.658 [DataOverHttps](INFO): Moved sent file to Logs/20180919T201049/Express0005.lzma.bak
2018-09-19T20:23:42.658Z,1537388622.658 [DataOverHttps](INFO): SBD MOMSN=8539662
2018-09-19T20:23:51.597Z,1537388631.597 [DataOverHttps](INFO): Sending 842 bytes from file Logs/20180919T201414/Express0001.lzma
2018-09-19T20:23:52.357Z,1537388632.357 [DataOverHttps](INFO): Moved sent file to Logs/20180919T201414/Express0001.lzma.bak
2018-09-19T20:23:52.357Z,1537388632.357 [DataOverHttps](INFO): SBD MOMSN=8539665
2018-09-19T20:24:01.350Z,1537388641.350 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20180919T201414/Express0005.lzma
2018-09-19T20:24:02.113Z,1537388642.113 [DataOverHttps](INFO): Moved sent file to Logs/20180919T201414/Express0005.lzma.bak
2018-09-19T20:24:02.113Z,1537388642.113 [DataOverHttps](INFO): SBD MOMSN=8539686
2018-09-19T20:24:02.685Z,1537388642.685 [Default:CheckIn:Read_Iridium] Stopped
2018-09-19T20:24:02.685Z,1537388642.685 [Default:CheckIn:C.Wait] Running Loop=1
2018-09-19T20:24:02.685Z,1537388642.685 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-09-19T20:25:09.040Z,1537388709.040 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-09-19T20:25:09.040Z,1537388709.040 [NAL9602] Data Fault, FailCount= 1
2018-09-19T20:25:09.040Z,1537388709.040 [NAL9602](ERROR): Data Fault
2018-09-19T20:25:09.099Z,1537388709.099 [CBIT](ERROR): Data Fault in component: NAL9602
2018-09-19T20:25:09.440Z,1537388709.440 [NAL9602](INFO): Powering down
2018-09-19T20:25:10.294Z,1537388710.294 [CBIT](INFO): Clearing failed state for component NAL9602
2018-09-19T20:25:10.294Z,1537388710.294 [NAL9602] No Fault, FailCount= 1
2018-09-19T20:25:39.836Z,1537388739.836 [NAL9602](INFO): Powering up NAL9602
2018-09-19T20:25:40.313Z,1537388740.313 [CBIT](INFO): Clearing failed state for component BPC1
2018-09-19T20:25:40.313Z,1537388740.313 [BPC1] No Fault, FailCount= 1
2018-09-19T20:25:50.649Z,1537388750.649 [NAL9602](INFO): NAL9602 initialized
2018-09-19T20:26:27.955Z,1537388787.955 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-19T20:26:27.955Z,1537388787.955 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2018-09-19T20:26:27.955Z,1537388787.955 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-09-19T20:26:27.957Z,1537388787.957 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-09-19T20:26:27.957Z,1537388787.957 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-09-19T20:26:27.957Z,1537388787.957 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-09-19T20:26:27.971Z,1537388787.971 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-09-19T20:26:27.971Z,1537388787.971 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-09-19T20:26:28.344Z,1537388788.344 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-09-19T20:26:28.344Z,1537388788.344 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2018-09-19T20:26:28.345Z,1537388788.345 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-09-19T20:26:28.345Z,1537388788.345 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-09-19T20:26:28.734Z,1537388788.734 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-09-19T20:26:28.735Z,1537388788.735 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-09-19T20:26:28.735Z,1537388788.735 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-09-19T20:26:28.735Z,1537388788.735 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-09-19T20:26:28.740Z,1537388788.740 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-09-19T20:26:28.740Z,1537388788.740 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-09-19T20:26:32.456Z,1537388792.456 [CommandLine](IMPORTANT): got command restart application
2018-09-19T20:26:33.465Z,1537388793.465 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-19T20:26:33.466Z,1537388793.466 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:26:33.490Z,1537388793.490 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2018-09-19T20:26:33.491Z,1537388793.491 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:26:33.491Z,1537388793.491 [CommandLine](INFO): Join timeout helper Thread ID is 1765
2018-09-19T20:26:33.492Z,1537388793.492 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-09-19T20:26:33.493Z,1537388793.493 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:26:33.496Z,1537388793.496 [NavChartDb](INFO): Join timeout helper Thread ID is 1766
2018-09-19T20:26:33.506Z,1537388793.506 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-19T20:26:33.507Z,1537388793.507 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:26:33.514Z,1537388793.514 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-09-19T20:26:33.515Z,1537388793.515 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:26:33.515Z,1537388793.515 [Radio_Surface](INFO): Join timeout helper Thread ID is 1767
2018-09-19T20:26:33.759Z,1537388793.759 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-19T20:26:33.759Z,1537388793.759 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:26:33.766Z,1537388793.766 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2018-09-19T20:26:33.766Z,1537388793.766 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:26:33.767Z,1537388793.767 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1768
2018-09-19T20:26:34.087Z,1537388794.087 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-19T20:26:34.087Z,1537388794.087 [WetLabsSeaOWL_UV_A](INFO): Powering down
2018-09-19T20:26:34.088Z,1537388794.088 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:26:34.094Z,1537388794.094 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-09-19T20:26:34.094Z,1537388794.094 [logger ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:26:34.095Z,1537388794.095 [logger](INFO): Join timeout helper Thread ID is 1769
2018-09-19T20:26:34.106Z,1537388794.106 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-19T20:26:34.107Z,1537388794.107 [logger ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:26:34.115Z,1537388794.115 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-09-19T20:26:34.115Z,1537388794.115 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:26:34.115Z,1537388794.115 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-09-19T20:26:34.116Z,1537388794.116 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:26:34.116Z,1537388794.116 [controlThread](INFO): Join timeout helper Thread ID is 1770
2018-09-19T20:26:34.258Z,1537388794.258 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-09-19T20:26:34.259Z,1537388794.259 [controlThread](DEBUG): Uninitializing ControlThread
2018-09-19T20:26:34.259Z,1537388794.259 [Aanderaa_O2](INFO): Powering down
2018-09-19T20:26:34.260Z,1537388794.260 [AHRS_M2](INFO): Powering down
2018-09-19T20:26:34.331Z,1537388794.331 [NAL9602](INFO): Powering down
2018-09-19T20:26:34.402Z,1537388794.402 [RDI_Pathfinder](INFO): Powering down
2018-09-19T20:26:34.403Z,1537388794.403 [RDI_PathfinderUp](INFO): Powering down
2018-09-19T20:26:34.405Z,1537388794.405 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-09-19T20:26:34.406Z,1537388794.406 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-09-19T20:26:34.407Z,1537388794.407 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-09-19T20:26:34.408Z,1537388794.408 [MissionManager](INFO): Uninitializing Mission Default
2018-09-19T20:26:34.408Z,1537388794.408 [Default] Stopped
2018-09-19T20:26:34.408Z,1537388794.408 [Default](DEBUG): Aggregate::uninitialize Default
2018-09-19T20:26:34.408Z,1537388794.408 [Default:B.GoToSurface] Stopped
2018-09-19T20:26:34.408Z,1537388794.408 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-09-19T20:26:34.408Z,1537388794.408 [Default:CheckIn] Stopped
2018-09-19T20:26:34.408Z,1537388794.408 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-09-19T20:26:34.408Z,1537388794.408 [Default:CheckIn:C.Wait] Stopped
2018-09-19T20:26:34.408Z,1537388794.408 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-09-19T20:26:34.412Z,1537388794.412 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-09-19T20:26:34.412Z,1537388794.412 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-09-19T20:26:34.412Z,1537388794.412 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-09-19T20:26:34.412Z,1537388794.412 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-09-19T20:26:34.413Z,1537388794.413 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-09-19T20:26:34.413Z,1537388794.413 [BuoyancyServo](INFO): Powering down
2018-09-19T20:26:34.426Z,1537388794.426 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-09-19T20:26:34.427Z,1537388794.427 [ElevatorServo](INFO): Powering down
2018-09-19T20:26:34.428Z,1537388794.428 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-09-19T20:26:34.428Z,1537388794.428 [MassServo](INFO): Powering down
2018-09-19T20:26:34.429Z,1537388794.429 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-09-19T20:26:34.429Z,1537388794.429 [RudderServo](INFO): Powering down
2018-09-19T20:26:34.430Z,1537388794.430 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-09-19T20:26:34.430Z,1537388794.430 [ThrusterServo](INFO): Powering down
2018-09-19T20:26:34.431Z,1537388794.431 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-09-19T20:26:34.431Z,1537388794.431 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-09-19T20:26:34.431Z,1537388794.431 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-09-19T20:26:34.432Z,1537388794.432 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:26:34.481Z,1537388794.481 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:26:34.541Z,1537388794.541 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:26:34.652Z,1537388794.652 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-09-19T20:26:34.725Z,1537388794.725 [logger ThreadHandler](INFO): Thread cancelled.