2019-10-09T22:24:36.380Z,1570659876.380 [Supervisor](DEBUG): Initializing supervisor. 2019-10-09T22:24:36.383Z,1570659876.383 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-10-09T22:24:36.384Z,1570659876.384 [SyncHandler](INFO): Protected caller Thread ID is 2634 2019-10-09T22:24:36.384Z,1570659876.384 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-10-09T22:24:36.385Z,1570659876.385 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-10-09T22:24:36.386Z,1570659876.386 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2635 2019-10-09T22:24:36.388Z,1570659876.388 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-10-09T22:24:36.399Z,1570659876.399 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-10-09T22:24:36.400Z,1570659876.400 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-10-09T22:24:36.401Z,1570659876.401 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2636 2019-10-09T22:24:36.401Z,1570659876.401 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-10-09T22:24:36.402Z,1570659876.402 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-10-09T22:24:36.403Z,1570659876.403 [logger ThreadHandler](INFO): Protected caller Thread ID is 2637 2019-10-09T22:24:36.405Z,1570659876.405 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-10-09T22:24:36.405Z,1570659876.405 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-10-09T22:24:36.407Z,1570659876.407 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-10-09T22:24:36.598Z,1570659876.598 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-10-09T22:24:36.599Z,1570659876.599 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-10-09T22:24:36.676Z,1570659876.676 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-10-09T22:24:37.093Z,1570659877.093 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-10-09T22:24:37.094Z,1570659877.094 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-10-09T22:24:37.408Z,1570659877.408 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-10-09T22:24:37.409Z,1570659877.409 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-10-09T22:24:37.501Z,1570659877.501 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-10-09T22:24:37.502Z,1570659877.502 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-10-09T22:24:37.787Z,1570659877.787 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-10-09T22:24:37.788Z,1570659877.788 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-10-09T22:24:37.975Z,1570659877.975 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-10-09T22:24:37.975Z,1570659877.975 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-10-09T22:24:38.410Z,1570659878.410 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-10-09T22:24:38.410Z,1570659878.410 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-10-09T22:24:38.511Z,1570659878.511 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-10-09T22:24:38.511Z,1570659878.511 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-10-09T22:24:38.608Z,1570659878.608 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-10-09T22:24:38.608Z,1570659878.608 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-10-09T22:24:39.572Z,1570659879.572 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-10-09T22:24:39.572Z,1570659879.572 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-10-09T22:24:39.956Z,1570659879.956 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-10-09T22:24:39.957Z,1570659879.957 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-10-09T22:24:40.143Z,1570659880.143 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-10-09T22:24:40.144Z,1570659880.144 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-10-09T22:24:40.284Z,1570659880.284 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-10-09T22:24:40.285Z,1570659880.285 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-10-09T22:24:40.429Z,1570659880.429 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-10-09T22:24:40.431Z,1570659880.431 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2019-10-09T22:24:40.432Z,1570659880.432 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2019-10-09T22:24:40.520Z,1570659880.520 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2019-10-09T22:24:40.603Z,1570659880.603 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2019-10-09T22:24:40.704Z,1570659880.704 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2019-10-09T22:24:40.785Z,1570659880.785 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2019-10-09T22:24:40.878Z,1570659880.878 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2019-10-09T22:24:40.975Z,1570659880.975 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2019-10-09T22:24:41.178Z,1570659881.178 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2019-10-09T22:24:41.252Z,1570659881.252 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2019-10-09T22:24:41.413Z,1570659881.413 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2019-10-09T22:24:41.543Z,1570659881.543 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2019-10-09T22:24:41.658Z,1570659881.658 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2019-10-09T22:24:41.882Z,1570659881.882 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-10-09T22:24:41.883Z,1570659881.883 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-10-09T22:24:41.887Z,1570659881.887 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-10-09T22:24:41.954Z,1570659881.954 [VerticalControl](DEBUG): Construct VerticalControl. 2019-10-09T22:24:42.064Z,1570659882.064 [VerticalControl] Loaded 2019-10-09T22:24:42.065Z,1570659882.065 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-10-09T22:24:42.065Z,1570659882.065 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-10-09T22:24:42.132Z,1570659882.132 [HorizontalControl] Loaded 2019-10-09T22:24:42.133Z,1570659882.133 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-10-09T22:24:42.133Z,1570659882.133 [SpeedControl](DEBUG): Construct SpeedControl. 2019-10-09T22:24:42.139Z,1570659882.139 [SpeedControl] Loaded 2019-10-09T22:24:42.139Z,1570659882.139 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-10-09T22:24:42.140Z,1570659882.140 [LoopControl](DEBUG): Construct LoopControl. 2019-10-09T22:24:42.140Z,1570659882.140 [LoopControl] Loaded 2019-10-09T22:24:42.141Z,1570659882.141 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-10-09T22:24:42.141Z,1570659882.141 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-10-09T22:24:42.142Z,1570659882.142 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-10-09T22:24:42.155Z,1570659882.155 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-10-09T22:24:42.155Z,1570659882.155 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-10-09T22:24:42.253Z,1570659882.253 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-10-09T22:24:42.253Z,1570659882.253 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-10-09T22:24:42.354Z,1570659882.354 [BuoyancyServo] Loaded 2019-10-09T22:24:42.354Z,1570659882.354 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-10-09T22:24:42.365Z,1570659882.365 [ElevatorServo] Loaded 2019-10-09T22:24:42.365Z,1570659882.365 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-10-09T22:24:42.376Z,1570659882.376 [MassServo] Loaded 2019-10-09T22:24:42.377Z,1570659882.377 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-10-09T22:24:42.387Z,1570659882.387 [RudderServo] Loaded 2019-10-09T22:24:42.387Z,1570659882.387 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-10-09T22:24:42.398Z,1570659882.398 [ThrusterServo] Loaded 2019-10-09T22:24:42.399Z,1570659882.399 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-10-09T22:24:42.399Z,1570659882.399 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-10-09T22:24:42.400Z,1570659882.400 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-10-09T22:24:42.506Z,1570659882.506 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-10-09T22:24:42.506Z,1570659882.506 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-10-09T22:24:42.527Z,1570659882.527 [NavChart] Loaded 2019-10-09T22:24:42.527Z,1570659882.527 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-10-09T22:24:42.531Z,1570659882.531 [UniversalFixResidualReporter] Loaded 2019-10-09T22:24:42.531Z,1570659882.531 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-10-09T22:24:42.531Z,1570659882.531 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-10-09T22:24:42.532Z,1570659882.532 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-10-09T22:24:42.602Z,1570659882.602 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-10-09T22:24:42.602Z,1570659882.602 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-10-09T22:24:42.860Z,1570659882.860 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2019-10-09T22:24:42.865Z,1570659882.865 [AHRS_M2](INFO): created writer for : platform_orientation 2019-10-09T22:24:42.867Z,1570659882.867 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2019-10-09T22:24:42.872Z,1570659882.872 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2019-10-09T22:24:42.872Z,1570659882.872 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2019-10-09T22:24:42.877Z,1570659882.877 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2019-10-09T22:24:42.878Z,1570659882.878 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2019-10-09T22:24:42.883Z,1570659882.883 [AHRS_M2](INFO): created writer for : platform_roll_angle 2019-10-09T22:24:42.953Z,1570659882.953 [AHRS_M2] Loaded 2019-10-09T22:24:42.953Z,1570659882.953 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2019-10-09T22:24:43.029Z,1570659883.029 [DataOverHttps] Loaded 2019-10-09T22:24:43.029Z,1570659883.029 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-10-09T22:24:43.030Z,1570659883.030 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087D4E0 2019-10-09T22:24:43.031Z,1570659883.031 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2719 2019-10-09T22:24:43.044Z,1570659883.044 [Depth_Keller] Loaded 2019-10-09T22:24:43.044Z,1570659883.044 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-10-09T22:24:43.049Z,1570659883.049 [DropWeight] Loaded 2019-10-09T22:24:43.049Z,1570659883.049 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-10-09T22:24:43.146Z,1570659883.146 [NAL9602] Loaded 2019-10-09T22:24:43.146Z,1570659883.146 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-10-09T22:24:43.161Z,1570659883.161 [Onboard] Loaded 2019-10-09T22:24:43.162Z,1570659883.162 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-10-09T22:24:43.165Z,1570659883.165 [Radio_Surface] Loaded 2019-10-09T22:24:43.165Z,1570659883.165 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-10-09T22:24:43.166Z,1570659883.166 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408AD4E0 2019-10-09T22:24:43.166Z,1570659883.166 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2720 2019-10-09T22:24:44.813Z,1570659884.813 [BPC1] Loaded 2019-10-09T22:24:44.813Z,1570659884.813 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-10-09T22:24:44.813Z,1570659884.813 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-10-09T22:24:44.814Z,1570659884.814 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-10-09T22:24:44.928Z,1570659884.928 [DepthRateCalculator] Loaded 2019-10-09T22:24:44.963Z,1570659884.963 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-10-09T22:24:45.005Z,1570659885.005 [PitchRateCalculator] Loaded 2019-10-09T22:24:45.005Z,1570659885.005 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-10-09T22:24:45.047Z,1570659885.047 [SpeedCalculator] Loaded 2019-10-09T22:24:45.048Z,1570659885.048 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-10-09T22:24:45.124Z,1570659885.124 [TempGradientCalculator] Loaded 2019-10-09T22:24:45.125Z,1570659885.125 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-10-09T22:24:45.142Z,1570659885.142 [YawRateCalculator] Loaded 2019-10-09T22:24:45.142Z,1570659885.142 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-10-09T22:24:45.239Z,1570659885.239 [ElevatorOffsetCalculator] Loaded 2019-10-09T22:24:45.239Z,1570659885.239 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-10-09T22:24:45.240Z,1570659885.240 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-10-09T22:24:45.240Z,1570659885.240 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-10-09T22:24:45.581Z,1570659885.581 [Aanderaa_O2] Loaded 2019-10-09T22:24:45.582Z,1570659885.582 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2019-10-09T22:24:45.591Z,1570659885.591 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2019-10-09T22:24:45.596Z,1570659885.596 [CTD_Seabird](INFO): created writer for : sea_water_density 2019-10-09T22:24:45.597Z,1570659885.597 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2019-10-09T22:24:45.601Z,1570659885.601 [CTD_Seabird](INFO): created writer for : depth 2019-10-09T22:24:45.602Z,1570659885.602 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2019-10-09T22:24:45.607Z,1570659885.607 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2019-10-09T22:24:45.608Z,1570659885.608 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2019-10-09T22:24:45.613Z,1570659885.613 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2019-10-09T22:24:45.613Z,1570659885.613 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2019-10-09T22:24:45.618Z,1570659885.618 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2019-10-09T22:24:45.619Z,1570659885.619 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2019-10-09T22:24:45.624Z,1570659885.624 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2019-10-09T22:24:45.625Z,1570659885.625 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2019-10-09T22:24:45.630Z,1570659885.630 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2019-10-09T22:24:45.657Z,1570659885.657 [CTD_Seabird] Loaded 2019-10-09T22:24:45.657Z,1570659885.657 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2019-10-09T22:24:45.658Z,1570659885.658 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409B04E0 2019-10-09T22:24:45.658Z,1570659885.658 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2722 2019-10-09T22:24:45.673Z,1570659885.673 [PAR_Licor] Loaded 2019-10-09T22:24:45.673Z,1570659885.673 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2019-10-09T22:24:45.720Z,1570659885.720 [WetLabsBB2FL] Loaded 2019-10-09T22:24:45.721Z,1570659885.721 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-10-09T22:24:45.722Z,1570659885.722 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409E04E0 2019-10-09T22:24:45.722Z,1570659885.722 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2723 2019-10-09T22:24:45.723Z,1570659885.723 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-10-09T22:24:45.723Z,1570659885.723 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-10-09T22:24:45.756Z,1570659885.756 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-10-09T22:24:45.757Z,1570659885.757 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-10-09T22:24:46.043Z,1570659886.043 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-10-09T22:24:46.044Z,1570659886.044 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-10-09T22:24:46.174Z,1570659886.174 [SBIT](DEBUG): Construct Startup Built In Test. 2019-10-09T22:24:46.185Z,1570659886.185 [SBIT] Loaded 2019-10-09T22:24:46.185Z,1570659886.185 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-10-09T22:24:46.186Z,1570659886.186 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-10-09T22:24:46.197Z,1570659886.197 [IBIT] Loaded 2019-10-09T22:24:46.198Z,1570659886.198 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-10-09T22:24:46.201Z,1570659886.201 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-10-09T22:24:46.337Z,1570659886.337 [CBIT] Loaded 2019-10-09T22:24:46.338Z,1570659886.338 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-10-09T22:24:46.338Z,1570659886.338 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-10-09T22:24:46.342Z,1570659886.342 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-10-09T22:24:46.342Z,1570659886.342 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-10-09T22:24:46.349Z,1570659886.349 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-10-09T22:24:46.350Z,1570659886.350 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ADF4E0 2019-10-09T22:24:46.350Z,1570659886.350 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2724 2019-10-09T22:24:46.355Z,1570659886.355 [Supervisor](INFO): Main Thread ID is 2633 2019-10-09T22:24:46.355Z,1570659886.355 [Supervisor](DEBUG): Running supervisor. 2019-10-09T22:24:46.356Z,1570659886.356 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2725 2019-10-09T22:24:46.358Z,1570659886.358 [controlThread ThreadHandler](INFO): Handler Thread ID is 2726 2019-10-09T22:24:46.359Z,1570659886.359 [controlThread](DEBUG): Initializing ControlThread 2019-10-09T22:24:46.360Z,1570659886.360 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-10-09T22:24:46.361Z,1570659886.361 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-10-09T22:24:46.362Z,1570659886.362 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-10-09T22:24:46.362Z,1570659886.362 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-10-09T22:24:46.364Z,1570659886.364 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-10-09T22:24:46.364Z,1570659886.364 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-10-09T22:24:46.371Z,1570659886.371 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-10-09T22:24:46.371Z,1570659886.371 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-10-09T22:24:46.372Z,1570659886.372 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-10-09T22:24:46.372Z,1570659886.372 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-10-09T22:24:46.372Z,1570659886.372 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-10-09T22:24:46.373Z,1570659886.373 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-10-09T22:24:46.377Z,1570659886.377 [SBIT](INFO): Initialize SBIT Component. 2019-10-09T22:24:46.378Z,1570659886.378 [SBIT](IMPORTANT): git: 2019-09-10 2019-10-09T22:24:46.378Z,1570659886.378 [SBIT](INFO): git hash: e950883795b57abbf97d89589ea0268241928c93 2019-10-09T22:24:46.378Z,1570659886.378 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-10-09T22:24:46.379Z,1570659886.379 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018 2019-10-09T22:24:46.380Z,1570659886.380 [SBIT](INFO): Beginning SBIT in 23.000000 seconds. 2019-10-09T22:24:46.381Z,1570659886.381 [IBIT](INFO): Initialize IBIT Component. 2019-10-09T22:24:46.382Z,1570659886.382 [CBIT](DEBUG): Initialize CBIT Component. 2019-10-09T22:24:46.383Z,1570659886.383 [logger ThreadHandler](INFO): Handler Thread ID is 2727 2019-10-09T22:24:46.393Z,1570659886.393 [CBIT](DEBUG): Initialized mux pins. 2019-10-09T22:24:46.393Z,1570659886.393 [CBIT](DEBUG): Initializing the watchdog timer. 2019-10-09T22:24:46.401Z,1570659886.401 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2728 2019-10-09T22:24:46.402Z,1570659886.402 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-10-09T22:24:46.413Z,1570659886.413 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2729 2019-10-09T22:24:46.417Z,1570659886.417 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-10-09T22:24:46.417Z,1570659886.417 [CBIT](DEBUG): Initializing heartbeat. 2019-10-09T22:24:46.425Z,1570659886.425 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2730 2019-10-09T22:24:46.426Z,1570659886.426 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-10-09T22:24:46.429Z,1570659886.429 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-10-09T22:24:46.431Z,1570659886.431 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2732 2019-10-09T22:24:46.432Z,1570659886.432 [WetLabsBB2FL](INFO): Powering down 2019-10-09T22:24:46.459Z,1570659886.459 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2733 2019-10-09T22:24:46.462Z,1570659886.462 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-10-09T22:24:46.462Z,1570659886.462 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-10-09T22:24:46.462Z,1570659886.462 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-10-09T22:24:46.463Z,1570659886.463 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-10-09T22:24:46.463Z,1570659886.463 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-10-09T22:24:46.463Z,1570659886.463 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-10-09T22:24:46.463Z,1570659886.463 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-10-09T22:24:46.463Z,1570659886.463 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-10-09T22:24:46.463Z,1570659886.463 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-10-09T22:24:46.464Z,1570659886.464 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-10-09T22:24:46.464Z,1570659886.464 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-10-09T22:24:46.464Z,1570659886.464 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-10-09T22:24:46.464Z,1570659886.464 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-10-09T22:24:46.464Z,1570659886.464 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-10-09T22:24:46.465Z,1570659886.465 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-10-09T22:24:46.465Z,1570659886.465 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-10-09T22:24:46.489Z,1570659886.489 [CBIT](DEBUG): Deactivating GF circuits. 2019-10-09T22:24:46.489Z,1570659886.489 [CBIT](DEBUG): Deactivating emergency mode. 2019-10-09T22:24:46.525Z,1570659886.525 [CBIT](DEBUG): Backplane powered. 2019-10-09T22:24:46.526Z,1570659886.526 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-10-09T22:24:46.536Z,1570659886.536 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-10-09T22:24:46.546Z,1570659886.546 [MissionManager](DEBUG): 2019-10-09T22:24:46.547Z,1570659886.547 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-10-09T22:24:46.618Z,1570659886.618 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-10-09T22:24:46.619Z,1570659886.619 [Default:A.Wait](DEBUG): Construct Wait. 2019-10-09T22:24:46.633Z,1570659886.633 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-10-09T22:24:46.660Z,1570659886.660 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-10-09T22:24:46.662Z,1570659886.662 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-10-09T22:24:46.688Z,1570659886.688 [Default:E.Execute](DEBUG): Construct Execute. 2019-10-09T22:24:46.707Z,1570659886.707 [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-09T22:24:46.712Z,1570659886.712 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,BPC1,Aanderaa_O2,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-09T22:24:46.716Z,1570659886.716 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-10-09T22:24:46.782Z,1570659886.782 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar 2019-10-09T22:24:46.817Z,1570659886.817 [Radio_Surface](INFO): Powering up 2019-10-09T22:24:46.839Z,1570659886.839 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-10-09T22:24:46.881Z,1570659886.881 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-10-09T22:24:46.889Z,1570659886.889 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-10-09T22:24:46.890Z,1570659886.890 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-10-09T22:24:46.897Z,1570659886.897 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-10-09T22:24:46.898Z,1570659886.898 [MassServo](DEBUG): Initializing EZServoServo. 2019-10-09T22:24:46.905Z,1570659886.905 [MassServo](DEBUG): Initializing MassServo. 2019-10-09T22:24:46.906Z,1570659886.906 [RudderServo](DEBUG): Initializing EZServoServo. 2019-10-09T22:24:46.913Z,1570659886.913 [RudderServo](DEBUG): Initializing RudderServo. 2019-10-09T22:24:46.914Z,1570659886.914 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-10-09T22:24:46.925Z,1570659886.925 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-10-09T22:24:47.135Z,1570659887.135 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2019-10-09T22:24:47.135Z,1570659887.135 [DropWeight] Hardware Fault, FailCount= 1 2019-10-09T22:24:47.135Z,1570659887.135 [DropWeight](ERROR): Hardware Fault 2019-10-09T22:24:47.212Z,1570659887.212 [CommandLine](FAULT): Scheduling is paused 2019-10-09T22:24:47.213Z,1570659887.213 [CBIT](INFO): Critical error at 20191009T222447 2019-10-09T22:24:47.213Z,1570659887.213 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2019-10-09T22:24:47.215Z,1570659887.215 [CBIT](ERROR): Hardware Fault in component: DropWeight 2019-10-09T22:24:47.216Z,1570659887.216 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2019-10-09T22:24:47.882Z,1570659887.882 [CBIT](INFO): Critical error at 20191009T222447 2019-10-09T22:24:50.153Z,1570659890.153 [Aanderaa_O2](INFO): Powering down 2019-10-09T22:24:56.685Z,1570659896.685 [CBIT](CRITICAL): Environmental Failure. Press:14.608959 PSI. Humidity:39%. Temp:23 C. ABORTING MISSION 2019-10-09T22:24:57.109Z,1570659897.109 [CBIT](INFO): Critical error at 20191009T222456 2019-10-09T22:25:01.450Z,1570659901.450 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004520 2019-10-09T22:25:10.018Z,1570659910.018 [SBIT](IMPORTANT): Beginning Startup BIT 2019-10-09T22:25:10.062Z,1570659910.062 [CBIT](IMPORTANT): Beginning ground fault scan 2019-10-09T22:25:13.287Z,1570659913.287 [NAL9602](INFO): Powering up NAL9602 2019-10-09T22:25:21.501Z,1570659921.501 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.011785 CHAN A1 (24V): 0.048205 CHAN A2 (12V): -0.001535 CHAN A3 (5V): -0.001474 CHAN B0 (3.3V): -0.000075 CHAN B1 (3.15aV): -0.000249 CHAN B2 (3.15bV): -0.000157 CHAN B3 (GND): 0.000046 OPEN: -0.000394 Full Scale Calc: 4.765 mA, -1.589 mA 2019-10-09T22:25:24.186Z,1570659924.186 [NAL9602](INFO): NAL9602 initialized 2019-10-09T22:25:25.009Z,1570659925.009 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:25:56.366Z,1570659956.366 [CommandLine](IMPORTANT): got command ! echo 0 > /dev/loadB2 2019-10-09T22:25:59.683Z,1570659959.683 [CommandLine](IMPORTANT): got command gfscan 2019-10-09T22:26:00.042Z,1570659960.042 [CBIT](IMPORTANT): Beginning ground fault scan 2019-10-09T22:26:04.029Z,1570659964.029 [SBIT](IMPORTANT): SBIT PASSED 2019-10-09T22:26:04.089Z,1570659964.089 [CommandLine](IMPORTANT): got command configSet list 2019-10-09T22:26:04.089Z,1570659964.089 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-10-09T22:26:04.090Z,1570659964.090 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool; 2019-10-09T22:26:04.090Z,1570659964.090 [CommandLine](IMPORTANT): DAT.loadAtStartup=0 bool; 2019-10-09T22:26:04.090Z,1570659964.090 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool; 2019-10-09T22:26:04.427Z,1570659964.427 [MissionManager](IMPORTANT): Started mission Startup 2019-10-09T22:26:04.427Z,1570659964.427 [Startup] Running Loop=1 2019-10-09T22:26:04.427Z,1570659964.427 [Startup](DEBUG): Aggregate::initialize Startup 2019-10-09T22:26:04.427Z,1570659964.427 [Startup:A.GoToSurface] Running Loop=1 2019-10-09T22:26:04.427Z,1570659964.427 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-10-09T22:26:04.428Z,1570659964.428 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-10-09T22:26:04.429Z,1570659964.429 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-10-09T22:26:04.429Z,1570659964.429 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-10-09T22:26:04.430Z,1570659964.430 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-10-09T22:26:04.430Z,1570659964.430 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-10-09T22:26:04.432Z,1570659964.432 [Startup:StartupSatComms] Running Loop=1 2019-10-09T22:26:04.432Z,1570659964.432 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-10-09T22:26:04.432Z,1570659964.432 [Startup:StartupSatComms:A] Running Loop=1 2019-10-09T22:26:04.828Z,1570659964.828 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-10-09T22:26:10.884Z,1570659970.884 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.012727 CHAN A1 (24V): 0.037742 CHAN A2 (12V): -0.002120 CHAN A3 (5V): -0.001518 CHAN B0 (3.3V): 0.000281 CHAN B1 (3.15aV): -0.000169 CHAN B2 (3.15bV): -0.000052 CHAN B3 (GND): 0.000255 OPEN: -0.000311 Full Scale Calc: 4.765 mA, -1.589 mA 2019-10-09T22:26:36.814Z,1570659996.814 [CommandLine](IMPORTANT): got command gfscan 2019-10-09T22:26:37.152Z,1570659997.152 [CBIT](IMPORTANT): Beginning ground fault scan 2019-10-09T22:26:48.075Z,1570660008.075 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.013253 CHAN A1 (24V): -0.006067 CHAN A2 (12V): -0.002663 CHAN A3 (5V): -0.001347 CHAN B0 (3.3V): 0.000067 CHAN B1 (3.15aV): -0.000229 CHAN B2 (3.15bV): 0.000002 CHAN B3 (GND): 0.000056 OPEN: -0.000330 Full Scale Calc: 4.765 mA, -1.589 mA 2019-10-09T22:27:04.608Z,1570660024.608 [Startup:StartupSatComms:A](INFO): Timed out from 2019-10-09T22:26:04.4Z 2019-10-09T22:27:04.608Z,1570660024.608 [Startup:StartupSatComms:A] Stopped 2019-10-09T22:27:04.608Z,1570660024.608 [Startup:StartupSatComms:B] Running Loop=1 2019-10-09T22:27:05.003Z,1570660025.003 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-10-09T22:27:11.510Z,1570660031.510 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20191009T221042/Courier0004.lzma 2019-10-09T22:27:12.090Z,1570660032.090 [CommandLine](IMPORTANT): got command gfscan 2019-10-09T22:27:12.308Z,1570660032.308 [CBIT](IMPORTANT): Beginning ground fault scan 2019-10-09T22:27:13.518Z,1570660033.518 [DataOverHttps](INFO): Moved sent file to Logs/20191009T221042/Courier0004.lzma.bak 2019-10-09T22:27:13.519Z,1570660033.519 [DataOverHttps](INFO): SBD MOMSN=11867151 2019-10-09T22:27:23.207Z,1570660043.207 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.013727 CHAN A1 (24V): -0.036779 CHAN A2 (12V): -0.001700 CHAN A3 (5V): -0.001536 CHAN B0 (3.3V): 0.000022 CHAN B1 (3.15aV): -0.000086 CHAN B2 (3.15bV): -0.000167 CHAN B3 (GND): -0.000216 OPEN: -0.000548 Full Scale Calc: 4.765 mA, -1.589 mA 2019-10-09T22:27:30.441Z,1570660050.441 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20191009T221942/Courier0000.lzma 2019-10-09T22:27:31.383Z,1570660051.383 [CommandLine](INFO): End of History 2019-10-09T22:27:31.390Z,1570660051.390 [CommandLine](INFO): End of History 2019-10-09T22:27:31.405Z,1570660051.405 [CommandLine](INFO): End of History 2019-10-09T22:27:31.477Z,1570660051.477 [CommandLine](INFO): End of History 2019-10-09T22:27:31.480Z,1570660051.480 [CommandLine](INFO): End of History 2019-10-09T22:27:31.482Z,1570660051.482 [CommandLine](INFO): End of History 2019-10-09T22:27:31.484Z,1570660051.484 [CommandLine](INFO): End of History 2019-10-09T22:27:31.831Z,1570660051.831 [CommandLine](INFO): End of History 2019-10-09T22:27:31.838Z,1570660051.838 [CommandLine](INFO): End of History 2019-10-09T22:27:31.840Z,1570660051.840 [CommandLine](INFO): End of History 2019-10-09T22:27:31.851Z,1570660051.851 [CommandLine](INFO): End of History 2019-10-09T22:27:31.858Z,1570660051.858 [CommandLine](INFO): End of History 2019-10-09T22:27:31.881Z,1570660051.881 [CommandLine](INFO): End of History 2019-10-09T22:27:31.883Z,1570660051.883 [CommandLine](INFO): End of History 2019-10-09T22:27:31.902Z,1570660051.902 [CommandLine](INFO): End of History 2019-10-09T22:27:32.446Z,1570660052.446 [DataOverHttps](INFO): Moved sent file to Logs/20191009T221942/Courier0000.lzma.bak 2019-10-09T22:27:32.446Z,1570660052.446 [DataOverHttps](INFO): SBD MOMSN=11867154 2019-10-09T22:27:37.514Z,1570660057.514 [CommandLine](IMPORTANT): got command gfscan 2019-10-09T22:27:37.774Z,1570660057.774 [CBIT](IMPORTANT): Beginning ground fault scan 2019-10-09T22:27:46.618Z,1570660066.618 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-10-09T22:27:46.618Z,1570660066.618 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-10-09T22:27:46.662Z,1570660066.662 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-10-09T22:27:47.070Z,1570660067.070 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-10-09T22:27:47.070Z,1570660067.070 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-10-09T22:27:48.667Z,1570660068.667 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.013500 CHAN A1 (24V): -0.053884 CHAN A2 (12V): -0.001803 CHAN A3 (5V): -0.001322 CHAN B0 (3.3V): 0.000096 CHAN B1 (3.15aV): 0.000140 CHAN B2 (3.15bV): -0.000735 CHAN B3 (GND): 0.000079 OPEN: -0.000579 Full Scale Calc: 4.765 mA, -1.589 mA 2019-10-09T22:27:49.537Z,1570660069.537 [DataOverHttps](INFO): Sending 210 bytes from file Logs/20191009T222436/Courier0000.lzma 2019-10-09T22:27:51.542Z,1570660071.542 [DataOverHttps](INFO): Moved sent file to Logs/20191009T222436/Courier0000.lzma.bak 2019-10-09T22:27:51.542Z,1570660071.542 [DataOverHttps](INFO): SBD MOMSN=11867161 2019-10-09T22:28:04.839Z,1570660084.839 [Startup:StartupSatComms:B](INFO): Timed out from 2019-10-09T22:27:04.6Z 2019-10-09T22:28:04.839Z,1570660084.839 [Startup:StartupSatComms:B] Stopped 2019-10-09T22:28:04.839Z,1570660084.839 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2019-10-09T22:28:04.839Z,1570660084.839 [Startup:StartupSatComms] Stopped 2019-10-09T22:28:04.839Z,1570660084.839 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-10-09T22:28:04.840Z,1570660084.840 [Startup](INFO): Completed Startup 2019-10-09T22:28:04.840Z,1570660084.840 [MissionManager](INFO): Startup is completed. 2019-10-09T22:28:04.840Z,1570660084.840 [MissionManager](INFO): Uninitializing Mission Startup 2019-10-09T22:28:04.840Z,1570660084.840 [Startup] Stopped 2019-10-09T22:28:04.840Z,1570660084.840 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-10-09T22:28:04.840Z,1570660084.840 [Startup:A.GoToSurface] Stopped 2019-10-09T22:28:04.841Z,1570660084.841 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-10-09T22:28:05.238Z,1570660085.238 [MissionManager](IMPORTANT): Started mission Default 2019-10-09T22:28:05.238Z,1570660085.238 [Default] Running Loop=1 2019-10-09T22:28:05.239Z,1570660085.239 [Default](DEBUG): Aggregate::initialize Default 2019-10-09T22:28:05.239Z,1570660085.239 [Default:B.GoToSurface] Running Loop=1 2019-10-09T22:28:05.239Z,1570660085.239 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-10-09T22:28:05.239Z,1570660085.239 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-10-09T22:28:05.239Z,1570660085.239 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-10-09T22:28:05.240Z,1570660085.240 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-10-09T22:28:05.240Z,1570660085.240 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-10-09T22:28:05.240Z,1570660085.240 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-10-09T22:28:05.245Z,1570660085.245 [Default:A.Wait] Running Loop=1 2019-10-09T22:28:05.245Z,1570660085.245 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-10-09T22:28:07.471Z,1570660087.471 [DataOverHttps](INFO): Sending 797 bytes from file Logs/20191009T221042/Express0001.lzma 2019-10-09T22:28:09.474Z,1570660089.474 [DataOverHttps](INFO): Moved sent file to Logs/20191009T221042/Express0001.lzma.bak 2019-10-09T22:28:09.474Z,1570660089.474 [DataOverHttps](INFO): SBD MOMSN=11867167 2019-10-09T22:28:18.543Z,1570660098.543 [Default:A.Wait](INFO): Done Waiting. 2019-10-09T22:28:18.544Z,1570660098.544 [Default:A.Wait] Stopped 2019-10-09T22:28:18.544Z,1570660098.544 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-10-09T22:28:18.944Z,1570660098.944 [Default:CheckIn] Running Loop=1 2019-10-09T22:28:18.944Z,1570660098.944 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-10-09T22:28:18.944Z,1570660098.944 [Default:CheckIn:Read_GPS] Running Loop=1 2019-10-09T22:28:19.343Z,1570660099.343 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2019-10-09T22:30:01.119Z,1570660201.119 [CommandLine](IMPORTANT): got command gfscan 2019-10-09T22:30:01.194Z,1570660201.194 [CBIT](IMPORTANT): Beginning ground fault scan 2019-10-09T22:30:12.105Z,1570660212.105 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.013809 CHAN A1 (24V): -0.104517 CHAN A2 (12V): -0.001770 CHAN A3 (5V): -0.001425 CHAN B0 (3.3V): -0.000037 CHAN B1 (3.15aV): 0.000177 CHAN B2 (3.15bV): -0.000287 CHAN B3 (GND): -0.000163 OPEN: -0.000480 Full Scale Calc: 4.765 mA, -1.589 mA 2019-10-09T22:30:27.795Z,1570660227.795 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-10-09T22:30:28.617Z,1570660228.617 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:30:31.445Z,1570660231.445 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:30:34.673Z,1570660234.673 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:30:37.489Z,1570660237.489 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:30:40.725Z,1570660240.725 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:30:43.549Z,1570660243.549 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:30:46.377Z,1570660246.377 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:30:47.631Z,1570660247.631 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2019-10-09T22:30:47.631Z,1570660247.631 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-10-09T22:30:47.677Z,1570660247.677 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-10-09T22:30:48.144Z,1570660248.144 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-10-09T22:30:48.144Z,1570660248.144 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2019-10-09T22:30:49.613Z,1570660249.613 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:30:52.441Z,1570660252.441 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:30:52.934Z,1570660252.934 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2019-10-09T22:30:55.679Z,1570660255.679 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:30:57.254Z,1570660257.254 [CommandLine](IMPORTANT): got command gfscan 2019-10-09T22:30:57.329Z,1570660257.329 [CBIT](IMPORTANT): Beginning ground fault scan 2019-10-09T22:30:58.509Z,1570660258.509 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:31:01.737Z,1570660261.737 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:31:04.561Z,1570660264.561 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:31:07.789Z,1570660267.789 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:31:08.262Z,1570660268.262 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.013847 CHAN A1 (24V): 0.047707 CHAN A2 (12V): -0.002020 CHAN A3 (5V): -0.002118 CHAN B0 (3.3V): -0.000130 CHAN B1 (3.15aV): -0.000089 CHAN B2 (3.15bV): -0.000425 CHAN B3 (GND): 0.000168 OPEN: -0.000594 Full Scale Calc: 4.765 mA, -1.589 mA 2019-10-09T22:31:10.621Z,1570660270.621 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:31:13.449Z,1570660273.449 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:31:16.677Z,1570660276.677 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:31:19.513Z,1570660279.513 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:31:22.741Z,1570660282.741 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:31:25.569Z,1570660285.569 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:31:26.363Z,1570660286.363 [CommandLine](IMPORTANT): got command gfscan 2019-10-09T22:31:26.434Z,1570660286.434 [CBIT](IMPORTANT): Beginning ground fault scan 2019-10-09T22:31:28.809Z,1570660288.809 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:31:31.625Z,1570660291.625 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:31:34.453Z,1570660294.453 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:31:37.321Z,1570660297.321 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.014078 CHAN A1 (24V): 0.047521 CHAN A2 (12V): -0.002038 CHAN A3 (5V): -0.001581 CHAN B0 (3.3V): 0.000261 CHAN B1 (3.15aV): -0.000226 CHAN B2 (3.15bV): -0.000126 CHAN B3 (GND): -0.000135 OPEN: -0.000391 Full Scale Calc: 4.765 mA, -1.589 mA 2019-10-09T22:31:37.685Z,1570660297.685 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:31:40.529Z,1570660300.529 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:31:43.757Z,1570660303.757 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:31:46.573Z,1570660306.573 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:31:49.805Z,1570660309.805 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:31:52.637Z,1570660312.637 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:31:55.869Z,1570660315.869 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:31:58.697Z,1570660318.697 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:32:01.929Z,1570660321.929 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:32:04.757Z,1570660324.757 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:32:07.585Z,1570660327.585 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:32:10.813Z,1570660330.813 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:32:13.645Z,1570660333.645 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:32:16.873Z,1570660336.873 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:32:16.908Z,1570660336.908 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2019-10-09T22:32:16.911Z,1570660336.911 [BPC1](INFO): Received data from all battery sticks. 2019-10-09T22:32:19.708Z,1570660339.708 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:32:22.943Z,1570660342.943 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:32:25.769Z,1570660345.769 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:32:28.593Z,1570660348.593 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:32:31.825Z,1570660351.825 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:32:34.653Z,1570660354.653 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:32:37.881Z,1570660357.881 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:32:40.712Z,1570660360.712 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:32:43.953Z,1570660363.953 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:32:46.769Z,1570660366.769 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:32:50.005Z,1570660370.005 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:32:52.833Z,1570660372.833 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:32:55.661Z,1570660375.661 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:32:58.897Z,1570660378.897 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:33:01.717Z,1570660381.717 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:33:04.949Z,1570660384.949 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:33:07.787Z,1570660387.787 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:33:11.013Z,1570660391.013 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:33:13.841Z,1570660393.841 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:33:16.669Z,1570660396.669 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:33:19.118Z,1570660399.118 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-10-09T22:28:18.9Z 2019-10-09T22:33:19.119Z,1570660399.119 [Default:CheckIn:Read_GPS] Stopped 2019-10-09T22:33:19.119Z,1570660399.119 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-10-09T22:33:19.531Z,1570660399.531 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-10-09T22:33:20.005Z,1570660400.005 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:33:20.616Z,1570660400.616 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20191009T222436/Courier0004.lzma 2019-10-09T22:33:22.562Z,1570660402.562 [DataOverHttps](INFO): Moved sent file to Logs/20191009T222436/Courier0004.lzma.bak 2019-10-09T22:33:22.562Z,1570660402.562 [DataOverHttps](INFO): SBD MOMSN=11867236 2019-10-09T22:33:22.805Z,1570660402.805 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:33:26.037Z,1570660406.037 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:33:28.865Z,1570660408.865 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:33:31.697Z,1570660411.697 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:33:34.929Z,1570660414.929 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:33:37.753Z,1570660417.753 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:33:39.134Z,1570660419.134 [DataOverHttps](INFO): Sending 295 bytes from file Logs/20191009T221042/Express0005.lzma 2019-10-09T22:33:40.985Z,1570660420.985 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:33:41.138Z,1570660421.138 [DataOverHttps](INFO): Moved sent file to Logs/20191009T221042/Express0005.lzma.bak 2019-10-09T22:33:41.138Z,1570660421.138 [DataOverHttps](INFO): SBD MOMSN=11867239 2019-10-09T22:33:43.813Z,1570660423.813 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:33:47.045Z,1570660427.045 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:33:48.686Z,1570660428.686 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2019-10-09T22:33:48.686Z,1570660428.686 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-10-09T22:33:48.696Z,1570660428.696 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-10-09T22:33:49.090Z,1570660429.090 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-10-09T22:33:49.090Z,1570660429.090 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2019-10-09T22:33:49.881Z,1570660429.881 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:33:52.709Z,1570660432.709 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:33:55.945Z,1570660435.945 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:33:57.709Z,1570660437.709 [DataOverHttps](INFO): Sending 366 bytes from file Logs/20191009T221942/Express0001.lzma 2019-10-09T22:33:58.761Z,1570660438.761 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:33:59.714Z,1570660439.714 [DataOverHttps](INFO): Moved sent file to Logs/20191009T221942/Express0001.lzma.bak 2019-10-09T22:33:59.714Z,1570660439.714 [DataOverHttps](INFO): SBD MOMSN=11867246 2019-10-09T22:34:01.997Z,1570660441.997 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:34:04.821Z,1570660444.821 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:34:08.061Z,1570660448.061 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:34:10.889Z,1570660450.889 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:34:14.125Z,1570660454.125 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:34:15.354Z,1570660455.354 [DataOverHttps](INFO): Sending 791 bytes from file Logs/20191009T222436/Express0001.lzma 2019-10-09T22:34:16.941Z,1570660456.941 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:34:17.326Z,1570660457.326 [DataOverHttps](INFO): Moved sent file to Logs/20191009T222436/Express0001.lzma.bak 2019-10-09T22:34:17.326Z,1570660457.326 [DataOverHttps](INFO): SBD MOMSN=11867254 2019-10-09T22:34:19.778Z,1570660459.778 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:34:23.017Z,1570660463.017 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:34:25.841Z,1570660465.841 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:34:29.065Z,1570660469.065 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:34:31.893Z,1570660471.893 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:34:33.922Z,1570660473.922 [DataOverHttps](INFO): Sending 617 bytes from file Logs/20191009T222436/Express0005.lzma 2019-10-09T22:34:35.125Z,1570660475.125 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:34:35.926Z,1570660475.926 [DataOverHttps](INFO): Moved sent file to Logs/20191009T222436/Express0005.lzma.bak 2019-10-09T22:34:35.927Z,1570660475.927 [DataOverHttps](INFO): SBD MOMSN=11867280 2019-10-09T22:34:37.172Z,1570660477.172 [Default:CheckIn:Read_Iridium] Stopped 2019-10-09T22:34:37.172Z,1570660477.172 [Default:CheckIn:C.Wait] Running Loop=1 2019-10-09T22:34:37.172Z,1570660477.172 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-10-09T22:34:37.961Z,1570660477.961 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:34:40.781Z,1570660480.781 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:34:44.013Z,1570660484.013 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:34:46.841Z,1570660486.841 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:34:50.073Z,1570660490.073 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:34:52.909Z,1570660492.909 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:34:56.153Z,1570660496.153 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:34:58.969Z,1570660498.969 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:35:02.197Z,1570660502.197 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:35:05.025Z,1570660505.025 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:35:07.849Z,1570660507.849 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:35:11.085Z,1570660511.085 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:35:13.909Z,1570660513.909 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:35:17.145Z,1570660517.145 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:35:19.969Z,1570660519.969 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:35:23.209Z,1570660523.209 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:35:26.026Z,1570660526.026 [NAL9602](FAULT): GPS failed to acquire within timeout. 2019-10-09T22:35:26.026Z,1570660526.026 [NAL9602] Data Fault, FailCount= 1 2019-10-09T22:35:26.026Z,1570660526.026 [NAL9602](ERROR): Data Fault 2019-10-09T22:35:26.056Z,1570660526.056 [CBIT](ERROR): Data Fault in component: NAL9602 2019-10-09T22:35:26.422Z,1570660526.422 [NAL9602](INFO): Powering down 2019-10-09T22:35:27.259Z,1570660527.259 [CBIT](INFO): Clearing failed state for component NAL9602 2019-10-09T22:35:27.259Z,1570660527.259 [NAL9602] No Fault, FailCount= 1 2019-10-09T22:35:56.722Z,1570660556.722 [NAL9602](INFO): Powering up NAL9602 2019-10-09T22:36:07.630Z,1570660567.630 [NAL9602](INFO): NAL9602 initialized 2019-10-09T22:36:08.461Z,1570660568.461 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:36:11.281Z,1570660571.281 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:36:14.105Z,1570660574.105 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:36:16.125Z,1570660576.125 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:36:19.357Z,1570660579.357 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:36:22.185Z,1570660582.185 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:36:25.417Z,1570660585.417 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:36:27.290Z,1570660587.290 [CommandLine](IMPORTANT): got command get CBIT.gf24Offset 2019-10-09T22:36:27.291Z,1570660587.291 [CommandLine](IMPORTANT): CBIT.gf24Offset 107.000000 uA 2019-10-09T22:36:28.245Z,1570660588.245 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:36:31.483Z,1570660591.483 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:36:34.313Z,1570660594.313 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:36:37.133Z,1570660597.133 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:36:39.161Z,1570660599.161 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:36:42.389Z,1570660602.389 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:36:45.213Z,1570660605.213 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:36:48.445Z,1570660608.445 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:36:49.686Z,1570660609.686 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2019-10-09T22:36:49.687Z,1570660609.687 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-10-09T22:36:49.697Z,1570660609.697 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-10-09T22:36:50.103Z,1570660610.103 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-10-09T22:36:50.103Z,1570660610.103 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2019-10-09T22:36:51.273Z,1570660611.273 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:36:54.505Z,1570660614.505 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:36:57.340Z,1570660617.340 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:37:00.169Z,1570660620.169 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:37:02.185Z,1570660622.185 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:37:04.201Z,1570660624.201 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:37:07.437Z,1570660627.437 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:37:10.265Z,1570660630.265 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:37:13.089Z,1570660633.089 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:37:16.321Z,1570660636.321 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:37:19.165Z,1570660639.165 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:37:22.381Z,1570660642.381 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:37:25.217Z,1570660645.217 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:37:28.037Z,1570660648.037 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:37:30.461Z,1570660650.461 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:37:33.301Z,1570660653.301 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:37:36.533Z,1570660656.533 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:37:39.349Z,1570660659.349 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:37:42.177Z,1570660662.177 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:37:45.409Z,1570660665.409 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:37:48.241Z,1570660668.241 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:37:51.065Z,1570660671.065 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:37:53.093Z,1570660673.093 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:37:56.325Z,1570660676.325 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:37:59.145Z,1570660679.145 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:38:02.377Z,1570660682.377 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:38:05.205Z,1570660685.205 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:38:08.437Z,1570660688.437 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:38:11.272Z,1570660691.272 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:38:14.093Z,1570660694.093 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:38:16.117Z,1570660696.117 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:38:18.537Z,1570660698.537 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:38:21.369Z,1570660701.369 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:38:24.601Z,1570660704.601 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:38:27.429Z,1570660707.429 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:38:30.261Z,1570660710.261 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:38:33.485Z,1570660713.485 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:38:36.321Z,1570660716.321 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:38:39.145Z,1570660719.145 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:38:42.373Z,1570660722.373 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:38:45.201Z,1570660725.201 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:38:48.437Z,1570660728.437 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:38:51.265Z,1570660731.265 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:38:54.501Z,1570660734.501 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:38:57.325Z,1570660737.325 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:39:00.561Z,1570660740.561 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:39:03.381Z,1570660743.381 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:39:06.617Z,1570660746.617 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:39:09.441Z,1570660749.441 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:39:12.673Z,1570660752.673 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:39:15.501Z,1570660755.501 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:39:18.741Z,1570660758.741 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:39:21.565Z,1570660761.565 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:39:24.389Z,1570660764.389 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:39:27.221Z,1570660767.221 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:39:30.449Z,1570660770.449 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:39:33.289Z,1570660773.289 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:39:36.510Z,1570660776.510 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:39:37.823Z,1570660777.823 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-10-09T22:39:37.823Z,1570660777.823 [Default:CheckIn:C.Wait] Stopped 2019-10-09T22:39:37.824Z,1570660777.824 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-10-09T22:39:37.824Z,1570660777.824 [Default:CheckIn:D] Running Loop=1 2019-10-09T22:39:38.153Z,1570660778.153 [Default:CheckIn:D] Stopped 2019-10-09T22:39:38.153Z,1570660778.153 [Default:CheckIn:E] Running Loop=1 2019-10-09T22:39:38.560Z,1570660778.560 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.548580 min 2019-10-09T22:39:38.561Z,1570660778.561 [Default:CheckIn:E] Stopped 2019-10-09T22:39:38.561Z,1570660778.561 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-10-09T22:39:38.561Z,1570660778.561 [Default:CheckIn] Stopped 2019-10-09T22:39:38.561Z,1570660778.561 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-10-09T22:39:38.561Z,1570660778.561 [Default:CheckIn](INFO): Running loop #2 2019-10-09T22:39:38.561Z,1570660778.561 [Default:CheckIn] Running Loop=2 2019-10-09T22:39:38.561Z,1570660778.561 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-10-09T22:39:38.562Z,1570660778.562 [Default:CheckIn:Read_GPS] Running Loop=1 2019-10-09T22:39:39.349Z,1570660779.349 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:39:42.577Z,1570660782.577 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:39:45.397Z,1570660785.397 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:39:47.467Z,1570660787.467 [CBIT](INFO): Clearing failed state for component DropWeight 2019-10-09T22:39:47.467Z,1570660787.467 [DropWeight] No Fault, FailCount= 1 2019-10-09T22:39:48.637Z,1570660788.637 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:39:50.660Z,1570660790.660 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2019-10-09T22:39:50.660Z,1570660790.660 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-10-09T22:39:50.670Z,1570660790.670 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-10-09T22:39:51.082Z,1570660791.082 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-10-09T22:39:51.082Z,1570660791.082 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2019-10-09T22:39:51.461Z,1570660791.461 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:39:54.698Z,1570660794.698 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:39:57.525Z,1570660797.525 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:40:00.757Z,1570660800.757 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:40:03.581Z,1570660803.581 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:40:06.405Z,1570660806.405 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:40:09.641Z,1570660809.641 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:40:12.469Z,1570660812.469 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:40:15.301Z,1570660815.301 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:40:17.321Z,1570660817.321 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:40:20.545Z,1570660820.545 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:40:23.381Z,1570660823.381 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:40:26.613Z,1570660826.613 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:40:29.433Z,1570660829.433 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:40:32.669Z,1570660832.669 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:40:35.493Z,1570660835.493 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:40:38.321Z,1570660838.321 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:40:40.345Z,1570660840.345 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:40:42.769Z,1570660842.769 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:40:45.597Z,1570660845.597 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:40:48.825Z,1570660848.825 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:40:51.677Z,1570660851.677 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:40:54.485Z,1570660854.485 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:40:57.721Z,1570660857.721 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:41:00.549Z,1570660860.549 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:41:03.369Z,1570660863.369 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:41:06.605Z,1570660866.605 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:41:09.429Z,1570660869.429 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:41:12.665Z,1570660872.665 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:41:15.493Z,1570660875.493 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:41:18.729Z,1570660878.729 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:41:19.931Z,1570660879.931 [CommandLine](IMPORTANT): got command configSet CBIT.gf24Offset 154.000000 microampere persist 2019-10-09T22:41:21.553Z,1570660881.553 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:41:24.789Z,1570660884.789 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:41:27.613Z,15706