2020-12-03T17:44:36.981Z,1607017476.981 [Supervisor](DEBUG): Initializing supervisor.
2020-12-03T17:44:36.984Z,1607017476.984 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-12-03T17:44:36.985Z,1607017476.985 [SyncHandler](INFO): Protected caller Thread ID is 4583
2020-12-03T17:44:36.985Z,1607017476.985 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-12-03T17:44:36.986Z,1607017476.986 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-12-03T17:44:36.986Z,1607017476.986 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4584
2020-12-03T17:44:36.990Z,1607017476.990 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-12-03T17:44:37.008Z,1607017477.008 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-12-03T17:44:37.009Z,1607017477.009 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-12-03T17:44:37.009Z,1607017477.009 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4585
2020-12-03T17:44:37.010Z,1607017477.010 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-12-03T17:44:37.011Z,1607017477.011 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-12-03T17:44:37.011Z,1607017477.011 [logger ThreadHandler](INFO): Protected caller Thread ID is 4586
2020-12-03T17:44:37.014Z,1607017477.014 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-12-03T17:44:37.014Z,1607017477.014 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-12-03T17:44:37.016Z,1607017477.016 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-12-03T17:44:37.502Z,1607017477.502 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-12-03T17:44:37.503Z,1607017477.503 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-12-03T17:44:37.640Z,1607017477.640 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-12-03T17:44:37.640Z,1607017477.640 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-12-03T17:44:37.718Z,1607017477.718 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-12-03T17:44:37.917Z,1607017477.917 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-12-03T17:44:37.917Z,1607017477.917 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-12-03T17:44:38.029Z,1607017478.029 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-12-03T17:44:38.030Z,1607017478.030 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-12-03T17:44:38.176Z,1607017478.176 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-12-03T17:44:38.177Z,1607017478.177 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-12-03T17:44:38.554Z,1607017478.554 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-12-03T17:44:38.555Z,1607017478.555 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-12-03T17:44:38.657Z,1607017478.657 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-12-03T17:44:38.657Z,1607017478.657 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-12-03T17:44:39.145Z,1607017479.145 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-12-03T17:44:39.145Z,1607017479.145 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-12-03T17:44:39.328Z,1607017479.328 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-12-03T17:44:39.328Z,1607017479.328 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-12-03T17:44:39.616Z,1607017479.616 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-12-03T17:44:39.617Z,1607017479.617 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-12-03T17:44:39.824Z,1607017479.824 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-12-03T17:44:39.824Z,1607017479.824 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-12-03T17:44:39.962Z,1607017479.962 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-12-03T17:44:39.962Z,1607017479.962 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-12-03T17:44:40.600Z,1607017480.600 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-12-03T17:44:40.601Z,1607017480.601 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-12-03T17:44:40.914Z,1607017480.914 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-12-03T17:44:40.914Z,1607017480.914 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-12-03T17:44:41.112Z,1607017481.112 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-12-03T17:44:41.114Z,1607017481.114 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2020-12-03T17:44:41.115Z,1607017481.115 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2020-12-03T17:44:41.411Z,1607017481.411 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2020-12-03T17:44:41.542Z,1607017481.542 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2020-12-03T17:44:41.741Z,1607017481.741 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2020-12-03T17:44:41.823Z,1607017481.823 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2020-12-03T17:44:42.071Z,1607017482.071 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-12-03T17:44:42.072Z,1607017482.072 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2020-12-03T17:44:42.173Z,1607017482.173 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2020-12-03T17:44:42.276Z,1607017482.276 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2020-12-03T17:44:42.366Z,1607017482.366 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2020-12-03T17:44:42.506Z,1607017482.506 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2020-12-03T17:44:42.601Z,1607017482.601 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2020-12-03T17:44:42.698Z,1607017482.698 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2020-12-03T17:44:42.698Z,1607017482.698 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-12-03T17:44:42.711Z,1607017482.711 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-12-03T17:44:42.732Z,1607017482.732 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-12-03T17:44:42.732Z,1607017482.732 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-12-03T17:44:42.811Z,1607017482.811 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-12-03T17:44:42.811Z,1607017482.811 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-12-03T17:44:42.862Z,1607017482.862 [VerticalControl](DEBUG): Construct VerticalControl.
2020-12-03T17:44:42.920Z,1607017482.920 [VerticalControl] Loaded
2020-12-03T17:44:42.921Z,1607017482.921 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-12-03T17:44:42.921Z,1607017482.921 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-12-03T17:44:42.958Z,1607017482.958 [HorizontalControl] Loaded
2020-12-03T17:44:42.959Z,1607017482.959 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-12-03T17:44:42.959Z,1607017482.959 [SpeedControl](DEBUG): Construct SpeedControl.
2020-12-03T17:44:42.962Z,1607017482.962 [SpeedControl] Loaded
2020-12-03T17:44:42.963Z,1607017482.963 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-12-03T17:44:42.963Z,1607017482.963 [LoopControl](DEBUG): Construct LoopControl.
2020-12-03T17:44:42.964Z,1607017482.964 [LoopControl] Loaded
2020-12-03T17:44:42.964Z,1607017482.964 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-12-03T17:44:42.964Z,1607017482.964 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-12-03T17:44:42.965Z,1607017482.965 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-12-03T17:44:43.074Z,1607017483.074 [BuoyancyServo] Loaded
2020-12-03T17:44:43.074Z,1607017483.074 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-12-03T17:44:43.090Z,1607017483.090 [ElevatorServo] Loaded
2020-12-03T17:44:43.090Z,1607017483.090 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-12-03T17:44:43.106Z,1607017483.106 [MassServo] Loaded
2020-12-03T17:44:43.106Z,1607017483.106 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-12-03T17:44:43.121Z,1607017483.121 [RudderServo] Loaded
2020-12-03T17:44:43.121Z,1607017483.121 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-12-03T17:44:43.136Z,1607017483.136 [ThrusterServo] Loaded
2020-12-03T17:44:43.136Z,1607017483.136 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-12-03T17:44:43.137Z,1607017483.137 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-12-03T17:44:43.137Z,1607017483.137 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-12-03T17:44:43.176Z,1607017483.176 [DepthRateCalculator] Loaded
2020-12-03T17:44:43.176Z,1607017483.176 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-12-03T17:44:43.179Z,1607017483.179 [PitchRateCalculator] Loaded
2020-12-03T17:44:43.179Z,1607017483.179 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-12-03T17:44:43.187Z,1607017483.187 [SpeedCalculator] Loaded
2020-12-03T17:44:43.187Z,1607017483.187 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-12-03T17:44:43.198Z,1607017483.198 [TempGradientCalculator] Loaded
2020-12-03T17:44:43.198Z,1607017483.198 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-12-03T17:44:43.201Z,1607017483.201 [YawRateCalculator] Loaded
2020-12-03T17:44:43.201Z,1607017483.201 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-12-03T17:44:43.216Z,1607017483.216 [ElevatorOffsetCalculator] Loaded
2020-12-03T17:44:43.216Z,1607017483.216 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-12-03T17:44:43.217Z,1607017483.217 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-12-03T17:44:43.217Z,1607017483.217 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-12-03T17:44:43.297Z,1607017483.297 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-12-03T17:44:43.298Z,1607017483.298 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-12-03T17:44:43.308Z,1607017483.308 [NavChart] Loaded
2020-12-03T17:44:43.309Z,1607017483.309 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-12-03T17:44:43.312Z,1607017483.312 [UniversalFixResidualReporter] Loaded
2020-12-03T17:44:43.313Z,1607017483.313 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-12-03T17:44:43.313Z,1607017483.313 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-12-03T17:44:43.314Z,1607017483.314 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-12-03T17:44:43.622Z,1607017483.622 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-12-03T17:44:43.625Z,1607017483.625 [AHRS_M2](INFO): created writer for : platform_orientation
2020-12-03T17:44:43.627Z,1607017483.627 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-12-03T17:44:43.630Z,1607017483.630 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-12-03T17:44:43.631Z,1607017483.631 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-12-03T17:44:43.634Z,1607017483.634 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-12-03T17:44:43.634Z,1607017483.634 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-12-03T17:44:43.637Z,1607017483.637 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-12-03T17:44:43.674Z,1607017483.674 [AHRS_M2] Loaded
2020-12-03T17:44:43.674Z,1607017483.674 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-12-03T17:44:43.745Z,1607017483.745 [DataOverHttps] Loaded
2020-12-03T17:44:43.745Z,1607017483.745 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-12-03T17:44:43.747Z,1607017483.747 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408514E0
2020-12-03T17:44:43.747Z,1607017483.747 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4669
2020-12-03T17:44:43.764Z,1607017483.764 [Depth_Keller] Loaded
2020-12-03T17:44:43.764Z,1607017483.764 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-12-03T17:44:43.819Z,1607017483.819 [NAL9602] Loaded
2020-12-03T17:44:43.819Z,1607017483.819 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-12-03T17:44:43.846Z,1607017483.846 [Onboard] Loaded
2020-12-03T17:44:43.846Z,1607017483.846 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-12-03T17:44:43.847Z,1607017483.847 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408814E0
2020-12-03T17:44:43.847Z,1607017483.847 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4670
2020-12-03T17:44:43.858Z,1607017483.858 [Radio_Surface] Loaded
2020-12-03T17:44:43.858Z,1607017483.858 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-12-03T17:44:43.859Z,1607017483.859 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408B14E0
2020-12-03T17:44:43.859Z,1607017483.859 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4671
2020-12-03T17:44:43.933Z,1607017483.933 [DAT] Loaded
2020-12-03T17:44:43.933Z,1607017483.933 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-12-03T17:44:44.723Z,1607017484.723 [BPC1] Loaded
2020-12-03T17:44:44.723Z,1607017484.723 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-12-03T17:44:44.723Z,1607017484.723 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-12-03T17:44:44.724Z,1607017484.724 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-12-03T17:44:44.811Z,1607017484.811 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-12-03T17:44:44.812Z,1607017484.812 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-12-03T17:44:44.910Z,1607017484.910 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-12-03T17:44:44.911Z,1607017484.911 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-12-03T17:44:44.938Z,1607017484.938 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-12-03T17:44:44.939Z,1607017484.939 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-12-03T17:44:45.145Z,1607017485.145 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-12-03T17:44:45.148Z,1607017485.148 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-12-03T17:44:45.148Z,1607017485.148 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-12-03T17:44:45.151Z,1607017485.151 [CTD_Seabird](INFO): created writer for : depth
2020-12-03T17:44:45.151Z,1607017485.151 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-12-03T17:44:45.154Z,1607017485.154 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-12-03T17:44:45.154Z,1607017485.154 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-12-03T17:44:45.157Z,1607017485.157 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-12-03T17:44:45.158Z,1607017485.158 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-12-03T17:44:45.161Z,1607017485.161 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-12-03T17:44:45.161Z,1607017485.161 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-12-03T17:44:45.164Z,1607017485.164 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-12-03T17:44:45.165Z,1607017485.165 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-12-03T17:44:45.168Z,1607017485.168 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-12-03T17:44:45.181Z,1607017485.181 [CTD_Seabird] Loaded
2020-12-03T17:44:45.181Z,1607017485.181 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-12-03T17:44:45.182Z,1607017485.182 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A594E0
2020-12-03T17:44:45.183Z,1607017485.183 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4672
2020-12-03T17:44:45.211Z,1607017485.211 [ESPComponent] Loaded
2020-12-03T17:44:45.212Z,1607017485.212 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2020-12-03T17:44:45.228Z,1607017485.228 [PAR_Licor] Loaded
2020-12-03T17:44:45.229Z,1607017485.229 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-12-03T17:44:45.242Z,1607017485.242 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-12-03T17:44:45.242Z,1607017485.242 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-12-03T17:44:45.244Z,1607017485.244 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-12-03T17:44:45.244Z,1607017485.244 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-12-03T17:44:45.246Z,1607017485.246 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-12-03T17:44:45.246Z,1607017485.246 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-12-03T17:44:45.248Z,1607017485.248 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-12-03T17:44:45.248Z,1607017485.248 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-12-03T17:44:45.250Z,1607017485.250 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-12-03T17:44:45.250Z,1607017485.250 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-12-03T17:44:45.253Z,1607017485.253 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-12-03T17:44:45.253Z,1607017485.253 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-12-03T17:44:45.255Z,1607017485.255 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-12-03T17:44:45.255Z,1607017485.255 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-12-03T17:44:45.257Z,1607017485.257 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-12-03T17:44:45.259Z,1607017485.259 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-12-03T17:44:45.259Z,1607017485.259 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-12-03T17:44:45.260Z,1607017485.260 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-12-03T17:44:45.262Z,1607017485.262 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-12-03T17:44:45.262Z,1607017485.262 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-12-03T17:44:45.264Z,1607017485.264 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-12-03T17:44:45.264Z,1607017485.264 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-12-03T17:44:45.266Z,1607017485.266 [WetLabsBB2FL] Loaded
2020-12-03T17:44:45.266Z,1607017485.266 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-12-03T17:44:45.267Z,1607017485.267 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A894E0
2020-12-03T17:44:45.268Z,1607017485.268 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4673
2020-12-03T17:44:45.269Z,1607017485.269 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-12-03T17:44:45.269Z,1607017485.269 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-12-03T17:44:45.563Z,1607017485.563 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-12-03T17:44:45.563Z,1607017485.563 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-12-03T17:44:45.698Z,1607017485.698 [SBIT](DEBUG): Construct Startup Built In Test.
2020-12-03T17:44:45.707Z,1607017485.707 [SBIT] Loaded
2020-12-03T17:44:45.707Z,1607017485.707 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-12-03T17:44:45.708Z,1607017485.708 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-12-03T17:44:45.720Z,1607017485.720 [IBIT] Loaded
2020-12-03T17:44:45.720Z,1607017485.720 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-12-03T17:44:45.723Z,1607017485.723 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-12-03T17:44:45.820Z,1607017485.820 [CBIT] Loaded
2020-12-03T17:44:45.820Z,1607017485.820 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-12-03T17:44:45.821Z,1607017485.821 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-12-03T17:44:45.825Z,1607017485.825 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-12-03T17:44:45.826Z,1607017485.826 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-12-03T17:44:45.834Z,1607017485.834 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-12-03T17:44:45.870Z,1607017485.870 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B674E0
2020-12-03T17:44:45.871Z,1607017485.871 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4674
2020-12-03T17:44:45.875Z,1607017485.875 [Supervisor](INFO): Main Thread ID is 4582
2020-12-03T17:44:45.876Z,1607017485.876 [Supervisor](DEBUG): Running supervisor.
2020-12-03T17:44:45.876Z,1607017485.876 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4675
2020-12-03T17:44:45.885Z,1607017485.885 [controlThread ThreadHandler](INFO): Handler Thread ID is 4676
2020-12-03T17:44:45.886Z,1607017485.886 [controlThread](DEBUG): Initializing ControlThread
2020-12-03T17:44:45.887Z,1607017485.887 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-12-03T17:44:45.888Z,1607017485.888 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-12-03T17:44:45.889Z,1607017485.889 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-12-03T17:44:45.889Z,1607017485.889 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-12-03T17:44:45.890Z,1607017485.890 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-12-03T17:44:45.891Z,1607017485.891 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-12-03T17:44:45.891Z,1607017485.891 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-12-03T17:44:45.891Z,1607017485.891 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-12-03T17:44:45.892Z,1607017485.892 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-12-03T17:44:45.892Z,1607017485.892 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-12-03T17:44:45.893Z,1607017485.893 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-12-03T17:44:45.894Z,1607017485.894 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-12-03T17:44:45.901Z,1607017485.901 [SBIT](INFO): Initialize SBIT Component.
2020-12-03T17:44:45.901Z,1607017485.901 [SBIT](IMPORTANT): git: 2020-11-24-6-gcb638d650
2020-12-03T17:44:45.902Z,1607017485.902 [SBIT](INFO): git hash: cb638d650ac65501896d406bf5988c2fe747cbc6
2020-12-03T17:44:45.902Z,1607017485.902 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-12-03T17:44:45.903Z,1607017485.903 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Tue May 26 08:17:09 PDT 2020
2020-12-03T17:44:45.904Z,1607017485.904 [SBIT](INFO): Beginning SBIT in 34.000000 seconds.
2020-12-03T17:44:45.905Z,1607017485.905 [IBIT](INFO): Initialize IBIT Component.
2020-12-03T17:44:45.906Z,1607017485.906 [CBIT](DEBUG): Initialize CBIT Component.
2020-12-03T17:44:45.906Z,1607017485.906 [logger ThreadHandler](INFO): Handler Thread ID is 4677
2020-12-03T17:44:45.917Z,1607017485.917 [CBIT](DEBUG): Initialized mux pins.
2020-12-03T17:44:45.917Z,1607017485.917 [CBIT](DEBUG): Initializing the watchdog timer.
2020-12-03T17:44:45.925Z,1607017485.925 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4678
2020-12-03T17:44:45.926Z,1607017485.926 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-12-03T17:44:45.937Z,1607017485.937 [Onboard ThreadHandler](INFO): Handler Thread ID is 4679
2020-12-03T17:44:45.946Z,1607017485.946 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-12-03T17:44:45.946Z,1607017485.946 [CBIT](DEBUG): Initializing heartbeat.
2020-12-03T17:44:45.967Z,1607017485.967 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4680
2020-12-03T17:44:45.977Z,1607017485.977 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4681
2020-12-03T17:44:45.978Z,1607017485.978 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-12-03T17:44:45.982Z,1607017485.982 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4683
2020-12-03T17:44:45.983Z,1607017485.983 [WetLabsBB2FL](INFO): Powering down
2020-12-03T17:44:46.017Z,1607017486.017 [CBIT](DEBUG): Deactivating GF circuits.
2020-12-03T17:44:46.017Z,1607017486.017 [CBIT](DEBUG): Deactivating emergency mode.
2020-12-03T17:44:46.053Z,1607017486.053 [CBIT](DEBUG): Backplane powered.
2020-12-03T17:44:46.105Z,1607017486.105 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-12-03T17:44:46.113Z,1607017486.113 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4684
2020-12-03T17:44:46.116Z,1607017486.116 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-12-03T17:44:46.116Z,1607017486.116 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-12-03T17:44:46.117Z,1607017486.117 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-12-03T17:44:46.117Z,1607017486.117 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-12-03T17:44:46.117Z,1607017486.117 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-12-03T17:44:46.117Z,1607017486.117 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-12-03T17:44:46.117Z,1607017486.117 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-12-03T17:44:46.117Z,1607017486.117 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-12-03T17:44:46.118Z,1607017486.118 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-12-03T17:44:46.118Z,1607017486.118 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-12-03T17:44:46.118Z,1607017486.118 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-12-03T17:44:46.118Z,1607017486.118 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-12-03T17:44:46.118Z,1607017486.118 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-12-03T17:44:46.118Z,1607017486.118 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-12-03T17:44:46.118Z,1607017486.118 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-12-03T17:44:46.119Z,1607017486.119 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-12-03T17:44:46.140Z,1607017486.140 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-12-03T17:44:46.261Z,1607017486.261 [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>


2020-12-03T17:44:46.262Z,1607017486.262 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-12-03T17:44:46.373Z,1607017486.373 [Radio_Surface](INFO): Powering up
2020-12-03T17:44:46.416Z,1607017486.416 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-12-03T17:44:46.421Z,1607017486.421 [Default:A.Wait](DEBUG): Construct Wait.
2020-12-03T17:44:46.423Z,1607017486.423 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-12-03T17:44:46.479Z,1607017486.479 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-12-03T17:44:46.499Z,1607017486.499 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-12-03T17:44:46.535Z,1607017486.535 [Default:E.Execute](DEBUG): Construct Execute.
2020-12-03T17:44:46.563Z,1607017486.563 [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>


2020-12-03T17:44:46.574Z,1607017486.574 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,NAL9602,DAT,BPC1,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-12-03T17:44:46.599Z,1607017486.599 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-12-03T17:44:46.693Z,1607017486.693 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar
2020-12-03T17:44:46.701Z,1607017486.701 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-12-03T17:44:46.701Z,1607017486.701 [DAT](INFO): Powering up
2020-12-03T17:44:46.701Z,1607017486.701 [DAT](DEBUG): Initializing DAT.
2020-12-03T17:44:46.755Z,1607017486.755 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-12-03T17:44:46.771Z,1607017486.771 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-12-03T17:44:46.780Z,1607017486.780 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-12-03T17:44:46.786Z,1607017486.786 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-12-03T17:44:46.848Z,1607017486.848 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-12-03T17:44:46.857Z,1607017486.857 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-12-03T17:44:46.858Z,1607017486.858 [MassServo](DEBUG): Initializing EZServoServo.
2020-12-03T17:44:46.869Z,1607017486.869 [MassServo](DEBUG): Initializing MassServo.
2020-12-03T17:44:46.870Z,1607017486.870 [RudderServo](DEBUG): Initializing EZServoServo.
2020-12-03T17:44:46.886Z,1607017486.886 [RudderServo](DEBUG): Initializing RudderServo.
2020-12-03T17:44:46.887Z,1607017486.887 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-12-03T17:44:46.897Z,1607017486.897 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-12-03T17:44:47.705Z,1607017487.705 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-12-03T17:44:47.705Z,1607017487.705 [RudderServo](FAULT): Rudder failed to initialize
2020-12-03T17:44:47.705Z,1607017487.705 [RudderServo] Communications Fault, FailCount= 1
2020-12-03T17:44:47.705Z,1607017487.705 [RudderServo](ERROR): Communications Fault
2020-12-03T17:44:47.805Z,1607017487.805 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-12-03T17:44:48.010Z,1607017488.010 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-12-03T17:44:48.010Z,1607017488.010 [RudderServo](INFO): Powering down
2020-12-03T17:44:48.663Z,1607017488.663 [RudderServo](DEBUG): Initializing EZServoServo.
2020-12-03T17:44:48.782Z,1607017488.782 [RudderServo](DEBUG): Initializing RudderServo.
2020-12-03T17:44:48.785Z,1607017488.785 [CBIT](INFO): Clearing failed state for component RudderServo
2020-12-03T17:44:48.785Z,1607017488.785 [RudderServo] No Fault, FailCount= 1
2020-12-03T17:44:53.013Z,1607017493.013 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2020-12-03T17:44:57.110Z,1607017497.110 [DAT](INFO): DAT read: 

2020-12-03T17:44:57.112Z,1607017497.112 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2020-12-03T17:44:58.322Z,1607017498.322 [DAT](INFO): DAT read: MF Frequency Band

2020-12-03T17:44:58.324Z,1607017498.324 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.12.21

2020-12-03T17:44:58.324Z,1607017498.324 [DAT](INFO): DAT read: Dec  3 2020 17:43:10 

2020-12-03T17:44:59.126Z,1607017499.126 [DAT](INFO): DAT read: Features enabled [Bearing]

2020-12-03T17:44:59.128Z,1607017499.128 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2020-12-03T17:44:59.128Z,1607017499.128 [DAT](INFO): commRate: 800
2020-12-03T17:44:59.728Z,1607017499.728 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005414
2020-12-03T17:45:01.150Z,1607017501.150 [DAT](INFO): entering command mode
2020-12-03T17:45:01.554Z,1607017501.554 [DAT](INFO): DAT read: 

2020-12-03T17:45:01.554Z,1607017501.554 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:01.958Z,1607017501.958 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:02.362Z,1607017502.362 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:02.762Z,1607017502.762 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:03.170Z,1607017503.170 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:03.574Z,1607017503.574 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:03.978Z,1607017503.978 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:04.382Z,1607017504.382 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:04.787Z,1607017504.787 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:05.198Z,1607017505.198 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:05.594Z,1607017505.594 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:05.994Z,1607017505.994 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:06.398Z,1607017506.398 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:06.806Z,1607017506.806 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:07.206Z,1607017507.206 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:07.610Z,1607017507.610 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:08.018Z,1607017508.018 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:08.418Z,1607017508.418 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:08.822Z,1607017508.822 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:09.226Z,1607017509.226 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:09.634Z,1607017509.634 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:10.039Z,1607017510.039 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:10.442Z,1607017510.442 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:10.846Z,1607017510.846 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:11.246Z,1607017511.246 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:11.656Z,1607017511.656 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:12.054Z,1607017512.054 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:12.458Z,1607017512.458 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:12.866Z,1607017512.866 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:13.271Z,1607017513.271 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:13.670Z,1607017513.670 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:14.074Z,1607017514.074 [NAL9602](INFO): Powering up NAL9602
2020-12-03T17:45:14.075Z,1607017514.075 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:14.478Z,1607017514.478 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:14.886Z,1607017514.886 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:15.286Z,1607017515.286 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:15.690Z,1607017515.690 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:16.094Z,1607017516.094 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:16.502Z,1607017516.502 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T17:45:16.502Z,1607017516.502 [DAT](FAULT): failed to enter command mode
2020-12-03T17:45:16.912Z,1607017516.912 [DAT](INFO): entering command mode
2020-12-03T17:45:17.306Z,1607017517.306 [DAT](INFO): DAT read: user:1>

2020-12-03T17:45:17.307Z,1607017517.307 [DAT](INFO): DAT read: Command '+++' not found

2020-12-03T17:45:17.307Z,1607017517.307 [DAT](INFO): DAT read: Error

2020-12-03T17:45:17.308Z,1607017517.308 [DAT](INFO): setting verbose to 3
2020-12-03T17:45:17.710Z,1607017517.710 [DAT](INFO): DAT read: user:2>

2020-12-03T17:45:17.711Z,1607017517.711 [DAT](INFO): DAT read: Verbose         | 3           

2020-12-03T17:45:17.712Z,1607017517.712 [DAT](INFO): set verbose to 3
2020-12-03T17:45:17.712Z,1607017517.712 [DAT](INFO): setting DatVerbose to 27440
2020-12-03T17:45:18.118Z,1607017518.118 [DAT](INFO): DAT read: user:3>

2020-12-03T17:45:18.119Z,1607017518.119 [DAT](INFO): DAT read: DatVerbose      | 27440       

2020-12-03T17:45:18.119Z,1607017518.119 [DAT](INFO): set DatVerbose to 27440
2020-12-03T17:45:18.120Z,1607017518.120 [DAT](INFO): setting transmit power to 8
2020-12-03T17:45:18.523Z,1607017518.523 [DAT](INFO): DAT read: user:4>

2020-12-03T17:45:18.524Z,1607017518.524 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2020-12-03T17:45:18.524Z,1607017518.524 [DAT](INFO): set transmit power to 8
2020-12-03T17:45:18.525Z,1607017518.525 [DAT](INFO): setting local address to 5
2020-12-03T17:45:18.922Z,1607017518.922 [DAT](INFO): DAT read: user:5>

2020-12-03T17:45:18.923Z,1607017518.923 [DAT](INFO): DAT read: LocalAddr       | 5           

2020-12-03T17:45:18.923Z,1607017518.923 [DAT](INFO): set local address to 5
2020-12-03T17:45:20.571Z,1607017520.571 [SBIT](IMPORTANT): Beginning Startup BIT
2020-12-03T17:45:20.578Z,1607017520.578 [CBIT](IMPORTANT): Beginning ground fault scan
2020-12-03T17:45:24.986Z,1607017524.986 [NAL9602](INFO): NAL9602 initialized
2020-12-03T17:45:31.513Z,1607017531.513 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.025954
CHAN A1 (24V): -0.007543
CHAN A2 (12V): -0.005051
CHAN A3 (5V): -0.003373
CHAN B0 (3.3V): -0.001020
CHAN B1 (3.15aV): -0.001019
CHAN B2 (3.15bV): -0.000817
CHAN B3 (GND): 0.002553
OPEN: 0.004485
Full Scale Calc: 4.765 mA, -1.589 mA
2020-12-03T17:46:14.714Z,1607017574.714 [SBIT](IMPORTANT): SBIT PASSED
2020-12-03T17:46:14.714Z,1607017574.714 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-12-03T17:46:14.715Z,1607017574.715 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=1 bool;

2020-12-03T17:46:14.715Z,1607017574.715 [SBIT](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;

2020-12-03T17:46:14.715Z,1607017574.715 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2020-12-03T17:46:14.716Z,1607017574.716 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;

2020-12-03T17:46:14.716Z,1607017574.716 [SBIT](IMPORTANT): DAT.verbosity=3 count;

2020-12-03T17:46:14.716Z,1607017574.716 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2020-12-03T17:46:14.716Z,1607017574.716 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2020-12-03T17:46:14.716Z,1607017574.716 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;

2020-12-03T17:46:14.716Z,1607017574.716 [SBIT](IMPORTANT): Express linearApproximation WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 0.100000 microgram_per_liter;

2020-12-03T17:46:14.716Z,1607017574.716 [SBIT](IMPORTANT): Express linearApproximation depth 5.000000 meter;

2020-12-03T17:46:14.717Z,1607017574.717 [SBIT](IMPORTANT): Express linearApproximation latitude 0.005000 degree;

2020-12-03T17:46:14.717Z,1607017574.717 [SBIT](IMPORTANT): Express linearApproximation longitude 0.005000 degree;

2020-12-03T17:46:14.717Z,1607017574.717 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=168 cubic_centimeter;

2020-12-03T17:46:14.717Z,1607017574.717 [SBIT](IMPORTANT): VerticalControl.massDefault=3.7 millimeter;

2020-12-03T17:46:15.115Z,1607017575.115 [MissionManager](IMPORTANT): Started mission Startup
2020-12-03T17:46:15.115Z,1607017575.115 [Startup] Running Loop=1
2020-12-03T17:46:15.115Z,1607017575.115 [Startup](DEBUG): Aggregate::initialize Startup
2020-12-03T17:46:15.115Z,1607017575.115 [Startup:A.GoToSurface] Running Loop=1
2020-12-03T17:46:15.115Z,1607017575.115 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-12-03T17:46:15.116Z,1607017575.116 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-12-03T17:46:15.116Z,1607017575.116 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-12-03T17:46:15.145Z,1607017575.145 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-12-03T17:46:15.145Z,1607017575.145 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-12-03T17:46:15.146Z,1607017575.146 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-12-03T17:46:15.148Z,1607017575.148 [Startup:StartupSatComms] Running Loop=1
2020-12-03T17:46:15.148Z,1607017575.148 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-12-03T17:46:15.148Z,1607017575.148 [Startup:StartupSatComms:A] Running Loop=1
2020-12-03T17:46:15.511Z,1607017575.511 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-12-03T17:47:15.287Z,1607017635.287 [Startup:StartupSatComms:A](INFO): Timed out from 2020-12-03T17:46:15.1Z
2020-12-03T17:47:15.287Z,1607017635.287 [Startup:StartupSatComms:A] Stopped
2020-12-03T17:47:15.287Z,1607017635.287 [Startup:StartupSatComms:B] Running Loop=1
2020-12-03T17:47:15.691Z,1607017635.691 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-12-03T17:47:24.664Z,1607017644.664 [DataOverHttps](INFO): Sending 1010 bytes from file Logs/20201202T221250/Courier0004.lzma
2020-12-03T17:47:25.666Z,1607017645.666 [DataOverHttps](INFO): Moved sent file to Logs/20201202T221250/Courier0004.lzma.bak
2020-12-03T17:47:25.666Z,1607017645.666 [DataOverHttps](INFO): SBD MOMSN=13387017
2020-12-03T17:47:43.104Z,1607017663.104 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20201203T174436/Courier0000.lzma
2020-12-03T17:47:44.107Z,1607017664.107 [DataOverHttps](INFO): Moved sent file to Logs/20201203T174436/Courier0000.lzma.bak
2020-12-03T17:47:44.107Z,1607017664.107 [DataOverHttps](INFO): SBD MOMSN=13387387
2020-12-03T17:47:46.005Z,1607017666.005 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-12-03T17:47:46.005Z,1607017666.005 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T17:47:46.019Z,1607017666.019 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T17:47:46.427Z,1607017666.427 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T17:47:46.427Z,1607017666.427 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-12-03T17:47:53.654Z,1607017673.654 [CommandLine](IMPORTANT): got command show stack
2020-12-03T17:47:53.655Z,1607017673.655 [CommandLine](IMPORTANT): Behavior Stack:

2020-12-03T17:47:53.655Z,1607017673.655 [Startup](IMPORTANT): Priority 0: Startup:A.GoToSurface
2020-12-03T17:47:53.655Z,1607017673.655 [Startup:StartupSatComms](IMPORTANT): Priority 1: Startup:StartupSatComms:B
2020-12-03T17:48:03.867Z,1607017683.867 [DataOverHttps](INFO): Sending 1036 bytes from file Logs/20201202T221250/Express0001.lzma
2020-12-03T17:48:04.866Z,1607017684.866 [DataOverHttps](INFO): Moved sent file to Logs/20201202T221250/Express0001.lzma.bak
2020-12-03T17:48:04.867Z,1607017684.867 [DataOverHttps](INFO): SBD MOMSN=13387390
2020-12-03T17:48:15.495Z,1607017695.495 [Startup:StartupSatComms:B](INFO): Timed out from 2020-12-03T17:47:15.3Z
2020-12-03T17:48:15.495Z,1607017695.495 [Startup:StartupSatComms:B] Stopped
2020-12-03T17:48:15.495Z,1607017695.495 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-12-03T17:48:15.495Z,1607017695.495 [Startup:StartupSatComms] Stopped
2020-12-03T17:48:15.495Z,1607017695.495 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-12-03T17:48:15.496Z,1607017695.496 [Startup](INFO): Completed Startup
2020-12-03T17:48:15.496Z,1607017695.496 [MissionManager](INFO): Startup is completed.
2020-12-03T17:48:15.497Z,1607017695.497 [MissionManager](INFO): Uninitializing Mission Startup
2020-12-03T17:48:15.497Z,1607017695.497 [Startup] Stopped
2020-12-03T17:48:15.497Z,1607017695.497 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-12-03T17:48:15.497Z,1607017695.497 [Startup:A.GoToSurface] Stopped
2020-12-03T17:48:15.497Z,1607017695.497 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-12-03T17:48:15.910Z,1607017695.910 [MissionManager](IMPORTANT): Started mission Default
2020-12-03T17:48:15.911Z,1607017695.911 [Default] Running Loop=1
2020-12-03T17:48:15.911Z,1607017695.911 [Default](DEBUG): Aggregate::initialize Default
2020-12-03T17:48:15.911Z,1607017695.911 [Default:B.GoToSurface] Running Loop=1
2020-12-03T17:48:15.911Z,1607017695.911 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-12-03T17:48:15.911Z,1607017695.911 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-12-03T17:48:15.911Z,1607017695.911 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-12-03T17:48:15.912Z,1607017695.912 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-12-03T17:48:15.912Z,1607017695.912 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-12-03T17:48:15.913Z,1607017695.913 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-12-03T17:48:15.913Z,1607017695.913 [Default:A.Wait] Running Loop=1
2020-12-03T17:48:15.913Z,1607017695.913 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-12-03T17:48:26.263Z,1607017706.263 [DataOverHttps](INFO): Sending 625 bytes from file Logs/20201202T221250/Express0005.lzma
2020-12-03T17:48:27.262Z,1607017707.262 [DataOverHttps](INFO): Moved sent file to Logs/20201202T221250/Express0005.lzma.bak
2020-12-03T17:48:27.263Z,1607017707.263 [DataOverHttps](INFO): SBD MOMSN=13387418
2020-12-03T17:48:29.219Z,1607017709.219 [Default:A.Wait](INFO): Done Waiting.
2020-12-03T17:48:29.219Z,1607017709.219 [Default:A.Wait] Stopped
2020-12-03T17:48:29.219Z,1607017709.219 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-12-03T17:48:29.627Z,1607017709.627 [Default:CheckIn] Running Loop=1
2020-12-03T17:48:29.627Z,1607017709.627 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-03T17:48:29.627Z,1607017709.627 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-03T17:48:30.033Z,1607017710.033 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-12-03T17:49:07.965Z,1607017747.965 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.799999 degree
2020-12-03T17:49:07.966Z,1607017747.966 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2020-12-03T17:49:07.967Z,1607017747.967 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -122.800003 degree
2020-12-03T17:49:07.968Z,1607017747.968 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2020-12-03T17:49:08.060Z,1607017748.060 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,NAL9602,DAT,BPC1,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-12-03T17:49:08.423Z,1607017748.423 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T17:49:08.423Z,1607017748.423 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-12-03T17:49:08.423Z,1607017748.423 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T17:49:08.437Z,1607017748.437 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:08.844Z,1607017748.844 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:08.844Z,1607017748.844 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-12-03T17:49:09.622Z,1607017749.622 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T17:49:09.623Z,1607017749.623 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-12-03T17:49:09.623Z,1607017749.623 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T17:49:09.638Z,1607017749.638 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:10.044Z,1607017750.044 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:10.044Z,1607017750.044 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-12-03T17:49:10.839Z,1607017750.839 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T17:49:10.839Z,1607017750.839 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-12-03T17:49:10.839Z,1607017750.839 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T17:49:10.882Z,1607017750.882 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:11.255Z,1607017751.255 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:11.255Z,1607017751.255 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-12-03T17:49:12.058Z,1607017752.058 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T17:49:12.058Z,1607017752.058 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2020-12-03T17:49:12.058Z,1607017752.058 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T17:49:12.068Z,1607017752.068 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:12.481Z,1607017752.481 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:12.481Z,1607017752.481 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2020-12-03T17:49:13.282Z,1607017753.282 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T17:49:13.282Z,1607017753.282 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2020-12-03T17:49:13.282Z,1607017753.282 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T17:49:13.296Z,1607017753.296 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:13.738Z,1607017753.738 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:13.738Z,1607017753.738 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2020-12-03T17:49:14.494Z,1607017754.494 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T17:49:14.495Z,1607017754.495 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2020-12-03T17:49:14.495Z,1607017754.495 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T17:49:14.538Z,1607017754.538 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:14.938Z,1607017754.938 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:14.938Z,1607017754.938 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2020-12-03T17:49:15.683Z,1607017755.683 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T17:49:15.683Z,1607017755.683 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2020-12-03T17:49:15.683Z,1607017755.683 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T17:49:15.693Z,1607017755.693 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:16.099Z,1607017756.099 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:16.099Z,1607017756.099 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2020-12-03T17:49:16.892Z,1607017756.892 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T17:49:16.892Z,1607017756.892 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2020-12-03T17:49:16.892Z,1607017756.892 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T17:49:16.939Z,1607017756.939 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:17.303Z,1607017757.303 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:17.303Z,1607017757.303 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2020-12-03T17:49:18.114Z,1607017758.114 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T17:49:18.115Z,1607017758.115 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2020-12-03T17:49:18.115Z,1607017758.115 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T17:49:18.125Z,1607017758.125 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:18.535Z,1607017758.535 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:18.535Z,1607017758.535 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2020-12-03T17:49:19.331Z,1607017759.331 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T17:49:19.331Z,1607017759.331 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2020-12-03T17:49:19.331Z,1607017759.331 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T17:49:19.377Z,1607017759.377 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:19.734Z,1607017759.734 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:19.735Z,1607017759.735 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2020-12-03T17:49:20.531Z,1607017760.531 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T17:49:20.531Z,1607017760.531 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2020-12-03T17:49:20.531Z,1607017760.531 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T17:49:20.585Z,1607017760.585 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:20.940Z,1607017760.940 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:20.940Z,1607017760.940 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2020-12-03T17:49:21.739Z,1607017761.739 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T17:49:21.739Z,1607017761.739 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2020-12-03T17:49:21.739Z,1607017761.739 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T17:49:21.786Z,1607017761.786 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:22.177Z,1607017762.177 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:22.177Z,1607017762.177 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2020-12-03T17:49:22.956Z,1607017762.956 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T17:49:22.956Z,1607017762.956 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2020-12-03T17:49:22.956Z,1607017762.956 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T17:49:22.966Z,1607017762.966 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:23.387Z,1607017763.387 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:23.387Z,1607017763.387 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2020-12-03T17:49:24.183Z,1607017764.183 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T17:49:24.183Z,1607017764.183 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2020-12-03T17:49:24.183Z,1607017764.183 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T17:49:24.217Z,1607017764.217 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:24.595Z,1607017764.595 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:24.595Z,1607017764.595 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2020-12-03T17:49:25.378Z,1607017765.378 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T17:49:25.378Z,1607017765.378 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2020-12-03T17:49:25.378Z,1607017765.378 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T17:49:25.413Z,1607017765.413 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:25.791Z,1607017765.791 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:25.791Z,1607017765.791 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2020-12-03T17:49:26.593Z,1607017766.593 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T17:49:26.593Z,1607017766.593 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2020-12-03T17:49:26.593Z,1607017766.593 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T17:49:26.603Z,1607017766.603 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:27.064Z,1607017767.064 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:27.064Z,1607017767.064 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2020-12-03T17:49:27.822Z,1607017767.822 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T17:49:27.823Z,1607017767.823 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2020-12-03T17:49:27.823Z,1607017767.823 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T17:49:27.847Z,1607017767.847 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:28.224Z,1607017768.224 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:28.224Z,1607017768.224 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2020-12-03T17:49:29.031Z,1607017769.031 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T17:49:29.031Z,1607017769.031 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2020-12-03T17:49:29.031Z,1607017769.031 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T17:49:29.041Z,1607017769.041 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:29.433Z,1607017769.433 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:29.433Z,1607017769.433 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2020-12-03T17:49:30.231Z,1607017770.231 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T17:49:30.231Z,1607017770.231 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2020-12-03T17:49:30.231Z,1607017770.231 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T17:49:30.242Z,1607017770.242 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:30.645Z,1607017770.645 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:30.645Z,1607017770.645 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2020-12-03T17:49:31.458Z,1607017771.458 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T17:49:31.458Z,1607017771.458 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21
2020-12-03T17:49:31.458Z,1607017771.458 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T17:49:31.477Z,1607017771.477 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:31.882Z,1607017771.882 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:31.882Z,1607017771.882 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21
2020-12-03T17:49:32.662Z,1607017772.662 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T17:49:32.662Z,1607017772.662 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22
2020-12-03T17:49:32.662Z,1607017772.662 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T17:49:32.685Z,1607017772.685 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:33.026Z,1607017773.026 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1607017728.000000 second
2020-12-03T17:49:33.097Z,1607017773.097 [Default:CheckIn:Read_GPS] Stopped
2020-12-03T17:49:33.097Z,1607017773.097 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-03T17:49:33.106Z,1607017773.106 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T17:49:33.106Z,1607017773.106 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22
2020-12-03T17:49:33.467Z,1607017773.467 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-12-03T17:49:38.639Z,1607017778.639 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20201203T174436/Courier0004.lzma
2020-12-03T17:49:39.658Z,1607017779.658 [DataOverHttps](INFO): Moved sent file to Logs/20201203T174436/Courier0004.lzma.bak
2020-12-03T17:49:39.659Z,1607017779.659 [DataOverHttps](INFO): SBD MOMSN=13387442
2020-12-03T17:49:40.974Z,1607017780.974 [CommandLine](IMPORTANT): got command failComponent
2020-12-03T17:49:40.975Z,1607017780.975 [CommandLine](IMPORTANT): Failed components:
2020-12-03T17:49:40.975Z,1607017780.975 [CommandLine](IMPORTANT): No failed Components.
2020-12-03T17:49:44.758Z,1607017784.758 [CommandLine](IMPORTANT): got command show stack
2020-12-03T17:49:44.758Z,1607017784.758 [CommandLine](IMPORTANT): Behavior Stack:

2020-12-03T17:49:44.759Z,1607017784.759 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface
2020-12-03T17:49:44.759Z,1607017784.759 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:Read_Iridium
2020-12-03T17:50:06.571Z,1607017806.571 [DataOverHttps](INFO): Sending 1040 bytes from file Logs/20201203T174436/Express0001.lzma
2020-12-03T17:50:07.570Z,1607017807.570 [DataOverHttps](INFO): Moved sent file to Logs/20201203T174436/Express0001.lzma.bak
2020-12-03T17:50:07.571Z,1607017807.571 [DataOverHttps](INFO): SBD MOMSN=13387444
2020-12-03T17:50:18.705Z,1607017818.705 [CommandLine](IMPORTANT): got command failComponent
2020-12-03T17:50:18.705Z,1607017818.705 [CommandLine](IMPORTANT): Failed components:
2020-12-03T17:50:18.706Z,1607017818.706 [CommandLine](IMPORTANT): No failed Components.
2020-12-03T17:50:27.613Z,1607017827.613 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-12-03T17:50:31.741Z,1607017831.741 [DataOverHttps](INFO): Sending 313 bytes from file Logs/20201203T174436/Express0005.lzma
2020-12-03T17:50:32.742Z,1607017832.742 [DataOverHttps](INFO): Moved sent file to Logs/20201203T174436/Express0005.lzma.bak
2020-12-03T17:50:32.743Z,1607017832.743 [DataOverHttps](INFO): SBD MOMSN=13387472
2020-12-03T17:50:38.960Z,1607017838.960 [Default:CheckIn:Read_Iridium] Stopped
2020-12-03T17:50:38.965Z,1607017838.965 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-03T17:50:38.965Z,1607017838.965 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-03T17:50:58.317Z,1607017858.317 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-03T17:55:39.569Z,1607018139.569 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-03T17:55:39.569Z,1607018139.569 [Default:CheckIn:C.Wait] Stopped
2020-12-03T17:55:39.569Z,1607018139.569 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-03T17:55:39.569Z,1607018139.569 [Default:CheckIn:D] Running Loop=1
2020-12-03T17:55:39.989Z,1607018139.989 [Default:CheckIn:D] Stopped
2020-12-03T17:55:39.989Z,1607018139.989 [Default:CheckIn:E] Running Loop=1
2020-12-03T17:55:40.400Z,1607018140.400 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.401302 min 
2020-12-03T17:55:40.400Z,1607018140.400 [Default:CheckIn:E] Stopped
2020-12-03T17:55:40.400Z,1607018140.400 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-03T17:55:40.400Z,1607018140.400 [Default:CheckIn] Stopped
2020-12-03T17:55:40.400Z,1607018140.400 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-03T17:55:40.400Z,1607018140.400 [Default:CheckIn](INFO): Running loop #2
2020-12-03T17:55:40.400Z,1607018140.400 [Default:CheckIn] Running Loop=2
2020-12-03T17:55:40.401Z,1607018140.401 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-03T17:55:40.401Z,1607018140.401 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-03T17:55:41.978Z,1607018141.978 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-12-03T17:55:41.978Z,1607018141.978 [NAL9602] Data Fault, FailCount= 1
2020-12-03T17:55:41.978Z,1607018141.978 [NAL9602](ERROR): Data Fault
2020-12-03T17:55:42.038Z,1607018142.038 [CBIT](ERROR): Data Fault in component: NAL9602
2020-12-03T17:55:42.386Z,1607018142.386 [NAL9602](INFO): Powering down
2020-12-03T17:55:43.226Z,1607018143.226 [CBIT](INFO): Clearing failed state for component NAL9602
2020-12-03T17:55:43.226Z,1607018143.226 [NAL9602] No Fault, FailCount= 1
2020-12-03T17:56:12.686Z,1607018172.686 [NAL9602](INFO): Powering up NAL9602
2020-12-03T17:56:23.594Z,1607018183.594 [NAL9602](INFO): NAL9602 initialized
2020-12-03T17:56:55.514Z,1607018215.514 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2020-12-03T18:00:40.549Z,1607018440.549 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-12-03T17:55:40.4Z
2020-12-03T18:00:40.550Z,1607018440.550 [Default:CheckIn:Read_GPS] Stopped
2020-12-03T18:00:40.550Z,1607018440.550 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-03T18:00:52.288Z,1607018452.288 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201203T174436/Courier0007.lzma
2020-12-03T18:00:53.290Z,1607018453.290 [DataOverHttps](INFO): Moved sent file to Logs/20201203T174436/Courier0007.lzma.bak
2020-12-03T18:00:53.291Z,1607018453.291 [DataOverHttps](INFO): SBD MOMSN=13387532
2020-12-03T18:01:14.203Z,1607018474.203 [DataOverHttps](INFO): Sending 534 bytes from file Logs/20201203T174436/Express0008.lzma
2020-12-03T18:01:15.230Z,1607018475.230 [DataOverHttps](INFO): Moved sent file to Logs/20201203T174436/Express0008.lzma.bak
2020-12-03T18:01:15.231Z,1607018475.231 [DataOverHttps](INFO): SBD MOMSN=13387534
2020-12-03T18:01:21.437Z,1607018481.437 [Default:CheckIn:Read_Iridium] Stopped
2020-12-03T18:01:21.437Z,1607018481.437 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-03T18:01:21.437Z,1607018481.437 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-03T18:01:26.265Z,1607018486.265 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-12-03T18:01:57.025Z,1607018517.025 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-03T18:06:22.134Z,1607018782.134 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-03T18:06:22.134Z,1607018782.134 [Default:CheckIn:C.Wait] Stopped
2020-12-03T18:06:22.134Z,1607018782.134 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-03T18:06:22.134Z,1607018782.134 [Default:CheckIn:D] Running Loop=1
2020-12-03T18:06:22.492Z,1607018782.492 [Default:CheckIn:D] Stopped
2020-12-03T18:06:22.492Z,1607018782.492 [Default:CheckIn:E] Running Loop=1
2020-12-03T18:06:22.920Z,1607018782.920 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.109690 min 
2020-12-03T18:06:22.920Z,1607018782.920 [Default:CheckIn:E] Stopped
2020-12-03T18:06:22.920Z,1607018782.920 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-03T18:06:22.920Z,1607018782.920 [Default:CheckIn] Stopped
2020-12-03T18:06:22.920Z,1607018782.920 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-03T18:06:22.920Z,1607018782.920 [Default:CheckIn](INFO): Running loop #3
2020-12-03T18:06:22.920Z,1607018782.920 [Default:CheckIn] Running Loop=3
2020-12-03T18:06:22.921Z,1607018782.921 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-03T18:06:22.921Z,1607018782.921 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-03T18:06:24.482Z,1607018784.482 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-12-03T18:06:24.482Z,1607018784.482 [NAL9602] Data Fault, FailCount= 2
2020-12-03T18:06:24.482Z,1607018784.482 [NAL9602](ERROR): Data Fault
2020-12-03T18:06:24.558Z,1607018784.558 [CBIT](ERROR): Data Fault in component: NAL9602
2020-12-03T18:06:24.890Z,1607018784.890 [NAL9602](INFO): Powering down
2020-12-03T18:06:25.741Z,1607018785.741 [CBIT](INFO): Clearing failed state for component NAL9602
2020-12-03T18:06:25.741Z,1607018785.741 [NAL9602] No Fault, FailCount= 2
2020-12-03T18:06:55.186Z,1607018815.186 [NAL9602](INFO): Powering up NAL9602
2020-12-03T18:07:06.094Z,1607018826.094 [NAL9602](INFO): NAL9602 initialized
2020-12-03T18:11:23.112Z,1607019083.112 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-12-03T18:06:22.9Z
2020-12-03T18:11:23.112Z,1607019083.112 [Default:CheckIn:Read_GPS] Stopped
2020-12-03T18:11:23.112Z,1607019083.112 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-03T18:11:33.607Z,1607019093.607 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-12-03T18:12:08.766Z,1607019128.766 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-12-03T18:12:18.236Z,1607019138.236 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201203T174436/Courier0010.lzma
2020-12-03T18:12:19.238Z,1607019139.238 [DataOverHttps](INFO): Moved sent file to Logs/20201203T174436/Courier0010.lzma.bak
2020-12-03T18:12:19.239Z,1607019139.239 [DataOverHttps](INFO): SBD MOMSN=13387607
2020-12-03T18:12:39.469Z,1607019159.469 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-03T18:12:46.885Z,1607019166.885 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20201203T174436/Express0011.lzma
2020-12-03T18:12:47.886Z,1607019167.886 [DataOverHttps](INFO): Moved sent file to Logs/20201203T174436/Express0011.lzma.bak
2020-12-03T18:12:47.887Z,1607019167.887 [DataOverHttps](INFO): SBD MOMSN=13387609
2020-12-03T18:12:54.041Z,1607019174.041 [Default:CheckIn:Read_Iridium] Stopped
2020-12-03T18:12:54.041Z,1607019174.041 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-03T18:12:54.041Z,1607019174.041 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-03T18:15:46.927Z,1607019346.927 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 5.
2020-12-03T18:15:46.930Z,1607019346.930 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2020-12-03T18:17:54.599Z,1607019474.599 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-03T18:17:54.599Z,1607019474.599 [Default:CheckIn:C.Wait] Stopped
2020-12-03T18:17:54.599Z,1607019474.599 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-03T18:17:54.599Z,1607019474.599 [Default:CheckIn:D] Running Loop=1
2020-12-03T18:17:55.054Z,1607019475.054 [Default:CheckIn:D] Stopped
2020-12-03T18:17:55.054Z,1607019475.054 [Default:CheckIn:E] Running Loop=1
2020-12-03T18:17:55.422Z,1607019475.422 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.652384 min 
2020-12-03T18:17:55.422Z,1607019475.422 [Default:CheckIn:E] Stopped
2020-12-03T18:17:55.422Z,1607019475.422 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-03T18:17:55.422Z,1607019475.422 [Default:CheckIn] Stopped
2020-12-03T18:17:55.422Z,1607019475.422 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-03T18:17:55.422Z,1607019475.422 [Default:CheckIn](INFO): Running loop #4
2020-12-03T18:17:55.422Z,1607019475.422 [Default:CheckIn] Running Loop=4
2020-12-03T18:17:55.422Z,1607019475.422 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-03T18:17:55.423Z,1607019475.423 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-03T18:17:57.022Z,1607019477.022 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-12-03T18:17:57.022Z,1607019477.022 [NAL9602] Data Fault, FailCount= 3
2020-12-03T18:17:57.022Z,1607019477.022 [NAL9602](ERROR): Data Fault
2020-12-03T18:17:57.058Z,1607019477.058 [CBIT](ERROR): Data Fault in component: NAL9602
2020-12-03T18:17:57.417Z,1607019477.417 [NAL9602](INFO): Powering down
2020-12-03T18:17:58.256Z,1607019478.256 [CBIT](INFO): Clearing failed state for component NAL9602
2020-12-03T18:17:58.256Z,1607019478.256 [NAL9602] No Fault, FailCount= 3
2020-12-03T18:18:27.722Z,1607019507.722 [NAL9602](INFO): Powering up NAL9602
2020-12-03T18:18:38.626Z,1607019518.626 [NAL9602](INFO): NAL9602 initialized
2020-12-03T18:22:55.605Z,1607019775.605 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-12-03T18:17:55.4Z
2020-12-03T18:22:55.605Z,1607019775.605 [Default:CheckIn:Read_GPS] Stopped
2020-12-03T18:22:55.605Z,1607019775.605 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-03T18:23:41.250Z,1607019821.250 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-12-03T18:24:11.949Z,1607019851.949 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-03T18:25:19.736Z,1607019919.736 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20201203T174436/Courier0013.lzma
2020-12-03T18:25:20.739Z,1607019920.739 [DataOverHttps](INFO): Moved sent file to Logs/20201203T174436/Courier0013.lzma.bak
2020-12-03T18:25:20.739Z,1607019920.739 [DataOverHttps](INFO): SBD MOMSN=13387738
2020-12-03T18:25:42.177Z,1607019942.177 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20201203T174436/Express0014.lzma
2020-12-03T18:25:43.178Z,1607019943.178 [DataOverHttps](INFO): Moved sent file to Logs/20201203T174436/Express0014.lzma.bak
2020-12-03T18:25:43.179Z,1607019943.179 [DataOverHttps](INFO): SBD MOMSN=13387744
2020-12-03T18:25:49.340Z,1607019949.340 [Default:CheckIn:Read_Iridium] Stopped
2020-12-03T18:25:49.340Z,1607019949.340 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-03T18:25:49.340Z,1607019949.340 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-03T18:30:49.905Z,1607020249.905 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-03T18:30:49.905Z,1607020249.905 [Default:CheckIn:C.Wait] Stopped
2020-12-03T18:30:49.905Z,1607020249.905 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-03T18:30:49.906Z,1607020249.906 [Default:CheckIn:D] Running Loop=1
2020-12-03T18:30:50.308Z,1607020250.308 [Default:CheckIn:D] Stopped
2020-12-03T18:30:50.308Z,1607020250.308 [Default:CheckIn:E] Running Loop=1
2020-12-03T18:30:50.715Z,1607020250.715 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.573279 min 
2020-12-03T18:30:50.716Z,1607020250.716 [Default:CheckIn:E] Stopped
2020-12-03T18:30:50.716Z,1607020250.716 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-03T18:30:50.716Z,1607020250.716 [Default:CheckIn] Stopped
2020-12-03T18:30:50.716Z,1607020250.716 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-03T18:30:50.716Z,1607020250.716 [Default:CheckIn](INFO): Running loop #5
2020-12-03T18:30:50.716Z,1607020250.716 [Default:CheckIn] Running Loop=5
2020-12-03T18:30:50.716Z,1607020250.716 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-03T18:30:50.716Z,1607020250.716 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-03T18:30:52.314Z,1607020252.314 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-12-03T18:30:52.314Z,1607020252.314 [NAL9602] Data Fault, FailCount= 4
2020-12-03T18:30:52.314Z,1607020252.314 [NAL9602](ERROR): Data Fault
2020-12-03T18:30:52.370Z,1607020252.370 [CBIT](ERROR): Data Fault in component: NAL9602
2020-12-03T18:30:52.721Z,1607020252.721 [NAL9602](INFO): Powering down
2020-12-03T18:30:53.565Z,1607020253.565 [CBIT](INFO): Clearing failed state for component NAL9602
2020-12-03T18:30:53.565Z,1607020253.565 [NAL9602] No Fault, FailCount= 4
2020-12-03T18:31:23.031Z,1607020283.031 [NAL9602](INFO): Powering up NAL9602
2020-12-03T18:31:33.934Z,1607020293.934 [NAL9602](INFO): NAL9602 initialized
2020-12-03T18:35:50.893Z,1607020550.893 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-12-03T18:30:50.7Z
2020-12-03T18:35:50.893Z,1607020550.893 [Default:CheckIn:Read_GPS] Stopped
2020-12-03T18:35:50.894Z,1607020550.894 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-03T18:36:36.594Z,1607020596.594 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-12-03T18:37:07.346Z,1607020627.346 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-03T18:38:37.168Z,1607020717.168 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201203T174436/Courier0016.lzma
2020-12-03T18:38:38.170Z,1607020718.170 [DataOverHttps](INFO): Moved sent file to Logs/20201203T174436/Courier0016.lzma.bak
2020-12-03T18:38:38.171Z,1607020718.171 [DataOverHttps](INFO): SBD MOMSN=13387828
2020-12-03T18:38:59.661Z,1607020739.661 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20201203T174436/Express0017.lzma
2020-12-03T18:39:00.663Z,1607020740.663 [DataOverHttps](INFO): Moved sent file to Logs/20201203T174436/Express0017.lzma.bak
2020-12-03T18:39:00.663Z,1607020740.663 [DataOverHttps](INFO): SBD MOMSN=13387835
2020-12-03T18:39:06.674Z,1607020746.674 [Default:CheckIn:Read_Iridium] Stopped
2020-12-03T18:39:06.674Z,1607020746.674 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-03T18:39:06.674Z,1607020746.674 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-03T18:44:07.248Z,1607021047.248 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-03T18:44:07.248Z,1607021047.248 [Default:CheckIn:C.Wait] Stopped
2020-12-03T18:44:07.248Z,1607021047.248 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-03T18:44:07.248Z,1607021047.248 [Default:CheckIn:D] Running Loop=1
2020-12-03T18:44:07.662Z,1607021047.662 [Default:CheckIn:D] Stopped
2020-12-03T18:44:07.662Z,1607021047.662 [Default:CheckIn:E] Running Loop=1
2020-12-03T18:44:08.049Z,1607021048.049 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 55.862528 min 
2020-12-03T18:44:08.050Z,1607021048.050 [Default:CheckIn:E] Stopped
2020-12-03T18:44:08.050Z,1607021048.050 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-03T18:44:08.050Z,1607021048.050 [Default:CheckIn] Stopped
2020-12-03T18:44:08.050Z,1607021048.050 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-03T18:44:08.050Z,1607021048.050 [Default:CheckIn](INFO): Running loop #6
2020-12-03T18:44:08.050Z,1607021048.050 [Default:CheckIn] Running Loop=6
2020-12-03T18:44:08.050Z,1607021048.050 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-03T18:44:08.050Z,1607021048.050 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-03T18:44:09.645Z,1607021049.645 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-12-03T18:44:09.646Z,1607021049.646 [NAL9602] Data Fault, FailCount= 5
2020-12-03T18:44:09.646Z,1607021049.646 [NAL9602](ERROR): Data Fault
2020-12-03T18:44:09.744Z,1607021049.744 [CBIT](ERROR): Data Fault in component: NAL9602
2020-12-03T18:44:09.745Z,1607021049.745 [CBIT](CRITICAL): Data Fault in component: NAL9602
2020-12-03T18:44:10.049Z,1607021050.049 [NAL9602](INFO): Powering down
2020-12-03T18:44:10.080Z,1607021050.080 [CommandLine](FAULT): Scheduling is paused
2020-12-03T18:44:10.080Z,1607021050.080 [CBIT](INFO): Critical error at 20201203T184409
2020-12-03T18:44:10.080Z,1607021050.080 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-12-03T18:46:09.701Z,1607021169.701 [CBIT](INFO): Clearing failed state for component NAL9602
2020-12-03T18:46:09.701Z,1607021169.701 [NAL9602] No Fault, FailCount= 5
2020-12-03T18:46:10.066Z,1607021170.066 [NAL9602](INFO): Powering up NAL9602
2020-12-03T18:46:20.979Z,1607021180.979 [NAL9602](INFO): NAL9602 initialized
2020-12-03T18:49:08.244Z,1607021348.244 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-12-03T18:44:08.1Z
2020-12-03T18:49:08.244Z,1607021348.244 [Default:CheckIn:Read_GPS] Stopped
2020-12-03T18:49:08.244Z,1607021348.244 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-03T18:51:23.610Z,1607021483.610 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-12-03T18:51:54.349Z,1607021514.349 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-03T18:51:59.677Z,1607021519.677 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20201203T174436/Courier0019.lzma
2020-12-03T18:52:00.678Z,1607021520.678 [DataOverHttps](INFO): Moved sent file to Logs/20201203T174436/Courier0019.lzma.bak
2020-12-03T18:52:00.679Z,1607021520.679 [DataOverHttps](INFO): SBD MOMSN=13387900
2020-12-03T18:52:31.565Z,1607021551.565 [DataOverHttps](INFO): Sending 247 bytes from file Logs/20201203T174436/Express0020.lzma
2020-12-03T18:52:32.567Z,1607021552.567 [DataOverHttps](INFO): Moved sent file to Logs/20201203T174436/Express0020.lzma.bak
2020-12-03T18:52:32.567Z,1607021552.567 [DataOverHttps](INFO): SBD MOMSN=13387908
2020-12-03T18:52:43.267Z,1607021563.267 [Default:CheckIn:Read_Iridium] Stopped
2020-12-03T18:52:43.268Z,1607021563.268 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-03T18:52:43.268Z,1607021563.268 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-03T18:57:43.839Z,1607021863.839 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-03T18:57:43.839Z,1607021863.839 [Default:CheckIn:C.Wait] Stopped
2020-12-03T18:57:43.839Z,1607021863.839 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-03T18:57:43.839Z,1607021863.839 [Default:CheckIn:D] Running Loop=1
2020-12-03T18:57:44.245Z,1607021864.245 [Default:CheckIn:D] Stopped
2020-12-03T18:57:44.245Z,1607021864.245 [Default:CheckIn:E] Running Loop=1
2020-12-03T18:57:44.661Z,1607021864.661 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 69.472233 min 
2020-12-03T18:57:44.661Z,1607021864.661 [Default:CheckIn:E] Stopped
2020-12-03T18:57:44.661Z,1607021864.661 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-03T18:57:44.661Z,1607021864.661 [Default:CheckIn] Stopped
2020-12-03T18:57:44.661Z,1607021864.661 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-03T18:57:44.661Z,1607021864.661 [Default:CheckIn](INFO): Running loop #7
2020-12-03T18:57:44.661Z,1607021864.661 [Default:CheckIn] Running Loop=7
2020-12-03T18:57:44.661Z,1607021864.661 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-03T18:57:44.661Z,1607021864.661 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-03T18:57:46.254Z,1607021866.254 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-12-03T18:57:46.254Z,1607021866.254 [NAL9602] Data Fault, FailCount= 1
2020-12-03T18:57:46.254Z,1607021866.254 [NAL9602](ERROR): Data Fault
2020-12-03T18:57:46.330Z,1607021866.330 [CBIT](ERROR): Data Fault in component: NAL9602
2020-12-03T18:57:46.657Z,1607021866.657 [NAL9602](INFO): Powering down
2020-12-03T18:57:47.500Z,1607021867.500 [CBIT](INFO): Clearing failed state for component NAL9602
2020-12-03T18:57:47.505Z,1607021867.505 [NAL9602] No Fault, FailCount= 1
2020-12-03T18:58:16.957Z,1607021896.957 [NAL9602](INFO): Powering up NAL9602
2020-12-03T18:58:27.870Z,1607021907.870 [NAL9602](INFO): NAL9602 initialized
2020-12-03T18:58:57.817Z,1607021937.817 [CommandLine](IMPORTANT): got command restart application
2020-12-03T18:58:58.821Z,1607021938.821 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-03T18:58:58.821Z,1607021938.821 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-12-03T18:58:58.989Z,1607021938.989 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-12-03T18:58:58.989Z,1607021938.989 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-12-03T18:58:58.990Z,1607021938.990 [CommandLine](INFO): Join timeout helper Thread ID is 4750
2020-12-03T18:58:59.005Z,1607021939.005 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-12-03T18:58:59.005Z,1607021939.005 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-12-03T18:58:59.005Z,1607021939.005 [NavChartDb](INFO): Join timeout helper Thread ID is 4751
2020-12-03T18:58:59.145Z,1607021939.145 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-03T18:58:59.145Z,1607021939.145 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-12-03T18:58:59.165Z,1607021939.165 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-12-03T18:58:59.165Z,1607021939.165 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-12-03T18:58:59.165Z,1607021939.165 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4752
2020-12-03T18:58:59.597Z,1607021939.597 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-03T18:58:59.597Z,1607021939.597 [WetLabsBB2FL](INFO): Powering down
2020-12-03T18:58:59.598Z,1607021939.598 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-12-03T18:58:59.609Z,1607021939.609 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-12-03T18:58:59.609Z,1607021939.609 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-12-03T18:58:59.609Z,1607021939.609 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4753
2020-12-03T18:58:59.989Z,1607021939.989 [CTD_Seabird](INFO): Powering down
2020-12-03T18:59:00.001Z,1607021940.001 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-03T18:59:00.001Z,1607021940.001 [CTD_Seabird](INFO): Powering down
2020-12-03T18:59:00.013Z,1607021940.013 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-12-03T18:59:00.025Z,1607021940.025 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-12-03T18:59:00.025Z,1607021940.025 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-12-03T18:59:00.025Z,1607021940.025 [Radio_Surface](INFO): Join timeout helper Thread ID is 4754
2020-12-03T18:59:00.389Z,1607021940.389 [Radio_Surface](INFO): Powering down
2020-12-03T18:59:00.389Z,1607021940.389 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-03T18:59:00.390Z,1607021940.390 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-12-03T18:59:00.402Z,1607021940.402 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-12-03T18:59:00.402Z,1607021940.402 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-12-03T18:59:00.402Z,1607021940.402 [Onboard](INFO): Join timeout helper Thread ID is 4755
2020-12-03T18:59:04.801Z,1607021944.801 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-03T18:59:04.801Z,1607021944.801 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-12-03T18:59:04.809Z,1607021944.809 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-12-03T18:59:04.809Z,1607021944.809 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-12-03T18:59:04.809Z,1607021944.809 [DataOverHttps](INFO): Join timeout helper Thread ID is 4756
2020-12-03T18:59:05.669Z,1607021945.669 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-03T18:59:05.672Z,1607021945.672 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-12-03T18:59:05.685Z,1607021945.685 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-12-03T18:59:05.685Z,1607021945.685 [logger ThreadHandler](INFO): Thread cancelled.
2020-12-03T18:59:05.686Z,1607021945.686 [logger](INFO): Join timeout helper Thread ID is 4757
2020-12-03T18:59:05.697Z,1607021945.697 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-03T18:59:05.697Z,1607021945.697 [logger ThreadHandler](INFO): Thread cancelled.
2020-12-03T18:59:05.705Z,1607021945.705 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-12-03T18:59:05.705Z,1607021945.705 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-12-03T18:59:05.706Z,1607021945.706 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-12-03T18:59:05.706Z,1607021945.706 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-12-03T18:59:05.706Z,1607021945.706 [controlThread](INFO): Join timeout helper Thread ID is 4758
2020-12-03T18:59:05.825Z,1607021945.825 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-03T18:59:05.825Z,1607021945.825 [controlThread](DEBUG): Uninitializing ControlThread
2020-12-03T18:59:05.825Z,1607021945.825 [AHRS_M2](INFO): Powering down
2020-12-03T18:59:05.897Z,1607021945.897 [NAL9602](INFO): Powering down
2020-12-03T18:59:05.898Z,1607021945.898 [DAT](INFO): Powering down
2020-12-03T18:59:06.019Z,1607021946.019 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-12-03T18:59:06.020Z,1607021946.020 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-12-03T18:59:06.020Z,1607021946.020 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-12-03T18:59:06.021Z,1607021946.021 [MissionManager](INFO): Uninitializing Mission Default
2020-12-03T18:59:06.021Z,1607021946.021 [Default] Stopped
2020-12-03T18:59:06.021Z,1607021946.021 [Default](DEBUG): Aggregate::uninitialize Default
2020-12-03T18:59:06.021Z,1607021946.021 [Default:B.GoToSurface] Stopped
2020-12-03T18:59:06.021Z,1607021946.021 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-12-03T18:59:06.021Z,1607021946.021 [Default:CheckIn] Stopped
2020-12-03T18:59:06.022Z,1607021946.022 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-03T18:59:06.022Z,1607021946.022 [Default:CheckIn:Read_GPS] Stopped
2020-12-03T18:59:06.024Z,1607021946.024 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-12-03T18:59:06.024Z,1607021946.024 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-12-03T18:59:06.024Z,1607021946.024 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-12-03T18:59:06.025Z,1607021946.025 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-12-03T18:59:06.025Z,1607021946.025 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-12-03T18:59:06.025Z,1607021946.025 [BuoyancyServo](INFO): Powering down
2020-12-03T18:59:06.037Z,1607021946.037 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-12-03T18:59:06.037Z,1607021946.037 [ElevatorServo](INFO): Powering down
2020-12-03T18:59:06.038Z,1607021946.038 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-12-03T18:59:06.038Z,1607021946.038 [MassServo](INFO): Powering down
2020-12-03T18:59:06.038Z,1607021946.038 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-12-03T18:59:06.039Z,1607021946.039 [RudderServo](INFO): Powering down
2020-12-03T18:59:06.039Z,1607021946.039 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-12-03T18:59:06.039Z,1607021946.039 [ThrusterServo](INFO): Powering down
2020-12-03T18:59:06.040Z,1607021946.040 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-12-03T18:59:06.040Z,1607021946.040 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-12-03T18:59:06.041Z,1607021946.041 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-12-03T18:59:06.041Z,1607021946.041 [CBIT](DEBUG): Powering off loads.
2020-12-03T18:59:06.052Z,1607021946.052 [CBIT](DEBUG): Disabling WDT.
2020-12-03T18:59:06.064Z,1607021946.064 [CBIT](DEBUG): Opening all GF detection circuits.
2020-12-03T18:59:06.065Z,1607021946.065 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-12-03T18:59:06.161Z,1607021946.161 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-12-03T18:59:06.163Z,1607021946.163 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-12-03T18:59:06.168Z,1607021946.168 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-12-03T18:59:06.211Z,1607021946.211 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-12-03T18:59:06.216Z,1607021946.216 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-12-03T18:59:06.251Z,1607021946.251 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-12-03T18:59:06.322Z,1607021946.322 [logger ThreadHandler](INFO): Thread cancelled.