2018-10-23T23:39:59.961Z,1540337999.961 [Supervisor](DEBUG): Initializing supervisor.
2018-10-23T23:39:59.964Z,1540337999.964 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-10-23T23:39:59.964Z,1540337999.964 [SyncHandler](INFO): Protected caller Thread ID is 799
2018-10-23T23:39:59.965Z,1540337999.965 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-10-23T23:39:59.966Z,1540337999.966 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-10-23T23:39:59.966Z,1540337999.966 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 800
2018-10-23T23:39:59.969Z,1540337999.969 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-10-23T23:39:59.980Z,1540337999.980 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-10-23T23:39:59.981Z,1540337999.981 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-10-23T23:39:59.982Z,1540337999.982 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 801
2018-10-23T23:39:59.982Z,1540337999.982 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-10-23T23:39:59.983Z,1540337999.983 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-10-23T23:39:59.984Z,1540337999.984 [logger ThreadHandler](INFO): Protected caller Thread ID is 802
2018-10-23T23:39:59.986Z,1540337999.986 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-10-23T23:39:59.986Z,1540337999.986 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-10-23T23:39:59.990Z,1540337999.990 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-10-23T23:40:00.429Z,1540338000.429 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-10-23T23:40:00.431Z,1540338000.431 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-10-23T23:40:00.881Z,1540338000.881 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-10-23T23:40:00.883Z,1540338000.883 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-10-23T23:40:01.084Z,1540338001.084 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-10-23T23:40:01.085Z,1540338001.085 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-10-23T23:40:01.231Z,1540338001.231 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-10-23T23:40:01.233Z,1540338001.233 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-10-23T23:40:01.675Z,1540338001.675 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-10-23T23:40:01.675Z,1540338001.675 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-10-23T23:40:01.772Z,1540338001.772 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-10-23T23:40:01.773Z,1540338001.773 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-10-23T23:40:01.916Z,1540338001.916 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-10-23T23:40:01.917Z,1540338001.917 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-10-23T23:40:02.019Z,1540338002.019 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-10-23T23:40:02.021Z,1540338002.021 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-10-23T23:40:02.169Z,1540338002.169 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-10-23T23:40:02.169Z,1540338002.169 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-10-23T23:40:02.516Z,1540338002.516 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-10-23T23:40:02.517Z,1540338002.517 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-10-23T23:40:02.723Z,1540338002.723 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-10-23T23:40:02.723Z,1540338002.723 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-10-23T23:40:02.938Z,1540338002.938 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-10-23T23:40:02.938Z,1540338002.938 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-10-23T23:40:03.021Z,1540338003.021 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-10-23T23:40:03.378Z,1540338003.378 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-10-23T23:40:03.379Z,1540338003.379 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-10-23T23:40:04.183Z,1540338004.183 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-10-23T23:40:04.185Z,1540338004.185 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2018-10-23T23:40:04.189Z,1540338004.189 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2018-10-23T23:40:04.275Z,1540338004.275 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2018-10-23T23:40:04.427Z,1540338004.427 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2018-10-23T23:40:04.527Z,1540338004.527 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2018-10-23T23:40:04.702Z,1540338004.702 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2018-10-23T23:40:04.785Z,1540338004.785 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2018-10-23T23:40:05.012Z,1540338005.012 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-10-23T23:40:05.013Z,1540338005.013 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2018-10-23T23:40:05.130Z,1540338005.130 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2018-10-23T23:40:05.231Z,1540338005.231 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2018-10-23T23:40:05.328Z,1540338005.328 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2018-10-23T23:40:05.432Z,1540338005.432 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2018-10-23T23:40:05.545Z,1540338005.545 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-10-23T23:40:05.554Z,1540338005.554 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-10-23T23:40:05.611Z,1540338005.611 [DepthRateCalculator] Loaded
2018-10-23T23:40:05.612Z,1540338005.612 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-10-23T23:40:05.618Z,1540338005.618 [PitchRateCalculator] Loaded
2018-10-23T23:40:05.618Z,1540338005.618 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-10-23T23:40:05.634Z,1540338005.634 [SpeedCalculator] Loaded
2018-10-23T23:40:05.634Z,1540338005.634 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-10-23T23:40:05.655Z,1540338005.655 [TempGradientCalculator] Loaded
2018-10-23T23:40:05.655Z,1540338005.655 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-10-23T23:40:05.661Z,1540338005.661 [YawRateCalculator] Loaded
2018-10-23T23:40:05.661Z,1540338005.661 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-10-23T23:40:05.701Z,1540338005.701 [ElevatorOffsetCalculator] Loaded
2018-10-23T23:40:05.701Z,1540338005.701 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-10-23T23:40:05.702Z,1540338005.702 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-10-23T23:40:05.702Z,1540338005.702 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-10-23T23:40:05.987Z,1540338005.987 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-10-23T23:40:05.989Z,1540338005.989 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-10-23T23:40:06.568Z,1540338006.568 [DataOverHttps] Loaded
2018-10-23T23:40:06.569Z,1540338006.569 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-10-23T23:40:06.596Z,1540338006.596 [Depth_Keller] Loaded
2018-10-23T23:40:06.596Z,1540338006.596 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-10-23T23:40:06.601Z,1540338006.601 [DropWeight] Loaded
2018-10-23T23:40:06.601Z,1540338006.601 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-10-23T23:40:06.699Z,1540338006.699 [NAL9602] Loaded
2018-10-23T23:40:06.699Z,1540338006.699 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-10-23T23:40:06.709Z,1540338006.709 [Radio_Surface] Loaded
2018-10-23T23:40:06.710Z,1540338006.710 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-10-23T23:40:06.711Z,1540338006.711 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407C34E0
2018-10-23T23:40:06.711Z,1540338006.711 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 882
2018-10-23T23:40:06.718Z,1540338006.718 [SCPI] Loaded
2018-10-23T23:40:06.719Z,1540338006.719 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2018-10-23T23:40:08.471Z,1540338008.471 [BPC1] Loaded
2018-10-23T23:40:08.471Z,1540338008.471 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-10-23T23:40:08.472Z,1540338008.472 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-10-23T23:40:08.473Z,1540338008.473 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-10-23T23:40:08.777Z,1540338008.777 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-10-23T23:40:08.777Z,1540338008.777 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-10-23T23:40:09.125Z,1540338009.125 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-10-23T23:40:09.126Z,1540338009.126 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-10-23T23:40:09.180Z,1540338009.180 [DeadReckonUsingSpeedCalculator] Loaded
2018-10-23T23:40:09.180Z,1540338009.180 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-10-23T23:40:09.197Z,1540338009.197 [NavChart] Loaded
2018-10-23T23:40:09.197Z,1540338009.197 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-10-23T23:40:09.201Z,1540338009.201 [UniversalFixResidualReporter] Loaded
2018-10-23T23:40:09.201Z,1540338009.201 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-10-23T23:40:09.202Z,1540338009.202 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-10-23T23:40:09.202Z,1540338009.202 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-10-23T23:40:09.650Z,1540338009.650 [CTD_NeilBrown] Loaded
2018-10-23T23:40:09.650Z,1540338009.650 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-10-23T23:40:09.651Z,1540338009.651 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408CE4E0
2018-10-23T23:40:09.651Z,1540338009.651 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 883
2018-10-23T23:40:09.708Z,1540338009.708 [CTD_Seabird] Loaded
2018-10-23T23:40:09.708Z,1540338009.708 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-10-23T23:40:09.709Z,1540338009.709 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408FE4E0
2018-10-23T23:40:09.709Z,1540338009.709 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 884
2018-10-23T23:40:09.759Z,1540338009.759 [WetLabsBB2FL] Loaded
2018-10-23T23:40:09.759Z,1540338009.759 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-10-23T23:40:09.760Z,1540338009.760 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4092E4E0
2018-10-23T23:40:09.760Z,1540338009.760 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 885
2018-10-23T23:40:09.761Z,1540338009.761 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-10-23T23:40:09.762Z,1540338009.762 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-10-23T23:40:09.851Z,1540338009.851 [VerticalControl](DEBUG): Construct VerticalControl.
2018-10-23T23:40:09.951Z,1540338009.951 [VerticalControl] Loaded
2018-10-23T23:40:09.952Z,1540338009.952 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-10-23T23:40:09.952Z,1540338009.952 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-10-23T23:40:10.020Z,1540338010.020 [HorizontalControl] Loaded
2018-10-23T23:40:10.021Z,1540338010.021 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-10-23T23:40:10.021Z,1540338010.021 [SpeedControl](DEBUG): Construct SpeedControl.
2018-10-23T23:40:10.027Z,1540338010.027 [SpeedControl] Loaded
2018-10-23T23:40:10.027Z,1540338010.027 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-10-23T23:40:10.028Z,1540338010.028 [LoopControl](DEBUG): Construct LoopControl.
2018-10-23T23:40:10.028Z,1540338010.028 [LoopControl] Loaded
2018-10-23T23:40:10.028Z,1540338010.028 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-10-23T23:40:10.029Z,1540338010.029 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-10-23T23:40:10.030Z,1540338010.030 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-10-23T23:40:10.053Z,1540338010.053 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-10-23T23:40:10.054Z,1540338010.054 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-10-23T23:40:10.211Z,1540338010.211 [SBIT](DEBUG): Construct Startup Built In Test.
2018-10-23T23:40:10.223Z,1540338010.223 [SBIT] Loaded
2018-10-23T23:40:10.223Z,1540338010.223 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-10-23T23:40:10.224Z,1540338010.224 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-10-23T23:40:10.243Z,1540338010.243 [IBIT] Loaded
2018-10-23T23:40:10.243Z,1540338010.243 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-10-23T23:40:10.246Z,1540338010.246 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-10-23T23:40:10.400Z,1540338010.400 [CBIT] Loaded
2018-10-23T23:40:10.401Z,1540338010.401 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-10-23T23:40:10.401Z,1540338010.401 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-10-23T23:40:10.402Z,1540338010.402 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-10-23T23:40:10.530Z,1540338010.530 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-10-23T23:40:10.531Z,1540338010.531 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-10-23T23:40:10.568Z,1540338010.568 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-10-23T23:40:10.569Z,1540338010.569 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-10-23T23:40:10.691Z,1540338010.691 [BuoyancyServo] Loaded
2018-10-23T23:40:10.691Z,1540338010.691 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-10-23T23:40:10.703Z,1540338010.703 [ElevatorServo] Loaded
2018-10-23T23:40:10.703Z,1540338010.703 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-10-23T23:40:10.714Z,1540338010.714 [MassServo] Loaded
2018-10-23T23:40:10.715Z,1540338010.714 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-10-23T23:40:10.726Z,1540338010.726 [RudderServo] Loaded
2018-10-23T23:40:10.726Z,1540338010.726 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-10-23T23:40:10.737Z,1540338010.737 [ThrusterServo] Loaded
2018-10-23T23:40:10.737Z,1540338010.737 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-10-23T23:40:10.737Z,1540338010.737 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-10-23T23:40:10.741Z,1540338010.741 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-10-23T23:40:10.742Z,1540338010.742 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-10-23T23:40:10.748Z,1540338010.748 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-10-23T23:40:10.750Z,1540338010.750 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0
2018-10-23T23:40:10.750Z,1540338010.750 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 886
2018-10-23T23:40:10.755Z,1540338010.755 [Supervisor](INFO): Main Thread ID is 795
2018-10-23T23:40:10.755Z,1540338010.755 [Supervisor](DEBUG): Running supervisor.
2018-10-23T23:40:10.755Z,1540338010.755 [CommandLine ThreadHandler](INFO): Handler Thread ID is 887
2018-10-23T23:40:10.758Z,1540338010.758 [controlThread ThreadHandler](INFO): Handler Thread ID is 888
2018-10-23T23:40:10.758Z,1540338010.758 [controlThread](DEBUG): Initializing ControlThread
2018-10-23T23:40:10.759Z,1540338010.759 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-10-23T23:40:10.759Z,1540338010.759 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-10-23T23:40:10.760Z,1540338010.760 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-10-23T23:40:10.760Z,1540338010.760 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-10-23T23:40:10.760Z,1540338010.760 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-10-23T23:40:10.761Z,1540338010.761 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-10-23T23:40:10.767Z,1540338010.767 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-23T23:40:10.768Z,1540338010.768 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-23T23:40:10.768Z,1540338010.768 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-23T23:40:10.769Z,1540338010.769 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-23T23:40:10.769Z,1540338010.769 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-23T23:40:10.770Z,1540338010.770 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-23T23:40:10.770Z,1540338010.770 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-10-23T23:40:10.770Z,1540338010.770 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-10-23T23:40:10.771Z,1540338010.771 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-10-23T23:40:10.772Z,1540338010.772 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-10-23T23:40:10.773Z,1540338010.773 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-10-23T23:40:10.774Z,1540338010.774 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-10-23T23:40:10.774Z,1540338010.774 [SBIT](INFO): Initialize SBIT Component.
2018-10-23T23:40:10.775Z,1540338010.775 [SBIT](IMPORTANT): git: 2018-10-09-31-g8f1ca26
2018-10-23T23:40:10.775Z,1540338010.775 [SBIT](INFO): git hash: 8f1ca2616133f591d84f59109522710795260801
2018-10-23T23:40:10.775Z,1540338010.775 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-10-23T23:40:10.775Z,1540338010.775 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-10-23T23:40:10.776Z,1540338010.776 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2018-10-23T23:40:10.777Z,1540338010.777 [IBIT](INFO): Initialize IBIT Component.
2018-10-23T23:40:10.778Z,1540338010.778 [CBIT](DEBUG): Initialize CBIT Component.
2018-10-23T23:40:10.778Z,1540338010.778 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-10-23T23:40:10.779Z,1540338010.779 [logger ThreadHandler](INFO): Handler Thread ID is 889
2018-10-23T23:40:10.797Z,1540338010.797 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 890
2018-10-23T23:40:10.803Z,1540338010.803 [Radio_Surface](INFO): Powering up
2018-10-23T23:40:10.809Z,1540338010.809 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 891
2018-10-23T23:40:10.821Z,1540338010.821 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 892
2018-10-23T23:40:10.822Z,1540338010.822 [CTD_Seabird](INFO): Initializing
2018-10-23T23:40:10.822Z,1540338010.822 [CTD_Seabird](INFO): Checking LCM
2018-10-23T23:40:10.919Z,1540338010.919 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-10-23T23:40:10.930Z,1540338010.930 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 893
2018-10-23T23:40:10.932Z,1540338010.932 [WetLabsBB2FL](INFO): Powering down
2018-10-23T23:40:10.947Z,1540338010.947 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 894
2018-10-23T23:40:11.050Z,1540338011.050 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-10-23T23:40:11.059Z,1540338011.059 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-10-23T23:40:11.059Z,1540338011.059 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-10-23T23:40:11.059Z,1540338011.059 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-10-23T23:40:11.059Z,1540338011.059 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-10-23T23:40:11.060Z,1540338011.060 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-10-23T23:40:11.060Z,1540338011.060 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-10-23T23:40:11.060Z,1540338011.060 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-10-23T23:40:11.060Z,1540338011.060 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-10-23T23:40:11.060Z,1540338011.060 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-10-23T23:40:11.077Z,1540338011.077 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-10-23T23:40:11.077Z,1540338011.077 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-10-23T23:40:11.077Z,1540338011.077 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-10-23T23:40:11.077Z,1540338011.077 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-10-23T23:40:11.077Z,1540338011.077 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-10-23T23:40:11.078Z,1540338011.078 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-10-23T23:40:11.078Z,1540338011.078 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-10-23T23:40:11.170Z,1540338011.170 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2018-10-23T23:40:11.170Z,1540338011.170 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-10-23T23:40:11.261Z,1540338011.261 [CTD_Seabird](INFO): LCM OK
2018-10-23T23:40:11.261Z,1540338011.261 [CTD_Seabird](INFO): Powering up
2018-10-23T23:40:11.299Z,1540338011.299 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-10-23T23:40:11.300Z,1540338011.300 [Default:A.Wait](DEBUG): Construct Wait.
2018-10-23T23:40:11.302Z,1540338011.302 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-10-23T23:40:11.350Z,1540338011.350 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-10-23T23:40:11.352Z,1540338011.352 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-10-23T23:40:11.378Z,1540338011.378 [Default:E.Execute](DEBUG): Construct Execute.
2018-10-23T23:40:11.397Z,1540338011.397 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2018-10-23T23:40:11.402Z,1540338011.402 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,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-10-23T23:40:11.429Z,1540338011.429 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-10-23T23:40:11.437Z,1540338011.437 [Depth_Keller](ERROR): Pressure reading out of range: 1817.135132 decibar
2018-10-23T23:40:11.554Z,1540338011.554 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-10-23T23:40:12.105Z,1540338012.105 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-10-23T23:40:12.113Z,1540338012.113 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-10-23T23:40:12.127Z,1540338012.127 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-10-23T23:40:12.133Z,1540338012.133 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-10-23T23:40:12.155Z,1540338012.155 [MassServo](DEBUG): Initializing EZServoServo.
2018-10-23T23:40:12.161Z,1540338012.161 [MassServo](DEBUG): Initializing MassServo.
2018-10-23T23:40:12.175Z,1540338012.175 [RudderServo](DEBUG): Initializing EZServoServo.
2018-10-23T23:40:12.181Z,1540338012.181 [RudderServo](DEBUG): Initializing RudderServo.
2018-10-23T23:40:12.195Z,1540338012.195 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-10-23T23:40:12.201Z,1540338012.201 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-10-23T23:40:12.331Z,1540338012.331 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-10-23T23:40:12.331Z,1540338012.331 [DropWeight] Hardware Fault, FailCount= 1
2018-10-23T23:40:12.331Z,1540338012.331 [DropWeight](ERROR): Hardware Fault
2018-10-23T23:40:12.334Z,1540338012.334 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-10-23T23:40:12.378Z,1540338012.378 [CommandLine](FAULT): Scheduling is paused
2018-10-23T23:40:12.379Z,1540338012.379 [CBIT](INFO): Critical error at 20181023T234012
2018-10-23T23:40:12.379Z,1540338012.379 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-10-23T23:40:12.381Z,1540338012.381 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-10-23T23:40:12.382Z,1540338012.382 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-10-23T23:40:13.017Z,1540338013.017 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-10-23T23:40:13.017Z,1540338013.017 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-10-23T23:40:13.017Z,1540338013.017 [BuoyancyServo] Communications Fault, FailCount= 1
2018-10-23T23:40:13.017Z,1540338013.017 [BuoyancyServo](ERROR): Communications Fault
2018-10-23T23:40:13.219Z,1540338013.219 [CBIT](INFO): Critical error at 20181023T234012
2018-10-23T23:40:13.221Z,1540338013.221 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-10-23T23:40:13.267Z,1540338013.267 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-10-23T23:40:13.268Z,1540338013.268 [BuoyancyServo](INFO): Powering down
2018-10-23T23:40:15.765Z,1540338015.765 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-10-23T23:40:15.765Z,1540338015.765 [BuoyancyServo] No Fault, FailCount= 1
2018-10-23T23:40:16.046Z,1540338016.046 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-10-23T23:40:16.166Z,1540338016.166 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-10-23T23:40:16.458Z,1540338016.458 [Radio_Surface](INFO): Powering down
2018-10-23T23:40:16.601Z,1540338016.601 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-10-23T23:40:16.670Z,1540338016.670 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2018-10-23T23:40:16.770Z,1540338016.770 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd
2018-10-23T23:40:17.127Z,1540338017.127 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-10-23T23:40:17.127Z,1540338017.127 [CTD_Seabird](INFO): Powering down
2018-10-23T23:40:20.879Z,1540338020.879 [CBIT](CRITICAL): Environmental Failure. Press:0.000000 PSI. Humidity:2139112%. Temp:4835256 C. ABORTING MISSION
2018-10-23T23:40:21.276Z,1540338021.276 [CBIT](INFO): Critical error at 20181023T234020
2018-10-23T23:40:36.838Z,1540338036.838 [NAL9602](INFO): Powering up NAL9602
2018-10-23T23:40:39.291Z,1540338039.291 [SBIT](IMPORTANT): Beginning Startup BIT
2018-10-23T23:40:39.295Z,1540338039.295 [CBIT](IMPORTANT): Beginning ground fault scan
2018-10-23T23:40:48.034Z,1540338048.034 [NAL9602](INFO): NAL9602 initialized
2018-10-23T23:40:50.095Z,1540338050.095 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.007676
CHAN A1 (24V): 0.002166
CHAN A2 (12V): -0.005999
CHAN A3 (5V): -0.002763
CHAN B0 (3.3V): -0.000772
CHAN B1 (3.15aV): -0.001302
CHAN B2 (3.15bV): -0.001284
CHAN B3 (GND): -0.000338
OPEN: -0.000980
Full Scale Calc: 4.765 mA, -1.589 mA
2018-10-23T23:40:52.492Z,1540338052.492 [SBIT](FAULT): Mass: EXPECTED:0.009000 ACTUAL:0.005441
2018-10-23T23:41:07.147Z,1540338067.147 [CommandLine](IMPORTANT): got command report touch sea_water_pressure
2018-10-23T23:41:07.266Z,1540338067.266 [Reporter](INFO): sea_water_pressure 15.919514 db
2018-10-23T23:41:07.662Z,1540338067.662 [Reporter](INFO): sea_water_pressure 15.912564 db
2018-10-23T23:41:08.062Z,1540338068.062 [Reporter](INFO): sea_water_pressure 15.926466 db
2018-10-23T23:41:08.472Z,1540338068.472 [Reporter](INFO): sea_water_pressure 15.925163 db
2018-10-23T23:41:08.860Z,1540338068.860 [Reporter](INFO): sea_water_pressure 15.909523 db
2018-10-23T23:41:09.275Z,1540338069.275 [Reporter](INFO): sea_water_pressure 15.906917 db
2018-10-23T23:41:09.670Z,1540338069.670 [Reporter](INFO): sea_water_pressure 15.921688 db
2018-10-23T23:41:10.071Z,1540338070.071 [Reporter](INFO): sea_water_pressure 15.909523 db
2018-10-23T23:41:10.463Z,1540338070.463 [Reporter](INFO): sea_water_pressure 15.912998 db
2018-10-23T23:41:10.870Z,1540338070.870 [Reporter](INFO): sea_water_pressure 15.912564 db
2018-10-23T23:41:11.271Z,1540338071.271 [Reporter](INFO): sea_water_pressure 15.914738 db
2018-10-23T23:41:11.673Z,1540338071.673 [Reporter](INFO): sea_water_pressure 15.915605 db
2018-10-23T23:41:12.115Z,1540338072.115 [Reporter](INFO): sea_water_pressure 15.908655 db
2018-10-23T23:41:12.517Z,1540338072.517 [Reporter](INFO): sea_water_pressure 15.921688 db
2018-10-23T23:41:12.871Z,1540338072.871 [Reporter](INFO): sea_water_pressure 15.928203 db
2018-10-23T23:41:13.271Z,1540338073.271 [Reporter](INFO): sea_water_pressure 15.926030 db
2018-10-23T23:41:13.669Z,1540338073.669 [Reporter](INFO): sea_water_pressure 15.911697 db
2018-10-23T23:41:14.071Z,1540338074.071 [Reporter](INFO): sea_water_pressure 15.913434 db
2018-10-23T23:41:14.470Z,1540338074.470 [Reporter](INFO): sea_water_pressure 15.920817 db
2018-10-23T23:41:14.871Z,1540338074.871 [Reporter](INFO): sea_water_pressure 15.907786 db
2018-10-23T23:41:15.270Z,1540338075.270 [Reporter](INFO): sea_water_pressure 15.915605 db
2018-10-23T23:41:15.670Z,1540338075.670 [Reporter](INFO): sea_water_pressure 15.914738 db
2018-10-23T23:41:16.071Z,1540338076.071 [Reporter](INFO): sea_water_pressure 15.922991 db
2018-10-23T23:41:16.473Z,1540338076.473 [Reporter](INFO): sea_water_pressure 15.919514 db
2018-10-23T23:41:16.881Z,1540338076.881 [Reporter](INFO): sea_water_pressure 15.919514 db
2018-10-23T23:41:17.281Z,1540338077.281 [Reporter](INFO): sea_water_pressure 15.920384 db
2018-10-23T23:41:17.701Z,1540338077.701 [Reporter](INFO): sea_water_pressure 15.907786 db
2018-10-23T23:41:18.036Z,1540338078.036 [BPC1](ERROR): BPC1A: No match for serial number 01EB in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2018-10-23T23:41:18.038Z,1540338078.038 [BPC1](ERROR): BPC1B: No match for serial number 01FB in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2018-10-23T23:41:18.038Z,1540338078.038 [BPC1](FAULT): Failed to parse data from all battery packs.
2018-10-23T23:41:18.038Z,1540338078.038 [BPC1] Data Fault, FailCount= 1
2018-10-23T23:41:18.038Z,1540338078.038 [BPC1](ERROR): Data Fault
2018-10-23T23:41:18.098Z,1540338078.098 [CBIT](ERROR): Data Fault in component: BPC1
2018-10-23T23:41:18.102Z,1540338078.102 [Reporter](INFO): sea_water_pressure 15.922122 db
2018-10-23T23:41:18.454Z,1540338078.454 [Reporter](INFO): sea_water_pressure 15.908655 db
2018-10-23T23:41:18.874Z,1540338078.874 [Reporter](INFO): sea_water_pressure 15.909089 db
2018-10-23T23:41:19.284Z,1540338079.284 [Reporter](INFO): sea_water_pressure 15.899966 db
2018-10-23T23:41:19.650Z,1540338079.650 [Reporter](INFO): sea_water_pressure 15.920384 db
2018-10-23T23:41:20.055Z,1540338080.055 [Reporter](INFO): sea_water_pressure 15.924292 db
2018-10-23T23:41:20.473Z,1540338080.473 [Reporter](INFO): sea_water_pressure 15.878247 db
2018-10-23T23:41:20.854Z,1540338080.854 [Reporter](INFO): sea_water_pressure 15.915172 db
2018-10-23T23:41:21.419Z,1540338081.419 [Reporter](INFO): sea_water_pressure 15.919080 db
2018-10-23T23:41:22.147Z,1540338082.147 [Reporter](INFO): sea_water_pressure 15.925163 db
2018-10-23T23:41:22.331Z,1540338082.331 [Reporter](INFO): sea_water_pressure 15.916909 db
2018-10-23T23:41:22.695Z,1540338082.695 [Reporter](INFO): sea_water_pressure 15.911261 db
2018-10-23T23:41:23.091Z,1540338083.091 [Reporter](INFO): sea_water_pressure 15.926030 db
2018-10-23T23:41:23.499Z,1540338083.499 [Reporter](INFO): sea_water_pressure 15.924292 db
2018-10-23T23:41:23.879Z,1540338083.879 [Reporter](INFO): sea_water_pressure 15.911697 db
2018-10-23T23:41:24.355Z,1540338084.355 [Reporter](INFO): sea_water_pressure 15.918647 db
2018-10-23T23:41:24.864Z,1540338084.864 [Reporter](INFO): sea_water_pressure 15.918213 db
2018-10-23T23:41:25.027Z,1540338085.027 [Reporter](INFO): sea_water_pressure 15.920817 db
2018-10-23T23:41:25.434Z,1540338085.434 [Reporter](INFO): sea_water_pressure 15.906048 db
2018-10-23T23:41:25.835Z,1540338085.835 [Reporter](INFO): sea_water_pressure 15.909959 db
2018-10-23T23:41:26.227Z,1540338086.227 [Reporter](INFO): sea_water_pressure 15.921688 db
2018-10-23T23:41:26.627Z,1540338086.627 [Reporter](INFO): sea_water_pressure 15.916909 db
2018-10-23T23:41:27.034Z,1540338087.034 [Reporter](INFO): sea_water_pressure 15.924292 db
2018-10-23T23:41:27.435Z,1540338087.435 [Reporter](INFO): sea_water_pressure 15.913434 db
2018-10-23T23:41:27.859Z,1540338087.859 [Reporter](INFO): sea_water_pressure 15.913434 db
2018-10-23T23:41:28.323Z,1540338088.323 [Reporter](INFO): sea_water_pressure 15.924292 db
2018-10-23T23:41:28.595Z,1540338088.595 [Reporter](INFO): sea_water_pressure 15.917342 db
2018-10-23T23:41:28.991Z,1540338088.991 [Reporter](INFO): sea_water_pressure 15.909523 db
2018-10-23T23:41:29.389Z,1540338089.389 [Reporter](INFO): sea_water_pressure 15.924292 db
2018-10-23T23:41:29.794Z,1540338089.794 [Reporter](INFO): sea_water_pressure 15.925163 db
2018-10-23T23:41:30.190Z,1540338090.190 [Reporter](INFO): sea_water_pressure 15.911261 db
2018-10-23T23:41:30.587Z,1540338090.587 [Reporter](INFO): sea_water_pressure 15.915172 db
2018-10-23T23:41:30.984Z,1540338090.984 [Reporter](INFO): sea_water_pressure 15.915172 db
2018-10-23T23:41:31.388Z,1540338091.388 [Reporter](INFO): sea_water_pressure 15.893886 db
2018-10-23T23:41:31.785Z,1540338091.785 [Reporter](INFO): sea_water_pressure 15.913434 db
2018-10-23T23:41:32.198Z,1540338092.198 [Reporter](INFO): sea_water_pressure 15.910392 db
2018-10-23T23:41:32.594Z,1540338092.594 [Reporter](INFO): sea_water_pressure 15.919950 db
2018-10-23T23:41:32.995Z,1540338092.995 [Reporter](INFO): sea_water_pressure 15.909523 db
2018-10-23T23:41:33.383Z,1540338093.383 [SBIT](CRITICAL): SBIT FAILED
2018-10-23T23:41:33.403Z,1540338093.403 [CommandLine](IMPORTANT): got command configSet list
2018-10-23T23:41:33.404Z,1540338093.404 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-10-23T23:41:33.417Z,1540338093.417 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2018-10-23T23:41:33.417Z,1540338093.417 [CommandLine](IMPORTANT): Depth_Keller.offset=-4.87 decibar;

2018-10-23T23:41:33.417Z,1540338093.417 [CommandLine](IMPORTANT): Depth_Keller.scale=43.44 micropascal;

2018-10-23T23:41:33.417Z,1540338093.417 [CommandLine](IMPORTANT): Onboard.loadAtStartup=0 bool;

2018-10-23T23:41:33.423Z,1540338093.423 [CBIT](INFO): Critical error at 20181023T234133
2018-10-23T23:41:33.430Z,1540338093.430 [Reporter](INFO): sea_water_pressure 15.918213 db
2018-10-23T23:41:33.767Z,1540338093.767 [MissionManager](IMPORTANT): Started mission Startup
2018-10-23T23:41:33.767Z,1540338093.767 [Startup] Running Loop=1
2018-10-23T23:41:33.767Z,1540338093.767 [Startup](DEBUG): Aggregate::initialize Startup
2018-10-23T23:41:33.767Z,1540338093.767 [Startup:A.GoToSurface] Running Loop=1
2018-10-23T23:41:33.768Z,1540338093.768 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-10-23T23:41:33.768Z,1540338093.768 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-10-23T23:41:33.769Z,1540338093.769 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-10-23T23:41:33.769Z,1540338093.769 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-10-23T23:41:33.770Z,1540338093.770 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-10-23T23:41:33.770Z,1540338093.770 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-10-23T23:41:33.831Z,1540338093.831 [Reporter](INFO): sea_water_pressure 15.912130 db
2018-10-23T23:41:34.142Z,1540338094.142 [Radio_Surface](INFO): Powering up
2018-10-23T23:41:34.180Z,1540338094.180 [Reporter](INFO): sea_water_pressure 15.914302 db
2018-10-23T23:41:34.579Z,1540338094.579 [Reporter](INFO): sea_water_pressure 15.910392 db
2018-10-23T23:41:35.018Z,1540338095.018 [Reporter](INFO): sea_water_pressure 15.919080 db
2018-10-23T23:41:35.759Z,1540338095.759 [Reporter](INFO): sea_water_pressure 15.906484 db
2018-10-23T23:41:35.835Z,1540338095.835 [Reporter](INFO): sea_water_pressure 15.907786 db
2018-10-23T23:41:36.234Z,1540338096.234 [Reporter](INFO): sea_water_pressure 15.900836 db
2018-10-23T23:41:36.646Z,1540338096.646 [Reporter](INFO): sea_water_pressure 15.918213 db
2018-10-23T23:41:37.039Z,1540338097.039 [Reporter](INFO): sea_water_pressure 15.913867 db
2018-10-23T23:41:37.467Z,1540338097.467 [Reporter](INFO): sea_water_pressure 15.910827 db
2018-10-23T23:41:37.867Z,1540338097.867 [Reporter](INFO): sea_water_pressure 15.913867 db
2018-10-23T23:41:38.259Z,1540338098.259 [Reporter](INFO): sea_water_pressure 15.899098 db
2018-10-23T23:41:38.667Z,1540338098.667 [Reporter](INFO): sea_water_pressure 15.910392 db
2018-10-23T23:41:39.038Z,1540338099.038 [Reporter](INFO): sea_water_pressure 15.898228 db
2018-10-23T23:41:39.431Z,1540338099.431 [Reporter](INFO): sea_water_pressure 15.911697 db
2018-10-23T23:41:39.863Z,1540338099.863 [Reporter](INFO): sea_water_pressure 15.912564 db
2018-10-23T23:41:40.239Z,1540338100.239 [Reporter](INFO): sea_water_pressure 15.914738 db
2018-10-23T23:41:40.639Z,1540338100.639 [Reporter](INFO): sea_water_pressure 15.910827 db
2018-10-23T23:41:41.050Z,1540338101.050 [Reporter](INFO): sea_water_pressure 15.913434 db
2018-10-23T23:41:41.431Z,1540338101.431 [Reporter](INFO): sea_water_pressure 15.902139 db
2018-10-23T23:41:41.835Z,1540338101.835 [Reporter](INFO): sea_water_pressure 15.913434 db
2018-10-23T23:41:42.249Z,1540338102.249 [Reporter](INFO): sea_water_pressure 15.914302 db
2018-10-23T23:41:42.651Z,1540338102.651 [Reporter](INFO): sea_water_pressure 15.920817 db
2018-10-23T23:41:43.059Z,1540338103.059 [Reporter](INFO): sea_water_pressure 15.906048 db
2018-10-23T23:41:43.463Z,1540338103.463 [Reporter](INFO): sea_water_pressure 15.905180 db
2018-10-23T23:41:43.865Z,1540338103.865 [Reporter](INFO): sea_water_pressure 15.908222 db
2018-10-23T23:41:44.248Z,1540338104.248 [Reporter](INFO): sea_water_pressure 15.907352 db
2018-10-23T23:41:44.647Z,1540338104.647 [Reporter](INFO): sea_water_pressure 15.912564 db
2018-10-23T23:41:45.039Z,1540338105.039 [Reporter](INFO): sea_water_pressure 15.909959 db
2018-10-23T23:41:45.439Z,1540338105.439 [Reporter](INFO): sea_water_pressure 15.924728 db
2018-10-23T23:41:45.847Z,1540338105.847 [Reporter](INFO): sea_water_pressure 15.906048 db
2018-10-23T23:41:46.231Z,1540338106.231 [Reporter](INFO): sea_water_pressure 15.913434 db
2018-10-23T23:41:46.639Z,1540338106.639 [Reporter](INFO): sea_water_pressure 15.906048 db
2018-10-23T23:41:47.047Z,1540338107.047 [Reporter](INFO): sea_water_pressure 15.902573 db
2018-10-23T23:41:47.450Z,1540338107.450 [Reporter](INFO): sea_water_pressure 15.887370 db
2018-10-23T23:41:47.866Z,1540338107.866 [Reporter](INFO): sea_water_pressure 15.921252 db
2018-10-23T23:41:48.263Z,1540338108.263 [Reporter](INFO): sea_water_pressure 15.911697 db
2018-10-23T23:41:48.663Z,1540338108.663 [Reporter](INFO): sea_water_pressure 15.903008 db
2018-10-23T23:41:49.066Z,1540338109.066 [Reporter](INFO): sea_water_pressure 15.896491 db
2018-10-23T23:41:49.447Z,1540338109.447 [Reporter](INFO): sea_water_pressure 15.907352 db
2018-10-23T23:41:49.847Z,1540338109.847 [Reporter](INFO): sea_water_pressure 15.909959 db
2018-10-23T23:41:50.239Z,1540338110.239 [Reporter](INFO): sea_water_pressure 15.919080 db
2018-10-23T23:41:50.648Z,1540338110.648 [Reporter](INFO): sea_water_pressure 15.910827 db
2018-10-23T23:41:51.047Z,1540338111.047 [Reporter](INFO): sea_water_pressure 15.901270 db
2018-10-23T23:41:51.455Z,1540338111.455 [Reporter](INFO): sea_water_pressure 15.916039 db
2018-10-23T23:41:51.867Z,1540338111.867 [Reporter](INFO): sea_water_pressure 15.911697 db
2018-10-23T23:41:52.239Z,1540338112.239 [Reporter](INFO): sea_water_pressure 15.912998 db
2018-10-23T23:41:52.639Z,1540338112.639 [Reporter](INFO): sea_water_pressure 15.906917 db
2018-10-23T23:41:53.070Z,1540338113.070 [Reporter](INFO): sea_water_pressure 15.910392 db
2018-10-23T23:41:53.451Z,1540338113.451 [Reporter](INFO): sea_water_pressure 15.896491 db
2018-10-23T23:41:53.859Z,1540338113.859 [Reporter](INFO): sea_water_pressure 15.904745 db
2018-10-23T23:41:54.270Z,1540338114.270 [Reporter](INFO): sea_water_pressure 15.916039 db
2018-10-23T23:41:54.639Z,1540338114.639 [Reporter](INFO): sea_water_pressure 15.905180 db
2018-10-23T23:41:55.043Z,1540338115.043 [Reporter](INFO): sea_water_pressure 15.894753 db
2018-10-23T23:41:55.443Z,1540338115.443 [Reporter](INFO): sea_water_pressure 15.916909 db
2018-10-23T23:41:55.843Z,1540338115.843 [Reporter](INFO): sea_water_pressure 15.912998 db
2018-10-23T23:41:56.243Z,1540338116.243 [Reporter](INFO): sea_water_pressure 15.906484 db
2018-10-23T23:41:56.647Z,1540338116.647 [Reporter](INFO): sea_water_pressure 15.912130 db
2018-10-23T23:41:57.043Z,1540338117.043 [Reporter](INFO): sea_water_pressure 15.904311 db
2018-10-23T23:41:57.443Z,1540338117.443 [Reporter](INFO): sea_water_pressure 15.899533 db
2018-10-23T23:41:57.843Z,1540338117.843 [Reporter](INFO): sea_water_pressure 15.898664 db
2018-10-23T23:41:58.271Z,1540338118.271 [Reporter](INFO): sea_water_pressure 15.916475 db
2018-10-23T23:41:58.667Z,1540338118.667 [Reporter](INFO): sea_water_pressure 15.911261 db
2018-10-23T23:41:59.064Z,1540338119.064 [Reporter](INFO): sea_water_pressure 15.909959 db
2018-10-23T23:41:59.456Z,1540338119.456 [Reporter](INFO): sea_water_pressure 15.907786 db
2018-10-23T23:41:59.843Z,1540338119.843 [Reporter](INFO): sea_water_pressure 15.905180 db
2018-10-23T23:42:00.151Z,1540338120.151 [CommandLine](IMPORTANT): got command configSet Depth_Keller.offset 0.000000 decibar
2018-10-23T23:42:00.247Z,1540338120.247 [Reporter](INFO): sea_water_pressure 20.780392 db
2018-10-23T23:42:00.639Z,1540338120.639 [Reporter](INFO): sea_water_pressure 20.775180 db
2018-10-23T23:42:01.039Z,1540338121.039 [Reporter](INFO): sea_water_pressure 20.769098 db
2018-10-23T23:42:01.439Z,1540338121.439 [Reporter](INFO): sea_water_pressure 20.782564 db
2018-10-23T23:42:01.843Z,1540338121.843 [Reporter](INFO): sea_water_pressure 20.788647 db
2018-10-23T23:42:02.243Z,1540338122.243 [Reporter](INFO): sea_water_pressure 20.780392 db
2018-10-23T23:42:02.639Z,1540338122.639 [Reporter](INFO): sea_water_pressure 20.778222 db
2018-10-23T23:42:03.043Z,1540338123.043 [Reporter](INFO): sea_water_pressure 20.770402 db
2018-10-23T23:42:03.472Z,1540338123.472 [Reporter](INFO): sea_water_pressure 20.759541 db
2018-10-23T23:42:03.870Z,1540338123.870 [Reporter](INFO): sea_water_pressure 20.769966 db
2018-10-23T23:42:04.267Z,1540338124.267 [Reporter](INFO): sea_water_pressure 20.786475 db
2018-10-23T23:42:04.663Z,1540338124.663 [Reporter](INFO): sea_water_pressure 20.780392 db
2018-10-23T23:42:05.043Z,1540338125.043 [Reporter](INFO): sea_water_pressure 20.764753 db
2018-10-23T23:42:05.443Z,1540338125.443 [Reporter](INFO): sea_water_pressure 20.779523 db
2018-10-23T23:42:05.843Z,1540338125.843 [Reporter](INFO): sea_water_pressure 20.776483 db
2018-10-23T23:42:06.243Z,1540338126.243 [Reporter](INFO): sea_water_pressure 20.772139 db
2018-10-23T23:42:06.643Z,1540338126.643 [Reporter](INFO): sea_water_pressure 20.776917 db
2018-10-23T23:42:07.111Z,1540338127.111 [Reporter](INFO): sea_water_pressure 20.780392 db
2018-10-23T23:42:07.395Z,1540338127.395 [Reporter](INFO): sea_water_pressure 20.766927 db
2018-10-23T23:42:07.795Z,1540338127.795 [Reporter](INFO): sea_water_pressure 20.771270 db
2018-10-23T23:42:08.195Z,1540338128.195 [Reporter](INFO): sea_water_pressure 20.771270 db
2018-10-23T23:42:08.625Z,1540338128.625 [Reporter](INFO): sea_water_pressure 20.776048 db
2018-10-23T23:42:09.026Z,1540338129.026 [Reporter](INFO): sea_water_pressure 20.768664 db
2018-10-23T23:42:09.422Z,1540338129.422 [Reporter](INFO): sea_water_pressure 20.786039 db
2018-10-23T23:42:09.825Z,1540338129.825 [Reporter](INFO): sea_water_pressure 20.777352 db
2018-10-23T23:42:10.195Z,1540338130.195 [Reporter](INFO): sea_water_pressure 20.763450 db
2018-10-23T23:42:10.642Z,1540338130.642 [Reporter](INFO): sea_water_pressure 20.783434 db
2018-10-23T23:42:10.985Z,1540338130.985 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-23T23:42:10.985Z,1540338130.985 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2018-10-23T23:42:10.985Z,1540338130.985 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-10-23T23:42:10.987Z,1540338130.987 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-23T23:42:10.987Z,1540338130.987 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-10-23T23:42:10.987Z,1540338130.987 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-23T23:42:11.000Z,1540338131.000 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-10-23T23:42:11.000Z,1540338131.000 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-23T23:42:11.002Z,1540338131.002 [Reporter](INFO): sea_water_pressure 20.768664 db
2018-10-23T23:42:11.416Z,1540338131.416 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-10-23T23:42:11.416Z,1540338131.416 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2018-10-23T23:42:11.417Z,1540338131.417 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-23T23:42:11.417Z,1540338131.417 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-10-23T23:42:11.418Z,1540338131.418 [Reporter](INFO): sea_water_pressure 20.779523 db
2018-10-23T23:42:11.787Z,1540338131.787 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-23T23:42:11.787Z,1540338131.787 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-23T23:42:11.788Z,1540338131.788 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-23T23:42:11.788Z,1540338131.788 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-23T23:42:11.789Z,1540338131.789 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-23T23:42:11.789Z,1540338131.789 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-23T23:42:11.803Z,1540338131.803 [Reporter](INFO): sea_water_pressure 20.770836 db
2018-10-23T23:42:12.202Z,1540338132.202 [Reporter](INFO): sea_water_pressure 20.771703 db
2018-10-23T23:42:12.600Z,1540338132.600 [Reporter](INFO): sea_water_pressure 20.777352 db
2018-10-23T23:42:13.004Z,1540338133.004 [Reporter](INFO): sea_water_pressure 20.771270 db
2018-10-23T23:42:13.398Z,1540338133.398 [Reporter](INFO): sea_water_pressure 20.770402 db
2018-10-23T23:42:13.811Z,1540338133.811 [Reporter](INFO): sea_water_pressure 20.771270 db
2018-10-23T23:42:14.212Z,1540338134.212 [Reporter](INFO): sea_water_pressure 20.773008 db
2018-10-23T23:42:14.619Z,1540338134.619 [Reporter](INFO): sea_water_pressure 20.781261 db
2018-10-23T23:42:15.011Z,1540338135.011 [Reporter](INFO): sea_water_pressure 20.779523 db
2018-10-23T23:42:15.399Z,1540338135.399 [Reporter](INFO): sea_water_pressure 20.769966 db
2018-10-23T23:42:15.798Z,1540338135.798 [Reporter](INFO): sea_water_pressure 20.780392 db
2018-10-23T23:42:16.204Z,1540338136.204 [Reporter](INFO): sea_water_pressure 20.763450 db
2018-10-23T23:42:16.605Z,1540338136.605 [Reporter](INFO): sea_water_pressure 20.772139 db
2018-10-23T23:42:17.020Z,1540338137.020 [Reporter](INFO): sea_water_pressure 20.768228 db
2018-10-23T23:42:17.402Z,1540338137.402 [Reporter](INFO): sea_water_pressure 20.785172 db
2018-10-23T23:42:17.813Z,1540338137.813 [Reporter](INFO): sea_water_pressure 20.780392 db
2018-10-23T23:42:18.195Z,1540338138.195 [Reporter](INFO): sea_water_pressure 20.766058 db
2018-10-23T23:42:18.588Z,1540338138.588 [Reporter](INFO): sea_water_pressure 20.768228 db
2018-10-23T23:42:19.028Z,1540338139.028 [Radio_Surface](INFO): Powering down
2018-10-23T23:42:19.042Z,1540338139.042 [Reporter](INFO): sea_water_pressure 20.776917 db
2018-10-23T23:42:19.430Z,1540338139.430 [Reporter](INFO): sea_water_pressure 20.778655 db
2018-10-23T23:42:19.829Z,1540338139.829 [Reporter](INFO): sea_water_pressure 20.769098 db
2018-10-23T23:42:20.208Z,1540338140.208 [Reporter](INFO): sea_water_pressure 20.762583 db
2018-10-23T23:42:20.595Z,1540338140.595 [Reporter](INFO): sea_water_pressure 20.765623 db
2018-10-23T23:42:20.995Z,1540338140.995 [Reporter](INFO): sea_water_pressure 20.778655 db
2018-10-23T23:42:21.391Z,1540338141.391 [Reporter](INFO): sea_water_pressure 20.780827 db
2018-10-23T23:42:21.426Z,1540338141.426 [CommandLine](IMPORTANT): got command configSet Depth_Keller.offset -20.700001 decibar
2018-10-23T23:42:21.784Z,1540338141.784 [Startup:StartupSatComms] Running Loop=1
2018-10-23T23:42:21.784Z,1540338141.784 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-10-23T23:42:21.784Z,1540338141.784 [Startup:StartupSatComms:A] Running Loop=1
2018-10-23T23:42:21.802Z,1540338141.802 [Reporter](INFO): sea_water_pressure 0.071703 db
2018-10-23T23:42:22.190Z,1540338142.190 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-10-23T23:42:22.204Z,1540338142.204 [Reporter](INFO): sea_water_pressure 0.062147 db
2018-10-23T23:42:22.606Z,1540338142.606 [Reporter](INFO): sea_water_pressure 0.087776 db
2018-10-23T23:42:23.058Z,1540338143.058 [Reporter](INFO): sea_water_pressure 0.067795 db
2018-10-23T23:42:23.794Z,1540338143.794 [Reporter](INFO): sea_water_pressure 0.073008 db
2018-10-23T23:42:23.875Z,1540338143.875 [Reporter](INFO): sea_water_pressure 0.073875 db
2018-10-23T23:42:24.306Z,1540338144.306 [Reporter](INFO): sea_water_pressure 0.069098 db
2018-10-23T23:42:24.675Z,1540338144.675 [Reporter](INFO): sea_water_pressure 0.071703 db
2018-10-23T23:42:25.095Z,1540338145.095 [Reporter](INFO): sea_water_pressure 0.088211 db
2018-10-23T23:42:25.098Z,1540338145.098 [Radio_Surface](INFO): Powering up
2018-10-23T23:42:25.507Z,1540338145.507 [Reporter](INFO): sea_water_pressure 0.071270 db
2018-10-23T23:42:25.875Z,1540338145.875 [Reporter](INFO): sea_water_pressure 0.073008 db
2018-10-23T23:42:26.275Z,1540338146.275 [Reporter](INFO): sea_water_pressure 0.080391 db
2018-10-23T23:42:26.679Z,1540338146.679 [Reporter](INFO): sea_water_pressure 0.083866 db
2018-10-23T23:42:27.063Z,1540338147.063 [Reporter](INFO): sea_water_pressure 0.074310 db
2018-10-23T23:42:27.471Z,1540338147.471 [Reporter](INFO): sea_water_pressure 0.063450 db
2018-10-23T23:42:27.903Z,1540338147.903 [Reporter](INFO): sea_water_pressure 0.073875 db
2018-10-23T23:42:28.271Z,1540338148.271 [Reporter](INFO): sea_water_pressure 0.082998 db
2018-10-23T23:42:28.675Z,1540338148.675 [Reporter](INFO): sea_water_pressure 0.085604 db
2018-10-23T23:42:29.066Z,1540338149.066 [Reporter](INFO): sea_water_pressure 0.081696 db
2018-10-23T23:42:29.503Z,1540338149.503 [Reporter](INFO): sea_water_pressure 0.075178 db
2018-10-23T23:42:29.897Z,1540338149.897 [Reporter](INFO): sea_water_pressure 0.075613 db
2018-10-23T23:42:30.287Z,1540338150.287 [Reporter](INFO): sea_water_pressure 0.067795 db
2018-10-23T23:42:30.675Z,1540338150.675 [Reporter](INFO): sea_water_pressure 0.069532 db
2018-10-23T23:42:31.079Z,1540338151.079 [Reporter](INFO): sea_water_pressure 0.075613 db
2018-10-23T23:42:31.486Z,1540338151.486 [Reporter](INFO): sea_water_pressure 0.071703 db
2018-10-23T23:42:31.867Z,1540338151.867 [Reporter](INFO): sea_water_pressure 0.065622 db
2018-10-23T23:42:32.275Z,1540338152.275 [Reporter](INFO): sea_water_pressure 0.067360 db
2018-10-23T23:42:32.694Z,1540338152.694 [Reporter](INFO): sea_water_pressure 0.076483 db
2018-10-23T23:42:33.063Z,1540338153.063 [Reporter](INFO): sea_water_pressure 0.066057 db
2018-10-23T23:42:33.463Z,1540338153.463 [Reporter](INFO): sea_water_pressure 0.069532 db
2018-10-23T23:42:33.899Z,1540338153.899 [Reporter](INFO): sea_water_pressure 0.073875 db
2018-10-23T23:42:34.263Z,1540338154.263 [Reporter](INFO): sea_water_pressure 0.062582 db
2018-10-23T23:42:34.703Z,1540338154.703 [Reporter](INFO): sea_water_pressure 0.069965 db
2018-10-23T23:42:35.103Z,1540338155.103 [Reporter](INFO): sea_water_pressure 0.067360 db
2018-10-23T23:42:35.503Z,1540338155.503 [Reporter](INFO): sea_water_pressure 0.071703 db
2018-10-23T23:42:35.899Z,1540338155.899 [Reporter](INFO): sea_water_pressure 0.067795 db
2018-10-23T23:42:36.263Z,1540338156.263 [Reporter](INFO): sea_water_pressure 0.082129 db
2018-10-23T23:42:36.671Z,1540338156.671 [Reporter](INFO): sea_water_pressure 0.065622 db
2018-10-23T23:42:37.067Z,1540338157.067 [Reporter](INFO): sea_water_pressure 0.060410 db
2018-10-23T23:42:37.497Z,1540338157.497 [Reporter](INFO): sea_water_pressure 0.065187 db
2018-10-23T23:42:37.871Z,1540338157.871 [Reporter](INFO): sea_water_pressure 0.074745 db
2018-10-23T23:42:38.267Z,1540338158.267 [Reporter](INFO): sea_water_pressure 0.073441 db
2018-10-23T23:42:38.699Z,1540338158.699 [Reporter](INFO): sea_water_pressure 0.074745 db
2018-10-23T23:42:39.067Z,1540338159.067 [Reporter](INFO): sea_water_pressure 0.081261 db
2018-10-23T23:42:39.467Z,1540338159.467 [Reporter](INFO): sea_water_pressure 0.077351 db
2018-10-23T23:42:39.908Z,1540338159.908 [Reporter](INFO): sea_water_pressure 0.073008 db
2018-10-23T23:42:40.276Z,1540338160.276 [Reporter](INFO): sea_water_pressure 0.064320 db
2018-10-23T23:42:40.700Z,1540338160.700 [Reporter](INFO): sea_water_pressure 0.073875 db
2018-10-23T23:42:41.091Z,1540338161.091 [Reporter](INFO): sea_water_pressure 0.061277 db
2018-10-23T23:42:41.463Z,1540338161.463 [Reporter](INFO): sea_water_pressure 0.073875 db
2018-10-23T23:42:41.867Z,1540338161.867 [Reporter](INFO): sea_water_pressure 0.068663 db
2018-10-23T23:42:42.275Z,1540338162.275 [Reporter](INFO): sea_water_pressure 0.081696 db
2018-10-23T23:42:42.667Z,1540338162.667 [Reporter](INFO): sea_water_pressure 0.077785 db
2018-10-23T23:42:43.067Z,1540338163.067 [Reporter](INFO): sea_water_pressure 0.064753 db
2018-10-23T23:42:43.475Z,1540338163.475 [Reporter](INFO): sea_water_pressure 0.056499 db
2018-10-23T23:42:43.867Z,1540338163.867 [Reporter](INFO): sea_water_pressure 0.069532 db
2018-10-23T23:42:44.267Z,1540338164.267 [Reporter](INFO): sea_water_pressure 0.074745 db
2018-10-23T23:42:44.675Z,1540338164.675 [Reporter](INFO): sea_water_pressure 0.072573 db
2018-10-23T23:42:45.091Z,1540338165.091 [Reporter](INFO): sea_water_pressure 0.077351 db
2018-10-23T23:42:45.491Z,1540338165.491 [Reporter](INFO): sea_water_pressure 0.067360 db
2018-10-23T23:42:45.902Z,1540338165.902 [Reporter](INFO): sea_water_pressure 0.070835 db
2018-10-23T23:42:46.291Z,1540338166.291 [Reporter](INFO): sea_water_pressure 0.071270 db
2018-10-23T23:42:46.675Z,1540338166.675 [Reporter](INFO): sea_water_pressure 0.066925 db
2018-10-23T23:42:47.075Z,1540338167.075 [Reporter](INFO): sea_water_pressure 0.070835 db
2018-10-23T23:42:47.471Z,1540338167.471 [Reporter](INFO): sea_water_pressure 0.080826 db
2018-10-23T23:42:47.871Z,1540338167.871 [Reporter](INFO): sea_water_pressure 0.066490 db
2018-10-23T23:42:48.267Z,1540338168.267 [Reporter](INFO): sea_water_pressure 0.060844 db
2018-10-23T23:42:48.671Z,1540338168.671 [Reporter](INFO): sea_water_pressure 0.062147 db
2018-10-23T23:42:49.071Z,1540338169.071 [Reporter](INFO): sea_water_pressure 0.077785 db
2018-10-23T23:42:49.475Z,1540338169.475 [Reporter](INFO): sea_water_pressure 0.064753 db
2018-10-23T23:42:49.867Z,1540338169.867 [Reporter](INFO): sea_water_pressure 0.067360 db
2018-10-23T23:42:50.287Z,1540338170.287 [Reporter](INFO): sea_water_pressure 0.076916 db
2018-10-23T23:42:50.687Z,1540338170.687 [Reporter](INFO): sea_water_pressure 0.076483 db
2018-10-23T23:42:51.091Z,1540338171.091 [Reporter](INFO): sea_water_pressure 0.070400 db
2018-10-23T23:42:51.491Z,1540338171.491 [Reporter](INFO): sea_water_pressure 0.072138 db
2018-10-23T23:42:51.868Z,1540338171.868 [Reporter](INFO): sea_water_pressure 0.083866 db
2018-10-23T23:42:52.267Z,1540338172.267 [Reporter](INFO): sea_water_pressure 0.060844 db
2018-10-23T23:42:52.671Z,1540338172.671 [Reporter](INFO): sea_water_pressure 0.073875 db
2018-10-23T23:42:53.075Z,1540338173.075 [Reporter](INFO): sea_water_pressure 0.076916 db
2018-10-23T23:42:53.467Z,1540338173.467 [Reporter](INFO): sea_water_pressure 0.065187 db
2018-10-23T23:42:53.871Z,1540338173.871 [Reporter](INFO): sea_water_pressure 0.070835 db
2018-10-23T23:42:54.271Z,1540338174.271 [Reporter](INFO): sea_water_pressure 0.085604 db
2018-10-23T23:42:54.679Z,1540338174.679 [Reporter](INFO): sea_water_pressure 0.076916 db
2018-10-23T23:42:55.154Z,1540338175.154 [Reporter](INFO): sea_water_pressure 0.069532 db
2018-10-23T23:42:55.458Z,1540338175.458 [CommandLine](IMPORTANT): got command report clear
2018-10-23T23:43:15.335Z,1540338195.335 [CommandLine](IMPORTANT): got command get sea_water_pressure pound_per_square_inch
2018-10-23T23:43:15.335Z,1540338195.335 [CommandLine](IMPORTANT): sea_water_pressure 0.103996 psi
2018-10-23T23:43:21.831Z,1540338201.831 [Startup:StartupSatComms:A](INFO): Timed out from 2018-10-23T23:42:21.8Z
2018-10-23T23:43:21.831Z,1540338201.831 [Startup:StartupSatComms:A] Stopped
2018-10-23T23:43:21.831Z,1540338201.831 [Startup:StartupSatComms:B] Running Loop=1
2018-10-23T23:43:22.236Z,1540338202.236 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-10-23T23:44:11.837Z,1540338251.837 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-23T23:44:11.837Z,1540338251.837 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2018-10-23T23:44:11.837Z,1540338251.837 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-10-23T23:44:11.839Z,1540338251.839 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-10-23T23:44:11.839Z,1540338251.839 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-10-23T23:44:11.839Z,1540338251.839 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-10-23T23:44:11.899Z,1540338251.899 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-10-23T23:44:11.899Z,1540338251.899 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-10-23T23:44:12.289Z,1540338252.289 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-10-23T23:44:12.290Z,1540338252.290 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2018-10-23T23:44:12.290Z,1540338252.290 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-10-23T23:44:12.290Z,1540338252.290 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-10-23T23:44:12.636Z,1540338252.636 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-10-23T23:44:12.637Z,1540338252.637 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-10-23T23:44:12.637Z,1540338252.637 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-10-23T23:44:12.638Z,1540338252.638 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-10-23T23:44:12.638Z,1540338252.638 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-10-23T23:44:12.638Z,1540338252.638 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-10-23T23:44:21.845Z,1540338261.845 [Startup:StartupSatComms:B](INFO): Timed out from 2018-10-23T23:43:21.8Z
2018-10-23T23:44:21.845Z,1540338261.845 [Startup:StartupSatComms:B] Stopped
2018-10-23T23:44:21.845Z,1540338261.845 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-10-23T23:44:21.845Z,1540338261.845 [Startup:StartupSatComms] Stopped
2018-10-23T23:44:21.846Z,1540338261.846 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-10-23T23:44:21.847Z,1540338261.847 [Startup](INFO): Completed Startup
2018-10-23T23:44:21.847Z,1540338261.847 [MissionManager](INFO): Startup is completed.
2018-10-23T23:44:21.847Z,1540338261.847 [MissionManager](INFO): Uninitializing Mission Startup
2018-10-23T23:44:21.847Z,1540338261.847 [Startup] Stopped
2018-10-23T23:44:21.847Z,1540338261.847 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-10-23T23:44:21.847Z,1540338261.847 [Startup:A.GoToSurface] Stopped
2018-10-23T23:44:21.848Z,1540338261.848 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-10-23T23:44:22.246Z,1540338262.246 [MissionManager](IMPORTANT): Started mission Default
2018-10-23T23:44:22.246Z,1540338262.246 [Default] Running Loop=1
2018-10-23T23:44:22.246Z,1540338262.246 [Default](DEBUG): Aggregate::initialize Default
2018-10-23T23:44:22.246Z,1540338262.246 [Default:B.GoToSurface] Running Loop=1
2018-10-23T23:44:22.246Z,1540338262.246 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-10-23T23:44:22.247Z,1540338262.247 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-10-23T23:44:22.247Z,1540338262.247 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-10-23T23:44:22.247Z,1540338262.247 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-10-23T23:44:22.247Z,1540338262.247 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-10-23T23:44:22.248Z,1540338262.248 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-10-23T23:44:22.248Z,1540338262.248 [Default:A.Wait] Running Loop=1
2018-10-23T23:44:22.248Z,1540338262.248 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-10-23T23:44:27.040Z,1