2019-09-06T22:05:55.778Z,1567807555.778 [Supervisor](DEBUG): Initializing supervisor.
2019-09-06T22:05:55.781Z,1567807555.781 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-09-06T22:05:55.781Z,1567807555.781 [SyncHandler](INFO): Protected caller Thread ID is 2964
2019-09-06T22:05:55.782Z,1567807555.782 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-09-06T22:05:55.783Z,1567807555.783 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-09-06T22:05:55.783Z,1567807555.783 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2965
2019-09-06T22:05:55.786Z,1567807555.786 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-09-06T22:05:55.797Z,1567807555.797 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-09-06T22:05:55.798Z,1567807555.798 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-09-06T22:05:55.799Z,1567807555.799 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2966
2019-09-06T22:05:55.799Z,1567807555.799 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-09-06T22:05:55.800Z,1567807555.800 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-09-06T22:05:55.801Z,1567807555.801 [logger ThreadHandler](INFO): Protected caller Thread ID is 2967
2019-09-06T22:05:55.803Z,1567807555.803 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-09-06T22:05:55.803Z,1567807555.803 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-09-06T22:05:55.805Z,1567807555.805 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-09-06T22:05:55.898Z,1567807555.898 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-09-06T22:05:55.898Z,1567807555.898 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-09-06T22:05:56.447Z,1567807556.447 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-09-06T22:05:56.448Z,1567807556.448 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-09-06T22:05:56.545Z,1567807556.545 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-09-06T22:05:56.546Z,1567807556.546 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-09-06T22:05:56.645Z,1567807556.645 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-09-06T22:05:56.646Z,1567807556.646 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-09-06T22:05:56.725Z,1567807556.725 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-09-06T22:05:56.862Z,1567807556.862 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-09-06T22:05:56.863Z,1567807556.863 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-09-06T22:05:57.157Z,1567807557.157 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-09-06T22:05:57.158Z,1567807557.158 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-09-06T22:05:57.689Z,1567807557.689 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-09-06T22:05:57.690Z,1567807557.690 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-09-06T22:05:57.833Z,1567807557.833 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-09-06T22:05:57.834Z,1567807557.834 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-09-06T22:05:58.033Z,1567807558.033 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-09-06T22:05:58.034Z,1567807558.034 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-09-06T22:05:58.485Z,1567807558.485 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-09-06T22:05:58.486Z,1567807558.486 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-09-06T22:05:58.698Z,1567807558.698 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-09-06T22:05:58.699Z,1567807558.699 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-09-06T22:05:58.898Z,1567807558.898 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-09-06T22:05:58.898Z,1567807558.898 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-09-06T22:05:59.288Z,1567807559.288 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-09-06T22:05:59.288Z,1567807559.288 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-09-06T22:05:59.620Z,1567807559.620 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-09-06T22:05:59.622Z,1567807559.622 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2019-09-06T22:05:59.623Z,1567807559.623 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2019-09-06T22:05:59.703Z,1567807559.703 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2019-09-06T22:05:59.858Z,1567807559.858 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2019-09-06T22:05:59.965Z,1567807559.965 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2019-09-06T22:06:00.049Z,1567807560.049 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2019-09-06T22:06:00.141Z,1567807560.141 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2019-09-06T22:06:00.334Z,1567807560.334 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2019-09-06T22:06:00.639Z,1567807560.639 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-09-06T22:06:00.640Z,1567807560.640 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2019-09-06T22:06:00.779Z,1567807560.779 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2019-09-06T22:06:00.904Z,1567807560.904 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2019-09-06T22:06:01.173Z,1567807561.173 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2019-09-06T22:06:01.473Z,1567807561.473 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2019-09-06T22:06:01.474Z,1567807561.474 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-09-06T22:06:01.478Z,1567807561.478 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-09-06T22:06:01.546Z,1567807561.546 [VerticalControl](DEBUG): Construct VerticalControl.
2019-09-06T22:06:01.658Z,1567807561.658 [VerticalControl] Loaded
2019-09-06T22:06:01.659Z,1567807561.659 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-09-06T22:06:01.659Z,1567807561.659 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-09-06T22:06:01.728Z,1567807561.728 [HorizontalControl] Loaded
2019-09-06T22:06:01.728Z,1567807561.728 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-09-06T22:06:01.729Z,1567807561.729 [SpeedControl](DEBUG): Construct SpeedControl.
2019-09-06T22:06:01.735Z,1567807561.735 [SpeedControl] Loaded
2019-09-06T22:06:01.735Z,1567807561.735 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-09-06T22:06:01.736Z,1567807561.736 [LoopControl](DEBUG): Construct LoopControl.
2019-09-06T22:06:01.736Z,1567807561.736 [LoopControl] Loaded
2019-09-06T22:06:01.736Z,1567807561.736 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-09-06T22:06:01.737Z,1567807561.737 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-09-06T22:06:01.737Z,1567807561.737 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-09-06T22:06:01.779Z,1567807561.779 [DepthRateCalculator] Loaded
2019-09-06T22:06:01.779Z,1567807561.779 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-09-06T22:06:01.784Z,1567807561.784 [PitchRateCalculator] Loaded
2019-09-06T22:06:01.784Z,1567807561.784 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-09-06T22:06:01.800Z,1567807561.800 [SpeedCalculator] Loaded
2019-09-06T22:06:01.800Z,1567807561.800 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-09-06T22:06:01.821Z,1567807561.821 [TempGradientCalculator] Loaded
2019-09-06T22:06:01.821Z,1567807561.821 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-09-06T22:06:01.826Z,1567807561.826 [YawRateCalculator] Loaded
2019-09-06T22:06:01.826Z,1567807561.826 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-09-06T22:06:01.855Z,1567807561.855 [ElevatorOffsetCalculator] Loaded
2019-09-06T22:06:01.856Z,1567807561.856 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-09-06T22:06:01.856Z,1567807561.856 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-09-06T22:06:01.857Z,1567807561.857 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-09-06T22:06:01.889Z,1567807561.889 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-09-06T22:06:01.889Z,1567807561.889 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-09-06T22:06:01.960Z,1567807561.960 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-09-06T22:06:01.960Z,1567807561.960 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-09-06T22:06:02.250Z,1567807562.250 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-09-06T22:06:02.251Z,1567807562.251 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-09-06T22:06:02.349Z,1567807562.349 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-09-06T22:06:02.350Z,1567807562.350 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-09-06T22:06:02.695Z,1567807562.695 [AHRS_M2] Loaded
2019-09-06T22:06:02.695Z,1567807562.695 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-09-06T22:06:02.778Z,1567807562.778 [DataOverHttps] Loaded
2019-09-06T22:06:02.778Z,1567807562.778 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-09-06T22:06:02.779Z,1567807562.779 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408BB4E0
2019-09-06T22:06:02.779Z,1567807562.779 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3047
2019-09-06T22:06:02.793Z,1567807562.793 [Depth_Keller] Loaded
2019-09-06T22:06:02.793Z,1567807562.793 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-09-06T22:06:02.798Z,1567807562.798 [DropWeight] Loaded
2019-09-06T22:06:02.798Z,1567807562.798 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-09-06T22:06:02.896Z,1567807562.896 [NAL9602] Loaded
2019-09-06T22:06:02.896Z,1567807562.896 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-09-06T22:06:02.912Z,1567807562.912 [Onboard] Loaded
2019-09-06T22:06:02.912Z,1567807562.912 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-09-06T22:06:02.916Z,1567807562.916 [Radio_Surface] Loaded
2019-09-06T22:06:02.916Z,1567807562.916 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-09-06T22:06:02.917Z,1567807562.917 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408EB4E0
2019-09-06T22:06:02.917Z,1567807562.917 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3048
2019-09-06T22:06:02.963Z,1567807562.963 [Rowe_600] Loaded
2019-09-06T22:06:02.963Z,1567807562.963 [ComponentRegistry](DEBUG): SyncComponent "Rowe_600" handled in the control thread.
2019-09-06T22:06:04.459Z,1567807564.459 [BPC1] Loaded
2019-09-06T22:06:04.459Z,1567807564.459 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-09-06T22:06:04.459Z,1567807564.459 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-09-06T22:06:04.460Z,1567807564.460 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-09-06T22:06:04.563Z,1567807564.563 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-09-06T22:06:04.563Z,1567807564.563 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-09-06T22:06:04.584Z,1567807564.584 [NavChart] Loaded
2019-09-06T22:06:04.584Z,1567807564.584 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-09-06T22:06:04.588Z,1567807564.588 [UniversalFixResidualReporter] Loaded
2019-09-06T22:06:04.588Z,1567807564.588 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-09-06T22:06:04.589Z,1567807564.589 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-09-06T22:06:04.589Z,1567807564.589 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-09-06T22:06:04.721Z,1567807564.721 [SBIT](DEBUG): Construct Startup Built In Test.
2019-09-06T22:06:04.732Z,1567807564.732 [SBIT] Loaded
2019-09-06T22:06:04.733Z,1567807564.733 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-09-06T22:06:04.733Z,1567807564.733 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-09-06T22:06:04.745Z,1567807564.745 [IBIT] Loaded
2019-09-06T22:06:04.745Z,1567807564.745 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-09-06T22:06:04.748Z,1567807564.748 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-09-06T22:06:04.887Z,1567807564.887 [CBIT] Loaded
2019-09-06T22:06:04.887Z,1567807564.887 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-09-06T22:06:04.888Z,1567807564.888 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-09-06T22:06:04.888Z,1567807564.888 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-09-06T22:06:04.990Z,1567807564.990 [BuoyancyServo] Loaded
2019-09-06T22:06:04.990Z,1567807564.990 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-09-06T22:06:05.001Z,1567807565.001 [ElevatorServo] Loaded
2019-09-06T22:06:05.002Z,1567807565.002 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-09-06T22:06:05.013Z,1567807565.013 [MassServo] Loaded
2019-09-06T22:06:05.013Z,1567807565.013 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-09-06T22:06:05.024Z,1567807565.024 [RudderServo] Loaded
2019-09-06T22:06:05.024Z,1567807565.024 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-09-06T22:06:05.035Z,1567807565.035 [ThrusterServo] Loaded
2019-09-06T22:06:05.035Z,1567807565.035 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-09-06T22:06:05.036Z,1567807565.036 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-09-06T22:06:05.036Z,1567807565.036 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-09-06T22:06:05.049Z,1567807565.049 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-09-06T22:06:05.050Z,1567807565.050 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-09-06T22:06:05.224Z,1567807565.224 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-09-06T22:06:05.230Z,1567807565.230 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-09-06T22:06:05.231Z,1567807565.231 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-09-06T22:06:05.236Z,1567807565.236 [CTD_Seabird](INFO): created writer for : depth
2019-09-06T22:06:05.237Z,1567807565.237 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-09-06T22:06:05.242Z,1567807565.242 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-09-06T22:06:05.242Z,1567807565.242 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-09-06T22:06:05.247Z,1567807565.247 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-09-06T22:06:05.248Z,1567807565.248 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-09-06T22:06:05.253Z,1567807565.253 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-09-06T22:06:05.254Z,1567807565.254 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-09-06T22:06:05.259Z,1567807565.259 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-09-06T22:06:05.259Z,1567807565.259 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-09-06T22:06:05.265Z,1567807565.265 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-09-06T22:06:05.292Z,1567807565.292 [CTD_Seabird] Loaded
2019-09-06T22:06:05.292Z,1567807565.292 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-09-06T22:06:05.293Z,1567807565.293 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A6F4E0
2019-09-06T22:06:05.293Z,1567807565.293 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3049
2019-09-06T22:06:05.341Z,1567807565.341 [WetLabsBB2FL] Loaded
2019-09-06T22:06:05.342Z,1567807565.342 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-09-06T22:06:05.343Z,1567807565.343 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A9F4E0
2019-09-06T22:06:05.343Z,1567807565.343 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3050
2019-09-06T22:06:05.344Z,1567807565.344 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-09-06T22:06:05.348Z,1567807565.348 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-09-06T22:06:05.348Z,1567807565.348 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-09-06T22:06:05.355Z,1567807565.355 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-09-06T22:06:05.356Z,1567807565.356 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACF4E0
2019-09-06T22:06:05.356Z,1567807565.356 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3051
2019-09-06T22:06:05.361Z,1567807565.361 [Supervisor](INFO): Main Thread ID is 2963
2019-09-06T22:06:05.361Z,1567807565.361 [Supervisor](DEBUG): Running supervisor.
2019-09-06T22:06:05.362Z,1567807565.362 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3052
2019-09-06T22:06:05.364Z,1567807565.364 [controlThread ThreadHandler](INFO): Handler Thread ID is 3053
2019-09-06T22:06:05.365Z,1567807565.365 [controlThread](DEBUG): Initializing ControlThread
2019-09-06T22:06:05.365Z,1567807565.365 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-09-06T22:06:05.367Z,1567807565.367 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-09-06T22:06:05.368Z,1567807565.368 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-09-06T22:06:05.368Z,1567807565.368 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-09-06T22:06:05.369Z,1567807565.369 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-09-06T22:06:05.369Z,1567807565.369 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-09-06T22:06:05.369Z,1567807565.369 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-09-06T22:06:05.370Z,1567807565.370 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-09-06T22:06:05.370Z,1567807565.370 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-09-06T22:06:05.370Z,1567807565.370 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-09-06T22:06:05.375Z,1567807565.375 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-09-06T22:06:05.376Z,1567807565.376 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-09-06T22:06:05.376Z,1567807565.376 [SBIT](INFO): Initialize SBIT Component.
2019-09-06T22:06:05.377Z,1567807565.377 [SBIT](IMPORTANT): git: 2019-08-21a
2019-09-06T22:06:05.377Z,1567807565.377 [SBIT](INFO): git hash: 91726968a91bb65c09438a13bfa0101a916cbc64
2019-09-06T22:06:05.377Z,1567807565.377 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-09-06T22:06:05.377Z,1567807565.377 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-09-06T22:06:05.378Z,1567807565.378 [SBIT](INFO): Beginning SBIT in 59.000000 seconds.
2019-09-06T22:06:05.379Z,1567807565.379 [IBIT](INFO): Initialize IBIT Component.
2019-09-06T22:06:05.380Z,1567807565.380 [CBIT](DEBUG): Initialize CBIT Component.
2019-09-06T22:06:05.381Z,1567807565.381 [logger ThreadHandler](INFO): Handler Thread ID is 3054
2019-09-06T22:06:05.393Z,1567807565.393 [CBIT](DEBUG): Initialized mux pins.
2019-09-06T22:06:05.393Z,1567807565.393 [CBIT](DEBUG): Initializing the watchdog timer.
2019-09-06T22:06:05.401Z,1567807565.401 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3055
2019-09-06T22:06:05.402Z,1567807565.402 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-09-06T22:06:05.413Z,1567807565.413 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3056
2019-09-06T22:06:05.417Z,1567807565.417 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-09-06T22:06:05.417Z,1567807565.417 [CBIT](DEBUG): Initializing heartbeat.
2019-09-06T22:06:05.425Z,1567807565.425 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3057
2019-09-06T22:06:05.426Z,1567807565.426 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-09-06T22:06:05.429Z,1567807565.429 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-09-06T22:06:05.430Z,1567807565.430 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3059
2019-09-06T22:06:05.432Z,1567807565.432 [WetLabsBB2FL](INFO): Powering down
2019-09-06T22:06:05.461Z,1567807565.461 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3060
2019-09-06T22:06:05.464Z,1567807565.464 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-09-06T22:06:05.465Z,1567807565.465 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-09-06T22:06:05.465Z,1567807565.465 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-09-06T22:06:05.465Z,1567807565.465 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-09-06T22:06:05.465Z,1567807565.465 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-09-06T22:06:05.465Z,1567807565.465 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-09-06T22:06:05.465Z,1567807565.465 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-09-06T22:06:05.466Z,1567807565.466 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-09-06T22:06:05.466Z,1567807565.466 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-09-06T22:06:05.466Z,1567807565.466 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-09-06T22:06:05.466Z,1567807565.466 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-09-06T22:06:05.466Z,1567807565.466 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-09-06T22:06:05.467Z,1567807565.467 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-09-06T22:06:05.467Z,1567807565.467 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-09-06T22:06:05.467Z,1567807565.467 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-09-06T22:06:05.467Z,1567807565.467 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-09-06T22:06:05.489Z,1567807565.489 [CBIT](DEBUG): Deactivating GF circuits.
2019-09-06T22:06:05.489Z,1567807565.489 [CBIT](DEBUG): Deactivating emergency mode.
2019-09-06T22:06:05.525Z,1567807565.525 [CBIT](DEBUG): Backplane powered.
2019-09-06T22:06:05.527Z,1567807565.527 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-09-06T22:06:05.536Z,1567807565.536 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-09-06T22:06:05.547Z,1567807565.547 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2019-09-06T22:06:05.548Z,1567807565.548 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-09-06T22:06:05.711Z,1567807565.711 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-09-06T22:06:05.714Z,1567807565.714 [Default:A.Wait](DEBUG): Construct Wait.
2019-09-06T22:06:05.715Z,1567807565.715 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-09-06T22:06:05.758Z,1567807565.758 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-09-06T22:06:05.760Z,1567807565.760 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-09-06T22:06:05.782Z,1567807565.782 [Default:E.Execute](DEBUG): Construct Execute.
2019-09-06T22:06:05.801Z,1567807565.801 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2019-09-06T22:06:05.817Z,1567807565.817 [Radio_Surface](INFO): Powering up
2019-09-06T22:06:05.823Z,1567807565.823 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,Rowe_600,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-09-06T22:06:05.853Z,1567807565.853 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-09-06T22:06:05.914Z,1567807565.914 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2019-09-06T22:06:05.973Z,1567807565.973 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-09-06T22:06:05.985Z,1567807565.985 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-09-06T22:06:05.996Z,1567807565.996 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-09-06T22:06:05.997Z,1567807565.997 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-09-06T22:06:06.005Z,1567807566.005 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-09-06T22:06:06.006Z,1567807566.006 [MassServo](DEBUG): Initializing EZServoServo.
2019-09-06T22:06:06.013Z,1567807566.013 [MassServo](DEBUG): Initializing MassServo.
2019-09-06T22:06:06.014Z,1567807566.014 [RudderServo](DEBUG): Initializing EZServoServo.
2019-09-06T22:06:06.021Z,1567807566.021 [RudderServo](DEBUG): Initializing RudderServo.
2019-09-06T22:06:06.022Z,1567807566.022 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-09-06T22:06:06.029Z,1567807566.029 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-09-06T22:06:06.349Z,1567807566.349 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-09-06T22:06:06.659Z,1567807566.659 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:06:06.659Z,1567807566.659 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:06:07.013Z,1567807567.013 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-09-06T22:06:07.013Z,1567807567.013 [RudderServo](FAULT): Rudder failed to initialize
2019-09-06T22:06:07.013Z,1567807567.013 [RudderServo] Communications Fault, FailCount= 1
2019-09-06T22:06:07.013Z,1567807567.013 [RudderServo](ERROR): Communications Fault
2019-09-06T22:06:07.124Z,1567807567.124 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-09-06T22:06:07.318Z,1567807567.318 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-09-06T22:06:07.318Z,1567807567.318 [RudderServo](INFO): Powering down
2019-09-06T22:06:07.570Z,1567807567.570 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:06:07.570Z,1567807567.570 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T22:06:07.989Z,1567807567.989 [RudderServo](DEBUG): Initializing EZServoServo.
2019-09-06T22:06:08.110Z,1567807568.110 [RudderServo](DEBUG): Initializing RudderServo.
2019-09-06T22:06:08.114Z,1567807568.114 [CBIT](INFO): Clearing failed state for component RudderServo
2019-09-06T22:06:08.114Z,1567807568.114 [RudderServo] No Fault, FailCount= 1
2019-09-06T22:06:08.404Z,1567807568.404 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:06:08.404Z,1567807568.404 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:06:09.597Z,1567807569.597 [Rowe_600](ERROR): Failed to parse:
:RA,  0.00,   0.00,  11.49,   9.92,  10.22
2019-09-06T22:06:10.462Z,1567807570.462 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:06:10.463Z,1567807570.463 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:06:11.624Z,1567807571.624 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:06:11.625Z,1567807571.625 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T22:06:12.477Z,1567807572.477 [Rowe_600](INFO): Inst Velocity X:3.000000
2019-09-06T22:06:12.477Z,1567807572.477 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T22:06:13.321Z,1567807573.321 [Rowe_600](INFO): Inst Velocity X:-2.000000
2019-09-06T22:06:13.321Z,1567807573.321 [Rowe_600](INFO): Inst Velocity Y:-1.000000
2019-09-06T22:06:14.511Z,1567807574.511 [Rowe_600](INFO): Inst Velocity X:-1.000000
2019-09-06T22:06:14.512Z,1567807574.512 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:06:15.357Z,1567807575.357 [Rowe_600](INFO): Inst Velocity X:-1.000000
2019-09-06T22:06:15.357Z,1567807575.357 [Rowe_600](INFO): Inst Velocity Y:7.000000
2019-09-06T22:06:16.532Z,1567807576.532 [Rowe_600](INFO): Inst Velocity X:2.000000
2019-09-06T22:06:16.532Z,1567807576.532 [Rowe_600](INFO): Inst Velocity Y:9.000000
2019-09-06T22:06:17.302Z,1567807577.302 [Rowe_600](INFO): Inst Velocity X:1.000000
2019-09-06T22:06:17.302Z,1567807577.302 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T22:06:17.510Z,1567807577.510 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004453
2019-09-06T22:06:18.514Z,1567807578.514 [Rowe_600](INFO): Inst Velocity X:-0.000000
2019-09-06T22:06:18.514Z,1567807578.514 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:06:19.322Z,1567807579.322 [Rowe_600](INFO): Inst Velocity X:1.000000
2019-09-06T22:06:19.323Z,1567807579.323 [Rowe_600](INFO): Inst Velocity Y:7.000000
2019-09-06T22:06:20.534Z,1567807580.534 [Rowe_600](INFO): Inst Velocity X:2.000000
2019-09-06T22:06:20.534Z,1567807580.534 [Rowe_600](INFO): Inst Velocity Y:9.000000
2019-09-06T22:06:21.346Z,1567807581.346 [Rowe_600](INFO): Inst Velocity X:2.000000
2019-09-06T22:06:21.346Z,1567807581.346 [Rowe_600](INFO): Inst Velocity Y:7.000000
2019-09-06T22:06:22.558Z,1567807582.558 [Rowe_600](INFO): Inst Velocity X:2.000000
2019-09-06T22:06:22.558Z,1567807582.558 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T22:06:23.362Z,1567807583.362 [Rowe_600](INFO): Inst Velocity X:3.000000
2019-09-06T22:06:23.362Z,1567807583.362 [Rowe_600](INFO): Inst Velocity Y:7.000000
2019-09-06T22:06:24.574Z,1567807584.574 [Rowe_600](INFO): Inst Velocity X:3.000000
2019-09-06T22:06:24.574Z,1567807584.574 [Rowe_600](INFO): Inst Velocity Y:-1.000000
2019-09-06T22:06:25.382Z,1567807585.382 [Rowe_600](INFO): Inst Velocity X:2.000000
2019-09-06T22:06:25.383Z,1567807585.383 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:06:26.599Z,1567807586.599 [Rowe_600](INFO): Inst Velocity X:2.000000
2019-09-06T22:06:26.599Z,1567807586.599 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T22:06:27.407Z,1567807587.407 [Rowe_600](INFO): Inst Velocity X:3.000000
2019-09-06T22:06:27.407Z,1567807587.407 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:06:28.619Z,1567807588.619 [Rowe_600](INFO): Inst Velocity X:2.000000
2019-09-06T22:06:28.619Z,1567807588.619 [Rowe_600](INFO): Inst Velocity Y:6.000000
2019-09-06T22:06:29.426Z,1567807589.426 [Rowe_600](INFO): Inst Velocity X:3.000000
2019-09-06T22:06:29.426Z,1567807589.426 [Rowe_600](INFO): Inst Velocity Y:9.000000
2019-09-06T22:06:30.639Z,1567807590.639 [Rowe_600](INFO): Inst Velocity X:1.000000
2019-09-06T22:06:30.640Z,1567807590.640 [Rowe_600](INFO): Inst Velocity Y:8.000000
2019-09-06T22:06:31.459Z,1567807591.459 [Rowe_600](INFO): Inst Velocity X:-2.000000
2019-09-06T22:06:31.459Z,1567807591.459 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:06:32.282Z,1567807592.282 [Rowe_600](INFO): Inst Velocity X:-1.000000
2019-09-06T22:06:32.283Z,1567807592.283 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:06:32.310Z,1567807592.310 [Rowe_600](ERROR): only read 0 of 1 data item for altitude
2019-09-06T22:06:32.310Z,1567807592.310 [Rowe_600](ERROR): Failed to parse:
:BD+1.16,       +1.83,       -2.68,  10.55,  1.00
2019-09-06T22:06:33.044Z,1567807593.044 [NAL9602](INFO): Powering up NAL9602
2019-09-06T22:06:33.474Z,1567807593.474 [Rowe_600](INFO): Inst Velocity X:-0.000000
2019-09-06T22:06:33.474Z,1567807593.474 [Rowe_600](INFO): Inst Velocity Y:-2.000000
2019-09-06T22:06:34.300Z,1567807594.300 [Rowe_600](INFO): Inst Velocity X:6.000000
2019-09-06T22:06:34.300Z,1567807594.300 [Rowe_600](INFO): Inst Velocity Y:-1.000000
2019-09-06T22:06:35.482Z,1567807595.482 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:06:35.482Z,1567807595.482 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:06:36.294Z,1567807596.294 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:06:36.294Z,1567807596.294 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:06:37.502Z,1567807597.502 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:06:37.502Z,1567807597.502 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:06:38.313Z,1567807598.313 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:06:38.314Z,1567807598.314 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:06:39.526Z,1567807599.526 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:06:39.526Z,1567807599.526 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:06:40.338Z,1567807600.338 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:06:40.338Z,1567807600.338 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T22:06:41.542Z,1567807601.542 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:06:41.542Z,1567807601.542 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:06:42.354Z,1567807602.354 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:06:42.354Z,1567807602.354 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:06:43.573Z,1567807603.573 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:06:43.573Z,1567807603.573 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:06:43.947Z,1567807603.947 [NAL9602](INFO): NAL9602 initialized
2019-09-06T22:06:44.404Z,1567807604.404 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:06:44.405Z,1567807604.405 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:06:44.769Z,1567807604.769 [NAL9602](DEBUG): Fix Requested
2019-09-06T22:06:45.609Z,1567807605.609 [Rowe_600](INFO): Inst Velocity X:11.000000
2019-09-06T22:06:45.609Z,1567807605.609 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T22:06:46.391Z,1567807606.391 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:06:46.391Z,1567807606.391 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:06:47.630Z,1567807607.630 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:06:47.630Z,1567807607.630 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:06:48.421Z,1567807608.421 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:06:48.422Z,1567807608.422 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:06:49.627Z,1567807609.627 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:06:49.627Z,1567807609.627 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:06:50.434Z,1567807610.434 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:06:50.434Z,1567807610.434 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:06:51.671Z,1567807611.671 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:06:51.671Z,1567807611.671 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:06:52.465Z,1567807612.465 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:06:52.465Z,1567807612.465 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T22:06:54.480Z,1567807614.480 [Rowe_600](INFO): Inst Velocity X:-0.000000
2019-09-06T22:06:54.480Z,1567807614.480 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T22:06:54.480Z,1567807614.480 [Rowe_600](ERROR): only read 0 of 4 data items
2019-09-06T22:06:54.481Z,1567807614.481 [Rowe_600](ERROR): Failed to parse:
:BI,    -,    -2,-32768,A
2019-09-06T22:06:55.290Z,1567807615.290 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:06:55.290Z,1567807615.290 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:06:56.494Z,1567807616.494 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:06:56.494Z,1567807616.494 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:06:57.309Z,1567807617.309 [Rowe_600](INFO): Inst Velocity X:11.000000
2019-09-06T22:06:57.309Z,1567807617.309 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:06:58.510Z,1567807618.510 [Rowe_600](INFO): Inst Velocity X:11.000000
2019-09-06T22:06:58.510Z,1567807618.510 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:06:59.318Z,1567807619.318 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:06:59.319Z,1567807619.319 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:07:00.530Z,1567807620.530 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:07:00.531Z,1567807620.531 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:07:01.352Z,1567807621.352 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:07:01.353Z,1567807621.353 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:07:02.564Z,1567807622.564 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:07:02.565Z,1567807622.565 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:07:03.368Z,1567807623.368 [Rowe_600](INFO): Inst Velocity X:5.000000
2019-09-06T22:07:03.368Z,1567807623.368 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:07:04.579Z,1567807624.579 [Rowe_600](INFO): Inst Velocity X:5.000000
2019-09-06T22:07:04.580Z,1567807624.580 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:07:05.001Z,1567807625.001 [SBIT](IMPORTANT): Beginning Startup BIT
2019-09-06T22:07:05.005Z,1567807625.005 [CBIT](IMPORTANT): Beginning ground fault scan
2019-09-06T22:07:05.379Z,1567807625.379 [Rowe_600](INFO): Inst Velocity X:3.000000
2019-09-06T22:07:05.379Z,1567807625.379 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T22:07:06.595Z,1567807626.595 [Rowe_600](INFO): Inst Velocity X:3.000000
2019-09-06T22:07:06.596Z,1567807626.596 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:07:07.528Z,1567807627.528 [Rowe_600](INFO): Inst Velocity X:4.000000
2019-09-06T22:07:07.529Z,1567807627.529 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:07:08.332Z,1567807628.332 [Rowe_600](INFO): Inst Velocity X:3.000000
2019-09-06T22:07:08.332Z,1567807628.332 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:07:09.530Z,1567807629.530 [Rowe_600](INFO): Inst Velocity X:3.000000
2019-09-06T22:07:09.530Z,1567807629.530 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T22:07:10.339Z,1567807630.339 [Rowe_600](INFO): Inst Velocity X:4.000000
2019-09-06T22:07:10.339Z,1567807630.339 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T22:07:11.549Z,1567807631.549 [Rowe_600](INFO): Inst Velocity X:3.000000
2019-09-06T22:07:11.550Z,1567807631.550 [Rowe_600](INFO): Inst Velocity Y:-2.000000
2019-09-06T22:07:12.369Z,1567807632.369 [Rowe_600](INFO): Inst Velocity X:3.000000
2019-09-06T22:07:12.369Z,1567807632.369 [Rowe_600](INFO): Inst Velocity Y:-1.000000
2019-09-06T22:07:13.570Z,1567807633.570 [Rowe_600](INFO): Inst Velocity X:3.000000
2019-09-06T22:07:13.571Z,1567807633.571 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T22:07:14.388Z,1567807634.388 [Rowe_600](INFO): Inst Velocity X:2.000000
2019-09-06T22:07:14.389Z,1567807634.389 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T22:07:15.617Z,1567807635.617 [Rowe_600](INFO): Inst Velocity X:4.000000
2019-09-06T22:07:15.617Z,1567807635.617 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:07:16.061Z,1567807636.061 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.012298
CHAN A1 (24V): 0.035732
CHAN A2 (12V): -0.006712
CHAN A3 (5V): -0.002411
CHAN B0 (3.3V): 0.000349
CHAN B1 (3.15aV): 0.000325
CHAN B2 (3.15bV): -0.001002
CHAN B3 (GND): 0.002065
OPEN: 0.006978
Full Scale Calc: 4.765 mA, -1.589 mA
2019-09-06T22:07:16.394Z,1567807636.394 [Rowe_600](INFO): Inst Velocity X:5.000000
2019-09-06T22:07:16.394Z,1567807636.394 [Rowe_600](INFO): Inst Velocity Y:-1.000000
2019-09-06T22:07:17.606Z,1567807637.606 [Rowe_600](INFO): Inst Velocity X:3.000000
2019-09-06T22:07:17.607Z,1567807637.607 [Rowe_600](INFO): Inst Velocity Y:-4.000000
2019-09-06T22:07:18.423Z,1567807638.423 [Rowe_600](INFO): Inst Velocity X:1.000000
2019-09-06T22:07:18.423Z,1567807638.423 [Rowe_600](INFO): Inst Velocity Y:-4.000000
2019-09-06T22:07:19.655Z,1567807639.655 [Rowe_600](INFO): Inst Velocity X:2.000000
2019-09-06T22:07:19.655Z,1567807639.655 [Rowe_600](INFO): Inst Velocity Y:-7.000000
2019-09-06T22:07:20.618Z,1567807640.618 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T22:07:20.621Z,1567807640.621 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T22:07:20.621Z,1567807640.621 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T22:07:21.403Z,1567807641.403 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T22:07:21.406Z,1567807641.406 [Rowe_600](INFO): Inst Velocity X:32768.000000
2019-09-06T22:07:21.406Z,1567807641.406 [Rowe_600](INFO): Inst Velocity Y:-32768.000000
2019-09-06T22:07:22.612Z,1567807642.612 [Rowe_600](ERROR): DVL BIT error. See manual. Result code: 4
2019-09-06T22:07:22.615Z,1567807642.615 [Rowe_600](INFO): Inst Velocity X:4.000000
2019-09-06T22:07:22.615Z,1567807642.615 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:07:23.426Z,1567807643.426 [Rowe_600](INFO): Inst Velocity X:4.000000
2019-09-06T22:07:23.426Z,1567807643.426 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:07:24.663Z,1567807644.663 [Rowe_600](INFO): Inst Velocity X:4.000000
2019-09-06T22:07:24.664Z,1567807644.664 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:07:25.456Z,1567807645.456 [Rowe_600](INFO): Inst Velocity X:2.000000
2019-09-06T22:07:25.456Z,1567807645.456 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:07:26.653Z,1567807646.653 [Rowe_600](ERROR): only read 0 of 1 data item for BIT error
2019-09-06T22:07:26.653Z,1567807646.653 [Rowe_600](ERROR): Failed to parse:
:TS,19090615052900,35.0,+150,1498.RA,  0.00,  11.50,  10.41
2019-09-06T22:07:27.471Z,1567807647.471 [Rowe_600](INFO): Inst Velocity X:-2.000000
2019-09-06T22:07:27.471Z,1567807647.471 [Rowe_600](INFO): Inst Velocity Y:-4.000000
2019-09-06T22:07:28.298Z,1567807648.298 [Rowe_600](INFO): Inst Velocity X:1.000000
2019-09-06T22:07:28.299Z,1567807648.299 [Rowe_600](INFO): Inst Velocity Y:-5.000000
2019-09-06T22:07:29.498Z,1567807649.498 [Rowe_600](INFO): Inst Velocity X:1.000000
2019-09-06T22:07:29.498Z,1567807649.498 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T22:07:30.308Z,1567807650.308 [Rowe_600](INFO): Inst Velocity X:4.000000
2019-09-06T22:07:30.309Z,1567807650.309 [Rowe_600](INFO): Inst Velocity Y:-2.000000
2019-09-06T22:07:31.507Z,1567807651.507 [Rowe_600](INFO): Inst Velocity X:4.000000
2019-09-06T22:07:31.507Z,1567807651.507 [Rowe_600](INFO): Inst Velocity Y:-1.000000
2019-09-06T22:07:32.310Z,1567807652.310 [Rowe_600](INFO): Inst Velocity X:5.000000
2019-09-06T22:07:32.310Z,1567807652.310 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:07:33.531Z,1567807653.531 [Rowe_600](INFO): Inst Velocity X:5.000000
2019-09-06T22:07:33.531Z,1567807653.531 [Rowe_600](INFO): Inst Velocity Y:6.000000
2019-09-06T22:07:34.330Z,1567807654.330 [Rowe_600](INFO): Inst Velocity X:-0.000000
2019-09-06T22:07:34.330Z,1567807654.330 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:07:35.553Z,1567807655.553 [Rowe_600](INFO): Inst Velocity X:-1.000000
2019-09-06T22:07:35.553Z,1567807655.553 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:07:36.365Z,1567807656.365 [Rowe_600](INFO): Inst Velocity X:-0.000000
2019-09-06T22:07:36.365Z,1567807656.365 [Rowe_600](INFO): Inst Velocity Y:6.000000
2019-09-06T22:07:37.570Z,1567807657.570 [Rowe_600](INFO): Inst Velocity X:-0.000000
2019-09-06T22:07:37.571Z,1567807657.571 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:07:38.374Z,1567807658.374 [Rowe_600](INFO): Inst Velocity X:-0.000000
2019-09-06T22:07:38.374Z,1567807658.374 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:07:39.586Z,1567807659.586 [Rowe_600](INFO): Inst Velocity X:-0.000000
2019-09-06T22:07:39.586Z,1567807659.586 [Rowe_600](INFO): Inst Velocity Y:6.000000
2019-09-06T22:07:40.409Z,1567807660.409 [Rowe_600](INFO): Inst Velocity X:1.000000
2019-09-06T22:07:40.409Z,1567807660.409 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:07:41.606Z,1567807661.606 [Rowe_600](INFO): Inst Velocity X:-1.000000
2019-09-06T22:07:41.606Z,1567807661.606 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:07:42.410Z,1567807662.410 [Rowe_600](INFO): Inst Velocity X:-0.000000
2019-09-06T22:07:42.411Z,1567807662.411 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:07:43.641Z,1567807663.641 [Rowe_600](INFO): Inst Velocity X:-0.000000
2019-09-06T22:07:43.641Z,1567807663.641 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T22:07:44.444Z,1567807664.444 [Rowe_600](INFO): Inst Velocity X:-2.000000
2019-09-06T22:07:44.444Z,1567807664.444 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:07:45.646Z,1567807665.646 [Rowe_600](INFO): Inst Velocity X:-2.000000
2019-09-06T22:07:45.646Z,1567807665.646 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T22:07:46.467Z,1567807666.467 [Rowe_600](INFO): Inst Velocity X:-1.000000
2019-09-06T22:07:46.467Z,1567807666.467 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:07:47.343Z,1567807667.343 [Rowe_600](INFO): Inst Velocity X:-0.000000
2019-09-06T22:07:47.343Z,1567807667.343 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:07:48.554Z,1567807668.554 [Rowe_600](INFO): Inst Velocity X:-0.000000
2019-09-06T22:07:48.554Z,1567807668.554 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:07:49.362Z,1567807669.362 [Rowe_600](INFO): Inst Velocity X:1.000000
2019-09-06T22:07:49.363Z,1567807669.363 [Rowe_600](INFO): Inst Velocity Y:-1.000000
2019-09-06T22:07:50.566Z,1567807670.566 [Rowe_600](INFO): Inst Velocity X:-0.000000
2019-09-06T22:07:50.566Z,1567807670.566 [Rowe_600](INFO): Inst Velocity Y:-1.000000
2019-09-06T22:07:51.396Z,1567807671.396 [Rowe_600](INFO): Inst Velocity X:5.000000
2019-09-06T22:07:51.396Z,1567807671.396 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:07:52.597Z,1567807672.597 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:07:52.597Z,1567807672.597 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:07:53.409Z,1567807673.409 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:07:53.409Z,1567807673.409 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:07:54.610Z,1567807674.610 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:07:54.611Z,1567807674.611 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T22:07:55.414Z,1567807675.414 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:07:55.414Z,1567807675.414 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:07:56.645Z,1567807676.645 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:07:56.645Z,1567807676.645 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:07:57.457Z,1567807677.457 [Rowe_600](INFO): Inst Velocity X:11.000000
2019-09-06T22:07:57.457Z,1567807677.457 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:07:58.268Z,1567807678.268 [SBIT](IMPORTANT): SBIT PASSED
2019-09-06T22:07:58.330Z,1567807678.330 [CommandLine](IMPORTANT): got command configSet list
2019-09-06T22:07:58.330Z,1567807678.330 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-09-06T22:07:58.331Z,1567807678.331 [CommandLine](IMPORTANT): Micromodem.loadAtStartup=0 bool;

2019-09-06T22:07:58.331Z,1567807678.331 [CommandLine](IMPORTANT): VerticalControl.massDefault=10 millimeter;

2019-09-06T22:07:58.646Z,1567807678.646 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:07:58.646Z,1567807678.646 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:07:58.657Z,1567807678.657 [MissionManager](IMPORTANT): Started mission Startup
2019-09-06T22:07:58.657Z,1567807678.657 [Startup] Running Loop=1
2019-09-06T22:07:58.657Z,1567807678.657 [Startup](DEBUG): Aggregate::initialize Startup
2019-09-06T22:07:58.657Z,1567807678.657 [Startup:A.GoToSurface] Running Loop=1
2019-09-06T22:07:58.658Z,1567807678.658 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-09-06T22:07:58.658Z,1567807678.658 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-09-06T22:07:58.659Z,1567807678.659 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-09-06T22:07:58.659Z,1567807678.659 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-09-06T22:07:58.659Z,1567807678.659 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-09-06T22:07:58.660Z,1567807678.660 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-09-06T22:07:58.661Z,1567807678.661 [Startup:StartupSatComms] Running Loop=1
2019-09-06T22:07:58.661Z,1567807678.661 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-09-06T22:07:58.661Z,1567807678.661 [Startup:StartupSatComms:A] Running Loop=1
2019-09-06T22:07:59.060Z,1567807679.060 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-09-06T22:07:59.458Z,1567807679.458 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:07:59.459Z,1567807679.459 [Rowe_600](INFO): Inst Velocity Y:7.000000
2019-09-06T22:08:00.289Z,1567807680.289 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:08:00.289Z,1567807680.289 [Rowe_600](INFO): Inst Velocity Y:6.000000
2019-09-06T22:08:01.478Z,1567807681.478 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:08:01.479Z,1567807681.479 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T22:08:02.305Z,1567807682.305 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:08:02.305Z,1567807682.305 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:08:03.507Z,1567807683.507 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:08:03.507Z,1567807683.507 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:08:04.302Z,1567807684.302 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:08:04.303Z,1567807684.303 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:08:05.516Z,1567807685.516 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:08:05.516Z,1567807685.516 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:08:06.322Z,1567807686.322 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:08:06.322Z,1567807686.322 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:08:07.559Z,1567807687.559 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:08:07.559Z,1567807687.559 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:08:08.356Z,1567807688.356 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:08:08.357Z,1567807688.357 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:08:09.555Z,1567807689.555 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:08:09.555Z,1567807689.555 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:08:10.377Z,1567807690.377 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:08:10.377Z,1567807690.377 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T22:08:11.578Z,1567807691.578 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:08:11.579Z,1567807691.579 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T22:08:12.392Z,1567807692.392 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:08:12.392Z,1567807692.392 [Rowe_600](INFO): Inst Velocity Y:6.000000
2019-09-06T22:08:13.611Z,1567807693.611 [Rowe_600](INFO): Inst Velocity X:11.000000
2019-09-06T22:08:13.611Z,1567807693.611 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:08:14.406Z,1567807694.406 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:08:14.406Z,1567807694.406 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T22:08:15.619Z,1567807695.619 [Rowe_600](INFO): Inst Velocity X:11.000000
2019-09-06T22:08:15.620Z,1567807695.620 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:08:16.430Z,1567807696.430 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:08:16.430Z,1567807696.430 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:08:17.639Z,1567807697.639 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:08:17.640Z,1567807697.640 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:08:18.447Z,1567807698.447 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:08:18.448Z,1567807698.448 [Rowe_600](INFO): Inst Velocity Y:6.000000
2019-09-06T22:08:19.655Z,1567807699.655 [Rowe_600](ERROR): Failed to parse:90615062200,35.0,+15.0,   0.0,1498.0,  0
2019-09-06T22:08:20.473Z,1567807700.473 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:08:20.473Z,1567807700.473 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:08:21.287Z,1567807701.287 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:08:21.287Z,1567807701.287 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T22:08:22.487Z,1567807702.487 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:08:22.487Z,1567807702.487 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T22:08:23.295Z,1567807703.295 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:08:23.295Z,1567807703.295 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T22:08:24.506Z,1567807704.506 [Rowe_600](INFO): Inst Velocity X:11.000000
2019-09-06T22:08:24.506Z,1567807704.506 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:08:25.310Z,1567807705.310 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:08:25.310Z,1567807705.310 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T22:08:26.529Z,1567807706.529 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:08:26.529Z,1567807706.529 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T22:08:27.354Z,1567807707.354 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:08:27.354Z,1567807707.354 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T22:08:28.546Z,1567807708.546 [Rowe_600](INFO): Inst Velocity X:11.000000
2019-09-06T22:08:28.547Z,1567807708.547 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:08:29.350Z,1567807709.350 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:08:29.350Z,1567807709.350 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T22:08:31.370Z,1567807711.370 [Rowe_600](INFO): Inst Velocity X:11.000000
2019-09-06T22:08:31.371Z,1567807711.371 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T22:08:33.395Z,1567807713.395 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:08:33.395Z,1567807713.395 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:08:38.643Z,1567807718.643 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:08:38.643Z,1567807718.643 [Rowe_600](INFO): Inst Velocity Y:-1.000000
2019-09-06T22:08:39.462Z,1567807719.462 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:08:39.462Z,1567807719.462 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:08:40.283Z,1567807720.283 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:08:40.283Z,1567807720.283 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:08:41.470Z,1567807721.470 [Rowe_600](INFO): Inst Velocity X:3.000000
2019-09-06T22:08:41.470Z,1567807721.470 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T22:08:42.286Z,1567807722.286 [Rowe_600](INFO): Inst Velocity X:5.000000
2019-09-06T22:08:42.286Z,1567807722.286 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T22:08:43.514Z,1567807723.514 [Rowe_600](INFO): Inst Velocity X:4.000000
2019-09-06T22:08:43.515Z,1567807723.515 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:08:44.305Z,1567807724.305 [Rowe_600](INFO): Inst Velocity X:4.000000
2019-09-06T22:08:44.305Z,1567807724.305 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T22:08:45.516Z,1567807725.516 [Rowe_600](INFO): Inst Velocity X:5.000000
2019-09-06T22:08:45.516Z,1567807725.516 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:08:46.349Z,1567807726.349 [Rowe_600](INFO): Inst Velocity X:6.000000
2019-09-06T22:08:46.350Z,1567807726.350 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:08:47.547Z,1567807727.547 [Rowe_600](INFO): Inst Velocity X:4.000000
2019-09-06T22:08:47.547Z,1567807727.547 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:08:48.339Z,1567807728.339 [Rowe_600](INFO): Inst Velocity X:5.000000
2019-09-06T22:08:48.339Z,1567807728.339 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:08:49.558Z,1567807729.558 [Rowe_600](INFO): Inst Velocity X:6.000000
2019-09-06T22:08:49.558Z,1567807729.558 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:08:50.382Z,1567807730.382 [Rowe_600](INFO): Inst Velocity X:5.000000
2019-09-06T22:08:50.382Z,1567807730.382 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:08:51.576Z,1567807731.576 [Rowe_600](INFO): Inst Velocity X:6.000000
2019-09-06T22:08:51.576Z,1567807731.576 [Rowe_600](INFO): Inst Velocity Y:-1.000000
2019-09-06T22:08:52.386Z,1567807732.386 [Rowe_600](INFO): Inst Velocity X:4.000000
2019-09-06T22:08:52.386Z,1567807732.386 [Rowe_600](INFO): Inst Velocity Y:-1.000000
2019-09-06T22:08:53.599Z,1567807733.599 [Rowe_600](INFO): Inst Velocity X:5.000000
2019-09-06T22:08:53.599Z,1567807733.599 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T22:08:54.422Z,1567807734.422 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:08:54.422Z,1567807734.422 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T22:08:55.611Z,1567807735.611 [Rowe_600](INFO): Inst Velocity X:6.000000
2019-09-06T22:08:55.611Z,1567807735.611 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:08:56.442Z,1567807736.442 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:08:56.442Z,1567807736.442 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T22:08:57.638Z,1567807737.638 [Rowe_600](INFO): Inst Velocity X:6.000000
2019-09-06T22:08:57.639Z,1567807737.639 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:08:58.449Z,1567807738.449 [Rowe_600](INFO): Inst Velocity X:5.000000
2019-09-06T22:08:58.449Z,1567807738.449 [Rowe_600](INFO): Inst Velocity Y:-2.000000
2019-09-06T22:08:59.216Z,1567807739.216 [Startup:StartupSatComms:A](INFO): Timed out from 2019-09-06T22:07:58.7Z
2019-09-06T22:08:59.216Z,1567807739.216 [Startup:StartupSatComms:A] Stopped
2019-09-06T22:08:59.216Z,1567807739.216 [Startup:StartupSatComms:B] Running Loop=1
2019-09-06T22:08:59.355Z,1567807739.355 [Rowe_600](INFO): Inst Velocity X:6.000000
2019-09-06T22:08:59.355Z,1567807739.355 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:08:59.407Z,1567807739.407 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-09-06T22:09:00.487Z,1567807740.487 [Rowe_600](INFO): Inst Velocity X:6.000000
2019-09-06T22:09:00.487Z,1567807740.487 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T22:09:01.286Z,1567807741.286 [Rowe_600](INFO): Inst Velocity X:4.000000
2019-09-06T22:09:01.287Z,1567807741.287 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T22:09:02.503Z,1567807742.503 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:09:02.503Z,1567807742.503 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T22:09:03.317Z,1567807743.317 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:09:03.317Z,1567807743.317 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:09:04.518Z,1567807744.518 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:09:04.518Z,1567807744.518 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:09:05.322Z,1567807745.322 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:09:05.322Z,1567807745.322 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:09:05.729Z,1567807745.729 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-09-06T22:09:05.729Z,1567807745.729 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-09-06T22:09:05.743Z,1567807745.743 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-09-06T22:09:06.150Z,1567807746.150 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-09-06T22:09:06.150Z,1567807746.150 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-09-06T22:09:06.538Z,1567807746.538 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:09:06.538Z,1567807746.538 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:09:06.824Z,1567807746.824 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190906T215233/Courier0007.lzma
2019-09-06T22:09:07.360Z,1567807747.360 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:09:07.360Z,1567807747.360 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:09:07.630Z,1567807747.630 [DataOverHttps](INFO): Moved sent file to Logs/20190906T215233/Courier0007.lzma.bak
2019-09-06T22:09:07.630Z,1567807747.630 [DataOverHttps](INFO): SBD MOMSN=11748454
2019-09-06T22:09:08.566Z,1567807748.566 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:09:08.566Z,1567807748.566 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:09:09.379Z,1567807749.379 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:09:09.379Z,1567807749.379 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:09:11.402Z,1567807751.402 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:09:11.402Z,1567807751.402 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:09:13.416Z,1567807753.416 [Rowe_600](INFO): Inst Velocity X:5.000000
2019-09-06T22:09:13.416Z,1567807753.416 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:09:15.421Z,1567807755.421 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:09:15.421Z,1567807755.421 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:09:16.641Z,1567807756.641 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:09:16.642Z,1567807756.642 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:09:17.442Z,1567807757.442 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:09:17.442Z,1567807757.442 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:09:18.259Z,1567807758.259 [Rowe_600](ERROR): only read 2 of 4 data items
2019-09-06T22:09:18.259Z,1567807758.259 [Rowe_600](ERROR): Failed to parse:
:RA,  0.00,   0.00,   9.95,

2019-09-06T22:09:19.464Z,1567807759.464 [Rowe_600](ERROR): Failed to parse:
:SA, +79.50, -23.84,241.74
2019-09-06T22:09:20.313Z,1567807760.313 [Rowe_600](INFO): Inst Velocity X:6.000000
2019-09-06T22:09:20.313Z,1567807760.313 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:09:21.482Z,1567807761.482 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:09:21.482Z,1567807761.482 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:09:22.294Z,1567807762.294 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:09:22.294Z,1567807762.294 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:09:23.511Z,1567807763.511 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:09:23.511Z,1567807763.511 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:09:24.136Z,1567807764.136 [DataOverHttps](INFO): Sending 95 bytes from file Logs/20190906T215233/Express0005.lzma
2019-09-06T22:09:24.341Z,1567807764.341 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:09:24.341Z,1567807764.341 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:09:24.942Z,1567807764.942 [DataOverHttps](INFO): Moved sent file to Logs/20190906T215233/Express0005.lzma.bak
2019-09-06T22:09:24.942Z,1567807764.942 [DataOverHttps](INFO): SBD MOMSN=11748456
2019-09-06T22:09:25.526Z,1567807765.526 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:09:25.527Z,1567807765.527 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:09:26.336Z,1567807766.336 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:09:26.336Z,1567807766.336 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:09:27.563Z,1567807767.563 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:09:27.563Z,1567807767.563 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T22:09:28.350Z,1567807768.350 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:09:28.351Z,1567807768.351 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:09:29.562Z,1567807769.562 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:09:29.562Z,1567807769.562 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:09:30.383Z,1567807770.383 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:09:30.383Z,1567807770.383 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:09:31.587Z,1567807771.587 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:09:31.587Z,1567807771.587 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:09:32.390Z,1567807772.390 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:09:32.390Z,1567807772.390 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:09:33.609Z,1567807773.609 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:09:33.609Z,1567807773.609 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:09:34.410Z,1567807774.410 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:09:34.410Z,1567807774.410 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:09:35.627Z,1567807775.627 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:09:35.627Z,1567807775.627 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:09:36.439Z,1567807776.439 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:09:36.439Z,1567807776.439 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:09:37.270Z,1567807777.270 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:09:37.271Z,1567807777.271 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:09:38.450Z,1567807778.450 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:09:38.450Z,1567807778.450 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:09:39.279Z,1567807779.279 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:09:39.279Z,1567807779.279 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:09:39.576Z,1567807779.576 [DataOverHttps](INFO): Sending 86 bytes from file Logs/20190906T215233/Express0008.lzma
2019-09-06T22:09:40.382Z,1567807780.382 [DataOverHttps](INFO): Moved sent file to Logs/20190906T215233/Express0008.lzma.bak
2019-09-06T22:09:40.382Z,1567807780.382 [DataOverHttps](INFO): SBD MOMSN=11748459
2019-09-06T22:09:40.491Z,1567807780.491 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:09:40.491Z,1567807780.491 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:09:41.292Z,1567807781.292 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:09:41.292Z,1567807781.292 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:09:42.502Z,1567807782.502 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:09:42.503Z,1567807782.503 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:09:43.307Z,1567807783.307 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:09:43.307Z,1567807783.307 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:09:44.518Z,1567807784.518 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:09:44.518Z,1567807784.518 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:09:45.319Z,1567807785.319 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:09:45.319Z,1567807785.319 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:09:45.703Z,1567807785.703 [NAL9602](INFO): SBD MO Status=2, MOMSN=646, MT Status=2, MTMSN=0
2019-09-06T22:09:45.704Z,1567807785.704 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-06T22:09:46.530Z,1567807786.530 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:09:46.530Z,1567807786.530 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:09:47.352Z,1567807787.352 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:09:47.352Z,1567807787.352 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:09:49.359Z,1567807789.359 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:09:49.359Z,1567807789.359 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:09:51.379Z,1567807791.379 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:09:51.379Z,1567807791.379 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:09:53.421Z,1567807793.421 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:09:53.421Z,1567807793.421 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:09:54.632Z,1567807794.632 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:09:54.632Z,1567807794.632 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:09:55.154Z,1567807795.154 [DataOverHttps](INFO): Sending 716 bytes from file Logs/20190906T220555/Express0001.lzma
2019-09-06T22:09:55.419Z,1567807795.419 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:09:55.419Z,1567807795.419 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:09:55.958Z,1567807795.958 [DataOverHttps](INFO): Moved sent file to Logs/20190906T220555/Express0001.lzma.bak
2019-09-06T22:09:55.958Z,1567807795.958 [DataOverHttps](INFO): SBD MOMSN=11748462
2019-09-06T22:09:56.634Z,1567807796.634 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:09:56.634Z,1567807796.634 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:09:57.451Z,1567807797.451 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:09:57.452Z,1567807797.452 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:09:58.261Z,1567807798.261 [Rowe_600](ERROR): Failed to parse:
:RA,  0.00,   0.00,  11.49,   9.92,  10.22
2019-09-06T22:09:59.471Z,1567807799.471 [Rowe_600](INFO): Inst Velocity X:6.000000
2019-09-06T22:09:59.471Z,1567807799.471 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:09:59.501Z,1567807799.501 [Startup:StartupSatComms:B](INFO): Timed out from 2019-09-06T22:08:59.2Z
2019-09-06T22:09:59.501Z,1567807799.501 [Startup:StartupSatComms:B] Stopped
2019-09-06T22:09:59.501Z,1567807799.501 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-09-06T22:09:59.501Z,1567807799.501 [Startup:StartupSatComms] Stopped
2019-09-06T22:09:59.501Z,1567807799.501 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-09-06T22:09:59.502Z,1567807799.502 [Startup](INFO): Completed Startup
2019-09-06T22:09:59.502Z,1567807799.502 [MissionManager](INFO): Startup is completed.
2019-09-06T22:09:59.502Z,1567807799.502 [MissionManager](INFO): Uninitializing Mission Startup
2019-09-06T22:09:59.502Z,1567807799.502 [Startup] Stopped
2019-09-06T22:09:59.503Z,1567807799.503 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-09-06T22:09:59.503Z,1567807799.503 [Startup:A.GoToSurface] Stopped
2019-09-06T22:09:59.503Z,1567807799.503 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-09-06T22:09:59.870Z,1567807799.870 [MissionManager](IMPORTANT): Started mission Default
2019-09-06T22:09:59.870Z,1567807799.870 [Default] Running Loop=1
2019-09-06T22:09:59.870Z,1567807799.870 [Default](DEBUG): Aggregate::initialize Default
2019-09-06T22:09:59.870Z,1567807799.870 [Default:B.GoToSurface] Running Loop=1
2019-09-06T22:09:59.870Z,1567807799.870 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-09-06T22:09:59.871Z,1567807799.871 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-09-06T22:09:59.871Z,1567807799.871 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-09-06T22:09:59.871Z,1567807799.871 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-09-06T22:09:59.872Z,1567807799.872 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-09-06T22:09:59.872Z,1567807799.872 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-09-06T22:09:59.872Z,1567807799.872 [Default:A.Wait] Running Loop=1
2019-09-06T22:09:59.872Z,1567807799.872 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-09-06T22:10:00.288Z,1567807800.288 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:10:00.288Z,1567807800.288 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:10:01.494Z,1567807801.494 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:10:01.494Z,1567807801.494 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:10:02.290Z,1567807802.290 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:10:02.290Z,1567807802.290 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:10:03.503Z,1567807803.503 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:10:03.504Z,1567807803.504 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:10:04.320Z,1567807804.320 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:10:04.321Z,1567807804.321 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:10:05.527Z,1567807805.527 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:10:05.527Z,1567807805.527 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:10:06.326Z,1567807806.326 [Rowe_600](INFO): Inst Velocity X:6.000000
2019-09-06T22:10:06.326Z,1567807806.326 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:10:07.546Z,1567807807.546 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:10:07.546Z,1567807807.546 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:10:08.350Z,1567807808.350 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:10:08.350Z,1567807808.350 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:10:09.559Z,1567807809.559 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:10:09.559Z,1567807809.559 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:10:09.944Z,1567807809.944 [NAL9602](INFO): SBD MO Status=2, MOMSN=646, MT Status=2, MTMSN=0
2019-09-06T22:10:09.944Z,1567807809.944 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-06T22:10:10.389Z,1567807810.389 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:10:10.389Z,1567807810.389 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:10:11.582Z,1567807811.582 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:10:11.582Z,1567807811.582 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:10:12.386Z,1567807812.386 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:10:12.386Z,1567807812.386 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:10:13.202Z,1567807813.202 [Default:A.Wait](INFO): Done Waiting.
2019-09-06T22:10:13.202Z,1567807813.202 [Default:A.Wait] Stopped
2019-09-06T22:10:13.202Z,1567807813.202 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-09-06T22:10:13.606Z,1567807813.606 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:10:13.606Z,1567807813.606 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:10:13.617Z,1567807813.617 [Default:CheckIn] Running Loop=1
2019-09-06T22:10:13.617Z,1567807813.617 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-09-06T22:10:13.617Z,1567807813.617 [Default:CheckIn:Read_GPS] Running Loop=1
2019-09-06T22:10:14.017Z,1567807814.017 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-09-06T22:10:14.429Z,1567807814.429 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:10:14.429Z,1567807814.429 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:10:15.623Z,1567807815.623 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:10:15.623Z,1567807815.623 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:10:16.426Z,1567807816.426 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:10:16.426Z,1567807816.426 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:10:17.661Z,1567807817.661 [Rowe_600](ERROR): Failed to parse:
:RA,  0.00,   0.00,  11.50,   9.91
2019-09-06T22:10:18.463Z,1567807818.463 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:10:18.463Z,1567807818.463 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:10:19.276Z,1567807819.276 [Rowe_600](INFO): Inst Velocity X:10.000000
2019-09-06T22:10:19.276Z,1567807819.276 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:10:19.278Z,1567807819.278 [Rowe_600](ERROR): only read 0 of 1 data item for altitude
2019-09-06T22:10:19.279Z,1567807819.279 [Rowe_600](ERROR): Failed to parse:
:BD,       +1.71,       +    -4.09,  10.54,  1.00
2019-09-06T22:10:20.465Z,1567807820.465 [Rowe_600](INFO): Inst Velocity X:6.000000
2019-09-06T22:10:20.466Z,1567807820.466 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:10:21.292Z,1567807821.292 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:10:21.292Z,1567807821.292 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:10:22.487Z,1567807822.487 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:10:22.487Z,1567807822.487 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:10:23.298Z,1567807823.298 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:10:23.298Z,1567807823.298 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:10:24.553Z,1567807824.553 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:10:24.553Z,1567807824.553 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:10:25.314Z,1567807825.314 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:10:25.314Z,1567807825.314 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:10:26.537Z,1567807826.537 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:10:26.537Z,1567807826.537 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:10:27.346Z,1567807827.346 [Rowe_600](INFO): Inst Velocity X:9.000000
2019-09-06T22:10:27.346Z,1567807827.346 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:10:28.551Z,1567807828.551 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:10:28.551Z,1567807828.551 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:10:29.358Z,1567807829.358 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:10:29.358Z,1567807829.358 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:10:30.574Z,1567807830.574 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:10:30.574Z,1567807830.574 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:10:31.383Z,1567807831.383 [Rowe_600](INFO): Inst Velocity X:6.000000
2019-09-06T22:10:31.383Z,1567807831.383 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:10:32.586Z,1567807832.586 [Rowe_600](INFO): Inst Velocity X:5.000000
2019-09-06T22:10:32.586Z,1567807832.586 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:10:33.394Z,1567807833.394 [Rowe_600](INFO): Inst Velocity X:8.000000
2019-09-06T22:10:33.394Z,1567807833.394 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:10:34.634Z,1567807834.634 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:10:34.634Z,1567807834.634 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:10:35.423Z,1567807835.423 [Rowe_600](INFO): Inst Velocity X:7.000000
2019-09-06T22:10:35.423Z,1567807835.423 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:10:37.439Z,1567807837.439 [Rowe_600](ERROR): only read 0 of 1 data item for BIT error
2019-09-06T22:10:37.439Z,1567807837.439 [Rowe_600](ERROR): Failed to parse:
:TS,190906150839+15.0,   0.0,1498.0,  0
2019-09-06T22:10:38.281Z,1567807838.281 [Rowe_600](INFO): Inst Velocity X:6.000000
2019-09-06T22:10:38.282Z,1567807838.282 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:10:39.458Z,1567807839.458 [Rowe_600](INFO): Inst Velocity X:5.000000
2019-09-06T22:10:39.459Z,1567807839.459 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:10:40.278Z,1567807840.278 [Rowe_600](INFO): Inst Velocity X:5.000000
2019-09-06T22:10:40.279Z,1567807840.279 [Rowe_600](INFO): Inst Velocity Y:-1.000000
2019-09-06T22:10:41.485Z,1567807841.485 [Rowe_600](INFO): Inst Velocity X:4.000000
2019-09-06T22:10:41.486Z,1567807841.486 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T22:10:42.287Z,1567807842.287 [Rowe_600](INFO): Inst Velocity X:5.000000
2019-09-06T22:10:42.287Z,1567807842.287 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:10:43.508Z,1567807843.508 [Rowe_600](INFO): Inst Velocity X:6.000000
2019-09-06T22:10:43.509Z,1567807843.509 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T22:10:44.314Z,1567807844.314 [Rowe_600](INFO): Inst Velocity X:6.000000
2019-09-06T22:10:44.315Z,1567807844.315 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:10:45.514Z,1567807845.514 [Rowe_600](INFO): Inst Velocity X:6.000000
2019-09-06T22:10:45.514Z,1567807845.514 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T22:10:46.323Z,1567807846.323 [Rowe_600](INFO): Inst Velocity X:4.000000
2019-09-06T22:10:46.323Z,1567807846.323 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:10:47.543Z,1567807847.543 [Rowe_600](INFO): Inst Velocity X:4.000000
2019-09-06T22:10:47.543Z,1567807847.543 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T22:10:48.347Z,1567807848.347 [Rowe_600](INFO): Inst Velocity X:4.000000
2019-09-06T22:10:48.347Z,1567807848.347 [Rowe_600](INFO): Inst Velocity Y:1.000000
2019-09-06T22:10:49.554Z,1567807849.554 [Rowe_600](INFO): Inst Velocity X:4.000000
2019-09-06T22:10:49.554Z,1567807849.554 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:10:50.367Z,1567807850.367 [Rowe_600](INFO): Inst Velocity X:5.000000
2019-09-06T22:10:50.367Z,1567807850.367 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:10:51.590Z,1567807851.590 [Rowe_600](INFO): Inst Velocity X:5.000000
2019-09-06T22:10:51.591Z,1567807851.591 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T22:10:52.397Z,1567807852.397 [Rowe_600](INFO): Inst Velocity X:5.000000
2019-09-06T22:10:52.398Z,1567807852.398 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:10:53.610Z,1567807853.610 [Rowe_600](INFO): Inst Velocity X:4.000000
2019-09-06T22:10:53.610Z,1567807853.610 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:10:54.410Z,1567807854.410 [Rowe_600](INFO): Inst Velocity X:5.000000
2019-09-06T22:10:54.410Z,1567807854.410 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:10:55.615Z,1567807855.615 [Rowe_600](INFO): Inst Velocity X:3.000000
2019-09-06T22:10:55.615Z,1567807855.615 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:10:56.431Z,1567807856.431 [Rowe_600](INFO): Inst Velocity X:4.000000
2019-09-06T22:10:56.431Z,1567807856.431 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:10:57.640Z,1567807857.640 [Rowe_600](INFO): Inst Velocity X:5.000000
2019-09-06T22:10:57.640Z,1567807857.640 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:10:58.446Z,1567807858.446 [Rowe_600](INFO): Inst Velocity X:4.000000
2019-09-06T22:10:58.446Z,1567807858.446 [Rowe_600](INFO): Inst Velocity Y:0.000000
2019-09-06T22:11:00.484Z,1567807860.484 [Rowe_600](INFO): Inst Velocity X:4.000000
2019-09-06T22:11:00.484Z,1567807860.484 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:11:01.292Z,1567807861.292 [Rowe_600](INFO): Inst Velocity X:3.000000
2019-09-06T22:11:01.293Z,1567807861.293 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:11:02.482Z,1567807862.482 [Rowe_600](INFO): Inst Velocity X:3.000000
2019-09-06T22:11:02.483Z,1567807862.483 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:11:03.290Z,1567807863.290 [Rowe_600](INFO): Inst Velocity X:3.000000
2019-09-06T22:11:03.290Z,1567807863.290 [Rowe_600](INFO): Inst Velocity Y:2.000000
2019-09-06T22:11:04.520Z,1567807864.520 [Rowe_600](INFO): Inst Velocity X:2.000000
2019-09-06T22:11:04.520Z,1567807864.520 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:11:05.312Z,1567807865.312 [Rowe_600](INFO): Inst Velocity X:3.000000
2019-09-06T22:11:05.312Z,1567807865.312 [Rowe_600](INFO): Inst Velocity Y:7.000000
2019-09-06T22:11:06.536Z,1567807866.536 [Rowe_600](INFO): Inst Velocity X:3.000000
2019-09-06T22:11:06.536Z,1567807866.536 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:11:07.337Z,1567807867.337 [Rowe_600](INFO): Inst Velocity X:3.000000
2019-09-06T22:11:07.337Z,1567807867.337 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:11:08.549Z,1567807868.549 [Rowe_600](INFO): Inst Velocity X:4.000000
2019-09-06T22:11:08.549Z,1567807868.549 [Rowe_600](INFO): Inst Velocity Y:7.000000
2019-09-06T22:11:09.350Z,1567807869.350 [Rowe_600](INFO): Inst Velocity X:3.000000
2019-09-06T22:11:09.350Z,1567807869.350 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:11:10.568Z,1567807870.568 [Rowe_600](INFO): Inst Velocity X:3.000000
2019-09-06T22:11:10.568Z,1567807870.568 [Rowe_600](INFO): Inst Velocity Y:8.000000
2019-09-06T22:11:11.390Z,1567807871.390 [Rowe_600](INFO): Inst Velocity X:3.000000
2019-09-06T22:11:11.390Z,1567807871.390 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:11:12.607Z,1567807872.607 [Rowe_600](INFO): Inst Velocity X:2.000000
2019-09-06T22:11:12.607Z,1567807872.607 [Rowe_600](INFO): Inst Velocity Y:3.000000
2019-09-06T22:11:13.417Z,1567807873.417 [Rowe_600](INFO): Inst Velocity X:3.000000
2019-09-06T22:11:13.417Z,1567807873.417 [Rowe_600](INFO): Inst Velocity Y:7.000000
2019-09-06T22:11:14.628Z,1567807874.628 [Rowe_600](INFO): Inst Velocity X:4.000000
2019-09-06T22:11:14.628Z,1567807874.628 [Rowe_600](INFO): Inst Velocity Y:7.000000
2019-09-06T22:11:14.753Z,1567807874.753 [CommandLine](IMPORTANT): got command restart application
2019-09-06T22:11:15.414Z,1567807875.414 [Rowe_600](INFO): Inst Velocity X:5.000000
2019-09-06T22:11:15.414Z,1567807875.414 [Rowe_600](INFO): Inst Velocity Y:5.000000
2019-09-06T22:11:15.757Z,1567807875.757 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-09-06T22:11:15.757Z,1567807875.757 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-06T22:11:15.757Z,1567807875.757 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-09-06T22:11:15.813Z,1567807875.813 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-09-06T22:11:15.813Z,1567807875.813 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-09-06T22:11:15.814Z,1567807875.814 [CommandLine](INFO): Join timeout helper Thread ID is 3103
2019-09-06T22:11:15.827Z,1567807875.827 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-09-06T22:11:15.827Z,1567807875.827 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-09-06T22:11:15.828Z,1567807875.828 [NavChartDb](INFO): Join timeout helper Thread ID is 3104
2019-09-06T22:11:16.169Z,1567807876.169 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-06T22:11:16.169Z,1567807876.169 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-09-06T22:11:16.189Z,1567807876.189 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-09-06T22:11:16.189Z,1567807876.189 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-09-06T22:11:16.189Z,1567807876.189 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3105
2019-09-06T22:11:16.557Z,1567807876.557 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-06T22:11:16.557Z,1567807876.557 [WetLabsBB2FL](INFO): Powering down
2019-09-06T22:11:16.558Z,1567807876.558 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-09-06T22:11:16.573Z,1567807876.573 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-09-06T22:11:16.573Z,1567807876.573 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-09-06T22:11:16.574Z,1567807876.574 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3106
2019-09-06T22:11:16.626Z,1567807876.626 [Rowe_600](INFO): Inst Velocity X:3.000000
2019-09-06T22:11:16.626Z,1567807876.626 [Rowe_600](INFO): Inst Velocity Y:4.000000
2019-09-06T22:11:16.749Z,1567807876.749 [CTD_Seabird](INFO): Powering down
2019-09-06T22:11:16.761Z,1567807876.761 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-06T22:11:16.761Z,1567807876.761 [CTD_Seabird](INFO): Powering down
2019-09-06T22:11:16.777Z,1567807876.777 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-09-06T22:11:16.785Z,1567807876.785 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-09-06T22:11:16.785Z,1567807876.785 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-09-06T22:11:16.785Z,1567807876.785 [Radio_Surface](INFO): Join timeout helper Thread ID is 3107
2019-09-06T22:11:16.897Z,1567807876.897 [Radio_Surface](INFO): Powering down
2019-09-06T22:11:16.898Z,1567807876.898 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-06T22:11:16.898Z,1567807876.898 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-09-06T22:11:16.906Z,1567807876.906 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-09-06T22:11:16.906Z,1567807876.906 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-09-06T22:11:16.906Z,1567807876.906 [DataOverHttps](INFO): Join timeout helper Thread ID is 3108
2019-09-06T22:11:17.049Z,1567807877.049 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-06T22:11:17.049Z,1567807877.049 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-09-06T22:11:17.053Z,1567807877.053 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-09-06T22:11:17.054Z,1567807877.054 [logger ThreadHandler](INFO): Thread cancelled.
2019-09-06T22:11:17.054Z,1567807877.054 [logger](INFO): Join timeout helper Thread ID is 3109
2019-09-06T22:11:17.085Z,1567807877.085 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-06T22:11:17.085Z,1567807877.085 [logger ThreadHandler](INFO): Thread cancelled.
2019-09-06T22:11:17.086Z,1567807877.086 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-09-06T22:11:17.086Z,1567807877.086 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-09-06T22:11:17.086Z,1567807877.086 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-09-06T22:11:17.087Z,1567807877.087 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-09-06T22:11:17.087Z,1567807877.087 [controlThread](INFO): Join timeout helper Thread ID is 3110
2019-09-06T22:11:17.112Z,1567807877.112 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-06T22:11:17.113Z,1567807877.113 [controlThread](DEBUG): Uninitializing ControlThread
2019-09-06T22:11:17.113Z,1567807877.113 [AHRS_M2](INFO): Powering down
2019-09-06T22:11:17.185Z,1567807877.185 [NAL9602](INFO): Powering down
2019-09-06T22:11:17.257Z,1567807877.257 [Rowe_600](INFO): Powering down
2019-09-06T22:11:17.259Z,1567807877.259 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-09-06T22:11:17.259Z,1567807877.259 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-09-06T22:11:17.260Z,1567807877.260 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-09-06T22:11:17.260Z,1567807877.260 [MissionManager](INFO): Uninitializing Mission Default
2019-09-06T22:11:17.261Z,1567807877.261 [Default] Stopped
2019-09-06T22:11:17.261Z,1567807877.261 [Default](DEBUG): Aggregate::uninitialize Default
2019-09-06T22:11:17.261Z,1567807877.261 [Default:B.GoToSurface] Stopped
2019-09-06T22:11:17.261Z,1567807877.261 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-09-06T22:11:17.261Z,1567807877.261 [Default:CheckIn] Stopped
2019-09-06T22:11:17.261Z,1567807877.261 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-09-06T22:11:17.261Z,1567807877.261 [Default:CheckIn:Read_GPS] Stopped
2019-09-06T22:11:17.263Z,1567807877.263 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-09-06T22:11:17.264Z,1567807877.264 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-09-06T22:11:17.264Z,1567807877.264 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-09-06T22:11:17.264Z,1567807877.264 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-09-06T22:11:17.264Z,1567807877.264 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-09-06T22:11:17.265Z,1567807877.265 [BuoyancyServo](INFO): Powering down
2019-09-06T22:11:17.277Z,1567807877.277 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-09-06T22:11:17.277Z,1567807877.277 [ElevatorServo](INFO): Powering down
2019-09-06T22:11:17.278Z,1567807877.278 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-09-06T22:11:17.278Z,1567807877.278 [MassServo](INFO): Powering down
2019-09-06T22:11:17.279Z,1567807877.279 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-09-06T22:11:17.279Z,1567807877.279 [RudderServo](INFO): Powering down
2019-09-06T22:11:17.279Z,1567807877.279 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-09-06T22:11:17.280Z,1567807877.280 [ThrusterServo](INFO): Powering down
2019-09-06T22:11:17.280Z,1567807877.280 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-09-06T22:11:17.281Z,1567807877.281 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-09-06T22:11:17.281Z,1567807877.281 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-09-06T22:11:17.281Z,1567807877.281 [CBIT](DEBUG): Powering off loads.
2019-09-06T22:11:17.293Z,1567807877.293 [CBIT](DEBUG): Disabling WDT.
2019-09-06T22:11:17.305Z,1567807877.305 [CBIT](DEBUG): Opening all GF detection circuits.
2019-09-06T22:11:17.305Z,1567807877.305 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-09-06T22:11:17.388Z,1567807877.388 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-09-06T22:11:17.394Z,1567807877.394 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-09-06T22:11:17.448Z,1567807877.448 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-09-06T22:11:17.450Z,1567807877.450 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-09-06T22:11:17.460Z,1567807877.460 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-09-06T22:11:17.515Z,1567807877.515 [logger ThreadHandler](INFO): Thread cancelled.