2019-02-16T16:53:23.707Z,1550336003.707 [Supervisor](DEBUG): Initializing supervisor.
2019-02-16T16:53:23.710Z,1550336003.710 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-02-16T16:53:23.711Z,1550336003.711 [SyncHandler](INFO): Protected caller Thread ID is 1677
2019-02-16T16:53:23.711Z,1550336003.711 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-02-16T16:53:23.712Z,1550336003.712 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-02-16T16:53:23.712Z,1550336003.712 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1678
2019-02-16T16:53:23.715Z,1550336003.715 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-02-16T16:53:23.727Z,1550336003.727 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-02-16T16:53:23.728Z,1550336003.728 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-02-16T16:53:23.728Z,1550336003.728 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1679
2019-02-16T16:53:23.729Z,1550336003.729 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-02-16T16:53:23.730Z,1550336003.730 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-02-16T16:53:23.730Z,1550336003.730 [logger ThreadHandler](INFO): Protected caller Thread ID is 1680
2019-02-16T16:53:23.732Z,1550336003.732 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-02-16T16:53:23.733Z,1550336003.733 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-02-16T16:53:23.734Z,1550336003.734 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-02-16T16:53:23.832Z,1550336003.832 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-02-16T16:53:23.833Z,1550336003.833 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-02-16T16:53:24.032Z,1550336004.032 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-02-16T16:53:24.033Z,1550336004.033 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-02-16T16:53:24.170Z,1550336004.170 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-02-16T16:53:24.171Z,1550336004.171 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-02-16T16:53:24.644Z,1550336004.644 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-02-16T16:53:24.645Z,1550336004.645 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-02-16T16:53:25.086Z,1550336005.086 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-02-16T16:53:25.087Z,1550336005.087 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-02-16T16:53:25.561Z,1550336005.561 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-02-16T16:53:25.562Z,1550336005.562 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-02-16T16:53:25.872Z,1550336005.872 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-02-16T16:53:25.872Z,1550336005.872 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-02-16T16:53:26.224Z,1550336006.224 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-02-16T16:53:26.225Z,1550336006.225 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-02-16T16:53:26.620Z,1550336006.620 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-02-16T16:53:26.620Z,1550336006.620 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-02-16T16:53:26.771Z,1550336006.771 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-02-16T16:53:26.771Z,1550336006.771 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-02-16T16:53:26.879Z,1550336006.879 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-02-16T16:53:26.880Z,1550336006.880 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-02-16T16:53:26.962Z,1550336006.962 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-02-16T16:53:27.065Z,1550336007.065 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-02-16T16:53:27.065Z,1550336007.065 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-02-16T16:53:27.264Z,1550336007.264 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-02-16T16:53:27.265Z,1550336007.265 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-02-16T16:53:27.475Z,1550336007.475 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-02-16T16:53:27.477Z,1550336007.477 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-02-16T16:53:27.477Z,1550336007.477 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-02-16T16:53:27.562Z,1550336007.562 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-02-16T16:53:27.804Z,1550336007.804 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-02-16T16:53:27.804Z,1550336007.804 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-02-16T16:53:28.261Z,1550336008.261 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-02-16T16:53:28.743Z,1550336008.743 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-02-16T16:53:29.043Z,1550336009.043 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-02-16T16:53:29.129Z,1550336009.129 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-02-16T16:53:29.228Z,1550336009.228 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-02-16T16:53:29.322Z,1550336009.322 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-02-16T16:53:29.476Z,1550336009.476 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-02-16T16:53:29.582Z,1550336009.582 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-02-16T16:53:29.682Z,1550336009.682 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2019-02-16T16:53:29.682Z,1550336009.682 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-02-16T16:53:29.685Z,1550336009.685 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-02-16T16:53:29.785Z,1550336009.785 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-02-16T16:53:29.785Z,1550336009.785 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-02-16T16:53:29.912Z,1550336009.912 [BuoyancyServo] Loaded
2019-02-16T16:53:29.912Z,1550336009.912 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-02-16T16:53:29.928Z,1550336009.928 [ElevatorServo] Loaded
2019-02-16T16:53:29.928Z,1550336009.928 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-02-16T16:53:29.943Z,1550336009.943 [MassServo] Loaded
2019-02-16T16:53:29.943Z,1550336009.943 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-02-16T16:53:29.959Z,1550336009.959 [RudderServo] Loaded
2019-02-16T16:53:29.959Z,1550336009.959 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-02-16T16:53:29.973Z,1550336009.973 [ThrusterServo] Loaded
2019-02-16T16:53:29.973Z,1550336009.973 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-02-16T16:53:29.974Z,1550336009.974 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-02-16T16:53:29.974Z,1550336009.974 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-02-16T16:53:30.105Z,1550336010.105 [SBIT](DEBUG): Construct Startup Built In Test.
2019-02-16T16:53:30.133Z,1550336010.133 [SBIT] Loaded
2019-02-16T16:53:30.134Z,1550336010.134 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-02-16T16:53:30.134Z,1550336010.134 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-02-16T16:53:30.163Z,1550336010.163 [IBIT] Loaded
2019-02-16T16:53:30.163Z,1550336010.163 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-02-16T16:53:30.166Z,1550336010.166 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-02-16T16:53:30.556Z,1550336010.556 [CBIT] Loaded
2019-02-16T16:53:30.557Z,1550336010.557 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-02-16T16:53:30.557Z,1550336010.557 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-02-16T16:53:30.558Z,1550336010.558 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-02-16T16:53:30.732Z,1550336010.732 [Aanderaa_O2] Loaded
2019-02-16T16:53:30.733Z,1550336010.733 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-02-16T16:53:30.742Z,1550336010.742 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-02-16T16:53:30.747Z,1550336010.747 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-02-16T16:53:30.749Z,1550336010.749 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-02-16T16:53:30.754Z,1550336010.754 [CTD_Seabird](INFO): created writer for : depth
2019-02-16T16:53:30.754Z,1550336010.754 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-02-16T16:53:30.759Z,1550336010.759 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-02-16T16:53:30.760Z,1550336010.760 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-02-16T16:53:30.765Z,1550336010.765 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-02-16T16:53:30.766Z,1550336010.766 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-02-16T16:53:30.771Z,1550336010.771 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-02-16T16:53:30.772Z,1550336010.772 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-02-16T16:53:30.777Z,1550336010.777 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-02-16T16:53:30.778Z,1550336010.778 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-02-16T16:53:30.783Z,1550336010.783 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-02-16T16:53:30.810Z,1550336010.810 [CTD_Seabird] Loaded
2019-02-16T16:53:30.810Z,1550336010.810 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-02-16T16:53:30.812Z,1550336010.812 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4062F4E0
2019-02-16T16:53:30.812Z,1550336010.812 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1759
2019-02-16T16:53:30.834Z,1550336010.834 [ESPComponent] Loaded
2019-02-16T16:53:30.834Z,1550336010.834 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-02-16T16:53:30.848Z,1550336010.848 [PAR_Licor] Loaded
2019-02-16T16:53:30.848Z,1550336010.848 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-02-16T16:53:30.896Z,1550336010.896 [WetLabsBB2FL] Loaded
2019-02-16T16:53:30.897Z,1550336010.897 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-02-16T16:53:30.898Z,1550336010.898 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4065F4E0
2019-02-16T16:53:30.898Z,1550336010.898 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1760
2019-02-16T16:53:30.899Z,1550336010.899 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-02-16T16:53:30.899Z,1550336010.899 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-02-16T16:53:30.940Z,1550336010.940 [DepthRateCalculator] Loaded
2019-02-16T16:53:30.940Z,1550336010.940 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-02-16T16:53:30.945Z,1550336010.945 [PitchRateCalculator] Loaded
2019-02-16T16:53:30.946Z,1550336010.946 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-02-16T16:53:30.962Z,1550336010.962 [SpeedCalculator] Loaded
2019-02-16T16:53:30.962Z,1550336010.962 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-02-16T16:53:30.983Z,1550336010.983 [TempGradientCalculator] Loaded
2019-02-16T16:53:30.983Z,1550336010.983 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-02-16T16:53:30.989Z,1550336010.989 [YawRateCalculator] Loaded
2019-02-16T16:53:30.989Z,1550336010.989 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-02-16T16:53:31.023Z,1550336011.023 [ElevatorOffsetCalculator] Loaded
2019-02-16T16:53:31.023Z,1550336011.023 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-02-16T16:53:31.024Z,1550336011.024 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-02-16T16:53:31.024Z,1550336011.024 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-02-16T16:53:31.052Z,1550336011.052 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-02-16T16:53:31.053Z,1550336011.053 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-02-16T16:53:31.154Z,1550336011.154 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-02-16T16:53:31.154Z,1550336011.154 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-02-16T16:53:31.213Z,1550336011.213 [DeadReckonUsingSpeedCalculator] Loaded
2019-02-16T16:53:31.214Z,1550336011.214 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-02-16T16:53:31.273Z,1550336011.273 [DeadReckonWithRespectToSeafloor] Loaded
2019-02-16T16:53:31.273Z,1550336011.273 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-02-16T16:53:31.290Z,1550336011.290 [NavChart] Loaded
2019-02-16T16:53:31.290Z,1550336011.290 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-02-16T16:53:31.294Z,1550336011.294 [UniversalFixResidualReporter] Loaded
2019-02-16T16:53:31.295Z,1550336011.295 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-02-16T16:53:31.295Z,1550336011.295 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-02-16T16:53:31.296Z,1550336011.296 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-02-16T16:53:31.573Z,1550336011.573 [AcousticModem_Benthos_ATM900] Loaded
2019-02-16T16:53:31.573Z,1550336011.573 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-02-16T16:53:31.647Z,1550336011.647 [DataOverHttps] Loaded
2019-02-16T16:53:31.647Z,1550336011.647 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-02-16T16:53:31.648Z,1550336011.648 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409274E0
2019-02-16T16:53:31.649Z,1550336011.649 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1761
2019-02-16T16:53:31.662Z,1550336011.662 [Depth_Keller] Loaded
2019-02-16T16:53:31.662Z,1550336011.662 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-02-16T16:53:31.752Z,1550336011.752 [NAL9602] Loaded
2019-02-16T16:53:31.753Z,1550336011.753 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-02-16T16:53:31.758Z,1550336011.758 [Onboard] Loaded
2019-02-16T16:53:31.758Z,1550336011.758 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-02-16T16:53:31.761Z,1550336011.761 [Radio_Surface] Loaded
2019-02-16T16:53:31.762Z,1550336011.762 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-02-16T16:53:31.763Z,1550336011.763 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409574E0
2019-02-16T16:53:31.763Z,1550336011.763 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1762
2019-02-16T16:53:31.810Z,1550336011.810 [PNI_TCM] Loaded
2019-02-16T16:53:31.810Z,1550336011.810 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2019-02-16T16:53:31.871Z,1550336011.871 [Rowe_600LCM] Loaded
2019-02-16T16:53:31.871Z,1550336011.871 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2019-02-16T16:53:31.872Z,1550336011.872 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 409874E0
2019-02-16T16:53:31.873Z,1550336011.873 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 1763
2019-02-16T16:53:33.727Z,1550336013.727 [BPC1] Loaded
2019-02-16T16:53:33.727Z,1550336013.727 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-02-16T16:53:33.728Z,1550336013.728 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-02-16T16:53:33.728Z,1550336013.728 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-02-16T16:53:33.792Z,1550336013.792 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-02-16T16:53:33.793Z,1550336013.793 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-02-16T16:53:33.862Z,1550336013.862 [VerticalControl](DEBUG): Construct VerticalControl.
2019-02-16T16:53:33.946Z,1550336013.946 [VerticalControl] Loaded
2019-02-16T16:53:33.946Z,1550336013.946 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-02-16T16:53:33.947Z,1550336013.947 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-02-16T16:53:34.005Z,1550336014.005 [HorizontalControl] Loaded
2019-02-16T16:53:34.005Z,1550336014.005 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-02-16T16:53:34.006Z,1550336014.006 [SpeedControl](DEBUG): Construct SpeedControl.
2019-02-16T16:53:34.008Z,1550336014.008 [SpeedControl] Loaded
2019-02-16T16:53:34.008Z,1550336014.008 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-02-16T16:53:34.009Z,1550336014.009 [LoopControl](DEBUG): Construct LoopControl.
2019-02-16T16:53:34.009Z,1550336014.009 [LoopControl] Loaded
2019-02-16T16:53:34.010Z,1550336014.010 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-02-16T16:53:34.010Z,1550336014.010 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-02-16T16:53:34.011Z,1550336014.011 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-02-16T16:53:34.024Z,1550336014.024 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-02-16T16:53:34.024Z,1550336014.024 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-02-16T16:53:34.256Z,1550336014.256 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-02-16T16:53:34.259Z,1550336014.259 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-02-16T16:53:34.260Z,1550336014.260 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-02-16T16:53:34.267Z,1550336014.267 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-02-16T16:53:34.268Z,1550336014.268 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AA94E0
2019-02-16T16:53:34.269Z,1550336014.269 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1764
2019-02-16T16:53:34.273Z,1550336014.273 [Supervisor](INFO): Main Thread ID is 807
2019-02-16T16:53:34.273Z,1550336014.273 [Supervisor](DEBUG): Running supervisor.
2019-02-16T16:53:34.274Z,1550336014.274 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1765
2019-02-16T16:53:34.276Z,1550336014.276 [controlThread ThreadHandler](INFO): Handler Thread ID is 1766
2019-02-16T16:53:34.277Z,1550336014.277 [controlThread](DEBUG): Initializing ControlThread
2019-02-16T16:53:34.279Z,1550336014.279 [SBIT](INFO): Initialize SBIT Component.
2019-02-16T16:53:34.279Z,1550336014.279 [SBIT](IMPORTANT): git: 2019-02-11-18-g088f29c
2019-02-16T16:53:34.279Z,1550336014.279 [SBIT](INFO): git hash: 088f29c08f1651a006237423a6ffed1cbba6c6ba
2019-02-16T16:53:34.280Z,1550336014.280 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-02-16T16:53:34.281Z,1550336014.281 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
2019-02-16T16:53:34.282Z,1550336014.282 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-02-16T16:53:34.283Z,1550336014.283 [IBIT](INFO): Initialize IBIT Component.
2019-02-16T16:53:34.284Z,1550336014.284 [CBIT](DEBUG): Initialize CBIT Component.
2019-02-16T16:53:34.285Z,1550336014.285 [logger ThreadHandler](INFO): Handler Thread ID is 1767
2019-02-16T16:53:34.298Z,1550336014.298 [CBIT](DEBUG): Initialized mux pins.
2019-02-16T16:53:34.298Z,1550336014.298 [CBIT](DEBUG): Initializing the watchdog timer.
2019-02-16T16:53:34.310Z,1550336014.310 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1768
2019-02-16T16:53:34.311Z,1550336014.311 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-02-16T16:53:34.314Z,1550336014.314 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-02-16T16:53:34.316Z,1550336014.316 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1770
2019-02-16T16:53:34.317Z,1550336014.317 [WetLabsBB2FL](INFO): Powering down
2019-02-16T16:53:34.322Z,1550336014.322 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-02-16T16:53:34.322Z,1550336014.322 [CBIT](DEBUG): Initializing heartbeat.
2019-02-16T16:53:34.375Z,1550336014.375 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1771
2019-02-16T16:53:34.376Z,1550336014.376 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-02-16T16:53:34.394Z,1550336014.394 [CBIT](DEBUG): Deactivating GF circuits.
2019-02-16T16:53:34.394Z,1550336014.394 [CBIT](DEBUG): Deactivating emergency mode.
2019-02-16T16:53:34.406Z,1550336014.406 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1772
2019-02-16T16:53:34.434Z,1550336014.434 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-02-16T16:53:34.435Z,1550336014.435 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-02-16T16:53:34.435Z,1550336014.435 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-02-16T16:53:34.435Z,1550336014.435 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-02-16T16:53:34.436Z,1550336014.436 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-02-16T16:53:34.436Z,1550336014.436 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-02-16T16:53:34.437Z,1550336014.437 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-16T16:53:34.439Z,1550336014.439 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-02-16T16:53:34.439Z,1550336014.439 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-02-16T16:53:34.443Z,1550336014.443 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-02-16T16:53:34.445Z,1550336014.445 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-02-16T16:53:34.450Z,1550336014.450 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-02-16T16:53:34.451Z,1550336014.451 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-02-16T16:53:34.452Z,1550336014.452 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-02-16T16:53:34.454Z,1550336014.454 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 1773
2019-02-16T16:53:34.455Z,1550336014.455 [Rowe_600LCM](INFO): Initializing
2019-02-16T16:53:34.464Z,1550336014.464 [Rowe_600LCM](INFO): Powering up
2019-02-16T16:53:34.491Z,1550336014.491 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1774
2019-02-16T16:53:34.494Z,1550336014.494 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-02-16T16:53:34.494Z,1550336014.494 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-02-16T16:53:34.494Z,1550336014.494 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-02-16T16:53:34.494Z,1550336014.494 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-02-16T16:53:34.495Z,1550336014.495 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-02-16T16:53:34.495Z,1550336014.495 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-02-16T16:53:34.495Z,1550336014.495 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-02-16T16:53:34.495Z,1550336014.495 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-02-16T16:53:34.495Z,1550336014.495 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-02-16T16:53:34.496Z,1550336014.496 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-02-16T16:53:34.496Z,1550336014.496 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-16T16:53:34.496Z,1550336014.496 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-02-16T16:53:34.496Z,1550336014.496 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-16T16:53:34.496Z,1550336014.496 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-02-16T16:53:34.497Z,1550336014.497 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-16T16:53:34.497Z,1550336014.497 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-02-16T16:53:34.527Z,1550336014.527 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-16T16:53:34.558Z,1550336014.558 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2019-02-16T16:53:34.594Z,1550336014.594 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-02-16T16:53:34.685Z,1550336014.685 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-02-16T16:53:34.686Z,1550336014.686 [Default:A.Wait](DEBUG): Construct Wait.
2019-02-16T16:53:34.688Z,1550336014.688 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-16T16:53:34.745Z,1550336014.745 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-02-16T16:53:34.781Z,1550336014.781 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-02-16T16:53:34.810Z,1550336014.810 [Radio_Surface](INFO): Powering up
2019-02-16T16:53:34.816Z,1550336014.816 [Default:E.Execute](DEBUG): Construct Execute.
2019-02-16T16:53:34.832Z,1550336014.832 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2019-02-16T16:53:34.837Z,1550336014.837 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-02-16T16:53:34.908Z,1550336014.908 [ESPComponent](INFO): powering down ESP
2019-02-16T16:53:35.368Z,1550336015.368 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-02-16T16:53:35.368Z,1550336015.368 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-02-16T16:53:36.019Z,1550336016.019 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-16T16:53:36.030Z,1550336016.030 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-16T16:53:36.062Z,1550336016.062 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-02-16T16:53:36.074Z,1550336016.074 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-02-16T16:53:36.098Z,1550336016.098 [MassServo](DEBUG): Initializing EZServoServo.
2019-02-16T16:53:36.110Z,1550336016.110 [MassServo](DEBUG): Initializing MassServo.
2019-02-16T16:53:36.135Z,1550336016.135 [RudderServo](DEBUG): Initializing EZServoServo.
2019-02-16T16:53:36.143Z,1550336016.143 [RudderServo](DEBUG): Initializing RudderServo.
2019-02-16T16:53:36.178Z,1550336016.178 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-02-16T16:53:36.190Z,1550336016.190 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-02-16T16:53:39.066Z,1550336019.066 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-16T16:53:39.309Z,1550336019.309 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2019-02-16T16:53:40.114Z,1550336020.114 [Aanderaa_O2](INFO): Powering down
2019-02-16T16:53:42.959Z,1550336022.959 [AcousticModem_Benthos_ATM900](DEBUG): 
2019-02-16T16:53:44.172Z,1550336024.172 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2019-02-16T16:53:44.173Z,1550336024.173 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2019-02-16T16:53:44.173Z,1550336024.173 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2019-02-16T16:53:44.174Z,1550336024.174 [AcousticModem_Benthos_ATM900](DEBUG): Feb 12 2019 07:26:33 
2019-02-16T16:53:44.575Z,1550336024.575 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low
2019-02-16T16:53:44.807Z,1550336024.807 [CBIT](CRITICAL): Environmental Failure. Press:14.858557 PSI. Humidity:29%. Temp:26 C. ABORTING MISSION
2019-02-16T16:53:44.807Z,1550336024.807 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals
2019-02-16T16:53:45.083Z,1550336025.083 [CommandLine](FAULT): Scheduling is paused
2019-02-16T16:53:45.083Z,1550336025.083 [CBIT](INFO): Critical error at 20190216T165344
2019-02-16T16:53:45.383Z,1550336025.383 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2019-02-16T16:53:45.787Z,1550336025.787 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2019-02-16T16:53:45.788Z,1550336025.788 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2019-02-16T16:53:45.788Z,1550336025.788 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2019-02-16T16:53:46.203Z,1550336026.203 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2019-02-16T16:53:46.595Z,1550336026.595 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2019-02-16T16:53:46.596Z,1550336026.596 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2019-02-16T16:53:46.999Z,1550336026.999 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2019-02-16T16:53:58.774Z,1550336038.774 [SBIT](IMPORTANT): Beginning Startup BIT
2019-02-16T16:53:58.785Z,1550336038.785 [CBIT](IMPORTANT): Beginning ground fault scan
2019-02-16T16:54:02.099Z,1550336042.099 [NAL9602](INFO): Powering up NAL9602
2019-02-16T16:54:09.895Z,1550336049.895 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.008330
CHAN A1 (24V): -0.013964
CHAN A2 (12V): -0.005085
CHAN A3 (5V): -0.002424
CHAN B0 (3.3V): -0.000914
CHAN B1 (3.15aV): -0.001356
CHAN B2 (3.15bV): -0.001208
CHAN B3 (GND): -0.000868
OPEN: 0.004523
Full Scale Calc: 4.765 mA, -1.589 mA
2019-02-16T16:54:12.999Z,1550336052.999 [NAL9602](INFO): NAL9602 initialized
2019-02-16T16:54:13.818Z,1550336053.818 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:54:52.131Z,1550336092.131 [SBIT](IMPORTANT): SBIT PASSED
2019-02-16T16:54:52.183Z,1550336092.183 [CommandLine](IMPORTANT): got command configSet list
2019-02-16T16:54:52.184Z,1550336092.184 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-02-16T16:54:52.185Z,1550336092.185 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-02-16T16:54:52.551Z,1550336092.551 [MissionManager](IMPORTANT): Started mission Startup
2019-02-16T16:54:52.552Z,1550336092.552 [Startup] Running Loop=1
2019-02-16T16:54:52.552Z,1550336092.552 [Startup](DEBUG): Aggregate::initialize Startup
2019-02-16T16:54:52.552Z,1550336092.552 [Startup:A.GoToSurface] Running Loop=1
2019-02-16T16:54:52.552Z,1550336092.552 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-02-16T16:54:52.553Z,1550336092.553 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-02-16T16:54:52.553Z,1550336092.553 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-02-16T16:54:52.554Z,1550336092.554 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-02-16T16:54:52.554Z,1550336092.554 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-02-16T16:54:52.555Z,1550336092.555 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-02-16T16:54:52.561Z,1550336092.561 [Startup:StartupSatComms] Running Loop=1
2019-02-16T16:54:52.561Z,1550336092.561 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-02-16T16:54:52.561Z,1550336092.561 [Startup:StartupSatComms:A] Running Loop=1
2019-02-16T16:54:52.947Z,1550336092.947 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-02-16T16:55:34.922Z,1550336134.922 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-02-16T16:55:34.922Z,1550336134.922 [Rowe_600LCM] Communications Fault, FailCount= 1
2019-02-16T16:55:34.922Z,1550336134.922 [Rowe_600LCM](ERROR): Communications Fault
2019-02-16T16:55:34.974Z,1550336134.974 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-02-16T16:55:35.326Z,1550336135.326 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-16T16:55:35.499Z,1550336135.499 [Rowe_600LCM](INFO): Powering down
2019-02-16T16:55:37.045Z,1550336137.045 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-02-16T16:55:37.046Z,1550336137.046 [Rowe_600LCM] No Fault, FailCount= 1
2019-02-16T16:55:37.114Z,1550336137.114 [Rowe_600LCM](INFO): Initializing
2019-02-16T16:55:37.115Z,1550336137.115 [Rowe_600LCM](INFO): Powering up
2019-02-16T16:55:41.242Z,1550336141.242 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-16T16:55:41.305Z,1550336141.305 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2019-02-16T16:55:44.236Z,1550336144.236 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2019-02-16T16:55:52.717Z,1550336152.717 [Startup:StartupSatComms:A](INFO): Timed out from 2019-02-16T16:54:52.6Z
2019-02-16T16:55:52.717Z,1550336152.717 [Startup:StartupSatComms:A] Stopped
2019-02-16T16:55:52.717Z,1550336152.717 [Startup:StartupSatComms:B] Running Loop=1
2019-02-16T16:55:53.125Z,1550336153.125 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-02-16T16:56:34.743Z,1550336194.743 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-02-16T16:56:34.743Z,1550336194.743 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-16T16:56:34.744Z,1550336194.744 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-02-16T16:56:34.744Z,1550336194.744 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-16T16:56:34.745Z,1550336194.745 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-02-16T16:56:34.745Z,1550336194.745 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-16T16:56:34.758Z,1550336194.758 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-16T16:56:34.759Z,1550336194.759 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-16T16:56:34.759Z,1550336194.759 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-16T16:56:35.175Z,1550336195.175 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-16T16:56:35.175Z,1550336195.175 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-02-16T16:56:35.176Z,1550336195.176 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-16T16:56:35.176Z,1550336195.176 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-02-16T16:56:35.176Z,1550336195.176 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-16T16:56:35.176Z,1550336195.176 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-02-16T16:56:35.570Z,1550336195.570 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-16T16:56:41.852Z,1550336201.852 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004779
2019-02-16T16:56:47.941Z,1550336207.941 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190216T163854/Courier0007.lzma
2019-02-16T16:56:48.748Z,1550336208.748 [DataOverHttps](INFO): Moved sent file to Logs/20190216T163854/Courier0007.lzma.bak
2019-02-16T16:56:48.748Z,1550336208.748 [DataOverHttps](INFO): SBD MOMSN=9993507
2019-02-16T16:56:52.920Z,1550336212.920 [Startup:StartupSatComms:B](INFO): Timed out from 2019-02-16T16:55:52.7Z
2019-02-16T16:56:52.921Z,1550336212.921 [Startup:StartupSatComms:B] Stopped
2019-02-16T16:56:52.921Z,1550336212.921 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-02-16T16:56:52.921Z,1550336212.921 [Startup:StartupSatComms] Stopped
2019-02-16T16:56:52.921Z,1550336212.921 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-02-16T16:56:52.926Z,1550336212.926 [Startup](INFO): Completed Startup
2019-02-16T16:56:52.926Z,1550336212.926 [MissionManager](INFO): Startup is completed.
2019-02-16T16:56:52.926Z,1550336212.926 [MissionManager](INFO): Uninitializing Mission Startup
2019-02-16T16:56:52.926Z,1550336212.926 [Startup] Stopped
2019-02-16T16:56:52.927Z,1550336212.927 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-02-16T16:56:52.927Z,1550336212.927 [Startup:A.GoToSurface] Stopped
2019-02-16T16:56:52.927Z,1550336212.927 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-02-16T16:56:53.339Z,1550336213.339 [MissionManager](IMPORTANT): Started mission Default
2019-02-16T16:56:53.339Z,1550336213.339 [Default] Running Loop=1
2019-02-16T16:56:53.340Z,1550336213.340 [Default](DEBUG): Aggregate::initialize Default
2019-02-16T16:56:53.340Z,1550336213.340 [Default:B.GoToSurface] Running Loop=1
2019-02-16T16:56:53.340Z,1550336213.340 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-02-16T16:56:53.340Z,1550336213.340 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-02-16T16:56:53.340Z,1550336213.340 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-02-16T16:56:53.341Z,1550336213.341 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-02-16T16:56:53.341Z,1550336213.341 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-02-16T16:56:53.341Z,1550336213.341 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-02-16T16:56:53.342Z,1550336213.342 [Default:A.Wait] Running Loop=1
2019-02-16T16:56:53.346Z,1550336213.346 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-02-16T16:57:02.434Z,1550336222.434 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20190216T165323/Courier0000.lzma
2019-02-16T16:57:03.239Z,1550336223.239 [DataOverHttps](INFO): Moved sent file to Logs/20190216T165323/Courier0000.lzma.bak
2019-02-16T16:57:03.240Z,1550336223.240 [DataOverHttps](INFO): SBD MOMSN=9993509
2019-02-16T16:57:06.657Z,1550336226.657 [Default:A.Wait](INFO): Done Waiting.
2019-02-16T16:57:06.657Z,1550336226.657 [Default:A.Wait] Stopped
2019-02-16T16:57:06.657Z,1550336226.657 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-02-16T16:57:07.061Z,1550336227.061 [Default:CheckIn] Running Loop=1
2019-02-16T16:57:07.061Z,1550336227.061 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-02-16T16:57:07.061Z,1550336227.061 [Default:CheckIn:Read_GPS] Running Loop=1
2019-02-16T16:57:07.467Z,1550336227.467 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-02-16T16:57:37.574Z,1550336257.574 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-02-16T16:57:37.574Z,1550336257.574 [Rowe_600LCM] Communications Fault, FailCount= 2
2019-02-16T16:57:37.574Z,1550336257.574 [Rowe_600LCM](ERROR): Communications Fault
2019-02-16T16:57:37.793Z,1550336257.793 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-02-16T16:57:37.978Z,1550336257.978 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-16T16:57:38.078Z,1550336258.078 [Rowe_600LCM](INFO): Powering down
2019-02-16T16:57:39.388Z,1550336259.388 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-02-16T16:57:39.389Z,1550336259.389 [Rowe_600LCM] No Fault, FailCount= 2
2019-02-16T16:57:39.694Z,1550336259.694 [Rowe_600LCM](INFO): Initializing
2019-02-16T16:57:39.695Z,1550336259.695 [Rowe_600LCM](INFO): Powering up
2019-02-16T16:57:43.919Z,1550336263.919 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-16T16:57:44.037Z,1550336264.037 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2019-02-16T16:59:16.703Z,1550336356.703 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-02-16T16:59:17.530Z,1550336357.530 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:59:22.382Z,1550336362.382 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:59:25.198Z,1550336365.198 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:59:28.038Z,1550336368.038 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:59:30.046Z,1550336370.046 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:59:32.078Z,1550336372.078 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:59:34.086Z,1550336374.086 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:59:35.272Z,1550336375.272 [CommandLine](IMPORTANT): got command get Radio_Surface.radio_surface_power
2019-02-16T16:59:35.272Z,1550336375.272 [CommandLine](IMPORTANT): Radio_Surface.radio_surface_power 1 enum
2019-02-16T16:59:35.739Z,1550336375.739 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-02-16T16:59:35.739Z,1550336375.739 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-16T16:59:35.741Z,1550336375.741 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-02-16T16:59:35.741Z,1550336375.741 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-16T16:59:35.742Z,1550336375.742 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-02-16T16:59:35.742Z,1550336375.742 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-16T16:59:35.755Z,1550336375.755 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-16T16:59:35.755Z,1550336375.755 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-16T16:59:35.755Z,1550336375.755 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-16T16:59:36.155Z,1550336376.155 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-16T16:59:36.155Z,1550336376.155 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-02-16T16:59:36.156Z,1550336376.156 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-16T16:59:36.156Z,1550336376.156 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-02-16T16:59:36.156Z,1550336376.156 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-16T16:59:36.156Z,1550336376.156 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-02-16T16:59:36.545Z,1550336376.545 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-16T16:59:37.322Z,1550336377.322 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:59:40.150Z,1550336380.150 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:59:40.266Z,1550336380.266 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-02-16T16:59:40.266Z,1550336380.266 [Rowe_600LCM] Communications Fault, FailCount= 3
2019-02-16T16:59:40.266Z,1550336380.266 [Rowe_600LCM](ERROR): Communications Fault
2019-02-16T16:59:40.628Z,1550336380.628 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-02-16T16:59:40.670Z,1550336380.670 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-16T16:59:40.797Z,1550336380.797 [Rowe_600LCM](INFO): Powering down
2019-02-16T16:59:42.212Z,1550336382.212 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-02-16T16:59:42.213Z,1550336382.213 [Rowe_600LCM] No Fault, FailCount= 3
2019-02-16T16:59:42.414Z,1550336382.414 [Rowe_600LCM](INFO): Initializing
2019-02-16T16:59:42.415Z,1550336382.415 [Rowe_600LCM](INFO): Powering up
2019-02-16T16:59:43.374Z,1550336383.374 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:59:46.206Z,1550336386.206 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:59:46.540Z,1550336386.540 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-16T16:59:46.619Z,1550336386.619 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2019-02-16T16:59:47.324Z,1550336387.324 [CommandLine](IMPORTANT): got command report touch Radio_Surface.radio_surface_power
2019-02-16T16:59:49.438Z,1550336389.438 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:59:52.274Z,1550336392.274 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:59:55.106Z,1550336395.106 [NAL9602](DEBUG): Fix Requested
2019-02-16T16:59:57.138Z,1550336397.138 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:00:00.350Z,1550336400.350 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:00:02.596Z,1550336402.596 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 3.000000 meter
2019-02-16T17:00:02.597Z,1550336402.597 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2019-02-16T17:00:02.816Z,1550336402.816 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,Maintain_Depth_Keller.depth,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-02-16T17:00:03.182Z,1550336403.182 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:00:03.632Z,1550336403.632 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -13.00 mm (1 active estimators).
2019-02-16T17:00:03.878Z,1550336403.878 [Radio_Surface](INFO): Powering down
2019-02-16T17:00:04.143Z,1550336404.143 [Reporter](INFO): Radio_Surface.radio_surface_power no_value
2019-02-16T17:00:06.094Z,1550336406.094 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:00:08.114Z,1550336408.114 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:00:10.134Z,1550336410.134 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:00:13.374Z,1550336413.374 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:00:16.190Z,1550336416.190 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:00:19.422Z,1550336419.422 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:00:20.712Z,1550336420.712 [CommandLine](IMPORTANT): got command maintain clear
2019-02-16T17:00:20.768Z,1550336420.768 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-02-16T17:00:21.654Z,1550336421.654 [Radio_Surface](INFO): Powering up
2019-02-16T17:00:22.258Z,1550336422.258 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:00:25.174Z,1550336425.174 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:00:27.273Z,1550336427.273 [Reporter](INFO): Radio_Surface.radio_surface_power 1 enum
2019-02-16T17:00:28.406Z,1550336428.406 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:00:31.234Z,1550336431.234 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:00:34.458Z,1550336434.458 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:00:37.290Z,1550336437.290 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:00:40.522Z,1550336440.522 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:00:43.346Z,1550336443.346 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:00:46.174Z,1550336446.174 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:00:48.202Z,1550336448.202 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:00:51.434Z,1550336451.434 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:00:54.262Z,1550336454.262 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:00:57.490Z,1550336457.490 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:01:00.314Z,1550336460.314 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:01:03.550Z,1550336463.550 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:01:06.382Z,1550336466.382 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:01:09.206Z,1550336469.206 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:01:11.230Z,1550336471.230 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:01:13.246Z,1550336473.246 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:01:16.478Z,1550336476.478 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:01:19.302Z,1550336479.302 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:01:22.538Z,1550336482.538 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:01:25.370Z,1550336485.370 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:01:27.566Z,1550336487.566 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-02-16T17:01:28.602Z,1550336488.602 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:01:31.429Z,1550336491.429 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:01:34.258Z,1550336494.258 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:01:36.274Z,1550336496.274 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:01:39.117Z,1550336499.117 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2019-02-16T17:01:39.506Z,1550336499.506 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:01:42.334Z,1550336502.334 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:01:42.878Z,1550336502.878 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-02-16T17:01:42.878Z,1550336502.878 [Rowe_600LCM] Communications Fault, FailCount= 4
2019-02-16T17:01:42.878Z,1550336502.878 [Rowe_600LCM](ERROR): Communications Fault
2019-02-16T17:01:43.186Z,1550336503.186 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-02-16T17:01:43.282Z,1550336503.282 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-16T17:01:43.377Z,1550336503.377 [Rowe_600LCM](INFO): Powering down
2019-02-16T17:01:44.823Z,1550336504.823 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2019-02-16T17:01:44.823Z,1550336504.823 [Rowe_600LCM] No Fault, FailCount= 4
2019-02-16T17:01:44.990Z,1550336504.990 [Rowe_600LCM](INFO): Initializing
2019-02-16T17:01:44.991Z,1550336504.991 [Rowe_600LCM](INFO): Powering up
2019-02-16T17:01:45.562Z,1550336505.562 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:01:48.394Z,1550336508.394 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:01:49.116Z,1550336509.116 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2019-02-16T17:01:49.193Z,1550336509.193 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2019-02-16T17:01:51.626Z,1550336511.626 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:01:54.454Z,1550336514.454 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:01:57.294Z,1550336517.294 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:01:59.298Z,1550336519.298 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:02:02.530Z,1550336522.530 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:02:05.358Z,1550336525.358 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:02:07.409Z,1550336527.409 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-02-16T16:57:07.1Z
2019-02-16T17:02:07.409Z,1550336527.409 [Default:CheckIn:Read_GPS] Stopped
2019-02-16T17:02:07.409Z,1550336527.409 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-02-16T17:02:07.818Z,1550336527.818 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-02-16T17:02:08.594Z,1550336528.594 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:02:09.889Z,1550336529.889 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-02-16T17:02:11.418Z,1550336531.418 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:02:14.658Z,1550336534.658 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:02:17.485Z,1550336537.485 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:02:20.314Z,1550336540.314 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:02:22.330Z,1550336542.330 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:02:24.350Z,1550336544.350 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:02:27.582Z,1550336547.582 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:02:27.763Z,1550336547.763 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-02-16T17:02:30.422Z,1550336550.422 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:02:33.638Z,1550336553.638 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:02:36.466Z,1550336556.466 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:02:36.904Z,1550336556.904 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-02-16T17:02:36.904Z,1550336556.904 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-16T17:02:36.905Z,1550336556.905 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-02-16T17:02:36.905Z,1550336556.905 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-16T17:02:36.907Z,1550336556.907 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2019-02-16T17:02:36.907Z,1550336556.907 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-16T17:02:36.919Z,1550336556.919 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-16T17:02:36.920Z,1550336556.920 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-16T17:02:36.920Z,1550336556.920 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-16T17:02:37.318Z,1550336557.318 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-16T17:02:37.318Z,1550336557.318 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-02-16T17:02:37.318Z,1550336557.318 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-16T17:02:37.319Z,1550336557.319 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-02-16T17:02:37.319Z,1550336557.319 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-16T17:02:37.319Z,1550336557.319 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2019-02-16T17:02:37.716Z,1550336557.716 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-16T17:02:39.706Z,1550336559.706 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:02:42.526Z,1550336562.526 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:02:45.354Z,1550336565.354 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:02:47.374Z,1550336567.374 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:02:49.394Z,1550336569.394 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:02:52.388Z,1550336572.388 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 3.000000 meter
2019-02-16T17:02:52.389Z,1550336572.389 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2019-02-16T17:02:52.626Z,1550336572.626 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:02:52.675Z,1550336572.675 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,Maintain_Depth_Keller.depth,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-02-16T17:02:53.590Z,1550336573.590 [Radio_Surface](INFO): Powering down
2019-02-16T17:02:54.055Z,1550336574.055 [Reporter](INFO): Radio_Surface.radio_surface_power no_value
2019-02-16T17:02:55.554Z,1550336575.554 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:02:58.382Z,1550336578.382 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:02:58.466Z,1550336578.466 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-02-16T17:03:00.412Z,1550336580.412 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:03:02.927Z,1550336582.927 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.211716
2019-02-16T17:03:03.638Z,1550336583.638 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:03:05.711Z,1550336585.711 [Radio_Surface](INFO): Powering up
2019-02-16T17:03:11.115Z,1550336591.115 [Reporter](INFO): Radio_Surface.radio_surface_power 1 enum
2019-02-16T17:03:11.770Z,1550336591.770 [Radio_Surface](INFO): Powering down
2019-02-16T17:03:11.787Z,1550336591.787 [Reporter](INFO): Radio_Surface.radio_surface_power no_value
2019-02-16T17:03:17.830Z,1550336597.830 [Radio_Surface](INFO): Powering up
2019-02-16T17:03:23.511Z,1550336603.511 [Reporter](INFO): Radio_Surface.radio_surface_power no_value
2019-02-16T17:03:23.906Z,1550336603.906 [Radio_Surface](INFO): Powering down
2019-02-16T17:03:23.915Z,1550336603.915 [Reporter](INFO): Radio_Surface.radio_surface_power no_value
2019-02-16T17:03:27.396Z,1550336607.396 [CommandLine](IMPORTANT): got command maintain clear
2019-02-16T17:03:27.536Z,1550336607.536 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-02-16T17:03:29.966Z,1550336609.966 [Radio_Surface](INFO): Powering up
2019-02-16T17:03:33.662Z,1550336613.662 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-02-16T17:03:34.804Z,1550336614.804 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response. 
2019-02-16T17:03:34.878Z,1550336614.878 [NAL9602](ERROR): received: 
+CSQ:0

OK
2019-02-16T17:03:34.878Z,1550336614.878 [NAL9602] Data Fault, FailCount= 1
2019-02-16T17:03:34.878Z,1550336614.878 [NAL9602](ERROR): Data Fault
2019-02-16T17:03:35.027Z,1550336615.027 [CBIT](ERROR): Data Fault in component: NAL9602
2019-02-16T17:03:35.215Z,1550336615.215 [NAL9602](INFO): Powering down
2019-02-16T17:03:35.300Z,1550336615.300 [Reporter](INFO): Radio_Surface.radio_surface_power 1 enum
2019-02-16T17:03:36.107Z,1550336616.107 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-16T17:03:36.107Z,1550336616.107 [NAL9602] No Fault, FailCount= 1
2019-02-16T17:03:45.422Z,1550336625.422 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2019-02-16T17:03:45.422Z,1550336625.422 [Rowe_600LCM] Communications Fault, FailCount= 5
2019-02-16T17:03:45.422Z,1550336625.422 [Rowe_600LCM](ERROR): Communications Fault
2019-02-16T17:03:45.830Z,1550336625.830 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-16T17:03:45.888Z,1550336625.888 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2019-02-16T17:03:45.888Z,1550336625.888 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2019-02-16T17:03:45.976Z,1550336625.976 [Rowe_600LCM](INFO): Powering down
2019-02-16T17:04:05.521Z,1550336645.521 [NAL9602](INFO): Powering up NAL9602
2019-02-16T17:04:07.153Z,1550336647.153 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2019-02-16T17:04:07.159Z,1550336647.159 [BPC1](FAULT): Failed to parse data from all battery packs.
2019-02-16T17:04:07.159Z,1550336647.159 [BPC1] Data Fault, FailCount= 1
2019-02-16T17:04:07.159Z,1550336647.159 [BPC1](ERROR): Data Fault
2019-02-16T17:04:07.213Z,1550336647.213 [CBIT](ERROR): Data Fault in component: BPC1
2019-02-16T17:04:16.415Z,1550336656.415 [NAL9602](INFO): NAL9602 initialized
2019-02-16T17:04:17.227Z,1550336657.227 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-02-16T17:04:17.227Z,1550336657.227 [NAL9602] Data Fault, FailCount= 2
2019-02-16T17:04:17.227Z,1550336657.227 [NAL9602](ERROR): Data Fault
2019-02-16T17:04:17.277Z,1550336657.277 [CBIT](ERROR): Data Fault in component: NAL9602
2019-02-16T17:04:17.635Z,1550336657.635 [NAL9602](INFO): Powering down
2019-02-16T17:04:18.476Z,1550336658.476 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-16T17:04:18.476Z,1550336658.476 [NAL9602] No Fault, FailCount= 2
2019-02-16T17:04:33.858Z,1550336673.858 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-02-16T17:04:47.927Z,1550336687.927 [NAL9602](INFO): Powering up NAL9602
2019-02-16T17:04:58.848Z,1550336698.848 [NAL9602](INFO): NAL9602 initialized
2019-02-16T17:04:59.662Z,1550336699.662 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:05:03.698Z,1550336703.698 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:05:08.138Z,1550336708.138 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:05:11.774Z,1550336711.774 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:05:16.222Z,1550336716.222 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:05:19.858Z,1550336719.858 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:05:24.302Z,1550336724.302 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:05:27.934Z,1550336727.934 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:05:32.378Z,1550336732.378 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:05:34.054Z,1550336734.054 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-02-16T17:05:36.014Z,1550336736.014 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:05:38.075Z,1550336738.075 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-02-16T17:05:38.075Z,1550336738.075 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-16T17:05:38.076Z,1550336738.076 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2019-02-16T17:05:38.076Z,1550336738.076 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-16T17:05:38.077Z,1550336738.077 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2019-02-16T17:05:38.077Z,1550336738.077 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-16T17:05:38.090Z,1550336738.090 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-16T17:05:38.091Z,1550336738.091 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-16T17:05:38.091Z,1550336738.091 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-16T17:05:38.475Z,1550336738.475 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-16T17:05:38.476Z,1550336738.476 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-02-16T17:05:38.476Z,1550336738.476 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-16T17:05:38.476Z,1550336738.476 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2019-02-16T17:05:38.477Z,1550336738.477 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-16T17:05:38.477Z,1550336738.477 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2019-02-16T17:05:38.868Z,1550336738.868 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-16T17:05:40.462Z,1550336740.462 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:05:43.694Z,1550336743.694 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:05:48.139Z,1550336748.139 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:05:51.778Z,1550336751.778 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:05:56.214Z,1550336756.214 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:05:58.404Z,1550336758.404 [CommandLine](IMPORTANT): got command quit
2019-02-16T17:05:59.413Z,1550336759.413 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-16T17:05:59.414Z,1550336759.414 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-16T17:05:59.602Z,1550336759.602 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-02-16T17:05:59.602Z,1550336759.602 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-16T17:05:59.603Z,1550336759.603 [CommandLine](INFO): Join timeout helper Thread ID is 1823
2019-02-16T17:05:59.604Z,1550336759.604 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-02-16T17:05:59.604Z,1550336759.604 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-16T17:05:59.605Z,1550336759.605 [NavChartDb](INFO): Join timeout helper Thread ID is 1824
2019-02-16T17:05:59.682Z,1550336759.682 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-16T17:05:59.683Z,1550336759.683 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-16T17:05:59.690Z,1550336759.690 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2019-02-16T17:05:59.690Z,1550336759.690 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-02-16T17:05:59.690Z,1550336759.690 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 1825
2019-02-16T17:05:59.698Z,1550336759.698 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-16T17:05:59.698Z,1550336759.698 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2019-02-16T17:05:59.781Z,1550336759.781 [Rowe_600LCM](INFO): Powering down
2019-02-16T17:05:59.793Z,1550336759.793 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-02-16T17:05:59.794Z,1550336759.794 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-02-16T17:05:59.794Z,1550336759.794 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-16T17:05:59.795Z,1550336759.795 [Radio_Surface](INFO): Join timeout helper Thread ID is 1827
2019-02-16T17:05:59.854Z,1550336759.854 [Radio_Surface](INFO): Powering down
2019-02-16T17:05:59.855Z,1550336759.855 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-16T17:05:59.856Z,1550336759.856 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-16T17:05:59.862Z,1550336759.862 [NAL9602](DEBUG): Fix Requested
2019-02-16T17:05:59.874Z,1550336759.874 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-02-16T17:05:59.874Z,1550336759.874 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-02-16T17:05:59.875Z,1550336759.875 [DataOverHttps](INFO): Join timeout helper Thread ID is 1828
2019-02-16T17:05:59.910Z,1550336759.910 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-16T17:05:59.911Z,1550336759.911 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-02-16T17:05:59.915Z,1550336759.915 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-02-16T17:05:59.915Z,1550336759.915 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-02-16T17:05:59.916Z,1550336759.916 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1829
2019-02-16T17:05:59.953Z,1550336759.953 [Reporter](INFO): Radio_Surface.radio_surface_power no_value
2019-02-16T17:06:00.318Z,1550336760.318 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-16T17:06:00.318Z,1550336760.318 [WetLabsBB2FL](INFO): Powering down
2019-02-16T17:06:00.319Z,1550336760.319 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-02-16T17:06:00.335Z,1550336760.335 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-02-16T17:06:00.335Z,1550336760.335 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-02-16T17:06:00.335Z,1550336760.335 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1830
2019-02-16T17:06:00.546Z,1550336760.546 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-16T17:06:00.546Z,1550336760.546 [CTD_Seabird](INFO): Powering down
2019-02-16T17:06:00.558Z,1550336760.558 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-02-16T17:06:00.567Z,1550336760.567 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-02-16T17:06:00.567Z,1550336760.567 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-16T17:06:00.567Z,1550336760.567 [logger](INFO): Join timeout helper Thread ID is 1831
2019-02-16T17:06:00.614Z,1550336760.614 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-16T17:06:00.614Z,1550336760.614 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-16T17:06:00.627Z,1550336760.627 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-02-16T17:06:00.627Z,1550336760.627 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-16T17:06:00.627Z,1550336760.627 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-02-16T17:06:00.627Z,1550336760.627 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-16T17:06:00.627Z,1550336760.627 [controlThread](INFO): Join timeout helper Thread ID is 1832
2019-02-16T17:06:00.634Z,1550336760.634 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-16T17:06:00.634Z,1550336760.634 [controlThread](DEBUG): Uninitializing ControlThread
2019-02-16T17:06:00.635Z,1550336760.635 [Aanderaa_O2](INFO): Powering down
2019-02-16T17:06:00.637Z,1550336760.637 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-02-16T17:06:00.758Z,1550336760.758 [NAL9602](INFO): Powering down
2019-02-16T17:06:00.760Z,1550336760.760 [PNI_TCM](INFO): Powering down
2019-02-16T17:06:00.847Z,1550336760.847 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-02-16T17:06:00.848Z,1550336760.848 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-02-16T17:06:00.849Z,1550336760.849 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-02-16T17:06:00.849Z,1550336760.849 [MissionManager](INFO): Uninitializing Mission Default
2019-02-16T17:06:00.849Z,1550336760.849 [Default] Stopped
2019-02-16T17:06:00.849Z,1550336760.849 [Default](DEBUG): Aggregate::uninitialize Default
2019-02-16T17:06:00.849Z,1550336760.849 [Default:B.GoToSurface] Stopped
2019-02-16T17:06:00.849Z,1550336760.849 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-02-16T17:06:00.850Z,1550336760.850 [Default:CheckIn] Stopped
2019-02-16T17:06:00.850Z,1550336760.850 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-02-16T17:06:00.850Z,1550336760.850 [Default:CheckIn:Read_Iridium] Stopped
2019-02-16T17:06:00.853Z,1550336760.853 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-02-16T17:06:00.853Z,1550336760.853 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-02-16T17:06:00.854Z,1550336760.854 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-02-16T17:06:00.854Z,1550336760.854 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-02-16T17:06:00.854Z,1550336760.854 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-02-16T17:06:00.854Z,1550336760.854 [BuoyancyServo](INFO): Powering down
2019-02-16T17:06:00.866Z,1550336760.866 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-02-16T17:06:00.866Z,1550336760.866 [ElevatorServo](INFO): Powering down
2019-02-16T17:06:00.867Z,1550336760.867 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-02-16T17:06:00.867Z,1550336760.867 [MassServo](INFO): Powering down
2019-02-16T17:06:00.868Z,1550336760.868 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-02-16T17:06:00.868Z,1550336760.868 [RudderServo](INFO): Powering down
2019-02-16T17:06:00.869Z,1550336760.869 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-02-16T17:06:00.869Z,1550336760.869 [ThrusterServo](INFO): Powering down
2019-02-16T17:06:00.870Z,1550336760.870 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-02-16T17:06:00.870Z,1550336760.870 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-02-16T17:06:00.871Z,1550336760.871 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-02-16T17:06:00.871Z,1550336760.871 [CBIT](DEBUG): Powering off loads.
2019-02-16T17:06:00.882Z,1550336760.882 [CBIT](DEBUG): Disabling WDT.
2019-02-16T17:06:00.894Z,1550336760.894 [CBIT](DEBUG): Opening all GF detection circuits.
2019-02-16T17:06:00.895Z,1550336760.895 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-16T17:06:00.948Z,1550336760.948 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-02-16T17:06:00.954Z,1550336760.954 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-02-16T17:06:01.036Z,1550336761.036 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2019-02-16T17:06:01.042Z,1550336761.042 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-16T17:06:01.047Z,1550336761.047 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-02-16T17:06:01.094Z,1550336761.094 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-16T17:06:01.153Z,1550336761.153 [logger ThreadHandler](INFO): Thread cancelled.