2019-08-19T03:42:17.496Z,1566186137.496 [Supervisor](DEBUG): Initializing supervisor.
2019-08-19T03:42:17.499Z,1566186137.499 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-08-19T03:42:17.500Z,1566186137.500 [SyncHandler](INFO): Protected caller Thread ID is 788
2019-08-19T03:42:17.500Z,1566186137.500 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-08-19T03:42:17.501Z,1566186137.501 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-08-19T03:42:17.501Z,1566186137.501 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 789
2019-08-19T03:42:17.504Z,1566186137.504 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-08-19T03:42:17.516Z,1566186137.516 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-08-19T03:42:17.517Z,1566186137.517 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-08-19T03:42:17.517Z,1566186137.517 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 790
2019-08-19T03:42:17.518Z,1566186137.518 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-08-19T03:42:17.519Z,1566186137.519 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-08-19T03:42:17.519Z,1566186137.519 [logger ThreadHandler](INFO): Protected caller Thread ID is 791
2019-08-19T03:42:17.521Z,1566186137.521 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-08-19T03:42:17.522Z,1566186137.522 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-08-19T03:42:17.523Z,1566186137.523 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-08-19T03:42:17.683Z,1566186137.683 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-08-19T03:42:17.684Z,1566186137.684 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-08-19T03:42:17.887Z,1566186137.887 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-08-19T03:42:17.888Z,1566186137.888 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-08-19T03:42:18.026Z,1566186138.026 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-08-19T03:42:18.027Z,1566186138.027 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-08-19T03:42:18.594Z,1566186138.594 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-08-19T03:42:18.595Z,1566186138.595 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-08-19T03:42:19.043Z,1566186139.043 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-08-19T03:42:19.044Z,1566186139.044 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-08-19T03:42:19.849Z,1566186139.849 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-08-19T03:42:19.850Z,1566186139.850 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-08-19T03:42:20.158Z,1566186140.158 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-08-19T03:42:20.159Z,1566186140.159 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-08-19T03:42:20.495Z,1566186140.495 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-08-19T03:42:20.495Z,1566186140.495 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-08-19T03:42:20.893Z,1566186140.893 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-08-19T03:42:20.894Z,1566186140.894 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-08-19T03:42:21.045Z,1566186141.045 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-08-19T03:42:21.045Z,1566186141.045 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-08-19T03:42:21.151Z,1566186141.151 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-08-19T03:42:21.152Z,1566186141.152 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-08-19T03:42:21.234Z,1566186141.234 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-08-19T03:42:21.336Z,1566186141.336 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-08-19T03:42:21.337Z,1566186141.337 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-08-19T03:42:21.535Z,1566186141.535 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-08-19T03:42:21.536Z,1566186141.536 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-08-19T03:42:21.744Z,1566186141.744 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-08-19T03:42:21.746Z,1566186141.746 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-08-19T03:42:21.747Z,1566186141.747 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-08-19T03:42:21.832Z,1566186141.832 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-08-19T03:42:22.064Z,1566186142.064 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-08-19T03:42:22.065Z,1566186142.065 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-08-19T03:42:22.162Z,1566186142.162 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-08-19T03:42:22.335Z,1566186142.335 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-08-19T03:42:22.548Z,1566186142.548 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-08-19T03:42:22.635Z,1566186142.635 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-08-19T03:42:22.739Z,1566186142.739 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-08-19T03:42:22.835Z,1566186142.835 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-08-19T03:42:22.996Z,1566186142.996 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-08-19T03:42:23.105Z,1566186143.105 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-08-19T03:42:23.205Z,1566186143.205 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-08-19T03:42:23.220Z,1566186143.220 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-08-19T03:42:23.319Z,1566186143.319 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-08-19T03:42:23.320Z,1566186143.320 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-08-19T03:42:23.451Z,1566186143.451 [BuoyancyServo] Loaded
2019-08-19T03:42:23.451Z,1566186143.451 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-08-19T03:42:23.466Z,1566186143.466 [ElevatorServo] Loaded
2019-08-19T03:42:23.466Z,1566186143.466 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-08-19T03:42:23.481Z,1566186143.481 [MassServo] Loaded
2019-08-19T03:42:23.481Z,1566186143.481 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-08-19T03:42:23.496Z,1566186143.496 [RudderServo] Loaded
2019-08-19T03:42:23.496Z,1566186143.496 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-08-19T03:42:23.511Z,1566186143.511 [ThrusterServo] Loaded
2019-08-19T03:42:23.511Z,1566186143.511 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-08-19T03:42:23.511Z,1566186143.511 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-08-19T03:42:23.512Z,1566186143.512 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-08-19T03:42:23.646Z,1566186143.646 [SBIT](DEBUG): Construct Startup Built In Test.
2019-08-19T03:42:23.676Z,1566186143.676 [SBIT] Loaded
2019-08-19T03:42:23.676Z,1566186143.676 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-08-19T03:42:23.677Z,1566186143.677 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-08-19T03:42:23.704Z,1566186143.704 [IBIT] Loaded
2019-08-19T03:42:23.705Z,1566186143.705 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-08-19T03:42:23.708Z,1566186143.708 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-08-19T03:42:24.097Z,1566186144.097 [CBIT] Loaded
2019-08-19T03:42:24.098Z,1566186144.098 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-08-19T03:42:24.098Z,1566186144.098 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-08-19T03:42:24.099Z,1566186144.099 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-08-19T03:42:24.828Z,1566186144.828 [Aanderaa_O2] Loaded
2019-08-19T03:42:24.844Z,1566186144.844 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-08-19T03:42:24.886Z,1566186144.886 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-08-19T03:42:24.891Z,1566186144.891 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-08-19T03:42:24.911Z,1566186144.911 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-08-19T03:42:24.978Z,1566186144.978 [CTD_Seabird](INFO): created writer for : depth
2019-08-19T03:42:24.978Z,1566186144.978 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-08-19T03:42:25.013Z,1566186145.013 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-08-19T03:42:25.014Z,1566186145.014 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-08-19T03:42:25.063Z,1566186145.063 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-08-19T03:42:25.064Z,1566186145.064 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-08-19T03:42:25.105Z,1566186145.105 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-08-19T03:42:25.105Z,1566186145.105 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-08-19T03:42:25.111Z,1566186145.111 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-08-19T03:42:25.111Z,1566186145.111 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-08-19T03:42:25.128Z,1566186145.128 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-08-19T03:42:25.157Z,1566186145.157 [CTD_Seabird] Loaded
2019-08-19T03:42:25.157Z,1566186145.157 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-08-19T03:42:25.159Z,1566186145.159 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4066B4E0
2019-08-19T03:42:25.159Z,1566186145.159 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 874
2019-08-19T03:42:25.190Z,1566186145.190 [ESPComponent] Loaded
2019-08-19T03:42:25.190Z,1566186145.190 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-08-19T03:42:25.204Z,1566186145.204 [PAR_Licor] Loaded
2019-08-19T03:42:25.204Z,1566186145.204 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-08-19T03:42:25.252Z,1566186145.252 [WetLabsBB2FL] Loaded
2019-08-19T03:42:25.252Z,1566186145.252 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-08-19T03:42:25.253Z,1566186145.253 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4069B4E0
2019-08-19T03:42:25.253Z,1566186145.253 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 875
2019-08-19T03:42:25.254Z,1566186145.254 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-08-19T03:42:25.255Z,1566186145.255 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-08-19T03:42:25.323Z,1566186145.323 [DepthRateCalculator] Loaded
2019-08-19T03:42:25.323Z,1566186145.323 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-08-19T03:42:25.329Z,1566186145.329 [PitchRateCalculator] Loaded
2019-08-19T03:42:25.329Z,1566186145.329 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-08-19T03:42:25.345Z,1566186145.345 [SpeedCalculator] Loaded
2019-08-19T03:42:25.345Z,1566186145.345 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-08-19T03:42:25.367Z,1566186145.367 [TempGradientCalculator] Loaded
2019-08-19T03:42:25.367Z,1566186145.367 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-08-19T03:42:25.373Z,1566186145.373 [YawRateCalculator] Loaded
2019-08-19T03:42:25.373Z,1566186145.373 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-08-19T03:42:25.406Z,1566186145.406 [ElevatorOffsetCalculator] Loaded
2019-08-19T03:42:25.406Z,1566186145.406 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-08-19T03:42:25.407Z,1566186145.407 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-08-19T03:42:25.407Z,1566186145.407 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-08-19T03:42:25.439Z,1566186145.439 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-08-19T03:42:25.439Z,1566186145.439 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-08-19T03:42:25.542Z,1566186145.542 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-08-19T03:42:25.543Z,1566186145.543 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-08-19T03:42:25.564Z,1566186145.564 [NavChart] Loaded
2019-08-19T03:42:25.564Z,1566186145.564 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-08-19T03:42:25.568Z,1566186145.568 [UniversalFixResidualReporter] Loaded
2019-08-19T03:42:25.568Z,1566186145.568 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-08-19T03:42:25.569Z,1566186145.569 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-08-19T03:42:25.570Z,1566186145.570 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-08-19T03:42:25.901Z,1566186145.901 [DataOverHttps] Loaded
2019-08-19T03:42:25.901Z,1566186145.901 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-08-19T03:42:25.902Z,1566186145.902 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409744E0
2019-08-19T03:42:25.903Z,1566186145.903 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 876
2019-08-19T03:42:25.916Z,1566186145.916 [Depth_Keller] Loaded
2019-08-19T03:42:25.916Z,1566186145.916 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-08-19T03:42:25.921Z,1566186145.921 [DropWeight] Loaded
2019-08-19T03:42:25.921Z,1566186145.921 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-08-19T03:42:26.013Z,1566186146.013 [NAL9602] Loaded
2019-08-19T03:42:26.013Z,1566186146.013 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-08-19T03:42:26.019Z,1566186146.019 [Onboard] Loaded
2019-08-19T03:42:26.019Z,1566186146.019 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-08-19T03:42:26.022Z,1566186146.022 [Radio_Surface] Loaded
2019-08-19T03:42:26.022Z,1566186146.022 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-08-19T03:42:26.023Z,1566186146.023 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A44E0
2019-08-19T03:42:26.024Z,1566186146.024 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 877
2019-08-19T03:42:26.151Z,1566186146.151 [DAT] Loaded
2019-08-19T03:42:26.152Z,1566186146.152 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2019-08-19T03:42:27.434Z,1566186147.434 [BPC1] Loaded
2019-08-19T03:42:27.434Z,1566186147.434 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-08-19T03:42:27.435Z,1566186147.435 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-08-19T03:42:27.435Z,1566186147.435 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-08-19T03:42:27.506Z,1566186147.506 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-08-19T03:42:27.507Z,1566186147.507 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-08-19T03:42:27.573Z,1566186147.573 [VerticalControl](DEBUG): Construct VerticalControl.
2019-08-19T03:42:27.658Z,1566186147.658 [VerticalControl] Loaded
2019-08-19T03:42:27.658Z,1566186147.658 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-08-19T03:42:27.659Z,1566186147.659 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-08-19T03:42:27.716Z,1566186147.716 [HorizontalControl] Loaded
2019-08-19T03:42:27.716Z,1566186147.716 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-08-19T03:42:27.717Z,1566186147.717 [SpeedControl](DEBUG): Construct SpeedControl.
2019-08-19T03:42:27.719Z,1566186147.719 [SpeedControl] Loaded
2019-08-19T03:42:27.719Z,1566186147.719 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-08-19T03:42:27.720Z,1566186147.720 [LoopControl](DEBUG): Construct LoopControl.
2019-08-19T03:42:27.720Z,1566186147.720 [LoopControl] Loaded
2019-08-19T03:42:27.720Z,1566186147.720 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-08-19T03:42:27.721Z,1566186147.721 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-08-19T03:42:27.721Z,1566186147.721 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-08-19T03:42:27.735Z,1566186147.735 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-08-19T03:42:27.735Z,1566186147.735 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-08-19T03:42:28.027Z,1566186148.027 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-08-19T03:42:28.030Z,1566186148.030 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-08-19T03:42:28.031Z,1566186148.031 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-08-19T03:42:28.038Z,1566186148.038 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-08-19T03:42:28.039Z,1566186148.039 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ADF4E0
2019-08-19T03:42:28.039Z,1566186148.039 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 878
2019-08-19T03:42:28.044Z,1566186148.044 [Supervisor](INFO): Main Thread ID is 802
2019-08-19T03:42:28.044Z,1566186148.044 [Supervisor](DEBUG): Running supervisor.
2019-08-19T03:42:28.045Z,1566186148.045 [CommandLine ThreadHandler](INFO): Handler Thread ID is 879
2019-08-19T03:42:28.047Z,1566186148.047 [controlThread ThreadHandler](INFO): Handler Thread ID is 880
2019-08-19T03:42:28.048Z,1566186148.048 [controlThread](DEBUG): Initializing ControlThread
2019-08-19T03:42:28.049Z,1566186148.049 [SBIT](INFO): Initialize SBIT Component.
2019-08-19T03:42:28.050Z,1566186148.050 [SBIT](IMPORTANT): git: 2019-08-13
2019-08-19T03:42:28.050Z,1566186148.050 [SBIT](INFO): git hash: 6b080875362fb71081cecd990260e30fa66d1cc0
2019-08-19T03:42:28.051Z,1566186148.051 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-08-19T03:42:28.052Z,1566186148.052 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-08-19T03:42:28.053Z,1566186148.053 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-08-19T03:42:28.054Z,1566186148.054 [IBIT](INFO): Initialize IBIT Component.
2019-08-19T03:42:28.054Z,1566186148.054 [CBIT](DEBUG): Initialize CBIT Component.
2019-08-19T03:42:28.055Z,1566186148.055 [logger ThreadHandler](INFO): Handler Thread ID is 881
2019-08-19T03:42:28.069Z,1566186148.069 [CBIT](DEBUG): Initialized mux pins.
2019-08-19T03:42:28.069Z,1566186148.069 [CBIT](DEBUG): Initializing the watchdog timer.
2019-08-19T03:42:28.081Z,1566186148.081 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 882
2019-08-19T03:42:28.082Z,1566186148.082 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-08-19T03:42:28.085Z,1566186148.085 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-08-19T03:42:28.087Z,1566186148.087 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 884
2019-08-19T03:42:28.088Z,1566186148.088 [WetLabsBB2FL](INFO): Powering down
2019-08-19T03:42:28.093Z,1566186148.093 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-08-19T03:42:28.093Z,1566186148.093 [CBIT](DEBUG): Initializing heartbeat.
2019-08-19T03:42:28.117Z,1566186148.117 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 885
2019-08-19T03:42:28.118Z,1566186148.118 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-08-19T03:42:28.129Z,1566186148.129 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 886
2019-08-19T03:42:28.141Z,1566186148.141 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 887
2019-08-19T03:42:28.147Z,1566186148.147 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-08-19T03:42:28.147Z,1566186148.147 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-08-19T03:42:28.148Z,1566186148.148 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-08-19T03:42:28.148Z,1566186148.148 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-08-19T03:42:28.148Z,1566186148.148 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-08-19T03:42:28.148Z,1566186148.148 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-08-19T03:42:28.148Z,1566186148.148 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-08-19T03:42:28.148Z,1566186148.148 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-08-19T03:42:28.149Z,1566186148.149 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-08-19T03:42:28.149Z,1566186148.149 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-08-19T03:42:28.149Z,1566186148.149 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-08-19T03:42:28.149Z,1566186148.149 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-08-19T03:42:28.150Z,1566186148.150 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-08-19T03:42:28.150Z,1566186148.150 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-08-19T03:42:28.150Z,1566186148.150 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-08-19T03:42:28.150Z,1566186148.150 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-08-19T03:42:28.165Z,1566186148.165 [CBIT](DEBUG): Deactivating GF circuits.
2019-08-19T03:42:28.165Z,1566186148.165 [CBIT](DEBUG): Deactivating emergency mode.
2019-08-19T03:42:28.201Z,1566186148.201 [CBIT](DEBUG): Backplane powered.
2019-08-19T03:42:28.205Z,1566186148.205 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-08-19T03:42:28.206Z,1566186148.206 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-08-19T03:42:28.206Z,1566186148.206 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-08-19T03:42:28.206Z,1566186148.206 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-08-19T03:42:28.207Z,1566186148.207 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-08-19T03:42:28.207Z,1566186148.207 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-08-19T03:42:28.208Z,1566186148.208 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-08-19T03:42:28.208Z,1566186148.208 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-08-19T03:42:28.215Z,1566186148.215 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-08-19T03:42:28.221Z,1566186148.221 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-08-19T03:42:28.222Z,1566186148.222 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-08-19T03:42:28.222Z,1566186148.222 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-08-19T03:42:28.223Z,1566186148.223 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-08-19T03:42:28.247Z,1566186148.247 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-08-19T03:42:28.282Z,1566186148.282 [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-08-19T03:42:28.283Z,1566186148.283 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-08-19T03:42:28.348Z,1566186148.348 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-08-19T03:42:28.349Z,1566186148.349 [Default:A.Wait](DEBUG): Construct Wait.
2019-08-19T03:42:28.351Z,1566186148.351 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-08-19T03:42:28.410Z,1566186148.410 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-08-19T03:42:28.412Z,1566186148.412 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-08-19T03:42:28.434Z,1566186148.434 [Default:E.Execute](DEBUG): Construct Execute.
2019-08-19T03:42:28.437Z,1566186148.437 [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-08-19T03:42:28.442Z,1566186148.442 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,DropWeight,NAL9602,Onboard,DAT,BPC1,PAR_Licor,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-08-19T03:42:28.533Z,1566186148.533 [Radio_Surface](INFO): Powering up
2019-08-19T03:42:28.592Z,1566186148.592 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2019-08-19T03:42:28.592Z,1566186148.592 [DAT](INFO): Powering up
2019-08-19T03:42:28.593Z,1566186148.593 [DAT](DEBUG): Initializing DAT.
2019-08-19T03:42:28.645Z,1566186148.645 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2019-08-19T03:42:28.699Z,1566186148.699 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-08-19T03:42:28.709Z,1566186148.709 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-08-19T03:42:28.710Z,1566186148.710 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-08-19T03:42:28.721Z,1566186148.721 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-08-19T03:42:28.722Z,1566186148.722 [MassServo](DEBUG): Initializing EZServoServo.
2019-08-19T03:42:28.733Z,1566186148.733 [MassServo](DEBUG): Initializing MassServo.
2019-08-19T03:42:28.734Z,1566186148.734 [RudderServo](DEBUG): Initializing EZServoServo.
2019-08-19T03:42:28.745Z,1566186148.745 [RudderServo](DEBUG): Initializing RudderServo.
2019-08-19T03:42:28.746Z,1566186148.746 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-08-19T03:42:28.757Z,1566186148.757 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-08-19T03:42:29.613Z,1566186149.613 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-08-19T03:42:29.613Z,1566186149.613 [RudderServo](FAULT): Rudder failed to initialize
2019-08-19T03:42:29.613Z,1566186149.613 [RudderServo] Communications Fault, FailCount= 1
2019-08-19T03:42:29.613Z,1566186149.613 [RudderServo](ERROR): Communications Fault
2019-08-19T03:42:29.725Z,1566186149.725 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-08-19T03:42:29.946Z,1566186149.946 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-08-19T03:42:29.946Z,1566186149.946 [RudderServo](INFO): Powering down
2019-08-19T03:42:30.592Z,1566186150.592 [RudderServo](DEBUG): Initializing EZServoServo.
2019-08-19T03:42:30.726Z,1566186150.726 [RudderServo](DEBUG): Initializing RudderServo.
2019-08-19T03:42:30.730Z,1566186150.730 [CBIT](INFO): Clearing failed state for component RudderServo
2019-08-19T03:42:30.730Z,1566186150.730 [RudderServo] No Fault, FailCount= 1
2019-08-19T03:42:31.869Z,1566186151.869 [Aanderaa_O2](INFO): Powering down
2019-08-19T03:42:43.894Z,1566186163.894 [DAT](INFO): setting local address to 3
2019-08-19T03:42:44.298Z,1566186164.298 [DAT](INFO): set local address to 3
2019-08-19T03:42:52.811Z,1566186172.811 [SBIT](IMPORTANT): Beginning Startup BIT
2019-08-19T03:42:52.815Z,1566186172.815 [CBIT](IMPORTANT): Beginning ground fault scan
2019-08-19T03:42:56.090Z,1566186176.090 [NAL9602](INFO): Powering up NAL9602
2019-08-19T03:43:03.854Z,1566186183.854 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.002479
CHAN A1 (24V): -0.005583
CHAN A2 (12V): 0.000042
CHAN A3 (5V): -0.003005
CHAN B0 (3.3V): -0.002423
CHAN B1 (3.15aV): -0.001940
CHAN B2 (3.15bV): -0.001621
CHAN B3 (GND): -0.003016
OPEN: 0.007048
Full Scale Calc: 4.765 mA, -1.589 mA
2019-08-19T03:43:06.998Z,1566186186.998 [NAL9602](INFO): NAL9602 initialized
2019-08-19T03:43:07.813Z,1566186187.813 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:43:46.454Z,1566186226.454 [SBIT](IMPORTANT): SBIT PASSED
2019-08-19T03:43:46.474Z,1566186226.474 [CommandLine](IMPORTANT): got command configSet list
2019-08-19T03:43:46.474Z,1566186226.474 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-08-19T03:43:46.475Z,1566186226.475 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-08-19T03:43:46.475Z,1566186226.475 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2019-08-19T03:43:46.475Z,1566186226.475 [CommandLine](IMPORTANT): BuoyancyServo.fastPumpDepth=6 meter;

2019-08-19T03:43:46.475Z,1566186226.475 [CommandLine](IMPORTANT): CBIT.stopDepth=10 meter;

2019-08-19T03:43:46.475Z,1566186226.475 [CommandLine](IMPORTANT): CTD_Seabird.minSalinityBound=0.01 practical_salinity_unit;

2019-08-19T03:43:46.476Z,1566186226.476 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2019-08-19T03:43:46.476Z,1566186226.476 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=1 bool;

2019-08-19T03:43:46.476Z,1566186226.476 [CommandLine](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;

2019-08-19T03:43:46.476Z,1566186226.476 [CommandLine](IMPORTANT): Express linearApproximation CTD_Seabird.sea_water_electrical_conductivity 0.001000 siemens_per_meter;

2019-08-19T03:43:46.476Z,1566186226.476 [CommandLine](IMPORTANT): Express linearApproximation mass_concentration_of_oxygen_in_sea_water 750.000000 microgram_per_liter;

2019-08-19T03:43:46.476Z,1566186226.476 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=475 cubic_centimeter;

2019-08-19T03:43:46.476Z,1566186226.476 [CommandLine](IMPORTANT): VerticalControl.elevDeadband=0.1 degree;

2019-08-19T03:43:46.476Z,1566186226.476 [CommandLine](IMPORTANT): VerticalControl.kiDepthBuoy=0.3 reciprocal_second;

2019-08-19T03:43:46.476Z,1566186226.476 [CommandLine](IMPORTANT): VerticalControl.kiPitchMass=0.001 reciprocal_second;

2019-08-19T03:43:46.477Z,1566186226.477 [CommandLine](IMPORTANT): VerticalControl.massDeadband=0.25 millimeter;

2019-08-19T03:43:46.477Z,1566186226.477 [CommandLine](IMPORTANT): VerticalControl.massDefault=1 millimeter;

2019-08-19T03:43:46.853Z,1566186226.853 [MissionManager](IMPORTANT): Started mission Startup
2019-08-19T03:43:46.854Z,1566186226.854 [Startup] Running Loop=1
2019-08-19T03:43:46.854Z,1566186226.854 [Startup](DEBUG): Aggregate::initialize Startup
2019-08-19T03:43:46.854Z,1566186226.854 [Startup:A.GoToSurface] Running Loop=1
2019-08-19T03:43:46.854Z,1566186226.854 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-08-19T03:43:46.855Z,1566186226.855 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-08-19T03:43:46.855Z,1566186226.855 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-08-19T03:43:46.855Z,1566186226.855 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-08-19T03:43:46.856Z,1566186226.856 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-08-19T03:43:46.856Z,1566186226.856 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-08-19T03:43:46.857Z,1566186226.857 [Startup:StartupSatComms] Running Loop=1
2019-08-19T03:43:46.857Z,1566186226.857 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-08-19T03:43:46.858Z,1566186226.858 [Startup:StartupSatComms:A] Running Loop=1
2019-08-19T03:43:47.262Z,1566186227.262 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-08-19T03:44:28.472Z,1566186268.472 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-19T03:44:28.472Z,1566186268.472 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-08-19T03:44:28.472Z,1566186268.472 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-19T03:44:28.483Z,1566186268.483 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-19T03:44:28.937Z,1566186268.937 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-19T03:44:28.937Z,1566186268.937 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-08-19T03:44:47.031Z,1566186287.031 [Startup:StartupSatComms:A](INFO): Timed out from 2019-08-19T03:43:46.9Z
2019-08-19T03:44:47.031Z,1566186287.031 [Startup:StartupSatComms:A] Stopped
2019-08-19T03:44:47.031Z,1566186287.031 [Startup:StartupSatComms:B] Running Loop=1
2019-08-19T03:44:47.439Z,1566186287.439 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-08-19T03:45:47.249Z,1566186347.249 [Startup:StartupSatComms:B](INFO): Timed out from 2019-08-19T03:44:47.0Z
2019-08-19T03:45:47.250Z,1566186347.250 [Startup:StartupSatComms:B] Stopped
2019-08-19T03:45:47.250Z,1566186347.250 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-08-19T03:45:47.250Z,1566186347.250 [Startup:StartupSatComms] Stopped
2019-08-19T03:45:47.250Z,1566186347.250 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-08-19T03:45:47.251Z,1566186347.251 [Startup](INFO): Completed Startup
2019-08-19T03:45:47.251Z,1566186347.251 [MissionManager](INFO): Startup is completed.
2019-08-19T03:45:47.251Z,1566186347.251 [MissionManager](INFO): Uninitializing Mission Startup
2019-08-19T03:45:47.251Z,1566186347.251 [Startup] Stopped
2019-08-19T03:45:47.251Z,1566186347.251 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-08-19T03:45:47.251Z,1566186347.251 [Startup:A.GoToSurface] Stopped
2019-08-19T03:45:47.251Z,1566186347.251 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-08-19T03:45:47.653Z,1566186347.653 [MissionManager](IMPORTANT): Started mission Default
2019-08-19T03:45:47.654Z,1566186347.654 [Default] Running Loop=1
2019-08-19T03:45:47.654Z,1566186347.654 [Default](DEBUG): Aggregate::initialize Default
2019-08-19T03:45:47.654Z,1566186347.654 [Default:B.GoToSurface] Running Loop=1
2019-08-19T03:45:47.654Z,1566186347.654 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-08-19T03:45:47.654Z,1566186347.654 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-08-19T03:45:47.654Z,1566186347.654 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-08-19T03:45:47.655Z,1566186347.655 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-08-19T03:45:47.655Z,1566186347.655 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-08-19T03:45:47.655Z,1566186347.655 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-08-19T03:45:47.656Z,1566186347.656 [Default:A.Wait] Running Loop=1
2019-08-19T03:45:47.656Z,1566186347.656 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-08-19T03:46:00.969Z,1566186360.969 [Default:A.Wait](INFO): Done Waiting.
2019-08-19T03:46:00.969Z,1566186360.969 [Default:A.Wait] Stopped
2019-08-19T03:46:00.969Z,1566186360.969 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-08-19T03:46:01.377Z,1566186361.377 [Default:CheckIn] Running Loop=1
2019-08-19T03:46:01.377Z,1566186361.377 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-19T03:46:01.377Z,1566186361.377 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-19T03:46:01.772Z,1566186361.772 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-08-19T03:46:29.368Z,1566186389.368 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-19T03:46:29.368Z,1566186389.368 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-08-19T03:46:29.368Z,1566186389.368 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-19T03:46:29.402Z,1566186389.402 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-19T03:46:29.709Z,1566186389.709 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-19T03:46:29.709Z,1566186389.709 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-08-19T03:46:31.087Z,1566186391.087 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005011
2019-08-19T03:48:10.223Z,1566186490.223 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-08-19T03:48:11.045Z,1566186491.045 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:48:15.893Z,1566186495.893 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:48:18.721Z,1566186498.721 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:48:21.953Z,1566186501.953 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:48:24.781Z,1566186504.781 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:48:28.053Z,1566186508.053 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:48:30.080Z,1566186510.080 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-19T03:48:30.080Z,1566186510.080 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-08-19T03:48:30.089Z,1566186510.089 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-19T03:48:30.104Z,1566186510.104 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-19T03:48:30.489Z,1566186510.489 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-19T03:48:30.489Z,1566186510.489 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-08-19T03:48:30.857Z,1566186510.857 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:48:33.685Z,1566186513.685 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:48:36.913Z,1566186516.913 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:48:39.745Z,1566186519.745 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:48:42.977Z,1566186522.977 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:48:45.805Z,1566186525.805 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:48:49.037Z,1566186529.037 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:48:51.865Z,1566186531.865 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:48:54.693Z,1566186534.693 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:48:56.713Z,1566186536.713 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:48:58.733Z,1566186538.733 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:49:01.965Z,1566186541.965 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:49:04.793Z,1566186544.793 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:49:08.041Z,1566186548.041 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:49:10.853Z,1566186550.853 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:49:14.085Z,1566186554.085 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:49:16.913Z,1566186556.913 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:49:19.741Z,1566186559.741 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:49:21.761Z,1566186561.761 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:49:24.989Z,1566186564.989 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:49:27.821Z,1566186567.821 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:49:31.049Z,1566186571.049 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:49:33.877Z,1566186573.877 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:49:37.137Z,1566186577.137 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:49:39.945Z,1566186579.945 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:49:42.777Z,1566186582.777 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:49:44.797Z,1566186584.797 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:49:48.029Z,1566186588.029 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:49:50.857Z,1566186590.857 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:49:54.089Z,1566186594.089 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:49:56.917Z,1566186596.917 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:50:00.149Z,1566186600.149 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:50:02.977Z,1566186602.977 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:50:05.805Z,1566186605.805 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:50:07.821Z,1566186607.821 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:50:09.841Z,1566186609.841 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:50:09.864Z,1566186609.864 [BPC1](ERROR): Battery stick #38 (s/n: 0139) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-08-19T03:50:09.865Z,1566186609.865 [BPC1](ERROR): Battery stick #39 (s/n: 00F0) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-08-19T03:50:09.865Z,1566186609.865 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2019-08-19T03:50:09.868Z,1566186609.868 [BPC1](INFO): Received data from all battery sticks.
2019-08-19T03:50:13.077Z,1566186613.077 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:50:15.901Z,1566186615.901 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:50:19.129Z,1566186619.129 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:50:21.965Z,1566186621.965 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:50:25.193Z,1566186625.193 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:50:28.021Z,1566186628.021 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:50:30.853Z,1566186630.853 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:50:31.288Z,1566186631.288 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-19T03:50:31.288Z,1566186631.288 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-08-19T03:50:31.288Z,1566186631.288 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-19T03:50:31.312Z,1566186631.312 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-19T03:50:31.684Z,1566186631.684 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-19T03:50:31.684Z,1566186631.684 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-08-19T03:50:32.873Z,1566186632.873 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:50:34.885Z,1566186634.885 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:50:38.121Z,1566186638.121 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:50:40.945Z,1566186640.945 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:50:44.185Z,1566186644.185 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:50:47.005Z,1566186647.005 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:50:50.245Z,1566186650.245 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:50:53.065Z,1566186653.065 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:50:55.901Z,1566186655.901 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:50:57.913Z,1566186657.913 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:51:01.153Z,1566186661.153 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:51:01.574Z,1566186661.574 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-08-19T03:46:01.4Z
2019-08-19T03:51:01.574Z,1566186661.574 [Default:CheckIn:Read_GPS] Stopped
2019-08-19T03:51:01.574Z,1566186661.574 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-19T03:51:01.980Z,1566186661.980 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-08-19T03:51:03.977Z,1566186663.977 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:51:06.980Z,1566186666.980 [DataOverHttps](INFO): Sending 69 bytes from file Logs/20190819T003049/Courier0057.lzma
2019-08-19T03:51:07.209Z,1566186667.209 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:51:07.787Z,1566186667.787 [DataOverHttps](INFO): Moved sent file to Logs/20190819T003049/Courier0057.lzma.bak
2019-08-19T03:51:07.787Z,1566186667.787 [DataOverHttps](INFO): SBD MOMSN=11590998
2019-08-19T03:51:10.037Z,1566186670.037 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:51:13.273Z,1566186673.273 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:51:16.097Z,1566186676.097 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:51:18.817Z,1566186678.817 [DataOverHttps](INFO): Sending 188 bytes from file Logs/20190819T034217/Courier0004.lzma
2019-08-19T03:51:18.925Z,1566186678.925 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:51:19.622Z,1566186679.622 [DataOverHttps](INFO): Moved sent file to Logs/20190819T034217/Courier0004.lzma.bak
2019-08-19T03:51:19.622Z,1566186679.622 [DataOverHttps](INFO): SBD MOMSN=11591001
2019-08-19T03:51:20.945Z,1566186680.945 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:51:24.177Z,1566186684.177 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:51:27.001Z,1566186687.001 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:51:30.402Z,1566186690.402 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:51:33.105Z,1566186693.105 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:51:35.933Z,1566186695.933 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:51:36.849Z,1566186696.849 [DataOverHttps](INFO): Sending 418 bytes from file Logs/20190819T003049/Express0058.lzma
2019-08-19T03:51:37.654Z,1566186697.654 [DataOverHttps](INFO): Moved sent file to Logs/20190819T003049/Express0058.lzma.bak
2019-08-19T03:51:37.655Z,1566186697.655 [DataOverHttps](INFO): SBD MOMSN=11591004
2019-08-19T03:51:37.949Z,1566186697.949 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:51:39.973Z,1566186699.973 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:51:42.001Z,1566186702.001 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:51:45.225Z,1566186705.225 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:51:48.053Z,1566186708.053 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:51:49.314Z,1566186709.314 [DataOverHttps](INFO): Sending 1130 bytes from file Logs/20190819T034217/Express0001.lzma
2019-08-19T03:51:50.522Z,1566186710.522 [DataOverHttps](INFO): Moved sent file to Logs/20190819T034217/Express0001.lzma.bak
2019-08-19T03:51:50.522Z,1566186710.522 [DataOverHttps](INFO): SBD MOMSN=11591014
2019-08-19T03:51:51.285Z,1566186711.285 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:51:54.121Z,1566186714.121 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:51:57.349Z,1566186717.349 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:52:00.173Z,1566186720.173 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:52:01.897Z,1566186721.897 [DataOverHttps](INFO): Sending 262 bytes from file Logs/20190819T034217/Express0005.lzma
2019-08-19T03:52:02.702Z,1566186722.702 [DataOverHttps](INFO): Moved sent file to Logs/20190819T034217/Express0005.lzma.bak
2019-08-19T03:52:02.703Z,1566186722.703 [DataOverHttps](INFO): SBD MOMSN=11591045
2019-08-19T03:52:03.005Z,1566186723.005 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:52:03.853Z,1566186723.853 [Default:CheckIn:Read_Iridium] Stopped
2019-08-19T03:52:03.853Z,1566186723.853 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-19T03:52:03.853Z,1566186723.853 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-19T03:52:05.021Z,1566186725.021 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:52:08.257Z,1566186728.257 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:52:11.081Z,1566186731.081 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:52:14.317Z,1566186734.317 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:52:17.145Z,1566186737.145 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:52:20.377Z,1566186740.377 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:52:23.205Z,1566186743.205 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:52:26.029Z,1566186746.029 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:52:28.049Z,1566186748.049 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:52:30.065Z,1566186750.065 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:52:32.098Z,1566186752.098 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-19T03:52:32.098Z,1566186752.098 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-08-19T03:52:32.098Z,1566186752.098 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-19T03:52:32.109Z,1566186752.109 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-19T03:52:32.512Z,1566186752.512 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-19T03:52:32.512Z,1566186752.512 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-08-19T03:52:33.305Z,1566186753.305 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:52:36.133Z,1566186756.133 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:52:39.365Z,1566186759.365 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:52:42.189Z,1566186762.189 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:52:45.425Z,1566186765.425 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:52:48.253Z,1566186768.253 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:52:51.081Z,1566186771.081 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:52:53.093Z,1566186773.093 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:52:56.333Z,1566186776.333 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:52:59.161Z,1566186779.161 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:53:02.393Z,1566186782.393 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:53:05.221Z,1566186785.221 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:53:08.439Z,1566186788.439 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-08-19T03:53:08.439Z,1566186788.439 [NAL9602] Data Fault, FailCount= 1
2019-08-19T03:53:08.439Z,1566186788.439 [NAL9602](ERROR): Data Fault
2019-08-19T03:53:08.468Z,1566186788.468 [CBIT](ERROR): Data Fault in component: NAL9602
2019-08-19T03:53:08.836Z,1566186788.836 [NAL9602](INFO): Powering down
2019-08-19T03:53:09.246Z,1566186789.246 [NAL9602](FAULT): LCB fault:  Current Limiter Activated.
2019-08-19T03:53:09.246Z,1566186789.246 [NAL9602] Hardware Fault, FailCount= 1
2019-08-19T03:53:09.246Z,1566186789.246 [NAL9602](ERROR): Hardware Fault
2019-08-19T03:53:09.682Z,1566186789.682 [CBIT](INFO): Clearing failed state for component NAL9602
2019-08-19T03:53:09.682Z,1566186789.682 [NAL9602] No Fault, FailCount= 1
2019-08-19T03:53:39.143Z,1566186819.143 [NAL9602](INFO): Powering up NAL9602
2019-08-19T03:53:59.747Z,1566186839.747 [NAL9602](ERROR): NAL9602 initialization error.
2019-08-19T03:53:59.747Z,1566186839.747 [NAL9602] Communications Fault, FailCount= 2
2019-08-19T03:53:59.748Z,1566186839.748 [NAL9602](ERROR): Communications Fault
2019-08-19T03:53:59.776Z,1566186839.776 [CBIT](ERROR): Communications Fault in component: NAL9602
2019-08-19T03:54:00.158Z,1566186840.158 [NAL9602](INFO): Powering down
2019-08-19T03:54:00.980Z,1566186840.980 [CBIT](INFO): Clearing failed state for component NAL9602
2019-08-19T03:54:00.980Z,1566186840.980 [NAL9602] No Fault, FailCount= 2
2019-08-19T03:54:30.455Z,1566186870.455 [NAL9602](INFO): Powering up NAL9602
2019-08-19T03:54:33.306Z,1566186873.306 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-19T03:54:33.306Z,1566186873.306 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-08-19T03:54:33.306Z,1566186873.306 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-19T03:54:33.317Z,1566186873.317 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-19T03:54:33.707Z,1566186873.707 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-19T03:54:33.707Z,1566186873.707 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-08-19T03:54:41.367Z,1566186881.367 [NAL9602](INFO): NAL9602 initialized
2019-08-19T03:54:42.185Z,1566186882.185 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:54:44.605Z,1566186884.605 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:54:47.845Z,1566186887.845 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:54:50.665Z,1566186890.665 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:54:53.901Z,1566186893.901 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:54:56.733Z,1566186896.733 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:54:59.561Z,1566186899.561 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:55:01.577Z,1566186901.577 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:55:03.601Z,1566186903.601 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:55:06.833Z,1566186906.833 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:55:09.661Z,1566186909.661 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:55:12.889Z,1566186912.889 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:55:15.721Z,1566186915.721 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:55:18.953Z,1566186918.953 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:55:21.781Z,1566186921.781 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:55:24.605Z,1566186924.605 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:55:26.633Z,1566186926.633 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:55:29.861Z,1566186929.861 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:55:32.689Z,1566186932.689 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:55:35.921Z,1566186935.921 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:55:38.749Z,1566186938.749 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:55:41.977Z,1566186941.977 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:55:44.805Z,1566186944.805 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:55:47.629Z,1566186947.629 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:55:49.657Z,1566186949.657 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:55:52.889Z,1566186952.889 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:55:55.717Z,1566186955.717 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:55:58.949Z,1566186958.949 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:56:01.777Z,1566186961.777 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:56:05.009Z,1566186965.009 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:56:07.837Z,1566186967.837 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:56:10.657Z,1566186970.657 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:56:12.685Z,1566186972.685 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:56:14.701Z,1566186974.701 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:56:17.929Z,1566186977.929 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:56:20.765Z,1566186980.765 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:56:23.993Z,1566186983.993 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:56:26.821Z,1566186986.821 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:56:30.049Z,1566186990.049 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:56:32.885Z,1566186992.885 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:56:34.506Z,1566186994.506 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-19T03:56:34.506Z,1566186994.506 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2019-08-19T03:56:34.506Z,1566186994.506 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-19T03:56:34.516Z,1566186994.516 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-19T03:56:34.922Z,1566186994.922 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-19T03:56:34.922Z,1566186994.922 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2019-08-19T03:56:35.713Z,1566186995.713 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:56:37.733Z,1566186997.733 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:56:40.957Z,1566187000.957 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:56:43.789Z,1566187003.789 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:56:47.017Z,1566187007.017 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:56:49.853Z,1566187009.853 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:56:53.077Z,1566187013.077 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:56:55.913Z,1566187015.913 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:56:58.741Z,1566187018.741 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:57:00.757Z,1566187020.757 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:57:02.773Z,1566187022.773 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:57:04.403Z,1566187024.403 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-08-19T03:57:04.403Z,1566187024.403 [Default:CheckIn:C.Wait] Stopped
2019-08-19T03:57:04.403Z,1566187024.403 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-19T03:57:04.404Z,1566187024.404 [Default:CheckIn:D] Running Loop=1
2019-08-19T03:57:04.807Z,1566187024.807 [Default:CheckIn:D] Stopped
2019-08-19T03:57:04.807Z,1566187024.807 [Default:CheckIn:E] Running Loop=1
2019-08-19T03:57:05.212Z,1566187025.212 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.285883 min 
2019-08-19T03:57:05.212Z,1566187025.212 [Default:CheckIn:E] Stopped
2019-08-19T03:57:05.212Z,1566187025.212 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-08-19T03:57:05.212Z,1566187025.212 [Default:CheckIn] Stopped
2019-08-19T03:57:05.212Z,1566187025.212 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-19T03:57:05.212Z,1566187025.212 [Default:CheckIn](INFO): Running loop #2
2019-08-19T03:57:05.212Z,1566187025.212 [Default:CheckIn] Running Loop=2
2019-08-19T03:57:05.231Z,1566187025.231 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-19T03:57:05.231Z,1566187025.231 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-19T03:57:06.009Z,1566187026.009 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:57:08.833Z,1566187028.833 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:57:12.073Z,1566187032.073 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:57:14.897Z,1566187034.897 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:57:18.133Z,1566187038.133 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:57:20.953Z,1566187040.953 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:57:23.789Z,1566187043.789 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:57:25.801Z,1566187045.801 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:57:29.041Z,1566187049.041 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:57:31.861Z,1566187051.861 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:57:35.101Z,1566187055.101 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:57:37.921Z,1566187057.921 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:57:41.161Z,1566187061.161 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:57:43.981Z,1566187063.981 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:57:46.813Z,1566187066.813 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:57:48.829Z,1566187068.829 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:57:50.857Z,1566187070.857 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:57:54.089Z,1566187074.089 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:57:56.917Z,1566187076.917 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:58:00.145Z,1566187080.145 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:58:02.977Z,1566187082.977 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:58:06.209Z,1566187086.209 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:58:09.037Z,1566187089.037 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:58:11.857Z,1566187091.857 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:58:13.881Z,1566187093.881 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:58:17.117Z,1566187097.117 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:58:19.945Z,1566187099.945 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:58:23.173Z,1566187103.173 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:58:26.005Z,1566187106.005 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:58:29.243Z,1566187109.243 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:58:32.069Z,1566187112.069 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:58:34.889Z,1566187114.889 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:58:35.328Z,1566187115.328 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-19T03:58:35.328Z,1566187115.328 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2019-08-19T03:58:35.328Z,1566187115.328 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-19T03:58:35.339Z,1566187115.339 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-19T03:58:35.737Z,1566187115.737 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-19T03:58:35.737Z,1566187115.737 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2019-08-19T03:58:36.917Z,1566187116.917 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:58:40.149Z,1566187120.149 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:58:42.977Z,1566187122.977 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:58:46.205Z,1566187126.205 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:58:49.037Z,1566187129.037 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:58:52.269Z,1566187132.269 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:58:55.097Z,1566187135.097 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:58:57.917Z,1566187137.917 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:58:59.941Z,1566187139.941 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:59:01.965Z,1566187141.965 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:59:05.193Z,1566187145.193 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:59:08.025Z,1566187148.025 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:59:11.253Z,1566187151.253 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:59:14.084Z,1566187154.084 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:59:17.313Z,1566187157.313 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:59:20.145Z,1566187160.145 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:59:22.973Z,1566187162.973 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:59:24.993Z,1566187164.993 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:59:28.225Z,1566187168.225 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:59:31.053Z,1566187171.053 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:59:34.285Z,1566187174.285 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:59:37.113Z,1566187177.113 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:59:40.345Z,1566187180.345 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:59:43.173Z,1566187183.173 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:59:45.997Z,1566187185.997 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:59:48.017Z,1566187188.017 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:59:50.033Z,1566187190.033 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:59:53.273Z,1566187193.273 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:59:56.093Z,1566187196.093 [NAL9602](DEBUG): Fix Requested
2019-08-19T03:59:59.333Z,1566187199.333 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:00:02.153Z,1566187202.153 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:00:05.393Z,1566187205.393 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:00:08.221Z,1566187208.221 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:00:11.049Z,1566187211.049 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:00:13.061Z,1566187213.061 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:00:16.297Z,1566187216.297 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:00:19.121Z,1566187219.121 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:00:22.361Z,1566187222.361 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:00:25.185Z,1566187225.185 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:00:28.421Z,1566187228.421 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:00:31.249Z,1566187231.249 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:00:34.077Z,1566187234.077 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:00:36.093Z,1566187236.093 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:00:36.532Z,1566187236.532 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-19T04:00:36.532Z,1566187236.532 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2019-08-19T04:00:36.532Z,1566187236.532 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-19T04:00:36.543Z,1566187236.543 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-19T04:00:36.942Z,1566187236.942 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-19T04:00:36.942Z,1566187236.942 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2019-08-19T04:00:39.329Z,1566187239.329 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:00:42.149Z,1566187242.149 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:00:45.385Z,1566187245.385 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:00:48.217Z,1566187248.217 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:00:51.445Z,1566187251.445 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:00:54.277Z,1566187254.277 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:00:57.105Z,1566187257.105 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:00:59.117Z,1566187259.117 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:01:01.145Z,1566187261.145 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:01:04.377Z,1566187264.377 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:01:07.205Z,1566187267.205 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:01:10.437Z,1566187270.437 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:01:13.265Z,1566187273.265 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:01:16.493Z,1566187276.493 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:01:19.325Z,1566187279.325 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:01:22.145Z,1566187282.145 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:01:24.173Z,1566187284.173 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:01:27.401Z,1566187287.401 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:01:30.233Z,1566187290.233 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:01:33.465Z,1566187293.465 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:01:36.293Z,1566187296.293 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:01:39.525Z,1566187299.525 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:01:42.353Z,1566187302.353 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:01:45.177Z,1566187305.177 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:01:47.201Z,1566187307.201 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:01:50.433Z,1566187310.433 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:01:53.261Z,1566187313.261 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:01:56.493Z,1566187316.493 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:01:59.321Z,1566187319.321 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:02:02.553Z,1566187322.553 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:02:05.381Z,1566187325.381 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:02:05.401Z,1566187325.401 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-08-19T03:57:05.2Z
2019-08-19T04:02:05.401Z,1566187325.401 [Default:CheckIn:Read_GPS] Stopped
2019-08-19T04:02:05.401Z,1566187325.401 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-19T04:02:08.229Z,1566187328.229 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:02:10.225Z,1566187330.225 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:02:11.748Z,1566187331.748 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190819T034217/Courier0007.lzma
2019-08-19T04:02:12.249Z,1566187332.249 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:02:12.555Z,1566187332.555 [DataOverHttps](INFO): Moved sent file to Logs/20190819T034217/Courier0007.lzma.bak
2019-08-19T04:02:12.555Z,1566187332.555 [DataOverHttps](INFO): SBD MOMSN=11591058
2019-08-19T04:02:15.477Z,1566187335.477 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:02:18.309Z,1566187338.309 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:02:21.541Z,1566187341.541 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:02:23.485Z,1566187343.485 [DataOverHttps](INFO): Sending 301 bytes from file Logs/20190819T034217/Express0008.lzma
2019-08-19T04:02:24.290Z,1566187344.290 [DataOverHttps](INFO): Moved sent file to Logs/20190819T034217/Express0008.lzma.bak
2019-08-19T04:02:24.291Z,1566187344.291 [DataOverHttps](INFO): SBD MOMSN=11591060
2019-08-19T04:02:24.377Z,1566187344.377 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:02:26.174Z,1566187346.174 [Default:CheckIn:Read_Iridium] Stopped
2019-08-19T04:02:26.174Z,1566187346.174 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-19T04:02:26.174Z,1566187346.174 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-19T04:02:27.609Z,1566187347.609 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:02:30.437Z,1566187350.437 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:02:33.269Z,1566187353.269 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:02:35.285Z,1566187355.285 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:02:37.320Z,1566187357.320 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-19T04:02:37.320Z,1566187357.320 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2019-08-19T04:02:37.320Z,1566187357.320 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-19T04:02:37.367Z,1566187357.367 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-19T04:02:37.741Z,1566187357.741 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-19T04:02:37.741Z,1566187357.741 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2019-08-19T04:02:38.513Z,1566187358.513 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:02:41.349Z,1566187361.349 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:02:44.581Z,1566187364.581 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:02:47.409Z,1566187367.409 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:02:50.633Z,1566187370.633 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:02:53.469Z,1566187373.469 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:02:56.297Z,1566187376.297 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:02:58.317Z,1566187378.317 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:03:00.337Z,1566187380.337 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:03:03.569Z,1566187383.569 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:03:06.393Z,1566187386.393 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:03:09.629Z,1566187389.629 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:03:12.457Z,1566187392.457 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:03:15.689Z,1566187395.689 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:03:18.517Z,1566187398.517 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:03:21.345Z,1566187401.345 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:03:23.365Z,1566187403.365 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:03:26.597Z,1566187406.597 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:03:29.425Z,1566187409.425 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:03:32.657Z,1566187412.657 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:03:35.481Z,1566187415.481 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:03:38.713Z,1566187418.713 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:03:41.537Z,1566187421.537 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:03:44.373Z,1566187424.373 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:03:46.393Z,1566187426.393 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:03:49.625Z,1566187429.625 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:03:52.453Z,1566187432.453 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:03:55.685Z,1566187435.685 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:03:58.513Z,1566187438.513 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:04:01.745Z,1566187441.745 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:04:04.573Z,1566187444.573 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:04:07.397Z,1566187447.397 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:04:09.421Z,1566187449.421 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:04:11.441Z,1566187451.441 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:04:14.673Z,1566187454.673 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:04:17.501Z,1566187457.501 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:04:20.733Z,1566187460.733 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:04:23.557Z,1566187463.557 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:04:26.393Z,1566187466.393 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:04:28.417Z,1566187468.417 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:04:30.437Z,1566187470.437 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:04:32.457Z,1566187472.457 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:04:34.477Z,1566187474.477 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:04:37.705Z,1566187477.705 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:04:38.148Z,1566187478.148 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-08-19T04:04:38.148Z,1566187478.148 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2019-08-19T04:04:38.148Z,1566187478.148 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-19T04:04:38.193Z,1566187478.193 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-19T04:04:38.561Z,1566187478.561 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-19T04:04:38.561Z,1566187478.561 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2019-08-19T04:04:39.954Z,1566187479.954 [DataOverHttps](IMPORTANT): SBD MTMSN=20190819T040257
2019-08-19T04:04:40.537Z,1566187480.537 [NAL9602](DEBUG): Fix Requested
2019-08-19T04:04:43.754Z,1566187483.754 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-08-19T04:04:43.754Z,1566187483.754 [NAL9602] Data Fault, FailCount= 3
2019-08-19T04:04:43.754Z,1566187483.754 [NAL9602](ERROR): Data Fault
2019-08-19T04:04:43.826Z,1566187483.826 [CBIT](ERROR): Data Fault in component: NAL9602
2019-08-19T04:04:44.158Z,1566187484.158 [NAL9602](INFO): Powering down
2019-08-19T04:04:44.729Z,1566187484.729 [DataOverHttps](INFO): Received command:restart app
2019-08-19T04:04:44.778Z,1566187484.778 [CommandLine](IMPORTANT): got command restart application
2019-08-19T04:04:44.994Z,1566187484.994 [CBIT](INFO): Clearing failed state for component NAL9602
2019-08-19T04:04:44.995Z,1566187484.995 [NAL9602] No Fault, FailCount= 3
2019-08-19T04:04:45.782Z,1566187485.782 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-08-19T04:04:45.783Z,1566187485.783 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-19T04:04:45.785Z,1566187485.785 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-08-19T04:04:45.869Z,1566187485.869 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-08-19T04:04:45.869Z,1566187485.869 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-08-19T04:04:45.870Z,1566187485.870 [CommandLine](INFO): Join timeout helper Thread ID is 1016
2019-08-19T04:04:45.870Z,1566187485.870 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-08-19T04:04:45.870Z,1566187485.870 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-08-19T04:04:45.871Z,1566187485.871 [NavChartDb](INFO): Join timeout helper Thread ID is 1017
2019-08-19T04:04:45.929Z,1566187485.929 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-19T04:04:45.929Z,1566187485.929 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-08-19T04:04:45.949Z,1566187485.949 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-08-19T04:04:45.949Z,1566187485.949 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-08-19T04:04:45.949Z,1566187485.949 [Radio_Surface](INFO): Join timeout helper Thread ID is 1018
2019-08-19T04:04:45.953Z,1566187485.953 [Radio_Surface](INFO): Powering down
2019-08-19T04:04:45.954Z,1566187485.954 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-19T04:04:45.954Z,1566187485.954 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-08-19T04:04:45.969Z,1566187485.969 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-08-19T04:04:45.969Z,1566187485.969 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-08-19T04:04:45.969Z,1566187485.969 [DataOverHttps](INFO): Join timeout helper Thread ID is 1019
2019-08-19T04:04:46.341Z,1566187486.341 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-19T04:04:46.341Z,1566187486.341 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-08-19T04:04:46.361Z,1566187486.361 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-08-19T04:04:46.361Z,1566187486.361 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-08-19T04:04:46.361Z,1566187486.361 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1020
2019-08-19T04:04:46.469Z,1566187486.469 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-19T04:04:46.469Z,1566187486.469 [WetLabsBB2FL](INFO): Powering down
2019-08-19T04:04:46.470Z,1566187486.470 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-08-19T04:04:46.482Z,1566187486.482 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-08-19T04:04:46.482Z,1566187486.482 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-08-19T04:04:46.482Z,1566187486.482 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1021
2019-08-19T04:04:46.605Z,1566187486.605 [CTD_Seabird](INFO): Powering down
2019-08-19T04:04:46.617Z,1566187486.617 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-19T04:04:46.617Z,1566187486.617 [CTD_Seabird](INFO): Powering down
2019-08-19T04:04:46.629Z,1566187486.629 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-08-19T04:04:46.634Z,1566187486.634 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-08-19T04:04:46.634Z,1566187486.634 [logger ThreadHandler](INFO): Thread cancelled.
2019-08-19T04:04:46.634Z,1566187486.634 [logger](INFO): Join timeout helper Thread ID is 1022
2019-08-19T04:04:46.645Z,1566187486.645 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-19T04:04:46.646Z,1566187486.646 [logger ThreadHandler](INFO): Thread cancelled.
2019-08-19T04:04:46.665Z,1566187486.665 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-08-19T04:04:46.666Z,1566187486.666 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-08-19T04:04:46.666Z,1566187486.666 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-08-19T04:04:46.666Z,1566187486.666 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-08-19T04:04:46.666Z,1566187486.666 [controlThread](INFO): Join timeout helper Thread ID is 1023
2019-08-19T04:04:46.973Z,1566187486.973 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-19T04:04:46.973Z,1566187486.973 [controlThread](DEBUG): Uninitializing ControlThread
2019-08-19T04:04:46.973Z,1566187486.973 [Aanderaa_O2](INFO): Powering down
2019-08-19T04:04:46.995Z,1566187486.995 [NAL9602](INFO): Powering down
2019-08-19T04:04:46.996Z,1566187486.996 [DAT](INFO): Powering down
2019-08-19T04:04:47.114Z,1566187487.114 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-08-19T04:04:47.115Z,1566187487.115 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-08-19T04:04:47.115Z,1566187487.115 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-08-19T04:04:47.115Z,1566187487.115 [MissionManager](INFO): Uninitializing Mission Default
2019-08-19T04:04:47.116Z,1566187487.116 [Default] Stopped
2019-08-19T04:04:47.116Z,1566187487.116 [Default](DEBUG): Aggregate::uninitialize Default
2019-08-19T04:04:47.116Z,1566187487.116 [Default:B.GoToSurface] Stopped
2019-08-19T04:04:47.116Z,1566187487.116 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-08-19T04:04:47.116Z,1566187487.116 [Default:CheckIn] Stopped
2019-08-19T04:04:47.116Z,1566187487.116 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-19T04:04:47.116Z,1566187487.116 [Default:CheckIn:C.Wait] Stopped
2019-08-19T04:04:47.116Z,1566187487.116 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-19T04:04:47.119Z,1566187487.119 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-08-19T04:04:47.119Z,1566187487.119 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-08-19T04:04:47.119Z,1566187487.119 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-08-19T04:04:47.119Z,1566187487.119 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-08-19T04:04:47.120Z,1566187487.120 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-08-19T04:04:47.120Z,1566187487.120 [BuoyancyServo](INFO): Powering down
2019-08-19T04:04:47.133Z,1566187487.133 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-08-19T04:04:47.133Z,1566187487.133 [ElevatorServo](INFO): Powering down
2019-08-19T04:04:47.134Z,1566187487.134 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-08-19T04:04:47.134Z,1566187487.134 [MassServo](INFO): Powering down
2019-08-19T04:04:47.135Z,1566187487.135 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-08-19T04:04:47.135Z,1566187487.135 [RudderServo](INFO): Powering down
2019-08-19T04:04:47.135Z,1566187487.135 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-08-19T04:04:47.136Z,1566187487.136 [ThrusterServo](INFO): Powering down
2019-08-19T04:04:47.136Z,1566187487.136 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-08-19T04:04:47.137Z,1566187487.137 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-08-19T04:04:47.137Z,1566187487.137 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-08-19T04:04:47.137Z,1566187487.137 [CBIT](DEBUG): Powering off loads.
2019-08-19T04:04:47.149Z,1566187487.149 [CBIT](DEBUG): Disabling WDT.
2019-08-19T04:04:47.161Z,1566187487.161 [CBIT](DEBUG): Opening all GF detection circuits.
2019-08-19T04:04:47.162Z,1566187487.162 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-08-19T04:04:47.196Z,1566187487.196 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-08-19T04:04:47.201Z,1566187487.201 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-08-19T04:04:47.265Z,1566187487.265 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-08-19T04:04:47.270Z,1566187487.270 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-08-19T04:04:47.317Z,1566187487.317 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-08-19T04:04:47.373Z,1566187487.373 [logger ThreadHandler](INFO): Thread cancelled.