2019-10-03T13:58:03.119Z,1570111083.119 [Supervisor](DEBUG): Initializing supervisor. 2019-10-03T13:58:03.122Z,1570111083.122 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-10-03T13:58:03.123Z,1570111083.123 [SyncHandler](INFO): Protected caller Thread ID is 6894 2019-10-03T13:58:03.123Z,1570111083.123 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-10-03T13:58:03.124Z,1570111083.124 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-10-03T13:58:03.125Z,1570111083.125 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6895 2019-10-03T13:58:03.128Z,1570111083.128 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-10-03T13:58:03.139Z,1570111083.139 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-10-03T13:58:03.140Z,1570111083.140 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-10-03T13:58:03.140Z,1570111083.140 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6896 2019-10-03T13:58:03.141Z,1570111083.141 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-10-03T13:58:03.142Z,1570111083.142 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-10-03T13:58:03.143Z,1570111083.143 [logger ThreadHandler](INFO): Protected caller Thread ID is 6897 2019-10-03T13:58:03.144Z,1570111083.144 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-10-03T13:58:03.145Z,1570111083.145 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-10-03T13:58:03.146Z,1570111083.146 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-10-03T13:58:03.631Z,1570111083.631 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-10-03T13:58:03.631Z,1570111083.631 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-10-03T13:58:03.770Z,1570111083.770 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-10-03T13:58:03.771Z,1570111083.771 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-10-03T13:58:03.850Z,1570111083.850 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-10-03T13:58:03.946Z,1570111083.946 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-10-03T13:58:03.946Z,1570111083.946 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-10-03T13:58:04.039Z,1570111084.039 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-10-03T13:58:04.040Z,1570111084.040 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-10-03T13:58:04.406Z,1570111084.406 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-10-03T13:58:04.406Z,1570111084.406 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-10-03T13:58:04.506Z,1570111084.506 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-10-03T13:58:04.506Z,1570111084.506 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-10-03T13:58:04.951Z,1570111084.951 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-10-03T13:58:04.951Z,1570111084.951 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-10-03T13:58:05.143Z,1570111085.143 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-10-03T13:58:05.144Z,1570111085.144 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-10-03T13:58:05.436Z,1570111085.436 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-10-03T13:58:05.437Z,1570111085.437 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-10-03T13:58:05.641Z,1570111085.641 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-10-03T13:58:05.642Z,1570111085.642 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-10-03T13:58:05.780Z,1570111085.780 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-10-03T13:58:05.780Z,1570111085.780 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-10-03T13:58:06.398Z,1570111086.398 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-10-03T13:58:06.399Z,1570111086.399 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-10-03T13:58:06.721Z,1570111086.721 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-10-03T13:58:06.722Z,1570111086.722 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-10-03T13:58:06.922Z,1570111086.922 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-10-03T13:58:06.924Z,1570111086.924 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2019-10-03T13:58:06.925Z,1570111086.925 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2019-10-03T13:58:07.112Z,1570111087.112 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2019-10-03T13:58:07.193Z,1570111087.193 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2019-10-03T13:58:07.325Z,1570111087.325 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2019-10-03T13:58:07.408Z,1570111087.408 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2019-10-03T13:58:07.695Z,1570111087.695 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-10-03T13:58:07.695Z,1570111087.695 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2019-10-03T13:58:07.924Z,1570111087.924 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2019-10-03T13:58:08.065Z,1570111088.065 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2019-10-03T13:58:08.241Z,1570111088.241 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2019-10-03T13:58:08.490Z,1570111088.490 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2019-10-03T13:58:08.835Z,1570111088.835 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2019-10-03T13:58:08.933Z,1570111088.933 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/ 2019-10-03T13:58:08.933Z,1570111088.933 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-10-03T13:58:08.939Z,1570111088.939 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-10-03T13:58:08.953Z,1570111088.953 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-10-03T13:58:08.954Z,1570111088.954 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-10-03T13:58:09.021Z,1570111089.021 [VerticalControl](DEBUG): Construct VerticalControl. 2019-10-03T13:58:09.132Z,1570111089.132 [VerticalControl] Loaded 2019-10-03T13:58:09.132Z,1570111089.132 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-10-03T13:58:09.133Z,1570111089.133 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-10-03T13:58:09.202Z,1570111089.202 [HorizontalControl] Loaded 2019-10-03T13:58:09.202Z,1570111089.202 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-10-03T13:58:09.203Z,1570111089.203 [SpeedControl](DEBUG): Construct SpeedControl. 2019-10-03T13:58:09.208Z,1570111089.208 [SpeedControl] Loaded 2019-10-03T13:58:09.209Z,1570111089.209 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-10-03T13:58:09.209Z,1570111089.209 [LoopControl](DEBUG): Construct LoopControl. 2019-10-03T13:58:09.210Z,1570111089.210 [LoopControl] Loaded 2019-10-03T13:58:09.210Z,1570111089.210 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-10-03T13:58:09.211Z,1570111089.211 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-10-03T13:58:09.211Z,1570111089.211 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-10-03T13:58:09.334Z,1570111089.334 [BuoyancyServo] Loaded 2019-10-03T13:58:09.335Z,1570111089.335 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-10-03T13:58:09.346Z,1570111089.346 [ElevatorServo] Loaded 2019-10-03T13:58:09.346Z,1570111089.346 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-10-03T13:58:09.357Z,1570111089.357 [MassServo] Loaded 2019-10-03T13:58:09.357Z,1570111089.357 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-10-03T13:58:09.368Z,1570111089.368 [RudderServo] Loaded 2019-10-03T13:58:09.368Z,1570111089.368 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-10-03T13:58:09.379Z,1570111089.379 [ThrusterServo] Loaded 2019-10-03T13:58:09.379Z,1570111089.379 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-10-03T13:58:09.379Z,1570111089.379 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-10-03T13:58:09.380Z,1570111089.380 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-10-03T13:58:09.421Z,1570111089.421 [DepthRateCalculator] Loaded 2019-10-03T13:58:09.422Z,1570111089.422 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-10-03T13:58:09.427Z,1570111089.427 [PitchRateCalculator] Loaded 2019-10-03T13:58:09.427Z,1570111089.427 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-10-03T13:58:09.442Z,1570111089.442 [SpeedCalculator] Loaded 2019-10-03T13:58:09.442Z,1570111089.442 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-10-03T13:58:09.463Z,1570111089.463 [TempGradientCalculator] Loaded 2019-10-03T13:58:09.463Z,1570111089.463 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-10-03T13:58:09.468Z,1570111089.468 [YawRateCalculator] Loaded 2019-10-03T13:58:09.469Z,1570111089.469 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-10-03T13:58:09.498Z,1570111089.498 [ElevatorOffsetCalculator] Loaded 2019-10-03T13:58:09.498Z,1570111089.498 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-10-03T13:58:09.499Z,1570111089.499 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-10-03T13:58:09.499Z,1570111089.499 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-10-03T13:58:09.601Z,1570111089.601 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-10-03T13:58:09.601Z,1570111089.601 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-10-03T13:58:09.622Z,1570111089.622 [NavChart] Loaded 2019-10-03T13:58:09.622Z,1570111089.622 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-10-03T13:58:09.626Z,1570111089.626 [UniversalFixResidualReporter] Loaded 2019-10-03T13:58:09.626Z,1570111089.626 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-10-03T13:58:09.626Z,1570111089.626 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-10-03T13:58:09.627Z,1570111089.627 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-10-03T13:58:09.864Z,1570111089.864 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2019-10-03T13:58:09.869Z,1570111089.869 [AHRS_M2](INFO): created writer for : platform_orientation 2019-10-03T13:58:09.871Z,1570111089.871 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2019-10-03T13:58:09.876Z,1570111089.876 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2019-10-03T13:58:09.877Z,1570111089.877 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2019-10-03T13:58:09.882Z,1570111089.882 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2019-10-03T13:58:09.882Z,1570111089.882 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2019-10-03T13:58:09.887Z,1570111089.887 [AHRS_M2](INFO): created writer for : platform_roll_angle 2019-10-03T13:58:09.958Z,1570111089.958 [AHRS_M2] Loaded 2019-10-03T13:58:09.959Z,1570111089.959 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2019-10-03T13:58:10.032Z,1570111090.032 [DataOverHttps] Loaded 2019-10-03T13:58:10.032Z,1570111090.032 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-10-03T13:58:10.034Z,1570111090.034 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407F84E0 2019-10-03T13:58:10.034Z,1570111090.034 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6976 2019-10-03T13:58:10.047Z,1570111090.047 [Depth_Keller] Loaded 2019-10-03T13:58:10.048Z,1570111090.048 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-10-03T13:58:10.052Z,1570111090.052 [DropWeight] Loaded 2019-10-03T13:58:10.053Z,1570111090.053 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-10-03T13:58:10.148Z,1570111090.148 [NAL9602] Loaded 2019-10-03T13:58:10.149Z,1570111090.149 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-10-03T13:58:10.164Z,1570111090.164 [Onboard] Loaded 2019-10-03T13:58:10.164Z,1570111090.164 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-10-03T13:58:10.168Z,1570111090.168 [Radio_Surface] Loaded 2019-10-03T13:58:10.168Z,1570111090.168 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-10-03T13:58:10.169Z,1570111090.169 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408284E0 2019-10-03T13:58:10.169Z,1570111090.169 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6977 2019-10-03T13:58:10.295Z,1570111090.295 [DAT] Loaded 2019-10-03T13:58:10.295Z,1570111090.295 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2019-10-03T13:58:11.793Z,1570111091.793 [BPC1] Loaded 2019-10-03T13:58:11.793Z,1570111091.793 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-10-03T13:58:11.793Z,1570111091.793 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-10-03T13:58:11.794Z,1570111091.794 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-10-03T13:58:11.864Z,1570111091.864 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-10-03T13:58:11.864Z,1570111091.864 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-10-03T13:58:11.963Z,1570111091.963 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-10-03T13:58:11.963Z,1570111091.963 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-10-03T13:58:11.996Z,1570111091.996 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-10-03T13:58:11.996Z,1570111091.996 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-10-03T13:58:12.168Z,1570111092.168 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2019-10-03T13:58:12.173Z,1570111092.173 [CTD_Seabird](INFO): created writer for : sea_water_density 2019-10-03T13:58:12.173Z,1570111092.173 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2019-10-03T13:58:12.178Z,1570111092.178 [CTD_Seabird](INFO): created writer for : depth 2019-10-03T13:58:12.178Z,1570111092.178 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2019-10-03T13:58:12.183Z,1570111092.183 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2019-10-03T13:58:12.184Z,1570111092.184 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2019-10-03T13:58:12.189Z,1570111092.189 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2019-10-03T13:58:12.190Z,1570111092.190 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2019-10-03T13:58:12.195Z,1570111092.195 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2019-10-03T13:58:12.195Z,1570111092.195 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2019-10-03T13:58:12.201Z,1570111092.201 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2019-10-03T13:58:12.201Z,1570111092.201 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2019-10-03T13:58:12.207Z,1570111092.207 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2019-10-03T13:58:12.207Z,1570111092.207 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): mass_concentration_of_oxygen_in_sea_water 2019-10-03T13:58:12.212Z,1570111092.212 [CTD_Seabird](INFO): created writer for : mass_concentration_of_oxygen_in_sea_water 2019-10-03T13:58:12.213Z,1570111092.213 [CTD_Seabird](DEBUG): LcmSlateWriter::add(DAtaURI): CTD_Seabird.sea_water_dissolved_oxygen_freq 2019-10-03T13:58:12.213Z,1570111092.213 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): dataName: sea_water_dissolved_oxygen_freq 2019-10-03T13:58:12.246Z,1570111092.246 [CTD_Seabird] Loaded 2019-10-03T13:58:12.246Z,1570111092.246 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2019-10-03T13:58:12.247Z,1570111092.247 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409BF4E0 2019-10-03T13:58:12.248Z,1570111092.248 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6978 2019-10-03T13:58:12.278Z,1570111092.278 [ESPComponent] Loaded 2019-10-03T13:58:12.279Z,1570111092.279 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2019-10-03T13:58:12.293Z,1570111092.293 [PAR_Licor] Loaded 2019-10-03T13:58:12.293Z,1570111092.293 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2019-10-03T13:58:12.299Z,1570111092.299 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470 2019-10-03T13:58:12.299Z,1570111092.299 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470 2019-10-03T13:58:12.304Z,1570111092.304 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650 2019-10-03T13:58:12.304Z,1570111092.304 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650 2019-10-03T13:58:12.308Z,1570111092.308 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl 2019-10-03T13:58:12.308Z,1570111092.308 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl 2019-10-03T13:58:12.313Z,1570111092.313 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm 2019-10-03T13:58:12.313Z,1570111092.313 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm 2019-10-03T13:58:12.317Z,1570111092.317 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm 2019-10-03T13:58:12.317Z,1570111092.317 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm 2019-10-03T13:58:12.322Z,1570111092.322 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm 2019-10-03T13:58:12.322Z,1570111092.322 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm 2019-10-03T13:58:12.326Z,1570111092.326 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm 2019-10-03T13:58:12.326Z,1570111092.326 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm 2019-10-03T13:58:12.330Z,1570111092.330 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water 2019-10-03T13:58:12.335Z,1570111092.335 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water 2019-10-03T13:58:12.335Z,1570111092.335 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 2019-10-03T13:58:12.335Z,1570111092.335 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water 2019-10-03T13:58:12.340Z,1570111092.340 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2019-10-03T13:58:12.340Z,1570111092.340 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2019-10-03T13:58:12.344Z,1570111092.344 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2019-10-03T13:58:12.344Z,1570111092.344 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2019-10-03T13:58:12.349Z,1570111092.349 [WetLabsBB2FL] Loaded 2019-10-03T13:58:12.349Z,1570111092.349 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-10-03T13:58:12.350Z,1570111092.350 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409EF4E0 2019-10-03T13:58:12.350Z,1570111092.350 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6979 2019-10-03T13:58:12.351Z,1570111092.351 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-10-03T13:58:12.352Z,1570111092.352 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-10-03T13:58:12.649Z,1570111092.649 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-10-03T13:58:12.650Z,1570111092.650 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-10-03T13:58:12.781Z,1570111092.781 [SBIT](DEBUG): Construct Startup Built In Test. 2019-10-03T13:58:12.792Z,1570111092.792 [SBIT] Loaded 2019-10-03T13:58:12.792Z,1570111092.792 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-10-03T13:58:12.793Z,1570111092.793 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-10-03T13:58:12.804Z,1570111092.804 [IBIT] Loaded 2019-10-03T13:58:12.804Z,1570111092.804 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-10-03T13:58:12.808Z,1570111092.808 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-10-03T13:58:13.072Z,1570111093.072 [CBIT] Loaded 2019-10-03T13:58:13.072Z,1570111093.072 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-10-03T13:58:13.072Z,1570111093.072 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-10-03T13:58:13.208Z,1570111093.208 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-10-03T13:58:13.209Z,1570111093.209 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-10-03T13:58:13.217Z,1570111093.217 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-10-03T13:58:13.218Z,1570111093.218 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACE4E0 2019-10-03T13:58:13.218Z,1570111093.218 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6980 2019-10-03T13:58:13.223Z,1570111093.223 [Supervisor](INFO): Main Thread ID is 6771 2019-10-03T13:58:13.223Z,1570111093.223 [Supervisor](DEBUG): Running supervisor. 2019-10-03T13:58:13.223Z,1570111093.223 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6981 2019-10-03T13:58:13.226Z,1570111093.226 [controlThread ThreadHandler](INFO): Handler Thread ID is 6982 2019-10-03T13:58:13.226Z,1570111093.226 [controlThread](DEBUG): Initializing ControlThread 2019-10-03T13:58:13.227Z,1570111093.227 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-10-03T13:58:13.229Z,1570111093.229 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-10-03T13:58:13.229Z,1570111093.229 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-10-03T13:58:13.230Z,1570111093.230 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-10-03T13:58:13.231Z,1570111093.231 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-10-03T13:58:13.231Z,1570111093.231 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-10-03T13:58:13.232Z,1570111093.232 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-10-03T13:58:13.232Z,1570111093.232 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-10-03T13:58:13.233Z,1570111093.233 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-10-03T13:58:13.233Z,1570111093.233 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-10-03T13:58:13.234Z,1570111093.234 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-10-03T13:58:13.234Z,1570111093.234 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-10-03T13:58:13.241Z,1570111093.241 [SBIT](INFO): Initialize SBIT Component. 2019-10-03T13:58:13.242Z,1570111093.242 [SBIT](IMPORTANT): git: 2019-10-03_B 2019-10-03T13:58:13.242Z,1570111093.242 [SBIT](INFO): git hash: 395bdff68a97d6c59eee2553e61a5ed127931da4 2019-10-03T13:58:13.242Z,1570111093.242 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-10-03T13:58:13.243Z,1570111093.243 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018 Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019 2019-10-03T13:58:13.244Z,1570111093.244 [SBIT](INFO): Beginning SBIT in 30.000000 seconds. 2019-10-03T13:58:13.245Z,1570111093.245 [IBIT](INFO): Initialize IBIT Component. 2019-10-03T13:58:13.246Z,1570111093.246 [CBIT](DEBUG): Initialize CBIT Component. 2019-10-03T13:58:13.247Z,1570111093.247 [logger ThreadHandler](INFO): Handler Thread ID is 6983 2019-10-03T13:58:13.257Z,1570111093.257 [CBIT](DEBUG): Initialized mux pins. 2019-10-03T13:58:13.257Z,1570111093.257 [CBIT](DEBUG): Initializing the watchdog timer. 2019-10-03T13:58:13.265Z,1570111093.265 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6984 2019-10-03T13:58:13.266Z,1570111093.266 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-10-03T13:58:13.277Z,1570111093.277 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6985 2019-10-03T13:58:13.281Z,1570111093.281 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-10-03T13:58:13.281Z,1570111093.281 [CBIT](DEBUG): Initializing heartbeat. 2019-10-03T13:58:13.289Z,1570111093.289 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6986 2019-10-03T13:58:13.290Z,1570111093.290 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-10-03T13:58:13.293Z,1570111093.293 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-10-03T13:58:13.294Z,1570111093.294 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6988 2019-10-03T13:58:13.296Z,1570111093.296 [WetLabsBB2FL](INFO): Powering down 2019-10-03T13:58:13.329Z,1570111093.329 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6989 2019-10-03T13:58:13.332Z,1570111093.332 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-10-03T13:58:13.332Z,1570111093.332 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-10-03T13:58:13.333Z,1570111093.333 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-10-03T13:58:13.333Z,1570111093.333 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-10-03T13:58:13.333Z,1570111093.333 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-10-03T13:58:13.333Z,1570111093.333 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-10-03T13:58:13.333Z,1570111093.333 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-10-03T13:58:13.334Z,1570111093.334 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-10-03T13:58:13.334Z,1570111093.334 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-10-03T13:58:13.334Z,1570111093.334 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-10-03T13:58:13.334Z,1570111093.334 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-10-03T13:58:13.334Z,1570111093.334 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-10-03T13:58:13.334Z,1570111093.334 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-10-03T13:58:13.335Z,1570111093.335 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-10-03T13:58:13.335Z,1570111093.335 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-10-03T13:58:13.335Z,1570111093.335 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-10-03T13:58:13.353Z,1570111093.353 [CBIT](DEBUG): Deactivating GF circuits. 2019-10-03T13:58:13.353Z,1570111093.353 [CBIT](DEBUG): Deactivating emergency mode. 2019-10-03T13:58:13.389Z,1570111093.389 [CBIT](DEBUG): Backplane powered. 2019-10-03T13:58:13.390Z,1570111093.390 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-10-03T13:58:13.399Z,1570111093.399 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-10-03T13:58:13.410Z,1570111093.410 [MissionManager](DEBUG): 2019-10-03T13:58:13.410Z,1570111093.410 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-10-03T13:58:13.485Z,1570111093.485 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-10-03T13:58:13.486Z,1570111093.486 [Default:A.Wait](DEBUG): Construct Wait. 2019-10-03T13:58:13.488Z,1570111093.488 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-10-03T13:58:13.518Z,1570111093.518 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-10-03T13:58:13.541Z,1570111093.541 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-10-03T13:58:13.546Z,1570111093.546 [Default:E.Execute](DEBUG): Construct Execute. 2019-10-03T13:58:13.566Z,1570111093.566 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2019-10-03T13:58:13.571Z,1570111093.571 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,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, 2019-10-03T13:58:13.589Z,1570111093.589 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-10-03T13:58:13.654Z,1570111093.654 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar 2019-10-03T13:58:13.673Z,1570111093.673 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2019-10-03T13:58:13.673Z,1570111093.673 [DAT](INFO): Powering up 2019-10-03T13:58:13.673Z,1570111093.673 [DAT](DEBUG): Initializing DAT. 2019-10-03T13:58:13.681Z,1570111093.681 [Radio_Surface](INFO): Powering up 2019-10-03T13:58:13.733Z,1570111093.733 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2019-10-03T13:58:13.761Z,1570111093.761 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-10-03T13:58:13.800Z,1570111093.800 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-10-03T13:58:13.809Z,1570111093.809 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-10-03T13:58:13.810Z,1570111093.810 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-10-03T13:58:13.821Z,1570111093.821 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-10-03T13:58:13.822Z,1570111093.822 [MassServo](DEBUG): Initializing EZServoServo. 2019-10-03T13:58:13.833Z,1570111093.833 [MassServo](DEBUG): Initializing MassServo. 2019-10-03T13:58:13.834Z,1570111093.834 [RudderServo](DEBUG): Initializing EZServoServo. 2019-10-03T13:58:13.853Z,1570111093.853 [RudderServo](DEBUG): Initializing RudderServo. 2019-10-03T13:58:13.854Z,1570111093.854 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-10-03T13:58:13.861Z,1570111093.861 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-10-03T13:58:14.757Z,1570111094.757 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2019-10-03T13:58:14.757Z,1570111094.757 [RudderServo](FAULT): Rudder failed to initialize 2019-10-03T13:58:14.757Z,1570111094.757 [RudderServo] Communications Fault, FailCount= 1 2019-10-03T13:58:14.757Z,1570111094.757 [RudderServo](ERROR): Communications Fault 2019-10-03T13:58:14.868Z,1570111094.868 [CBIT](ERROR): Communications Fault in component: RudderServo 2019-10-03T13:58:15.034Z,1570111095.034 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-10-03T13:58:15.034Z,1570111095.034 [RudderServo](INFO): Powering down 2019-10-03T13:58:15.731Z,1570111095.731 [RudderServo](DEBUG): Initializing EZServoServo. 2019-10-03T13:58:15.850Z,1570111095.850 [RudderServo](DEBUG): Initializing RudderServo. 2019-10-03T13:58:15.854Z,1570111095.854 [CBIT](INFO): Clearing failed state for component RudderServo 2019-10-03T13:58:15.854Z,1570111095.854 [RudderServo] No Fault, FailCount= 1 2019-10-03T13:58:20.322Z,1570111100.322 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2019-10-03T13:58:27.017Z,1570111107.017 [DAT](INFO): setting local address to 5 2019-10-03T13:58:27.418Z,1570111107.418 [DAT](INFO): set local address to 5 2019-10-03T13:58:40.330Z,1570111120.330 [NAL9602](INFO): Powering up NAL9602 2019-10-03T13:58:44.012Z,1570111124.012 [SBIT](IMPORTANT): Beginning Startup BIT 2019-10-03T13:58:44.020Z,1570111124.020 [CBIT](IMPORTANT): Beginning ground fault scan 2019-10-03T13:58:51.326Z,1570111131.326 [NAL9602](INFO): NAL9602 initialized 2019-10-03T13:58:52.149Z,1570111132.149 [NAL9602](DEBUG): Fix Requested 2019-10-03T13:58:55.042Z,1570111135.042 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.008528 CHAN A1 (24V): -0.003719 CHAN A2 (12V): -0.004148 CHAN A3 (5V): -0.002781 CHAN B0 (3.3V): -0.000940 CHAN B1 (3.15aV): -0.000853 CHAN B2 (3.15bV): -0.000741 CHAN B3 (GND): 0.003007 OPEN: 0.004865 Full Scale Calc: 4.765 mA, -1.589 mA 2019-10-03T13:59:23.455Z,1570111163.455 [SBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-14.727295 2019-10-03T13:59:23.455Z,1570111163.455 [SBIT](FAULT): Control surface position failure. 2019-10-03T13:59:37.270Z,1570111177.270 [SBIT](CRITICAL): SBIT FAILED 2019-10-03T13:59:37.318Z,1570111177.318 [CommandLine](IMPORTANT): got command configSet list 2019-10-03T13:59:37.318Z,1570111177.318 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-10-03T13:59:37.319Z,1570111177.319 [CommandLine](IMPORTANT): CBIT.gf24Offset=155 microampere; 2019-10-03T13:59:37.319Z,1570111177.319 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool; 2019-10-03T13:59:37.319Z,1570111177.319 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup=1 bool; 2019-10-03T13:59:37.319Z,1570111177.319 [CommandLine](IMPORTANT): Express linearApproximation DVL_micro.height_above_sea_floor 5.000000 meter; 2019-10-03T13:59:37.325Z,1570111177.325 [CommandLine](FAULT): Scheduling is paused 2019-10-03T13:59:37.326Z,1570111177.326 [CBIT](INFO): Critical error at 20191003T135937 2019-10-03T13:59:37.326Z,1570111177.326 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2019-10-03T13:59:37.680Z,1570111177.680 [MissionManager](IMPORTANT): Started mission Startup 2019-10-03T13:59:37.681Z,1570111177.681 [Startup] Running Loop=1 2019-10-03T13:59:37.681Z,1570111177.681 [Startup](DEBUG): Aggregate::initialize Startup 2019-10-03T13:59:37.681Z,1570111177.681 [Startup:A.GoToSurface] Running Loop=1 2019-10-03T13:59:37.681Z,1570111177.681 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-10-03T13:59:37.682Z,1570111177.682 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-10-03T13:59:37.682Z,1570111177.682 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-10-03T13:59:37.683Z,1570111177.683 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-10-03T13:59:37.683Z,1570111177.683 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-10-03T13:59:37.683Z,1570111177.683 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-10-03T13:59:37.685Z,1570111177.685 [Startup:StartupSatComms] Running Loop=1 2019-10-03T13:59:37.685Z,1570111177.685 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-10-03T13:59:37.685Z,1570111177.685 [Startup:StartupSatComms:A] Running Loop=1 2019-10-03T13:59:38.132Z,1570111178.132 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-10-03T13:59:42.458Z,1570111182.458 [CommandLine](IMPORTANT): got command failComponent 2019-10-03T13:59:42.459Z,1570111182.459 [CommandLine](IMPORTANT): Failed components: 2019-10-03T13:59:42.459Z,1570111182.459 [CommandLine](IMPORTANT): No failed Components. 2019-10-03T13:59:56.660Z,1570111196.660 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-03T14:00:03.116Z,1570111203.116 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-03T14:00:18.480Z,1570111218.480 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-03T14:00:34.232Z,1570111234.232 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-03T14:00:37.900Z,1570111237.900 [Startup:StartupSatComms:A](INFO): Timed out from 2019-10-03T13:59:37.7Z 2019-10-03T14:00:37.901Z,1570111237.901 [Startup:StartupSatComms:A] Stopped 2019-10-03T14:00:37.901Z,1570111237.901 [Startup:StartupSatComms:B] Running Loop=1 2019-10-03T14:00:38.303Z,1570111238.303 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-10-03T14:00:42.408Z,1570111242.408 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005142 2019-10-03T14:00:50.072Z,1570111250.072 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-03T14:00:50.900Z,1570111250.900 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20191003T133605/Courier0004.lzma 2019-10-03T14:00:52.907Z,1570111252.907 [DataOverHttps](INFO): Moved sent file to Logs/20191003T133605/Courier0004.lzma.bak 2019-10-03T14:00:52.907Z,1570111252.907 [DataOverHttps](INFO): SBD MOMSN=11844459 2019-10-03T14:01:05.435Z,1570111265.435 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-03T14:01:10.688Z,1570111270.688 [DataOverHttps](INFO): Sending 102 bytes from file Logs/20191003T135648/Courier0000.lzma 2019-10-03T14:01:12.694Z,1570111272.694 [DataOverHttps](INFO): Moved sent file to Logs/20191003T135648/Courier0000.lzma.bak 2019-10-03T14:01:12.694Z,1570111272.694 [DataOverHttps](INFO): SBD MOMSN=11844463 2019-10-03T14:01:13.511Z,1570111273.511 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-10-03T14:01:13.511Z,1570111273.511 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-10-03T14:01:13.545Z,1570111273.545 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-10-03T14:01:13.966Z,1570111273.966 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-10-03T14:01:13.966Z,1570111273.966 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-10-03T14:01:20.782Z,1570111280.782 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-03T14:01:30.457Z,1570111290.457 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20191003T135803/Courier0000.lzma 2019-10-03T14:01:32.462Z,1570111292.462 [DataOverHttps](INFO): Moved sent file to Logs/20191003T135803/Courier0000.lzma.bak 2019-10-03T14:01:32.462Z,1570111292.462 [DataOverHttps](INFO): SBD MOMSN=11844479 2019-10-03T14:01:36.957Z,1570111296.957 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-03T14:01:38.219Z,1570111298.219 [Startup:StartupSatComms:B](INFO): Timed out from 2019-10-03T14:00:37.9Z 2019-10-03T14:01:38.220Z,1570111298.220 [Startup:StartupSatComms:B] Stopped 2019-10-03T14:01:38.220Z,1570111298.220 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2019-10-03T14:01:38.220Z,1570111298.220 [Startup:StartupSatComms] Stopped 2019-10-03T14:01:38.220Z,1570111298.220 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-10-03T14:01:38.225Z,1570111298.225 [Startup](INFO): Completed Startup 2019-10-03T14:01:38.225Z,1570111298.225 [MissionManager](INFO): Startup is completed. 2019-10-03T14:01:38.225Z,1570111298.225 [MissionManager](INFO): Uninitializing Mission Startup 2019-10-03T14:01:38.225Z,1570111298.225 [Startup] Stopped 2019-10-03T14:01:38.226Z,1570111298.226 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-10-03T14:01:38.226Z,1570111298.226 [Startup:A.GoToSurface] Stopped 2019-10-03T14:01:38.226Z,1570111298.226 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-10-03T14:01:38.631Z,1570111298.631 [MissionManager](IMPORTANT): Started mission Default 2019-10-03T14:01:38.631Z,1570111298.631 [Default] Running Loop=1 2019-10-03T14:01:38.632Z,1570111298.632 [Default](DEBUG): Aggregate::initialize Default 2019-10-03T14:01:38.632Z,1570111298.632 [Default:B.GoToSurface] Running Loop=1 2019-10-03T14:01:38.632Z,1570111298.632 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-10-03T14:01:38.632Z,1570111298.632 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-10-03T14:01:38.632Z,1570111298.632 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-10-03T14:01:38.641Z,1570111298.641 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-10-03T14:01:38.641Z,1570111298.641 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-10-03T14:01:38.642Z,1570111298.642 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-10-03T14:01:38.642Z,1570111298.642 [Default:A.Wait] Running Loop=1 2019-10-03T14:01:38.642Z,1570111298.642 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-10-03T14:01:52.004Z,1570111312.004 [Default:A.Wait](INFO): Done Waiting. 2019-10-03T14:01:52.004Z,1570111312.004 [Default:A.Wait] Stopped 2019-10-03T14:01:52.004Z,1570111312.004 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-10-03T14:01:52.304Z,1570111312.304 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-03T14:01:52.357Z,1570111312.357 [Default:CheckIn] Running Loop=1 2019-10-03T14:01:52.357Z,1570111312.357 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-10-03T14:01:52.357Z,1570111312.357 [Default:CheckIn:Read_GPS] Running Loop=1 2019-10-03T14:01:52.750Z,1570111312.750 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2019-10-03T14:01:55.481Z,1570111315.481 [DataOverHttps](INFO): Sending 770 bytes from file Logs/20191003T133605/Express0001.lzma 2019-10-03T14:01:57.486Z,1570111317.486 [DataOverHttps](INFO): Moved sent file to Logs/20191003T133605/Express0001.lzma.bak 2019-10-03T14:01:57.487Z,1570111317.487 [DataOverHttps](INFO): SBD MOMSN=11844483 2019-10-03T14:02:07.659Z,1570111327.659 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-03T14:03:54.282Z,1570111434.282 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-10-03T14:03:55.101Z,1570111435.101 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:03:59.545Z,1570111439.545 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:04:02.793Z,1570111442.793 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:04:05.613Z,1570111445.613 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:04:08.436Z,1570111448.436 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:04:10.453Z,1570111450.453 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:04:13.685Z,1570111453.685 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:04:14.553Z,1570111454.553 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2019-10-03T14:04:14.553Z,1570111454.553 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-10-03T14:04:14.574Z,1570111454.574 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-10-03T14:04:14.958Z,1570111454.958 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-10-03T14:04:14.958Z,1570111454.958 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2019-10-03T14:04:16.509Z,1570111456.509 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:04:19.749Z,1570111459.749 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:04:22.573Z,1570111462.573 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:04:25.805Z,1570111465.805 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:04:28.633Z,1570111468.633 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:04:31.464Z,1570111471.464 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:04:34.693Z,1570111474.693 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:04:37.525Z,1570111477.525 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:04:40.753Z,1570111480.753 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:04:43.581Z,1570111483.581 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:04:46.813Z,1570111486.813 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:04:49.637Z,1570111489.637 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:04:52.477Z,1570111492.477 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:04:55.705Z,1570111495.705 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:04:58.529Z,1570111498.529 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:05:01.773Z,1570111501.773 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:05:04.589Z,1570111504.589 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:05:07.417Z,1570111507.417 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:05:09.445Z,1570111509.445 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:05:11.453Z,1570111511.453 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:05:14.692Z,1570111514.692 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:05:17.525Z,1570111517.525 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:05:20.753Z,1570111520.753 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:05:23.577Z,1570111523.577 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:05:26.805Z,1570111526.805 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:05:29.637Z,1570111529.637 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:05:32.473Z,1570111532.473 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:05:35.705Z,1570111535.705 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:05:38.525Z,1570111538.525 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:05:41.757Z,1570111541.757 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:05:44.585Z,1570111544.585 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:05:47.813Z,1570111547.813 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:05:50.648Z,1570111550.648 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:05:53.473Z,1570111553.473 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:05:56.714Z,1570111556.714 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:05:59.529Z,1570111559.529 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:06:02.761Z,1570111562.761 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:06:05.589Z,1570111565.589 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:06:08.424Z,1570111568.424 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:06:09.259Z,1570111569.259 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2019-10-03T14:06:10.441Z,1570111570.441 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:06:12.461Z,1570111572.461 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:06:15.693Z,1570111575.693 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:06:18.524Z,1570111578.524 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:06:21.756Z,1570111581.756 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:06:24.589Z,1570111584.589 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:06:27.825Z,1570111587.825 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:06:30.641Z,1570111590.641 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:06:33.469Z,1570111593.469 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:06:36.697Z,1570111596.697 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:06:39.525Z,1570111599.525 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:06:42.761Z,1570111602.761 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:06:45.585Z,1570111605.585 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:06:48.421Z,1570111608.421 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:06:50.433Z,1570111610.433 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:06:52.499Z,1570111612.499 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-10-03T14:01:52.4Z 2019-10-03T14:06:52.500Z,1570111612.500 [Default:CheckIn:Read_GPS] Stopped 2019-10-03T14:06:52.500Z,1570111612.500 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-10-03T14:06:52.899Z,1570111612.899 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-10-03T14:06:53.672Z,1570111613.672 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:06:56.497Z,1570111616.497 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:06:59.729Z,1570111619.729 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:07:00.924Z,1570111620.924 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20191003T135803/Courier0004.lzma 2019-10-03T14:07:02.557Z,1570111622.557 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:07:02.931Z,1570111622.931 [DataOverHttps](INFO): Moved sent file to Logs/20191003T135803/Courier0004.lzma.bak 2019-10-03T14:07:02.931Z,1570111622.931 [DataOverHttps](INFO): SBD MOMSN=11844502 2019-10-03T14:07:05.785Z,1570111625.785 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:07:08.617Z,1570111628.617 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:07:11.441Z,1570111631.441 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:07:13.465Z,1570111633.465 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:07:15.503Z,1570111635.503 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2019-10-03T14:07:15.504Z,1570111635.504 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-10-03T14:07:15.514Z,1570111635.514 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-10-03T14:07:15.978Z,1570111635.978 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-10-03T14:07:15.978Z,1570111635.978 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2019-10-03T14:07:16.701Z,1570111636.701 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:07:19.521Z,1570111639.521 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:07:22.769Z,1570111642.769 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:07:22.902Z,1570111642.902 [DataOverHttps](INFO): Sending 255 bytes from file Logs/20191003T133605/Express0005.lzma 2019-10-03T14:07:24.907Z,1570111644.907 [DataOverHttps](INFO): Moved sent file to Logs/20191003T133605/Express0005.lzma.bak 2019-10-03T14:07:24.907Z,1570111644.907 [DataOverHttps](INFO): SBD MOMSN=11844504 2019-10-03T14:07:25.605Z,1570111645.605 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:07:28.421Z,1570111648.421 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:07:30.449Z,1570111650.449 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:07:32.468Z,1570111652.468 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:07:35.700Z,1570111655.700 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:07:38.537Z,1570111658.537 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:07:41.769Z,1570111661.769 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:07:43.781Z,1570111663.781 [DataOverHttps](INFO): Sending 666 bytes from file Logs/20191003T135648/Express0001.lzma 2019-10-03T14:07:44.581Z,1570111664.581 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:07:45.786Z,1570111665.786 [DataOverHttps](INFO): Moved sent file to Logs/20191003T135648/Express0001.lzma.bak 2019-10-03T14:07:45.786Z,1570111665.786 [DataOverHttps](INFO): SBD MOMSN=11844514 2019-10-03T14:07:47.409Z,1570111667.409 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:07:49.429Z,1570111669.429 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:07:51.465Z,1570111671.465 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:07:53.475Z,1570111673.475 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:07:56.708Z,1570111676.708 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:07:59.545Z,1570111679.545 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:08:02.765Z,1570111682.765 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:08:05.589Z,1570111685.589 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:08:06.718Z,1570111686.718 [DataOverHttps](INFO): Sending 850 bytes from file Logs/20191003T135803/Express0001.lzma 2019-10-03T14:08:08.421Z,1570111688.421 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:08:08.722Z,1570111688.722 [DataOverHttps](INFO): Moved sent file to Logs/20191003T135803/Express0001.lzma.bak 2019-10-03T14:08:08.722Z,1570111688.722 [DataOverHttps](INFO): SBD MOMSN=11844528 2019-10-03T14:08:10.453Z,1570111690.453 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:08:12.457Z,1570111692.457 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:08:15.696Z,1570111695.696 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:08:18.529Z,1570111698.529 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:08:21.761Z,1570111701.761 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:08:24.577Z,1570111704.577 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:08:27.409Z,1570111707.409 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:08:27.796Z,1570111707.796 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20191003T135803/Express0005.lzma 2019-10-03T14:08:29.425Z,1570111709.425 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:08:29.802Z,1570111709.802 [DataOverHttps](INFO): Moved sent file to Logs/20191003T135803/Express0005.lzma.bak 2019-10-03T14:08:29.802Z,1570111709.802 [DataOverHttps](INFO): SBD MOMSN=11844551 2019-10-03T14:08:31.445Z,1570111711.445 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:08:33.107Z,1570111713.107 [Default:CheckIn:Read_Iridium] Stopped 2019-10-03T14:08:33.107Z,1570111713.107 [Default:CheckIn:C.Wait] Running Loop=1 2019-10-03T14:08:33.107Z,1570111713.107 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-10-03T14:08:33.477Z,1570111713.477 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:08:36.705Z,1570111716.705 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:08:39.525Z,1570111719.525 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:08:42.761Z,1570111722.761 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:08:45.585Z,1570111725.585 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:08:48.433Z,1570111728.433 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:08:50.557Z,1570111730.557 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:08:53.782Z,1570111733.782 [NAL9602](FAULT): GPS failed to acquire within timeout. 2019-10-03T14:08:53.782Z,1570111733.782 [NAL9602] Data Fault, FailCount= 1 2019-10-03T14:08:53.782Z,1570111733.782 [NAL9602](ERROR): Data Fault 2019-10-03T14:08:53.846Z,1570111733.846 [CBIT](ERROR): Data Fault in component: NAL9602 2019-10-03T14:08:54.188Z,1570111734.188 [NAL9602](INFO): Powering down 2019-10-03T14:08:55.036Z,1570111735.036 [CBIT](INFO): Clearing failed state for component NAL9602 2019-10-03T14:08:55.036Z,1570111735.036 [NAL9602] No Fault, FailCount= 1 2019-10-03T14:09:24.486Z,1570111764.486 [NAL9602](INFO): Powering up NAL9602 2019-10-03T14:09:30.172Z,1570111770.172 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-03T14:09:35.394Z,1570111775.394 [NAL9602](INFO): NAL9602 initialized 2019-10-03T14:09:36.221Z,1570111776.221 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:09:38.637Z,1570111778.637 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:09:41.871Z,1570111781.871 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:09:44.705Z,1570111784.705 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:09:45.528Z,1570111785.528 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-03T14:09:47.933Z,1570111787.933 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:09:50.761Z,1570111790.761 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:09:53.585Z,1570111793.585 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:09:55.605Z,1570111795.605 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:09:57.625Z,1570111797.625 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:10:00.457Z,1570111800.457 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-03T14:10:00.857Z,1570111800.857 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:10:03.681Z,1570111803.681 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:10:06.913Z,1570111806.913 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:10:09.741Z,1570111809.741 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:10:12.577Z,1570111812.577 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:10:14.589Z,1570111814.589 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:10:16.222Z,1570111816.222 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-03T14:10:16.617Z,1570111816.617 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:10:16.669Z,1570111816.669 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2019-10-03T14:10:16.669Z,1570111816.669 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-10-03T14:10:16.705Z,1570111816.705 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-10-03T14:10:17.066Z,1570111817.066 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-10-03T14:10:17.066Z,1570111817.066 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2019-10-03T14:10:19.845Z,1570111819.845 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:10:22.669Z,1570111822.669 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:10:25.909Z,1570111825.909 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:10:28.737Z,1570111828.737 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:10:31.973Z,1570111831.973 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:10:32.401Z,1570111832.401 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-03T14:10:34.797Z,1570111834.797 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:10:37.617Z,1570111837.617 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:10:40.849Z,1570111840.849 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:10:43.677Z,1570111843.677 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:10:46.913Z,1570111846.913 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:10:47.342Z,1570111847.342 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-03T14:10:49.749Z,1570111849.749 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:10:52.973Z,1570111852.973 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:10:55.801Z,1570111855.801 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:10:58.629Z,1570111858.629 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:11:01.861Z,1570111861.861 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:11:03.083Z,1570111863.083 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-03T14:11:04.697Z,1570111864.697 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:11:07.925Z,1570111867.925 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:11:10.749Z,1570111870.749 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:11:13.577Z,1570111873.577 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:11:15.597Z,1570111875.597 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:11:17.621Z,1570111877.621 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:11:18.448Z,1570111878.448 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-03T14:11:20.857Z,1570111880.857 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:11:23.677Z,1570111883.677 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:11:26.913Z,1570111886.913 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:11:29.737Z,1570111889.737 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:11:32.989Z,1570111892.989 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:11:33.788Z,1570111893.788 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-03T14:11:35.801Z,1570111895.801 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:11:38.629Z,1570111898.629 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:11:41.857Z,1570111901.857 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:11:44.693Z,1570111904.693 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:11:47.919Z,1570111907.919 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:11:49.966Z,1570111909.966 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-03T14:11:50.749Z,1570111910.749 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:11:53.581Z,1570111913.581 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:11:55.593Z,1570111915.593 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:11:57.609Z,1570111917.609 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:11:59.640Z,1570111919.640 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:12:02.874Z,1570111922.874 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:12:05.701Z,1570111925.701 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:12:08.921Z,1570111928.921 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:12:11.753Z,1570111931.753 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:12:14.577Z,1570111934.577 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:12:16.601Z,1570111936.601 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:12:18.625Z,1570111938.625 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:12:21.866Z,1570111941.866 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:12:24.693Z,1570111944.693 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:12:27.917Z,1570111947.917 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:12:30.741Z,1570111950.741 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:12:33.565Z,1570111953.565 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:12:35.589Z,1570111955.589 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:12:37.613Z,1570111957.613 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:12:39.629Z,1570111959.629 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:12:42.865Z,1570111962.865 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:12:45.689Z,1570111965.689 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:12:48.929Z,1570111968.929 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:12:51.749Z,1570111971.749 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:12:54.573Z,1570111974.573 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:12:56.593Z,1570111976.593 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:12:58.621Z,1570111978.621 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:13:01.853Z,1570111981.853 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:13:04.677Z,1570111984.677 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:13:07.909Z,1570111987.909 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:13:10.737Z,1570111990.737 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:13:13.969Z,1570111993.969 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:13:16.801Z,1570111996.801 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:13:17.643Z,1570111997.643 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2019-10-03T14:13:17.643Z,1570111997.643 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-10-03T14:13:17.653Z,1570111997.653 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-10-03T14:13:18.037Z,1570111998.037 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-10-03T14:13:18.038Z,1570111998.038 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2019-10-03T14:13:19.641Z,1570111999.641 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:13:22.861Z,1570112002.861 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:13:25.685Z,1570112005.685 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:13:28.917Z,1570112008.917 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:13:31.741Z,1570112011.741 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:13:33.791Z,1570112013.791 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-10-03T14:13:33.792Z,1570112013.792 [Default:CheckIn:C.Wait] Stopped 2019-10-03T14:13:33.792Z,1570112013.792 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-10-03T14:13:33.792Z,1570112013.792 [Default:CheckIn:D] Running Loop=1 2019-10-03T14:13:34.199Z,1570112014.199 [Default:CheckIn:D] Stopped 2019-10-03T14:13:34.199Z,1570112014.199 [Default:CheckIn:E] Running Loop=1 2019-10-03T14:13:34.569Z,1570112014.569 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:13:34.617Z,1570112014.617 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.926125 min 2019-10-03T14:13:34.617Z,1570112014.617 [Default:CheckIn:E] Stopped 2019-10-03T14:13:34.617Z,1570112014.617 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-10-03T14:13:34.617Z,1570112014.617 [Default:CheckIn] Stopped 2019-10-03T14:13:34.617Z,1570112014.617 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-10-03T14:13:34.617Z,1570112014.617 [Default:CheckIn](INFO): Running loop #2 2019-10-03T14:13:34.617Z,1570112014.617 [Default:CheckIn] Running Loop=2 2019-10-03T14:13:34.617Z,1570112014.617 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-10-03T14:13:34.618Z,1570112014.618 [Default:CheckIn:Read_GPS] Running Loop=1 2019-10-03T14:13:36.593Z,1570112016.593 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:13:38.617Z,1570112018.617 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:13:40.629Z,1570112020.629 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:13:43.861Z,1570112023.861 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:13:46.695Z,1570112026.695 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:13:49.928Z,1570112029.928 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:13:52.757Z,1570112032.757 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:13:55.581Z,1570112035.581 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:13:57.597Z,1570112037.597 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:13:59.621Z,1570112039.621 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:14:02.861Z,1570112042.861 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:14:05.677Z,1570112045.677 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:14:08.917Z,1570112048.917 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:14:11.737Z,1570112051.737 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:14:14.973Z,1570112054.973 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:14:17.803Z,1570112057.803 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:14:20.633Z,1570112060.633 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:14:23.861Z,1570112063.861 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:14:26.689Z,1570112066.689 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:14:29.925Z,1570112069.925 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:14:32.747Z,1570112072.747 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:14:35.577Z,1570112075.577 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:14:37.605Z,1570112077.605 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:14:39.617Z,1570112079.617 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:14:42.861Z,1570112082.861 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:14:45.677Z,1570112085.677 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:14:48.917Z,1570112088.917 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:14:51.733Z,1570112091.733 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:14:54.969Z,1570112094.969 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:14:57.793Z,1570112097.793 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:15:00.641Z,1570112100.641 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:15:03.855Z,1570112103.855 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:15:06.689Z,1570112106.689 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:15:09.917Z,1570112109.917 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:15:12.745Z,1570112112.745 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:15:15.593Z,1570112115.593 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:15:17.589Z,1570112117.589 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:15:19.621Z,1570112119.621 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:15:22.845Z,1570112122.845 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:15:25.669Z,1570112125.669 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:15:28.909Z,1570112128.909 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:15:31.733Z,1570112131.733 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:15:34.969Z,1570112134.969 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:15:37.797Z,1570112137.797 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:15:40.621Z,1570112140.621 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:15:43.849Z,1570112143.849 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:15:46.681Z,1570112146.681 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:15:49.913Z,1570112149.913 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:15:52.745Z,1570112152.745 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:15:55.569Z,1570112155.569 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:15:57.597Z,1570112157.597 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:15:59.611Z,1570112159.611 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:16:01.633Z,1570112161.633 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:16:04.861Z,1570112164.861 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:16:07.693Z,1570112167.693 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:16:10.917Z,1570112170.917 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:16:13.753Z,1570112173.753 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:16:16.581Z,1570112176.581 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:16:18.593Z,1570112178.593 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:16:18.883Z,1570112178.883 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2019-10-03T14:16:18.883Z,1570112178.883 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-10-03T14:16:18.905Z,1570112178.905 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-10-03T14:16:19.116Z,1570112179.116 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-10-03T14:16:19.116Z,1570112179.116 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2019-10-03T14:16:20.621Z,1570112180.621 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:16:23.849Z,1570112183.849 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:16:26.673Z,1570112186.673 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:16:29.905Z,1570112189.905 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:16:32.733Z,1570112192.733 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:16:35.573Z,1570112195.573 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:16:37.587Z,1570112197.587 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:16:39.601Z,1570112199.601 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:16:42.837Z,1570112202.837 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:16:45.661Z,1570112205.661 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:16:48.893Z,1570112208.893 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:16:51.733Z,1570112211.733 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:16:54.597Z,1570112214.597 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:16:56.585Z,1570112216.585 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:16:58.613Z,1570112218.613 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:17:00.629Z,1570112220.629 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:17:03.861Z,1570112223.861 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:17:06.685Z,1570112226.685 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:17:09.924Z,1570112229.924 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:17:12.753Z,1570112232.753 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:17:15.585Z,1570112235.585 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:17:17.599Z,1570112237.599 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:17:19.613Z,1570112239.613 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:17:22.849Z,1570112242.849 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:17:25.681Z,1570112245.681 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:17:28.913Z,1570112248.913 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:17:31.737Z,1570112251.737 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:17:34.969Z,1570112254.969 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:17:37.799Z,1570112257.799 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:17:40.629Z,1570112260.629 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:17:43.857Z,1570112263.857 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:17:46.685Z,1570112266.685 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:17:49.917Z,1570112269.917 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:17:52.747Z,1570112272.747 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:17:55.585Z,1570112275.585 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:17:57.595Z,1570112277.595 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:17:59.609Z,1570112279.609 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:18:02.849Z,1570112282.849 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:18:05.675Z,1570112285.675 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:18:08.917Z,1570112288.917 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:18:11.733Z,1570112291.733 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:18:14.565Z,1570112294.565 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:18:16.581Z,1570112296.581 [NAL9602](DEBUG): Fix Requested 2019-10-03T14:18:18.609Z,1570112298.6