2018-08-13T00:15:32.923Z,1534119332.923 [Supervisor](DEBUG): Initializing supervisor.
2018-08-13T00:15:32.926Z,1534119332.926 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-08-13T00:15:32.927Z,1534119332.927 [SyncHandler](INFO): Protected caller Thread ID is 5593
2018-08-13T00:15:32.927Z,1534119332.927 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-08-13T00:15:32.928Z,1534119332.928 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-08-13T00:15:32.928Z,1534119332.928 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5594
2018-08-13T00:15:33.055Z,1534119333.055 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-08-13T00:15:33.081Z,1534119333.081 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-08-13T00:15:33.082Z,1534119333.082 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-08-13T00:15:33.082Z,1534119333.082 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5595
2018-08-13T00:15:33.083Z,1534119333.083 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-08-13T00:15:33.084Z,1534119333.084 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-08-13T00:15:33.085Z,1534119333.085 [logger ThreadHandler](INFO): Protected caller Thread ID is 5596
2018-08-13T00:15:33.091Z,1534119333.091 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-08-13T00:15:33.092Z,1534119333.092 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-08-13T00:15:33.093Z,1534119333.093 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-08-13T00:15:33.383Z,1534119333.383 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-08-13T00:15:33.384Z,1534119333.384 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-08-13T00:15:33.528Z,1534119333.528 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-08-13T00:15:33.528Z,1534119333.528 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-08-13T00:15:33.877Z,1534119333.877 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-08-13T00:15:33.878Z,1534119333.878 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-08-13T00:15:34.018Z,1534119334.018 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-08-13T00:15:34.018Z,1534119334.018 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-08-13T00:15:34.101Z,1534119334.101 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-08-13T00:15:34.305Z,1534119334.305 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-08-13T00:15:34.305Z,1534119334.305 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-08-13T00:15:34.407Z,1534119334.407 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-08-13T00:15:34.408Z,1534119334.408 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-08-13T00:15:34.751Z,1534119334.751 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-08-13T00:15:34.752Z,1534119334.752 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-08-13T00:15:35.192Z,1534119335.192 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-08-13T00:15:35.192Z,1534119335.192 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-08-13T00:15:35.500Z,1534119335.500 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-08-13T00:15:35.500Z,1534119335.500 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-08-13T00:15:36.006Z,1534119336.006 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-08-13T00:15:36.007Z,1534119336.007 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-08-13T00:15:36.221Z,1534119336.221 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-08-13T00:15:36.221Z,1534119336.221 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-08-13T00:15:36.321Z,1534119336.321 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-08-13T00:15:36.321Z,1534119336.321 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-08-13T00:15:36.818Z,1534119336.818 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-08-13T00:15:36.819Z,1534119336.819 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-08-13T00:15:36.927Z,1534119336.927 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-08-13T00:15:36.929Z,1534119336.929 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2018-08-13T00:15:36.930Z,1534119336.930 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2018-08-13T00:15:37.163Z,1534119337.163 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-08-13T00:15:37.164Z,1534119337.164 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2018-08-13T00:15:37.264Z,1534119337.264 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2018-08-13T00:15:37.359Z,1534119337.359 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2018-08-13T00:15:37.463Z,1534119337.463 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2018-08-13T00:15:37.550Z,1534119337.550 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2018-08-13T00:15:37.686Z,1534119337.686 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2018-08-13T00:15:37.853Z,1534119337.853 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2018-08-13T00:15:37.967Z,1534119337.967 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2018-08-13T00:15:40.663Z,1534119340.663 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2018-08-13T00:15:47.409Z,1534119347.409 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2018-08-13T00:15:47.599Z,1534119347.599 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2018-08-13T00:15:47.599Z,1534119347.599 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-08-13T00:15:47.606Z,1534119347.606 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-08-13T00:15:47.758Z,1534119347.758 [SBIT](DEBUG): Construct Startup Built In Test.
2018-08-13T00:15:47.788Z,1534119347.788 [SBIT] Loaded
2018-08-13T00:15:47.789Z,1534119347.789 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-08-13T00:15:47.789Z,1534119347.789 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-08-13T00:15:47.817Z,1534119347.817 [IBIT] Loaded
2018-08-13T00:15:47.817Z,1534119347.817 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-08-13T00:15:47.821Z,1534119347.821 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-08-13T00:15:47.978Z,1534119347.978 [CBIT] Loaded
2018-08-13T00:15:47.978Z,1534119347.978 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-08-13T00:15:47.978Z,1534119347.978 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-08-13T00:15:47.979Z,1534119347.979 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-08-13T00:15:48.066Z,1534119348.066 [VerticalControl](DEBUG): Construct VerticalControl.
2018-08-13T00:15:48.165Z,1534119348.165 [VerticalControl] Loaded
2018-08-13T00:15:48.165Z,1534119348.165 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-08-13T00:15:48.166Z,1534119348.166 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-08-13T00:15:48.227Z,1534119348.227 [HorizontalControl] Loaded
2018-08-13T00:15:48.227Z,1534119348.227 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-08-13T00:15:48.228Z,1534119348.228 [SpeedControl](DEBUG): Construct SpeedControl.
2018-08-13T00:15:48.233Z,1534119348.233 [SpeedControl] Loaded
2018-08-13T00:15:48.234Z,1534119348.234 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-08-13T00:15:48.234Z,1534119348.234 [LoopControl](DEBUG): Construct LoopControl.
2018-08-13T00:15:48.235Z,1534119348.235 [LoopControl] Loaded
2018-08-13T00:15:48.235Z,1534119348.235 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-08-13T00:15:48.236Z,1534119348.236 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-08-13T00:15:48.236Z,1534119348.236 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-08-13T00:15:48.291Z,1534119348.291 [DepthRateCalculator] Loaded
2018-08-13T00:15:48.292Z,1534119348.292 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-08-13T00:15:48.297Z,1534119348.297 [PitchRateCalculator] Loaded
2018-08-13T00:15:48.297Z,1534119348.297 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-08-13T00:15:48.313Z,1534119348.313 [SpeedCalculator] Loaded
2018-08-13T00:15:48.314Z,1534119348.314 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-08-13T00:15:48.335Z,1534119348.335 [TempGradientCalculator] Loaded
2018-08-13T00:15:48.336Z,1534119348.336 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-08-13T00:15:48.351Z,1534119348.351 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-08-13T00:15:48.351Z,1534119348.351 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-08-13T00:15:48.357Z,1534119348.357 [YawRateCalculator] Loaded
2018-08-13T00:15:48.357Z,1534119348.357 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-08-13T00:15:48.386Z,1534119348.386 [ElevatorOffsetCalculator] Loaded
2018-08-13T00:15:48.386Z,1534119348.386 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-08-13T00:15:48.387Z,1534119348.387 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-08-13T00:15:48.387Z,1534119348.387 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-08-13T00:15:48.510Z,1534119348.510 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-08-13T00:15:48.510Z,1534119348.510 [StratificationFrontDetector](DEBUG): (re)initializing
2018-08-13T00:15:48.511Z,1534119348.511 [StratificationFrontDetector] Loaded
2018-08-13T00:15:48.511Z,1534119348.511 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-08-13T00:15:48.512Z,1534119348.512 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-08-13T00:15:48.512Z,1534119348.512 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-08-13T00:15:48.797Z,1534119348.797 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-08-13T00:15:48.798Z,1534119348.798 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-08-13T00:15:48.910Z,1534119348.910 [DeadReckonUsingMultipleVelocitySources] Loaded
2018-08-13T00:15:48.911Z,1534119348.911 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2018-08-13T00:15:48.965Z,1534119348.965 [DeadReckonUsingSpeedCalculator] Loaded
2018-08-13T00:15:48.965Z,1534119348.965 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-08-13T00:15:48.982Z,1534119348.982 [NavChart] Loaded
2018-08-13T00:15:48.982Z,1534119348.982 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-08-13T00:15:48.986Z,1534119348.986 [UniversalFixResidualReporter] Loaded
2018-08-13T00:15:48.987Z,1534119348.987 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-08-13T00:15:48.987Z,1534119348.987 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-08-13T00:15:48.988Z,1534119348.988 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-08-13T00:15:49.012Z,1534119349.012 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-08-13T00:15:49.013Z,1534119349.013 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-08-13T00:15:49.217Z,1534119349.217 [Aanderaa_O2] Loaded
2018-08-13T00:15:49.218Z,1534119349.218 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-08-13T00:15:49.263Z,1534119349.263 [WetLabsSeaOWL_UV_A] Loaded
2018-08-13T00:15:49.263Z,1534119349.263 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2018-08-13T00:15:49.264Z,1534119349.264 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 406D44E0
2018-08-13T00:15:49.265Z,1534119349.265 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 5675
2018-08-13T00:15:49.265Z,1534119349.265 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-08-13T00:15:49.266Z,1534119349.266 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-08-13T00:15:49.671Z,1534119349.671 [AHRS_M2] Loaded
2018-08-13T00:15:49.671Z,1534119349.671 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2018-08-13T00:15:49.738Z,1534119349.738 [DataOverHttps] Loaded
2018-08-13T00:15:49.738Z,1534119349.738 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-08-13T00:15:49.752Z,1534119349.752 [Depth_Keller] Loaded
2018-08-13T00:15:49.752Z,1534119349.752 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-08-13T00:15:49.757Z,1534119349.757 [DropWeight] Loaded
2018-08-13T00:15:49.757Z,1534119349.757 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-08-13T00:15:49.849Z,1534119349.849 [NAL9602] Loaded
2018-08-13T00:15:49.849Z,1534119349.849 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-08-13T00:15:49.854Z,1534119349.854 [Onboard] Loaded
2018-08-13T00:15:49.854Z,1534119349.854 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-08-13T00:15:49.861Z,1534119349.861 [Radio_Surface] Loaded
2018-08-13T00:15:49.862Z,1534119349.862 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-08-13T00:15:49.863Z,1534119349.863 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4092B4E0
2018-08-13T00:15:49.863Z,1534119349.863 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5676
2018-08-13T00:15:49.892Z,1534119349.892 [RDI_Pathfinder] Loaded
2018-08-13T00:15:49.892Z,1534119349.892 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2018-08-13T00:15:49.899Z,1534119349.899 [SCPI] Loaded
2018-08-13T00:15:49.899Z,1534119349.899 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2018-08-13T00:15:51.695Z,1534119351.695 [BPC1] Loaded
2018-08-13T00:15:51.695Z,1534119351.695 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-08-13T00:15:51.695Z,1534119351.695 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-08-13T00:15:51.696Z,1534119351.696 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-08-13T00:15:51.815Z,1534119351.815 [BuoyancyServo] Loaded
2018-08-13T00:15:51.815Z,1534119351.815 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-08-13T00:15:51.827Z,1534119351.827 [ElevatorServo] Loaded
2018-08-13T00:15:51.827Z,1534119351.827 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-08-13T00:15:51.838Z,1534119351.838 [MassServo] Loaded
2018-08-13T00:15:51.839Z,1534119351.839 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-08-13T00:15:51.850Z,1534119351.850 [RudderServo] Loaded
2018-08-13T00:15:51.850Z,1534119351.850 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-08-13T00:15:51.861Z,1534119351.861 [ThrusterServo] Loaded
2018-08-13T00:15:51.861Z,1534119351.861 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-08-13T00:15:51.862Z,1534119351.862 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-08-13T00:15:51.862Z,1534119351.862 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-08-13T00:15:51.990Z,1534119351.990 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-08-13T00:15:51.991Z,1534119351.991 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-08-13T00:15:52.028Z,1534119352.028 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-08-13T00:15:52.032Z,1534119352.032 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-08-13T00:15:52.033Z,1534119352.033 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-08-13T00:15:52.040Z,1534119352.040 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-08-13T00:15:52.041Z,1534119352.041 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409D44E0
2018-08-13T00:15:52.041Z,1534119352.041 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5677
2018-08-13T00:15:52.046Z,1534119352.046 [Supervisor](INFO): Main Thread ID is 795
2018-08-13T00:15:52.046Z,1534119352.046 [Supervisor](DEBUG): Running supervisor.
2018-08-13T00:15:52.047Z,1534119352.047 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5678
2018-08-13T00:15:52.049Z,1534119352.049 [controlThread ThreadHandler](INFO): Handler Thread ID is 5679
2018-08-13T00:15:52.050Z,1534119352.050 [controlThread](DEBUG): Initializing ControlThread
2018-08-13T00:15:52.051Z,1534119352.051 [SBIT](INFO): Initialize SBIT Component.
2018-08-13T00:15:52.051Z,1534119352.051 [SBIT](IMPORTANT): git: 2018-08-02-6-ga51bce9
2018-08-13T00:15:52.052Z,1534119352.052 [SBIT](INFO): git hash: a51bce9544ae2943cfba4f0cf10c7b00e5392907
2018-08-13T00:15:52.052Z,1534119352.052 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-08-13T00:15:52.053Z,1534119352.053 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-08-13T00:15:52.054Z,1534119352.054 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2018-08-13T00:15:52.055Z,1534119352.055 [IBIT](INFO): Initialize IBIT Component.
2018-08-13T00:15:52.056Z,1534119352.056 [CBIT](DEBUG): Initialize CBIT Component.
2018-08-13T00:15:52.056Z,1534119352.056 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-08-13T00:15:52.057Z,1534119352.057 [logger ThreadHandler](INFO): Handler Thread ID is 5680
2018-08-13T00:15:52.083Z,1534119352.083 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 5681
2018-08-13T00:15:52.084Z,1534119352.084 [WetLabsSeaOWL_UV_A](INFO): Powering down
2018-08-13T00:15:52.123Z,1534119352.123 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5682
2018-08-13T00:15:52.128Z,1534119352.128 [Radio_Surface](INFO): Powering up
2018-08-13T00:15:52.135Z,1534119352.135 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5683
2018-08-13T00:15:52.144Z,1534119352.144 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-08-13T00:15:52.144Z,1534119352.144 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-08-13T00:15:52.144Z,1534119352.144 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-08-13T00:15:52.144Z,1534119352.144 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-08-13T00:15:52.145Z,1534119352.145 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-08-13T00:15:52.145Z,1534119352.145 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-08-13T00:15:52.145Z,1534119352.145 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-08-13T00:15:52.145Z,1534119352.145 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-08-13T00:15:52.145Z,1534119352.145 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-08-13T00:15:52.145Z,1534119352.145 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-08-13T00:15:52.146Z,1534119352.146 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-08-13T00:15:52.146Z,1534119352.146 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-08-13T00:15:52.146Z,1534119352.146 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-08-13T00:15:52.146Z,1534119352.146 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-08-13T00:15:52.147Z,1534119352.147 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-08-13T00:15:52.147Z,1534119352.147 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-08-13T00:15:52.163Z,1534119352.163 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-08-13T00:15:52.165Z,1534119352.165 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-08-13T00:15:52.165Z,1534119352.165 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-08-13T00:15:52.166Z,1534119352.166 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-08-13T00:15:52.166Z,1534119352.166 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-08-13T00:15:52.171Z,1534119352.171 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-08-13T00:15:52.171Z,1534119352.171 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-08-13T00:15:52.171Z,1534119352.171 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-08-13T00:15:52.172Z,1534119352.172 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-08-13T00:15:52.173Z,1534119352.173 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-08-13T00:15:52.173Z,1534119352.173 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-08-13T00:15:52.174Z,1534119352.174 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-08-13T00:15:52.175Z,1534119352.175 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-08-13T00:15:52.175Z,1534119352.175 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-08-13T00:15:52.176Z,1534119352.176 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-13T00:15:52.176Z,1534119352.176 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-13T00:15:52.177Z,1534119352.177 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-13T00:15:52.177Z,1534119352.177 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-08-13T00:15:52.177Z,1534119352.177 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-08-13T00:15:52.196Z,1534119352.196 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-08-13T00:15:52.222Z,1534119352.222 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-08-13T00:15:52.251Z,1534119352.251 [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-08-13T00:15:52.251Z,1534119352.251 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-08-13T00:15:52.337Z,1534119352.337 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-08-13T00:15:52.339Z,1534119352.339 [Default:A.Wait](DEBUG): Construct Wait.
2018-08-13T00:15:52.340Z,1534119352.340 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-08-13T00:15:52.397Z,1534119352.397 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-08-13T00:15:52.400Z,1534119352.400 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-08-13T00:15:52.428Z,1534119352.428 [Default:E.Execute](DEBUG): Construct Execute.
2018-08-13T00:15:52.456Z,1534119352.456 [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 300</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-08-13T00:15:52.461Z,1534119352.461 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,SCPI,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-08-13T00:15:52.509Z,1534119352.509 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2018-08-13T00:15:52.812Z,1534119352.812 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-08-13T00:15:52.851Z,1534119352.851 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2018-08-13T00:15:53.049Z,1534119353.049 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-08-13T00:15:53.171Z,1534119353.171 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:53.199Z,1534119353.199 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:53.296Z,1534119353.296 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-08-13T00:15:53.303Z,1534119353.303 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-08-13T00:15:53.326Z,1534119353.326 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-08-13T00:15:53.335Z,1534119353.335 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-08-13T00:15:53.356Z,1534119353.356 [MassServo](DEBUG): Initializing EZServoServo.
2018-08-13T00:15:53.363Z,1534119353.363 [MassServo](DEBUG): Initializing MassServo.
2018-08-13T00:15:53.369Z,1534119353.369 [RudderServo](DEBUG): Initializing EZServoServo.
2018-08-13T00:15:53.375Z,1534119353.375 [RudderServo](DEBUG): Initializing RudderServo.
2018-08-13T00:15:53.396Z,1534119353.396 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-08-13T00:15:53.403Z,1534119353.403 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-08-13T00:15:53.569Z,1534119353.569 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-08-13T00:15:53.569Z,1534119353.569 [DropWeight] Hardware Fault, FailCount= 1
2018-08-13T00:15:53.569Z,1534119353.569 [DropWeight](ERROR): Hardware Fault
2018-08-13T00:15:53.599Z,1534119353.599 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-08-13T00:15:53.618Z,1534119353.618 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:53.620Z,1534119353.620 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:53.642Z,1534119353.642 [CommandLine](FAULT): Scheduling is paused
2018-08-13T00:15:53.643Z,1534119353.643 [CBIT](INFO): Critical error at 20180813T001553
2018-08-13T00:15:53.643Z,1534119353.643 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-08-13T00:15:53.648Z,1534119353.648 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-08-13T00:15:53.649Z,1534119353.649 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-08-13T00:15:53.950Z,1534119353.950 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:53.952Z,1534119353.952 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:54.290Z,1534119354.290 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-08-13T00:15:54.291Z,1534119354.291 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-08-13T00:15:54.291Z,1534119354.291 [BuoyancyServo] Communications Fault, FailCount= 1
2018-08-13T00:15:54.291Z,1534119354.291 [BuoyancyServo](ERROR): Communications Fault
2018-08-13T00:15:54.500Z,1534119354.500 [CBIT](INFO): Critical error at 20180813T001553
2018-08-13T00:15:54.503Z,1534119354.503 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-08-13T00:15:54.559Z,1534119354.559 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:54.560Z,1534119354.560 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:54.572Z,1534119354.572 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-08-13T00:15:54.573Z,1534119354.573 [BuoyancyServo](INFO): Powering down
2018-08-13T00:15:54.943Z,1534119354.943 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:54.945Z,1534119354.945 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:55.339Z,1534119355.339 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:55.340Z,1534119355.340 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:55.830Z,1534119355.830 [Aanderaa_O2](INFO): Powering down
2018-08-13T00:15:55.967Z,1534119355.967 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:55.968Z,1534119355.968 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:56.211Z,1534119356.211 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:56.212Z,1534119356.212 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:56.579Z,1534119356.579 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:56.580Z,1534119356.580 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:56.632Z,1534119356.632 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-08-13T00:15:56.632Z,1534119356.632 [BuoyancyServo] No Fault, FailCount= 1
2018-08-13T00:15:56.967Z,1534119356.967 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:56.969Z,1534119356.969 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:56.975Z,1534119356.975 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-08-13T00:15:57.095Z,1534119357.095 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-08-13T00:15:57.419Z,1534119357.419 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:57.420Z,1534119357.420 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:57.798Z,1534119357.798 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:57.800Z,1534119357.800 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:58.188Z,1534119358.188 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:58.190Z,1534119358.190 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:58.567Z,1534119358.567 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:58.568Z,1534119358.568 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:58.975Z,1534119358.975 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:58.976Z,1534119358.976 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:59.340Z,1534119359.340 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:59.341Z,1534119359.341 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:59.775Z,1534119359.775 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:15:59.776Z,1534119359.776 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-08-13T00:16:06.538Z,1534119366.538 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2018-08-13T00:16:16.614Z,1534119376.614 [SBIT](IMPORTANT): Beginning Startup BIT
2018-08-13T00:16:16.619Z,1534119376.619 [CBIT](IMPORTANT): Beginning ground fault scan
2018-08-13T00:16:19.856Z,1534119379.856 [NAL9602](INFO): Powering up NAL9602
2018-08-13T00:16:27.600Z,1534119387.600 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.020589
CHAN A1 (24V): -0.023378
CHAN A2 (12V): -0.007403
CHAN A3 (5V): -0.002030
CHAN B0 (3.3V): 0.000143
CHAN B1 (3.15aV): -0.000066
CHAN B2 (3.15bV): -0.000055
CHAN B3 (GND): 0.002389
OPEN: 0.004574
Full Scale Calc: 4.765 mA, -1.589 mA
2018-08-13T00:16:29.412Z,1534119389.412 [CommandLine](IMPORTANT): got command failComponent
2018-08-13T00:16:29.412Z,1534119389.412 [CommandLine](IMPORTANT): Failed components:
2018-08-13T00:16:29.413Z,1534119389.413 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2018-08-13T00:16:31.056Z,1534119391.056 [NAL9602](INFO): NAL9602 initialized
2018-08-13T00:16:42.763Z,1534119402.763 [CommandLine](IMPORTANT): got command report mod platform_orientation
2018-08-13T00:16:42.990Z,1534119402.990 [Reporter](INFO): platform_orientation 0.709966 rad
2018-08-13T00:16:43.386Z,1534119403.386 [Reporter](INFO): platform_orientation 0.709457 rad
2018-08-13T00:16:43.842Z,1534119403.842 [Reporter](INFO): platform_orientation 0.709317 rad
2018-08-13T00:16:44.198Z,1534119404.198 [Reporter](INFO): platform_orientation 0.709292 rad
2018-08-13T00:16:44.610Z,1534119404.610 [Reporter](INFO): platform_orientation 0.709387 rad
2018-08-13T00:16:45.018Z,1534119405.018 [Reporter](INFO): platform_orientation 0.709482 rad
2018-08-13T00:16:45.374Z,1534119405.374 [Reporter](INFO): platform_orientation 0.709496 rad
2018-08-13T00:16:45.858Z,1534119405.858 [Reporter](INFO): platform_orientation 0.709194 rad
2018-08-13T00:16:46.218Z,1534119406.218 [Reporter](INFO): platform_orientation 0.709583 rad
2018-08-13T00:16:46.606Z,1534119406.606 [Reporter](INFO): platform_orientation 0.709602 rad
2018-08-13T00:16:46.990Z,1534119406.990 [Reporter](INFO): platform_orientation 0.709650 rad
2018-08-13T00:16:47.468Z,1534119407.468 [Reporter](INFO): platform_orientation 0.709827 rad
2018-08-13T00:16:47.791Z,1534119407.791 [Reporter](INFO): platform_orientation 0.709626 rad
2018-08-13T00:16:48.191Z,1534119408.191 [Reporter](INFO): platform_orientation 0.710045 rad
2018-08-13T00:16:48.608Z,1534119408.608 [Reporter](INFO): platform_orientation 0.709747 rad
2018-08-13T00:16:48.976Z,1534119408.976 [Reporter](INFO): platform_orientation 0.709803 rad
2018-08-13T00:16:49.396Z,1534119409.396 [Reporter](INFO): platform_orientation 0.709880 rad
2018-08-13T00:16:49.774Z,1534119409.774 [Reporter](INFO): platform_orientation 0.709906 rad
2018-08-13T00:16:50.179Z,1534119410.179 [Reporter](INFO): platform_orientation 0.709622 rad
2018-08-13T00:16:50.571Z,1534119410.571 [Reporter](INFO): platform_orientation 0.709854 rad
2018-08-13T00:16:50.955Z,1534119410.955 [Reporter](INFO): platform_orientation 0.709855 rad
2018-08-13T00:16:51.341Z,1534119411.341 [Reporter](INFO): platform_orientation 0.709981 rad
2018-08-13T00:16:51.767Z,1534119411.767 [Reporter](INFO): platform_orientation 0.709616 rad
2018-08-13T00:16:52.160Z,1534119412.160 [Reporter](INFO): platform_orientation 0.709625 rad
2018-08-13T00:16:52.620Z,1534119412.620 [Reporter](INFO): platform_orientation 0.709537 rad
2018-08-13T00:16:52.963Z,1534119412.963 [Reporter](INFO): platform_orientation 0.709493 rad
2018-08-13T00:16:53.361Z,1534119413.361 [Reporter](INFO): platform_orientation 0.709763 rad
2018-08-13T00:16:53.816Z,1534119413.816 [Reporter](INFO): platform_orientation 0.709443 rad
2018-08-13T00:16:54.195Z,1534119414.195 [Reporter](INFO): platform_orientation 0.709901 rad
2018-08-13T00:16:54.576Z,1534119414.576 [Reporter](INFO): platform_orientation 0.709862 rad
2018-08-13T00:16:54.984Z,1534119414.984 [Reporter](INFO): platform_orientation 0.709689 rad
2018-08-13T00:16:55.372Z,1534119415.372 [Reporter](INFO): platform_orientation 0.709547 rad
2018-08-13T00:16:55.794Z,1534119415.794 [Reporter](INFO): platform_orientation 0.709930 rad
2018-08-13T00:16:56.187Z,1534119416.187 [Reporter](INFO): platform_orientation 0.709650 rad
2018-08-13T00:16:56.584Z,1534119416.584 [Reporter](INFO): platform_orientation 0.709875 rad
2018-08-13T00:16:56.960Z,1534119416.960 [Reporter](INFO): platform_orientation 0.710038 rad
2018-08-13T00:16:57.378Z,1534119417.378 [Reporter](INFO): platform_orientation 0.709999 rad
2018-08-13T00:16:57.801Z,1534119417.801 [Reporter](INFO): platform_orientation 0.710032 rad
2018-08-13T00:16:57.927Z,1534119417.927 [CommandLine](IMPORTANT): got command report clear
2018-08-13T00:17:10.679Z,1534119430.679 [SBIT](IMPORTANT): SBIT PASSED
2018-08-13T00:17:10.751Z,1534119430.751 [CommandLine](IMPORTANT): got command configSet list
2018-08-13T00:17:10.751Z,1534119430.751 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-08-13T00:17:10.752Z,1534119430.752 [CommandLine](IMPORTANT): Radio_Surface.loadAtStartup=1 bool;

2018-08-13T00:17:10.752Z,1534119430.752 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=160 cubic_centimeter;

2018-08-13T00:17:10.752Z,1534119430.752 [CommandLine](IMPORTANT): VerticalControl.massDefault=-1 millimeter;

2018-08-13T00:17:11.055Z,1534119431.055 [MissionManager](IMPORTANT): Started mission Startup
2018-08-13T00:17:11.055Z,1534119431.055 [Startup] Running Loop=1
2018-08-13T00:17:11.056Z,1534119431.056 [Startup](DEBUG): Aggregate::initialize Startup
2018-08-13T00:17:11.056Z,1534119431.056 [Startup:A.GoToSurface] Running Loop=1
2018-08-13T00:17:11.056Z,1534119431.056 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-08-13T00:17:11.056Z,1534119431.056 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-08-13T00:17:11.057Z,1534119431.057 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-08-13T00:17:11.057Z,1534119431.057 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-08-13T00:17:11.058Z,1534119431.058 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-08-13T00:17:11.058Z,1534119431.058 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-08-13T00:17:11.084Z,1534119431.084 [Startup:StartupSatComms] Running Loop=1
2018-08-13T00:17:11.084Z,1534119431.084 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-08-13T00:17:11.085Z,1534119431.085 [Startup:StartupSatComms:A] Running Loop=1
2018-08-13T00:17:11.491Z,1534119431.491 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-08-13T00:17:28.672Z,1534119448.672 [CommandLine](IMPORTANT): got command report touch AHRS_M2.durationOfLastRun
2018-08-13T00:17:29.072Z,1534119449.072 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002636 s
2018-08-13T00:17:29.519Z,1534119449.519 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002768 s
2018-08-13T00:17:29.891Z,1534119449.891 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002271 s
2018-08-13T00:17:30.287Z,1534119450.287 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002629 s
2018-08-13T00:17:30.691Z,1534119450.691 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002565 s
2018-08-13T00:17:31.092Z,1534119451.092 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002240 s
2018-08-13T00:17:31.496Z,1534119451.496 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.004487 s
2018-08-13T00:17:31.892Z,1534119451.892 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002484 s
2018-08-13T00:17:32.292Z,1534119452.292 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002585 s
2018-08-13T00:17:32.711Z,1534119452.711 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.006732 s
2018-08-13T00:17:33.092Z,1534119453.092 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002488 s
2018-08-13T00:17:33.496Z,1534119453.496 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002260 s
2018-08-13T00:17:33.912Z,1534119453.912 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002698 s
2018-08-13T00:17:34.288Z,1534119454.288 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002487 s
2018-08-13T00:17:34.713Z,1534119454.713 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002253 s
2018-08-13T00:17:35.119Z,1534119455.119 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002625 s
2018-08-13T00:17:35.488Z,1534119455.488 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002389 s
2018-08-13T00:17:35.913Z,1534119455.913 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.003130 s
2018-08-13T00:17:36.297Z,1534119456.297 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.010000 s
2018-08-13T00:17:36.707Z,1534119456.707 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002395 s
2018-08-13T00:17:37.112Z,1534119457.112 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002260 s
2018-08-13T00:17:37.539Z,1534119457.539 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002821 s
2018-08-13T00:17:37.888Z,1534119457.888 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002405 s
2018-08-13T00:17:38.288Z,1534119458.288 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002264 s
2018-08-13T00:17:38.707Z,1534119458.707 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002663 s
2018-08-13T00:17:39.085Z,1534119459.085 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002392 s
2018-08-13T00:17:39.508Z,1534119459.508 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002269 s
2018-08-13T00:17:39.891Z,1534119459.891 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002642 s
2018-08-13T00:17:40.303Z,1534119460.303 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002583 s
2018-08-13T00:17:40.700Z,1534119460.700 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002449 s
2018-08-13T00:17:41.103Z,1534119461.103 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002894 s
2018-08-13T00:17:41.505Z,1534119461.505 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.004779 s
2018-08-13T00:17:41.880Z,1534119461.880 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002256 s
2018-08-13T00:17:42.295Z,1534119462.295 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002656 s
2018-08-13T00:17:42.694Z,1534119462.694 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002400 s
2018-08-13T00:17:43.076Z,1534119463.076 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002293 s
2018-08-13T00:17:43.492Z,1534119463.492 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002803 s
2018-08-13T00:17:43.883Z,1534119463.883 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002377 s
2018-08-13T00:17:44.300Z,1534119464.300 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002348 s
2018-08-13T00:17:44.711Z,1534119464.711 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002633 s
2018-08-13T00:17:45.102Z,1534119465.102 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002489 s
2018-08-13T00:17:45.504Z,1534119465.504 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002269 s
2018-08-13T00:17:45.908Z,1534119465.908 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002791 s
2018-08-13T00:17:46.293Z,1534119466.293 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.006620 s
2018-08-13T00:17:46.707Z,1534119466.707 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002268 s
2018-08-13T00:17:47.088Z,1534119467.088 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002646 s
2018-08-13T00:17:47.513Z,1534119467.513 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002488 s
2018-08-13T00:17:47.880Z,1534119467.880 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002255 s
2018-08-13T00:17:48.299Z,1534119468.299 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002672 s
2018-08-13T00:17:48.687Z,1534119468.687 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002704 s
2018-08-13T00:17:49.088Z,1534119469.088 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002269 s
2018-08-13T00:17:49.533Z,1534119469.533 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.003149 s
2018-08-13T00:17:49.932Z,1534119469.932 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002524 s
2018-08-13T00:17:50.328Z,1534119470.328 [Reporter](INFO): AHRS_M2.durationOfLastRun 0.002251 s
2018-08-13T00:17:50.652Z,1534119470.652 [CommandLine](IMPORTANT): got command report clear
2018-08-13T00:18:11.483Z,1534119491.483 [Startup:StartupSatComms:A](INFO): Timed out from 2018-08-13T00:17:11.1Z
2018-08-13T00:18:11.483Z,1534119491.483 [Startup:StartupSatComms:A] Stopped
2018-08-13T00:18:11.483Z,1534119491.483 [Startup:StartupSatComms:B] Running Loop=1
2018-08-13T00:18:11.860Z,1534119491.860 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-08-13T00:18:52.277Z,1534119532.277 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-13T00:18:52.277Z,1534119532.277 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2018-08-13T00:18:52.278Z,1534119532.278 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-08-13T00:18:52.280Z,1534119532.280 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-13T00:18:52.280Z,1534119532.280 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-08-13T00:18:52.280Z,1534119532.280 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-13T00:18:52.317Z,1534119532.317 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-08-13T00:18:52.317Z,1534119532.317 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-13T00:18:52.703Z,1534119532.703 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-08-13T00:18:52.703Z,1534119532.703 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2018-08-13T00:18:52.703Z,1534119532.703 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-13T00:18:52.703Z,1534119532.703 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-08-13T00:18:53.074Z,1534119533.074 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-08-13T00:18:53.075Z,1534119533.075 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-08-13T00:18:53.075Z,1534119533.075 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-08-13T00:18:53.076Z,1534119533.076 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-13T00:18:53.076Z,1534119533.076 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-13T00:18:53.076Z,1534119533.076 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-13T00:18:53.469Z,1534119533.469 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-08-13T00:18:53.885Z,1534119533.885 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2018-08-13T00:18:54.269Z,1534119534.269 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2018-08-13T00:18:54.671Z,1534119534.671 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2018-08-13T00:18:55.072Z,1534119535.072 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2018-08-13T00:18:55.462Z,1534119535.462 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2018-08-13T00:18:55.866Z,1534119535.866 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2018-08-13T00:18:56.256Z,1534119536.256 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2018-08-13T00:18:56.681Z,1534119536.681 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-08-13T00:18:57.069Z,1534119537.069 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2018-08-13T00:18:57.470Z,1534119537.470 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2018-08-13T00:18:57.864Z,1534119537.864 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2018-08-13T00:18:58.261Z,1534119538.261 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2018-08-13T00:18:58.671Z,1534119538.671 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2018-08-13T00:18:59.057Z,1534119539.057 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2018-08-13T00:18:59.482Z,1534119539.482 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2018-08-13T00:18:59.849Z,1534119539.849 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2018-08-13T00:19:00.273Z,1534119540.273 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2018-08-13T00:19:00.682Z,1534119540.682 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2018-08-13T00:19:01.076Z,1534119541.076 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2018-08-13T00:19:01.485Z,1534119541.485 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2018-08-13T00:19:01.861Z,1534119541.861 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2018-08-13T00:19:02.260Z,1534119542.260 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2018-08-13T00:19:02.692Z,1534119542.692 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2018-08-13T00:19:03.065Z,1534119543.065 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2018-08-13T00:19:03.457Z,1534119543.457 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2018-08-13T00:19:03.893Z,1534119543.893 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2018-08-13T00:19:04.265Z,1534119544.265 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2018-08-13T00:19:04.681Z,1534119544.681 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2018-08-13T00:19:05.088Z,1534119545.088 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2018-08-13T00:19:05.469Z,1534119545.469 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2018-08-13T00:19:05.894Z,1534119545.894 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2018-08-13T00:19:06.263Z,1534119546.263 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2018-08-13T00:19:06.679Z,1534119546.679 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2018-08-13T00:19:07.057Z,1534119547.057 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2018-08-13T00:19:07.477Z,1534119547.477 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2018-08-13T00:19:07.857Z,1534119547.857 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2018-08-13T00:19:08.260Z,1534119548.260 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2018-08-13T00:19:08.683Z,1534119548.683 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2018-08-13T00:19:09.057Z,1534119549.057 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2018-08-13T00:19:09.471Z,1534119549.471 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2018-08-13T00:19:09.865Z,1534119549.865 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2018-08-13T00:19:10.263Z,1534119550.263 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2018-08-13T00:19:10.682Z,1534119550.682 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2018-08-13T00:19:11.072Z,1534119551.072 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2018-08-13T00:19:11.490Z,1534119551.490 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2018-08-13T00:19:11.497Z,1534119551.497 [Startup:StartupSatComms:B](INFO): Timed out from 2018-08-13T00:18:11.5Z
2018-08-13T00:19:11.498Z,1534119551.498 [Startup:StartupSatComms:B] Stopped
2018-08-13T00:19:11.498Z,1534119551.498 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-08-13T00:19:11.498Z,1534119551.498 [Startup:StartupSatComms] Stopped
2018-08-13T00:19:11.498Z,1534119551.498 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-08-13T00:19:11.503Z,1534119551.503 [Startup](INFO): Completed Startup
2018-08-13T00:19:11.503Z,1534119551.503 [MissionManager](INFO): Startup is completed.
2018-08-13T00:19:11.504Z,1534119551.504 [MissionManager](INFO): Uninitializing Mission Startup
2018-08-13T00:19:11.504Z,1534119551.504 [Startup] Stopped
2018-08-13T00:19:11.504Z,1534119551.504 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-08-13T00:19:11.504Z,1534119551.504 [Startup:A.GoToSurface] Stopped
2018-08-13T00:19:11.504Z,1534119551.504 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-08-13T00:19:11.875Z,1534119551.875 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2018-08-13T00:19:11.878Z,1534119551.878 [MissionManager](IMPORTANT): Started mission Default
2018-08-13T00:19:11.878Z,1534119551.878 [Default] Running Loop=1
2018-08-13T00:19:11.879Z,1534119551.879 [Default](DEBUG): Aggregate::initialize Default
2018-08-13T00:19:11.879Z,1534119551.879 [Default:B.GoToSurface] Running Loop=1
2018-08-13T00:19:11.879Z,1534119551.879 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-08-13T00:19:11.879Z,1534119551.879 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-08-13T00:19:11.879Z,1534119551.879 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-08-13T00:19:11.880Z,1534119551.880 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-08-13T00:19:11.880Z,1534119551.880 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-08-13T00:19:11.880Z,1534119551.880 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-08-13T00:19:11.881Z,1534119551.881 [Default:A.Wait] Running Loop=1
2018-08-13T00:19:11.881Z,1534119551.881 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-08-13T00:19:12.249Z,1534119552.249 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 20.0 s old, using for 20.0 s.
2018-08-13T00:19:18.812Z,1534119558.812 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004382
2018-08-13T00:19:25.268Z,1534119565.268 [Default:A.Wait](INFO): Done Waiting.
2018-08-13T00:19:25.268Z,1534119565.268 [Default:A.Wait] Stopped
2018-08-13T00:19:25.268Z,1534119565.268 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-08-13T00:19:25.657Z,1534119565.657 [Default:CheckIn] Running Loop=1
2018-08-13T00:19:25.657Z,1534119565.657 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-08-13T00:19:25.657Z,1534119565.657 [Default:CheckIn:Read_GPS] Running Loop=1
2018-08-13T00:19:26.050Z,1534119566.050 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-08-13T00:21:34.292Z,1534119694.292 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-08-13T00:21:53.137Z,1534119713.137 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-13T00:21:53.137Z,1534119713.137 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2018-08-13T00:21:53.138Z,1534119713.138 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-08-13T00:21:53.140Z,1534119713.140 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-13T00:21:53.140Z,1534119713.140 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-08-13T00:21:53.140Z,1534119713.140 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-13T00:21:53.165Z,1534119713.165 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-08-13T00:21:53.166Z,1534119713.166 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-13T00:21:53.598Z,1534119713.598 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-08-13T00:21:53.598Z,1534119713.598 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2018-08-13T00:21:53.598Z,1534119713.598 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-13T00:21:53.598Z,1534119713.598 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-08-13T00:21:53.949Z,1534119713.949 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-08-13T00:21:53.950Z,1534119713.950 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-08-13T00:21:53.954Z,1534119713.954 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-08-13T00:21:53.955Z,1534119713.955 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-13T00:21:53.955Z,1534119713.955 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-13T00:21:53.956Z,1534119713.956 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-13T00:21:54.333Z,1534119714.333 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2018-08-13T00:21:57.542Z,1534119717.542 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2018-08-13T00:21:57.649Z,1534119717.649 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.5 s old, using for 20.0 s.
2018-08-13T00:21:58.032Z,1534119718.032 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.9 s old, using for 20.0 s.
2018-08-13T00:21:58.445Z,1534119718.445 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.3 s old, using for 20.0 s.
2018-08-13T00:21:58.830Z,1534119718.830 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.7 s old, using for 20.0 s.
2018-08-13T00:21:59.212Z,1534119719.212 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.1 s old, using for 20.0 s.
2018-08-13T00:21:59.619Z,1534119719.619 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.5 s old, using for 20.0 s.
2018-08-13T00:22:00.009Z,1534119720.009 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s.
2018-08-13T00:22:00.467Z,1534119720.467 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.3 s old, using for 20.0 s.
2018-08-13T00:22:00.821Z,1534119720.821 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.7 s old, using for 20.0 s.
2018-08-13T00:22:01.219Z,1534119721.219 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.1 s old, using for 20.0 s.
2018-08-13T00:22:01.634Z,1534119721.634 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.5 s old, using for 20.0 s.
2018-08-13T00:22:02.037Z,1534119722.037 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.9 s old, using for 20.0 s.
2018-08-13T00:22:02.416Z,1534119722.416 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.3 s old, using for 20.0 s.
2018-08-13T00:22:02.828Z,1534119722.828 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.7 s old, using for 20.0 s.
2018-08-13T00:22:03.204Z,1534119723.204 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.1 s old, using for 20.0 s.
2018-08-13T00:22:03.631Z,1534119723.631 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.5 s old, using for 20.0 s.
2018-08-13T00:22:04.029Z,1534119724.029 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.9 s old, using for 20.0 s.
2018-08-13T00:22:04.429Z,1534119724.429 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.3 s old, using for 20.0 s.
2018-08-13T00:22:04.837Z,1534119724.837 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.7 s old, using for 20.0 s.
2018-08-13T00:22:05.212Z,1534119725.212 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.1 s old, using for 20.0 s.
2018-08-13T00:22:05.610Z,1534119725.610 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.5 s old, using for 20.0 s.
2018-08-13T00:22:06.021Z,1534119726.021 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.9 s old, using for 20.0 s.
2018-08-13T00:22:06.429Z,1534119726.429 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.3 s old, using for 20.0 s.
2018-08-13T00:22:06.820Z,1534119726.820 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.7 s old, using for 20.0 s.
2018-08-13T00:22:07.213Z,1534119727.213 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.1 s old, using for 20.0 s.
2018-08-13T00:22:07.639Z,1534119727.639 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.5 s old, using for 20.0 s.
2018-08-13T00:22:08.025Z,1534119728.025 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.9 s old, using for 20.0 s.
2018-08-13T00:22:08.440Z,1534119728.440 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.3 s old, using for 20.0 s.
2018-08-13T00:22:08.801Z,1534119728.801 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.7 s old, using for 20.0 s.
2018-08-13T00:22:09.205Z,1534119729.205 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.1 s old, using for 20.0 s.
2018-08-13T00:22:09.632Z,1534119729.632 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.5 s old, using for 20.0 s.
2018-08-13T00:22:10.014Z,1534119730.014 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.9 s old, using for 20.0 s.
2018-08-13T00:22:10.450Z,1534119730.450 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.3 s old, using for 20.0 s.
2018-08-13T00:22:10.816Z,1534119730.816 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.7 s old, using for 20.0 s.
2018-08-13T00:22:11.209Z,1534119731.209 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.1 s old, using for 20.0 s.
2018-08-13T00:22:11.617Z,1534119731.617 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.5 s old, using for 20.0 s.
2018-08-13T00:22:12.017Z,1534119732.017 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.9 s old, using for 20.0 s.
2018-08-13T00:22:12.414Z,1534119732.414 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.3 s old, using for 20.0 s.
2018-08-13T00:22:12.831Z,1534119732.831 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.7 s old, using for 20.0 s.
2018-08-13T00:24:25.769Z,1534119865.769 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-08-13T00:19:25.7Z
2018-08-13T00:24:25.769Z,1534119865.769 [Default:CheckIn:Read_GPS] Stopped
2018-08-13T00:24:25.769Z,1534119865.769 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-08-13T00:24:26.175Z,1534119866.175 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-08-13T00:24:30.600Z,1534119870.600 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180810T233543/Courier0859.lzma
2018-08-13T00:24:31.411Z,1534119871.411 [DataOverHttps](INFO): Moved sent file to Logs/20180810T233543/Courier0859.lzma.bak
2018-08-13T00:24:31.411Z,1534119871.411 [DataOverHttps](INFO): SBD MOMSN=8416035
2018-08-13T00:24:51.749Z,1534119891.749 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20180813T001532/Courier0000.lzma
2018-08-13T00:24:52.545Z,1534119892.545 [DataOverHttps](INFO): Moved sent file to Logs/20180813T001532/Courier0000.lzma.bak
2018-08-13T00:24:52.545Z,1534119892.545 [DataOverHttps](INFO): SBD MOMSN=8416037
2018-08-13T00:24:56.610Z,1534119896.610 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-13T00:24:56.610Z,1534119896.610 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2018-08-13T00:24:56.610Z,1534119896.610 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2018-08-13T00:24:56.612Z,1534119896.612 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-08-13T00:24:56.612Z,1534119896.612 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-08-13T00:24:56.612Z,1534119896.612 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-08-13T00:24:56.625Z,1534119896.625 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2018-08-13T00:24:56.625Z,1534119896.625 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-08-13T00:24:56.769Z,1534119896.769 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2018-08-13T00:24:56.769Z,1534119896.769 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2018-08-13T00:24:56.769Z,1534119896.769 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-08-13T00:24:56.769Z,1534119896.769 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-08-13T00:24:57.136Z,1534119897.136 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2018-08-13T00:24:57.136Z,1534119897.136 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2018-08-13T00:24:57.137Z,1534119897.137 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2018-08-13T00:24:57.137Z,1534119897.137 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-08-13T00:24:57.138Z,1534119897.138 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-08-13T00:24:57.138Z,1534119897.138 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-08-13T00:24:57.528Z,1534119897.528 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.9 s old, using for 20.0 s.
2018-08-13T00:24:57.937Z,1534119897.937 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.3 s old, using for 20.0 s.
2018-08-13T00:24:58.347Z,1534119898.347 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.7 s old, using for 20.0 s.
2018-08-13T00:24:58.751Z,1534119898.751 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.1 s old, using for 20.0 s.
2018-08-13T00:25:01.925Z,1534119901.925 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.3 s old, using for 20.0 s.
2018-08-13T00:25:02.043Z,1534119902.043 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.4 s old, using for 20.0 s.
2018-08-13T00:25:02.401Z,1534119902.401 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20180813T001532/Courier0004.lzma
2018-08-13T00:25:02.488Z,1534119902.488 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.9 s old, using for 20.0 s.
2018-08-13T00:25:02.821Z,1534119902.821 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.2 s old, using for 20.0 s.
2018-08-13T00:25:03.180Z,1534119903.180 [DataOverHttps](INFO): Moved sent file to Logs/20180813T001532/Courier0004.lzma.bak
2018-08-13T00:25:03.180Z,1534119903.180 [DataOverHttps](INFO): SBD MOMSN=8416042
2018-08-13T00:25:03.240Z,1534119903.240 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.6 s old, using for 20.0 s.
2018-08-13T00:25:03.970Z,1534119903.970 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.4 s old, using for 20.0 s.
2018-08-13T00:25:04.088Z,1534119904.088 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.5 s old, using for 20.0 s.
2018-08-13T00:25:07.252Z,1534119907.252 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.6 s old, using for 20.0 s.
2018-08-13T00:25:07.378Z,1534119907.378 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2018-08-13T00:25:07.765Z,1534119907.765 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2018-08-13T00:25:08.152Z,1534119908.152 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.5 s old, using for 20.0 s.
2018-08-13T00:25:08.580Z,1534119908.580 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2018-08-13T00:25:08.961Z,1534119908.961 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2018-08-13T00:25:09.361Z,1534119909.361 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2018-08-13T00:25:12.543Z,1534119912.543 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.9 s old, using for 20.0 s.
2018-08-13T00:25:12.670Z,1534119912.670 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.1 s old, using for 20.0 s.
2018-08-13T00:25:12.980Z,1534119912.980 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20180810T233543/Express0860.lzma
2018-08-13T00:25:13.037Z,1534119913.037 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2018-08-13T00:25:13.417Z,1534119913.417 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2018-08-13T00:25:13.776Z,1534119913.776 [DataOverHttps](INFO): Moved sent file to Logs/20180810T233543/Express0860.lzma.bak
2018-08-13T00:25:13.776Z,1534119913.776 [DataOverHttps](INFO): SBD MOMSN=8416044
2018-08-13T00:25:13.843Z,1534119913.843 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2018-08-13T00:25:14.560Z,1534119914.560 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2018-08-13T00:25:14.702Z,1534119914.702 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.1 s old, using for 20.0 s.
2018-08-13T00:25:15.062Z,1534119915.062 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.5 s old, using for 20.0 s.
2018-08-13T00:25:25.941Z,1534119925.941 [DataOverHttps](INFO): Sending 862 bytes from file Logs/20180813T001532/Express0001.lzma
2018-08-13T00:25:29.136Z,1534119929.136 [DataOverHttps](INFO): Moved sent file to Logs/20180813T001532/Express0001.lzma.bak
2018-08-13T00:25:29.137Z,1534119929.137 [DataOverHttps](INFO): SBD MOMSN=8416048
2018-08-13T00:25:42.154Z,1534119942.154 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20180813T001532/Express0005.lzma
2018-08-13T00:25:42.949Z,1534119942.949 [DataOverHttps](INFO): Moved sent file to Logs/20180813T001532/Express0005.lzma.bak
2018-08-13T00:25:42.949Z,1534119942.949 [DataOverHttps](INFO): SBD MOMSN=8416072
2018-08-13T00:25:43.928Z,1534119943.928 [Default:CheckIn:Read_Iridium] Stopped
2018-08-13T00:25:43.928Z,1534119943.928 [Default:CheckIn:C.Wait] Running Loop=1
2018-08-13T00:25:43.929Z,1534119943.929 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-08-13T00:26:23.692Z,1534119983.692 [CommandLine](IMPORTANT): got command restart application
2018-08-13T00:26:24.702Z,1534119984.702 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-13T00:26:24.702Z,1534119984.702 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-08-13T00:26:24.751Z,1534119984.751 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-08-13T00:26:24.751Z,1534119984.751 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-08-13T00:26:24.752Z,1534119984.752 [CommandLine](INFO): Join timeout helper Thread ID is 5710
2018-08-13T00:26:24.763Z,1534119984.763 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-08-13T00:26:24.763Z,1534119984.763 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-08-13T00:26:24.763Z,1534119984.763 [NavChartDb](INFO): Join timeout helper Thread ID is 5711
2018-08-13T00:26:24.956Z,1534119984.956 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-13T00:26:24.956Z,1534119984.956 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-08-13T00:26:24.975Z,1534119984.975 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-08-13T00:26:24.975Z,1534119984.975 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-08-13T00:26:24.976Z,1534119984.976 [Radio_Surface](INFO): Join timeout helper Thread ID is 5712
2018-08-13T00:26:25.057Z,1534119985.057 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-13T00:26:25.057Z,1534119985.057 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-08-13T00:26:25.074Z,1534119985.074 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2018-08-13T00:26:25.074Z,1534119985.074 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-08-13T00:26:25.075Z,1534119985.075 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 5713
2018-08-13T00:26:25.451Z,1534119985.451 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-13T00:26:25.451Z,1534119985.451 [WetLabsSeaOWL_UV_A](INFO): Powering down
2018-08-13T00:26:25.452Z,1534119985.452 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-08-13T00:26:25.458Z,1534119985.458 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-08-13T00:26:25.458Z,1534119985.458 [logger ThreadHandler](INFO): Thread cancelled.
2018-08-13T00:26:25.459Z,1534119985.459 [logger](INFO): Join timeout helper Thread ID is 5714
2018-08-13T00:26:25.467Z,1534119985.467 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-13T00:26:25.467Z,1534119985.467 [logger ThreadHandler](INFO): Thread cancelled.
2018-08-13T00:26:25.479Z,1534119985.479 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-08-13T00:26:25.479Z,1534119985.479 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-08-13T00:26:25.479Z,1534119985.479 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-08-13T00:26:25.480Z,1534119985.480 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-08-13T00:26:25.480Z,1534119985.480 [controlThread](INFO): Join timeout helper Thread ID is 5715
2018-08-13T00:26:25.538Z,1534119985.538 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-08-13T00:26:25.539Z,1534119985.539 [controlThread](DEBUG): Uninitializing ControlThread
2018-08-13T00:26:25.539Z,1534119985.539 [Aanderaa_O2](INFO): Powering down
2018-08-13T00:26:25.540Z,1534119985.540 [AHRS_M2](INFO): Powering down
2018-08-13T00:26:25.611Z,1534119985.611 [NAL9602](INFO): Powering down
2018-08-13T00:26:25.682Z,1534119985.682 [RDI_Pathfinder](INFO): Powering down
2018-08-13T00:26:25.684Z,1534119985.684 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-08-13T00:26:25.685Z,1534119985.685 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-08-13T00:26:25.686Z,1534119985.686 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-08-13T00:26:25.687Z,1534119985.687 [MissionManager](INFO): Uninitializing Mission Default
2018-08-13T00:26:25.687Z,1534119985.687 [Default] Stopped
2018-08-13T00:26:25.687Z,1534119985.687 [Default](DEBUG): Aggregate::uninitialize Default
2018-08-13T00:26:25.687Z,1534119985.687 [Default:B.GoToSurface] Stopped
2018-08-13T00:26:25.687Z,1534119985.687 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-08-13T00:26:25.687Z,1534119985.687 [Default:CheckIn] Stopped
2018-08-13T00:26:25.687Z,1534119985.687 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-08-13T00:26:25.687Z,1534119985.687 [Default:CheckIn:C.Wait] Stopped
2018-08-13T00:26:25.687Z,1534119985.687 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-08-13T00:26:25.691Z,1534119985.691 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-08-13T00:26:25.691Z,1534119985.691 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-08-13T00:26:25.691Z,1534119985.691 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-08-13T00:26:25.692Z,1534119985.692 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-08-13T00:26:25.692Z,1534119985.692 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-08-13T00:26:25.692Z,1534119985.692 [BuoyancyServo](INFO): Powering down
2018-08-13T00:26:25.706Z,1534119985.706 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-08-13T00:26:25.707Z,1534119985.707 [ElevatorServo](INFO): Powering down
2018-08-13T00:26:25.707Z,1534119985.707 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-08-13T00:26:25.708Z,1534119985.708 [MassServo](INFO): Powering down
2018-08-13T00:26:25.708Z,1534119985.708 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-08-13T00:26:25.709Z,1534119985.709 [RudderServo](INFO): Powering down
2018-08-13T00:26:25.709Z,1534119985.709 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-08-13T00:26:25.710Z,1534119985.710 [ThrusterServo](INFO): Powering down
2018-08-13T00:26:25.711Z,1534119985.711 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-08-13T00:26:25.711Z,1534119985.711 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-08-13T00:26:25.711Z,1534119985.711 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-08-13T00:26:25.712Z,1534119985.712 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-08-13T00:26:25.775Z,1534119985.775 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2018-08-13T00:26:25.837Z,1534119985.837 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-08-13T00:26:25.885Z,1534119985.885 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-08-13T00:26:25.943Z,1534119985.943 [logger ThreadHandler](INFO): Thread cancelled.