2019-10-09T22:56:28.649Z,1570661788.649 [Supervisor](DEBUG): Initializing supervisor. 2019-10-09T22:56:28.652Z,1570661788.652 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-10-09T22:56:28.653Z,1570661788.653 [SyncHandler](INFO): Protected caller Thread ID is 5886 2019-10-09T22:56:28.653Z,1570661788.653 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-10-09T22:56:28.654Z,1570661788.654 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-10-09T22:56:28.655Z,1570661788.655 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5887 2019-10-09T22:56:28.658Z,1570661788.658 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-10-09T22:56:28.669Z,1570661788.669 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-10-09T22:56:28.670Z,1570661788.670 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-10-09T22:56:28.671Z,1570661788.671 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5888 2019-10-09T22:56:28.672Z,1570661788.672 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-10-09T22:56:28.673Z,1570661788.673 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-10-09T22:56:28.673Z,1570661788.673 [logger ThreadHandler](INFO): Protected caller Thread ID is 5889 2019-10-09T22:56:28.675Z,1570661788.675 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-10-09T22:56:28.675Z,1570661788.675 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-10-09T22:56:28.677Z,1570661788.677 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-10-09T22:56:28.870Z,1570661788.870 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-10-09T22:56:28.871Z,1570661788.871 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-10-09T22:56:28.949Z,1570661788.949 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-10-09T22:56:29.372Z,1570661789.372 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-10-09T22:56:29.373Z,1570661789.373 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-10-09T22:56:29.688Z,1570661789.688 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-10-09T22:56:29.689Z,1570661789.689 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-10-09T22:56:29.783Z,1570661789.783 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-10-09T22:56:29.783Z,1570661789.783 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-10-09T22:56:30.070Z,1570661790.070 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-10-09T22:56:30.070Z,1570661790.070 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-10-09T22:56:30.259Z,1570661790.259 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-10-09T22:56:30.260Z,1570661790.260 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-10-09T22:56:30.732Z,1570661790.732 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-10-09T22:56:30.732Z,1570661790.732 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-10-09T22:56:30.987Z,1570661790.987 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-10-09T22:56:30.987Z,1570661790.987 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-10-09T22:56:31.198Z,1570661791.198 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-10-09T22:56:31.199Z,1570661791.199 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-10-09T22:56:31.780Z,1570661791.780 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-10-09T22:56:31.781Z,1570661791.781 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-10-09T22:56:32.164Z,1570661792.164 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-10-09T22:56:32.165Z,1570661792.165 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-10-09T22:56:32.351Z,1570661792.351 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-10-09T22:56:32.352Z,1570661792.352 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-10-09T22:56:32.493Z,1570661792.493 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-10-09T22:56:32.494Z,1570661792.494 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-10-09T22:56:32.638Z,1570661792.638 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-10-09T22:56:32.640Z,1570661792.640 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2019-10-09T22:56:32.641Z,1570661792.641 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2019-10-09T22:56:32.730Z,1570661792.730 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2019-10-09T22:56:32.814Z,1570661792.814 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2019-10-09T22:56:32.915Z,1570661792.915 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2019-10-09T22:56:32.996Z,1570661792.996 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2019-10-09T22:56:33.089Z,1570661793.089 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2019-10-09T22:56:33.187Z,1570661793.187 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2019-10-09T22:56:33.391Z,1570661793.391 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2019-10-09T22:56:33.466Z,1570661793.466 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2019-10-09T22:56:33.627Z,1570661793.627 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2019-10-09T22:56:33.757Z,1570661793.757 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2019-10-09T22:56:33.873Z,1570661793.873 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2019-10-09T22:56:34.100Z,1570661794.100 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-10-09T22:56:34.101Z,1570661794.101 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/ 2019-10-09T22:56:34.101Z,1570661794.102 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-10-09T22:56:34.107Z,1570661794.107 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-10-09T22:56:34.178Z,1570661794.178 [VerticalControl](DEBUG): Construct VerticalControl. 2019-10-09T22:56:34.296Z,1570661794.296 [VerticalControl] Loaded 2019-10-09T22:56:34.296Z,1570661794.296 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-10-09T22:56:34.297Z,1570661794.297 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-10-09T22:56:34.369Z,1570661794.369 [HorizontalControl] Loaded 2019-10-09T22:56:34.369Z,1570661794.369 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-10-09T22:56:34.370Z,1570661794.370 [SpeedControl](DEBUG): Construct SpeedControl. 2019-10-09T22:56:34.376Z,1570661794.376 [SpeedControl] Loaded 2019-10-09T22:56:34.376Z,1570661794.376 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-10-09T22:56:34.377Z,1570661794.377 [LoopControl](DEBUG): Construct LoopControl. 2019-10-09T22:56:34.377Z,1570661794.377 [LoopControl] Loaded 2019-10-09T22:56:34.378Z,1570661794.378 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-10-09T22:56:34.378Z,1570661794.378 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-10-09T22:56:34.378Z,1570661794.378 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-10-09T22:56:34.392Z,1570661794.392 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-10-09T22:56:34.392Z,1570661794.392 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-10-09T22:56:34.497Z,1570661794.497 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-10-09T22:56:34.497Z,1570661794.497 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-10-09T22:56:34.627Z,1570661794.627 [BuoyancyServo] Loaded 2019-10-09T22:56:34.627Z,1570661794.627 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-10-09T22:56:34.639Z,1570661794.639 [ElevatorServo] Loaded 2019-10-09T22:56:34.639Z,1570661794.639 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-10-09T22:56:34.650Z,1570661794.650 [MassServo] Loaded 2019-10-09T22:56:34.650Z,1570661794.650 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-10-09T22:56:34.662Z,1570661794.662 [RudderServo] Loaded 2019-10-09T22:56:34.662Z,1570661794.662 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-10-09T22:56:34.673Z,1570661794.673 [ThrusterServo] Loaded 2019-10-09T22:56:34.673Z,1570661794.673 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-10-09T22:56:34.674Z,1570661794.674 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-10-09T22:56:34.674Z,1570661794.674 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-10-09T22:56:34.797Z,1570661794.797 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-10-09T22:56:34.797Z,1570661794.797 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-10-09T22:56:34.819Z,1570661794.819 [NavChart] Loaded 2019-10-09T22:56:34.819Z,1570661794.819 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-10-09T22:56:34.823Z,1570661794.823 [UniversalFixResidualReporter] Loaded 2019-10-09T22:56:34.823Z,1570661794.823 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-10-09T22:56:34.823Z,1570661794.823 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-10-09T22:56:34.824Z,1570661794.824 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-10-09T22:56:34.898Z,1570661794.898 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-10-09T22:56:34.898Z,1570661794.898 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-10-09T22:56:35.169Z,1570661795.169 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2019-10-09T22:56:35.174Z,1570661795.174 [AHRS_M2](INFO): created writer for : platform_orientation 2019-10-09T22:56:35.176Z,1570661795.176 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2019-10-09T22:56:35.181Z,1570661795.181 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2019-10-09T22:56:35.182Z,1570661795.182 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2019-10-09T22:56:35.187Z,1570661795.187 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2019-10-09T22:56:35.188Z,1570661795.188 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2019-10-09T22:56:35.193Z,1570661795.193 [AHRS_M2](INFO): created writer for : platform_roll_angle 2019-10-09T22:56:35.267Z,1570661795.267 [AHRS_M2] Loaded 2019-10-09T22:56:35.268Z,1570661795.268 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2019-10-09T22:56:35.345Z,1570661795.345 [DataOverHttps] Loaded 2019-10-09T22:56:35.345Z,1570661795.345 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-10-09T22:56:35.346Z,1570661795.346 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087D4E0 2019-10-09T22:56:35.347Z,1570661795.347 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5971 2019-10-09T22:56:35.361Z,1570661795.361 [Depth_Keller] Loaded 2019-10-09T22:56:35.361Z,1570661795.361 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-10-09T22:56:35.366Z,1570661795.366 [DropWeight] Loaded 2019-10-09T22:56:35.367Z,1570661795.367 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-10-09T22:56:35.468Z,1570661795.468 [NAL9602] Loaded 2019-10-09T22:56:35.468Z,1570661795.468 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-10-09T22:56:35.484Z,1570661795.484 [Onboard] Loaded 2019-10-09T22:56:35.485Z,1570661795.485 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-10-09T22:56:35.488Z,1570661795.488 [Radio_Surface] Loaded 2019-10-09T22:56:35.488Z,1570661795.488 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-10-09T22:56:35.489Z,1570661795.489 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408AD4E0 2019-10-09T22:56:35.490Z,1570661795.490 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5972 2019-10-09T22:56:37.050Z,1570661797.050 [BPC1] Loaded 2019-10-09T22:56:37.051Z,1570661797.051 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-10-09T22:56:37.051Z,1570661797.051 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-10-09T22:56:37.052Z,1570661797.052 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-10-09T22:56:37.233Z,1570661797.233 [DepthRateCalculator] Loaded 2019-10-09T22:56:37.233Z,1570661797.233 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-10-09T22:56:37.239Z,1570661797.239 [PitchRateCalculator] Loaded 2019-10-09T22:56:37.239Z,1570661797.239 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-10-09T22:56:37.278Z,1570661797.278 [SpeedCalculator] Loaded 2019-10-09T22:56:37.278Z,1570661797.278 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-10-09T22:56:37.366Z,1570661797.366 [TempGradientCalculator] Loaded 2019-10-09T22:56:37.368Z,1570661797.368 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-10-09T22:56:37.385Z,1570661797.385 [YawRateCalculator] Loaded 2019-10-09T22:56:37.385Z,1570661797.385 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-10-09T22:56:37.495Z,1570661797.495 [ElevatorOffsetCalculator] Loaded 2019-10-09T22:56:37.495Z,1570661797.495 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-10-09T22:56:37.496Z,1570661797.496 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-10-09T22:56:37.496Z,1570661797.496 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-10-09T22:56:37.904Z,1570661797.904 [Aanderaa_O2] Loaded 2019-10-09T22:56:37.904Z,1570661797.904 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2019-10-09T22:56:37.913Z,1570661797.913 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2019-10-09T22:56:37.919Z,1570661797.919 [CTD_Seabird](INFO): created writer for : sea_water_density 2019-10-09T22:56:37.919Z,1570661797.919 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2019-10-09T22:56:37.924Z,1570661797.924 [CTD_Seabird](INFO): created writer for : depth 2019-10-09T22:56:37.925Z,1570661797.925 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2019-10-09T22:56:37.930Z,1570661797.930 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2019-10-09T22:56:37.931Z,1570661797.931 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2019-10-09T22:56:37.937Z,1570661797.937 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2019-10-09T22:56:37.937Z,1570661797.937 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2019-10-09T22:56:37.943Z,1570661797.943 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2019-10-09T22:56:37.943Z,1570661797.943 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2019-10-09T22:56:37.949Z,1570661797.949 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2019-10-09T22:56:37.950Z,1570661797.950 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2019-10-09T22:56:37.955Z,1570661797.955 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2019-10-09T22:56:37.983Z,1570661797.983 [CTD_Seabird] Loaded 2019-10-09T22:56:37.984Z,1570661797.984 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2019-10-09T22:56:37.985Z,1570661797.985 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409B04E0 2019-10-09T22:56:37.985Z,1570661797.985 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5973 2019-10-09T22:56:37.000Z,1570661798.000 [PAR_Licor] Loaded 2019-10-09T22:56:38.000Z,1570661798.000 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2019-10-09T22:56:38.051Z,1570661798.051 [WetLabsBB2FL] Loaded 2019-10-09T22:56:38.051Z,1570661798.051 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-10-09T22:56:38.052Z,1570661798.052 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409E04E0 2019-10-09T22:56:38.052Z,1570661798.052 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5974 2019-10-09T22:56:38.053Z,1570661798.053 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-10-09T22:56:38.054Z,1570661798.054 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-10-09T22:56:38.088Z,1570661798.088 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-10-09T22:56:38.089Z,1570661798.089 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-10-09T22:56:38.395Z,1570661798.395 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-10-09T22:56:38.396Z,1570661798.396 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-10-09T22:56:38.534Z,1570661798.534 [SBIT](DEBUG): Construct Startup Built In Test. 2019-10-09T22:56:38.545Z,1570661798.545 [SBIT] Loaded 2019-10-09T22:56:38.545Z,1570661798.545 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-10-09T22:56:38.546Z,1570661798.546 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-10-09T22:56:38.558Z,1570661798.558 [IBIT] Loaded 2019-10-09T22:56:38.558Z,1570661798.558 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-10-09T22:56:38.561Z,1570661798.561 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-10-09T22:56:38.705Z,1570661798.705 [CBIT] Loaded 2019-10-09T22:56:38.706Z,1570661798.706 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-10-09T22:56:38.706Z,1570661798.706 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-10-09T22:56:38.710Z,1570661798.710 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-10-09T22:56:38.711Z,1570661798.711 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-10-09T22:56:38.717Z,1570661798.717 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-10-09T22:56:38.718Z,1570661798.718 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ADF4E0 2019-10-09T22:56:38.719Z,1570661798.719 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5975 2019-10-09T22:56:38.723Z,1570661798.723 [Supervisor](INFO): Main Thread ID is 5626 2019-10-09T22:56:38.724Z,1570661798.724 [Supervisor](DEBUG): Running supervisor. 2019-10-09T22:56:38.724Z,1570661798.724 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5976 2019-10-09T22:56:38.727Z,1570661798.727 [controlThread ThreadHandler](INFO): Handler Thread ID is 5977 2019-10-09T22:56:38.727Z,1570661798.727 [controlThread](DEBUG): Initializing ControlThread 2019-10-09T22:56:38.728Z,1570661798.728 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-10-09T22:56:38.730Z,1570661798.730 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-10-09T22:56:38.730Z,1570661798.730 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-10-09T22:56:38.731Z,1570661798.731 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-10-09T22:56:38.732Z,1570661798.732 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-10-09T22:56:38.733Z,1570661798.733 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-10-09T22:56:38.739Z,1570661798.739 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-10-09T22:56:38.739Z,1570661798.739 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-10-09T22:56:38.740Z,1570661798.740 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-10-09T22:56:38.740Z,1570661798.740 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-10-09T22:56:38.740Z,1570661798.740 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-10-09T22:56:38.741Z,1570661798.741 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-10-09T22:56:38.745Z,1570661798.745 [SBIT](INFO): Initialize SBIT Component. 2019-10-09T22:56:38.746Z,1570661798.746 [SBIT](IMPORTANT): git: 2019-09-10 2019-10-09T22:56:38.746Z,1570661798.746 [SBIT](INFO): git hash: e950883795b57abbf97d89589ea0268241928c93 2019-10-09T22:56:38.746Z,1570661798.746 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-10-09T22:56:38.747Z,1570661798.747 [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:56:38.748Z,1570661798.748 [SBIT](INFO): Beginning SBIT in 23.000000 seconds. 2019-10-09T22:56:38.749Z,1570661798.749 [IBIT](INFO): Initialize IBIT Component. 2019-10-09T22:56:38.750Z,1570661798.750 [CBIT](DEBUG): Initialize CBIT Component. 2019-10-09T22:56:38.751Z,1570661798.751 [logger ThreadHandler](INFO): Handler Thread ID is 5978 2019-10-09T22:56:38.761Z,1570661798.761 [CBIT](DEBUG): Initialized mux pins. 2019-10-09T22:56:38.761Z,1570661798.761 [CBIT](DEBUG): Initializing the watchdog timer. 2019-10-09T22:56:38.769Z,1570661798.769 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5979 2019-10-09T22:56:38.770Z,1570661798.770 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-10-09T22:56:38.781Z,1570661798.781 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5980 2019-10-09T22:56:38.785Z,1570661798.785 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-10-09T22:56:38.785Z,1570661798.785 [CBIT](DEBUG): Initializing heartbeat. 2019-10-09T22:56:38.793Z,1570661798.793 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5981 2019-10-09T22:56:38.794Z,1570661798.794 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-10-09T22:56:38.797Z,1570661798.797 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-10-09T22:56:38.798Z,1570661798.798 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5983 2019-10-09T22:56:38.800Z,1570661798.800 [WetLabsBB2FL](INFO): Powering down 2019-10-09T22:56:38.829Z,1570661798.829 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5984 2019-10-09T22:56:38.832Z,1570661798.832 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-10-09T22:56:38.832Z,1570661798.832 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-10-09T22:56:38.833Z,1570661798.833 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-10-09T22:56:38.833Z,1570661798.833 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-10-09T22:56:38.833Z,1570661798.833 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-10-09T22:56:38.833Z,1570661798.833 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-10-09T22:56:38.833Z,1570661798.833 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-10-09T22:56:38.833Z,1570661798.833 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-10-09T22:56:38.834Z,1570661798.834 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-10-09T22:56:38.834Z,1570661798.834 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-10-09T22:56:38.834Z,1570661798.834 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-10-09T22:56:38.834Z,1570661798.834 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-10-09T22:56:38.834Z,1570661798.834 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-10-09T22:56:38.835Z,1570661798.835 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-10-09T22:56:38.835Z,1570661798.835 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-10-09T22:56:38.835Z,1570661798.835 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-10-09T22:56:38.857Z,1570661798.857 [CBIT](DEBUG): Deactivating GF circuits. 2019-10-09T22:56:38.857Z,1570661798.857 [CBIT](DEBUG): Deactivating emergency mode. 2019-10-09T22:56:38.893Z,1570661798.893 [CBIT](DEBUG): Backplane powered. 2019-10-09T22:56:38.894Z,1570661798.894 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-10-09T22:56:38.904Z,1570661798.904 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-10-09T22:56:38.914Z,1570661798.914 [MissionManager](DEBUG): 2019-10-09T22:56:38.915Z,1570661798.915 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-10-09T22:56:38.994Z,1570661798.994 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-10-09T22:56:38.995Z,1570661798.995 [Default:A.Wait](DEBUG): Construct Wait. 2019-10-09T22:56:39.013Z,1570661799.013 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-10-09T22:56:39.035Z,1570661799.035 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-10-09T22:56:39.038Z,1570661799.038 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-10-09T22:56:39.059Z,1570661799.059 [Default:E.Execute](DEBUG): Construct Execute. 2019-10-09T22:56:39.070Z,1570661799.070 [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:56:39.075Z,1570661799.075 [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:56:39.085Z,1570661799.085 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-10-09T22:56:39.129Z,1570661799.129 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar 2019-10-09T22:56:39.185Z,1570661799.185 [Radio_Surface](INFO): Powering up 2019-10-09T22:56:39.191Z,1570661799.191 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-10-09T22:56:39.205Z,1570661799.205 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-10-09T22:56:39.213Z,1570661799.213 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-10-09T22:56:39.214Z,1570661799.214 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-10-09T22:56:39.221Z,1570661799.221 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-10-09T22:56:39.222Z,1570661799.222 [MassServo](DEBUG): Initializing EZServoServo. 2019-10-09T22:56:39.229Z,1570661799.229 [MassServo](DEBUG): Initializing MassServo. 2019-10-09T22:56:39.230Z,1570661799.230 [RudderServo](DEBUG): Initializing EZServoServo. 2019-10-09T22:56:39.237Z,1570661799.237 [RudderServo](DEBUG): Initializing RudderServo. 2019-10-09T22:56:39.238Z,1570661799.238 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-10-09T22:56:39.249Z,1570661799.249 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-10-09T22:56:39.509Z,1570661799.509 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2019-10-09T22:56:39.509Z,1570661799.509 [DropWeight] Hardware Fault, FailCount= 1 2019-10-09T22:56:39.509Z,1570661799.509 [DropWeight](ERROR): Hardware Fault 2019-10-09T22:56:39.586Z,1570661799.586 [CommandLine](FAULT): Scheduling is paused 2019-10-09T22:56:39.586Z,1570661799.586 [CBIT](INFO): Critical error at 20191009T225639 2019-10-09T22:56:39.587Z,1570661799.587 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2019-10-09T22:56:39.590Z,1570661799.590 [CBIT](ERROR): Hardware Fault in component: DropWeight 2019-10-09T22:56:39.590Z,1570661799.590 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2019-10-09T22:56:40.202Z,1570661800.202 [CBIT](INFO): Critical error at 20191009T225639 2019-10-09T22:56:42.521Z,1570661802.521 [Aanderaa_O2](INFO): Powering down 2019-10-09T22:56:45.825Z,1570661805.825 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2019-10-09T22:56:48.907Z,1570661808.907 [CBIT](CRITICAL): Environmental Failure. Press:14.643071 PSI. Humidity:36%. Temp:26 C. ABORTING MISSION 2019-10-09T22:56:49.296Z,1570661809.296 [CBIT](INFO): Critical error at 20191009T225648 2019-10-09T22:57:02.208Z,1570661822.208 [SBIT](IMPORTANT): Beginning Startup BIT 2019-10-09T22:57:02.220Z,1570661822.220 [CBIT](IMPORTANT): Beginning ground fault scan 2019-10-09T22:57:05.502Z,1570661825.502 [NAL9602](INFO): Powering up NAL9602 2019-10-09T22:57:13.669Z,1570661833.669 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.011918 CHAN A1 (24V): 0.000642 CHAN A2 (12V): -0.002153 CHAN A3 (5V): -0.001421 CHAN B0 (3.3V): -0.000175 CHAN B1 (3.15aV): -0.000501 CHAN B2 (3.15bV): -0.000213 CHAN B3 (GND): -0.000238 OPEN: -0.000208 Full Scale Calc: 4.765 mA, -1.589 mA 2019-10-09T22:57:16.410Z,1570661836.410 [NAL9602](INFO): NAL9602 initialized 2019-10-09T22:57:17.233Z,1570661837.233 [NAL9602](DEBUG): Fix Requested 2019-10-09T22:57:55.895Z,1570661875.895 [SBIT](IMPORTANT): SBIT PASSED 2019-10-09T22:57:55.929Z,1570661875.929 [CommandLine](IMPORTANT): got command configSet list 2019-10-09T22:57:55.930Z,1570661875.930 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-10-09T22:57:55.931Z,1570661875.931 [CommandLine](IMPORTANT): CBIT.gf24Offset=154 microampere; 2019-10-09T22:57:55.931Z,1570661875.931 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool; 2019-10-09T22:57:55.931Z,1570661875.931 [CommandLine](IMPORTANT): DAT.loadAtStartup=0 bool; 2019-10-09T22:57:55.931Z,1570661875.931 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool; 2019-10-09T22:57:56.306Z,1570661876.306 [MissionManager](IMPORTANT): Started mission Startup 2019-10-09T22:57:56.307Z,1570661876.307 [Startup] Running Loop=1 2019-10-09T22:57:56.307Z,1570661876.307 [Startup](DEBUG): Aggregate::initialize Startup 2019-10-09T22:57:56.307Z,1570661876.307 [Startup:A.GoToSurface] Running Loop=1 2019-10-09T22:57:56.307Z,1570661876.307 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-10-09T22:57:56.308Z,1570661876.308 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-10-09T22:57:56.308Z,1570661876.308 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-10-09T22:57:56.308Z,1570661876.308 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-10-09T22:57:56.309Z,1570661876.309 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-10-09T22:57:56.309Z,1570661876.309 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-10-09T22:57:56.310Z,1570661876.310 [Startup:StartupSatComms] Running Loop=1 2019-10-09T22:57:56.311Z,1570661876.311 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-10-09T22:57:56.311Z,1570661876.311 [Startup:StartupSatComms:A] Running Loop=1 2019-10-09T22:57:56.719Z,1570661876.719 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-10-09T22:58:56.478Z,1570661936.478 [Startup:StartupSatComms:A](INFO): Timed out from 2019-10-09T22:57:56.3Z 2019-10-09T22:58:56.478Z,1570661936.478 [Startup:StartupSatComms:A] Stopped 2019-10-09T22:58:56.478Z,1570661936.478 [Startup:StartupSatComms:B] Running Loop=1 2019-10-09T22:58:56.891Z,1570661936.891 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-10-09T22:59:04.878Z,1570661944.878 [CommandLine](IMPORTANT): got command stop 2019-10-09T22:59:04.879Z,1570661944.879 [CommandLine](IMPORTANT): Scheduling is paused 2019-10-09T22:59:07.558Z,1570661947.558 [CommandLine](IMPORTANT): got command stop 2019-10-09T22:59:11.795Z,1570661951.795 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004948 2019-10-09T22:59:19.632Z,1570661959.632 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20191009T222436/Courier0007.lzma 2019-10-09T22:59:21.638Z,1570661961.638 [DataOverHttps](INFO): Moved sent file to Logs/20191009T222436/Courier0007.lzma.bak 2019-10-09T22:59:21.639Z,1570661961.639 [DataOverHttps](INFO): SBD MOMSN=11867472 2019-10-09T22:59:38.921Z,1570661978.921 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-10-09T22:59:38.921Z,1570661978.921 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-10-09T22:59:38.971Z,1570661978.971 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-10-09T22:59:39.082Z,1570661979.082 [CommandLine](IMPORTANT): got command get VerticalControl.massPositionLimitFwd 2019-10-09T22:59:39.082Z,1570661979.082 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd 26.000000 mm 2019-10-09T22:59:39.371Z,1570661979.371 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-10-09T22:59:39.371Z,1570661979.371 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-10-09T22:59:41.861Z,1570661981.861 [DataOverHttps](INFO): Sending 288 bytes from file Logs/20191009T225131/Courier0000.lzma 2019-10-09T22:59:43.866Z,1570661983.866 [DataOverHttps](INFO): Moved sent file to Logs/20191009T225131/Courier0000.lzma.bak 2019-10-09T22:59:43.866Z,1570661983.866 [DataOverHttps](INFO): SBD MOMSN=11867476 2019-10-09T22:59:46.759Z,1570661986.759 [CommandLine](IMPORTANT): got command get VerticalControl.massPositionLimitAft 2019-10-09T22:59:46.759Z,1570661986.759 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitAft -35.000000 mm 2019-10-09T22:59:56.706Z,1570661996.706 [Startup:StartupSatComms:B](INFO): Timed out from 2019-10-09T22:58:56.5Z 2019-10-09T22:59:56.706Z,1570661996.706 [Startup:StartupSatComms:B] Stopped 2019-10-09T22:59:56.706Z,1570661996.706 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2019-10-09T22:59:56.706Z,1570661996.706 [Startup:StartupSatComms] Stopped 2019-10-09T22:59:56.706Z,1570661996.706 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-10-09T22:59:56.707Z,1570661996.707 [Startup](INFO): Completed Startup 2019-10-09T22:59:56.707Z,1570661996.707 [MissionManager](INFO): Startup is completed. 2019-10-09T22:59:56.707Z,1570661996.707 [MissionManager](INFO): Uninitializing Mission Startup 2019-10-09T22:59:56.707Z,1570661996.707 [Startup] Stopped 2019-10-09T22:59:56.708Z,1570661996.708 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-10-09T22:59:56.708Z,1570661996.708 [Startup:A.GoToSurface] Stopped 2019-10-09T22:59:56.708Z,1570661996.708 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-10-09T22:59:57.106Z,1570661997.106 [MissionManager](IMPORTANT): Started mission Default 2019-10-09T22:59:57.106Z,1570661997.106 [Default] Running Loop=1 2019-10-09T22:59:57.106Z,1570661997.106 [Default](DEBUG): Aggregate::initialize Default 2019-10-09T22:59:57.106Z,1570661997.106 [Default:B.GoToSurface] Running Loop=1 2019-10-09T22:59:57.106Z,1570661997.106 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-10-09T22:59:57.107Z,1570661997.107 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-10-09T22:59:57.107Z,1570661997.107 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-10-09T22:59:57.107Z,1570661997.107 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-10-09T22:59:57.108Z,1570661997.108 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-10-09T22:59:57.108Z,1570661997.108 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-10-09T22:59:57.108Z,1570661997.108 [Default:A.Wait] Running Loop=1 2019-10-09T22:59:57.108Z,1570661997.108 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-10-09T23:00:00.769Z,1570662000.769 [DataOverHttps](INFO): Sending 259 bytes from file Logs/20191009T225352/Courier0000.lzma 2019-10-09T23:00:02.774Z,1570662002.774 [DataOverHttps](INFO): Moved sent file to Logs/20191009T225352/Courier0000.lzma.bak 2019-10-09T23:00:02.774Z,1570662002.774 [DataOverHttps](INFO): SBD MOMSN=11867484 2019-10-09T23:00:10.426Z,1570662010.426 [Default:A.Wait](INFO): Done Waiting. 2019-10-09T23:00:10.426Z,1570662010.426 [Default:A.Wait] Stopped 2019-10-09T23:00:10.426Z,1570662010.426 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-10-09T23:00:10.830Z,1570662010.830 [Default:CheckIn] Running Loop=1 2019-10-09T23:00:10.830Z,1570662010.830 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-10-09T23:00:10.831Z,1570662010.831 [Default:CheckIn:Read_GPS] Running Loop=1 2019-10-09T23:00:11.234Z,1570662011.234 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2019-10-09T23:00:34.107Z,1570662034.107 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction -10.000000 millimeter 2019-10-09T23:00:34.108Z,1570662034.108 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread. 2019-10-09T23:00:34.290Z,1570662034.290 [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,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-10-09T23:01:07.917Z,1570662067.917 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction -30.000000 millimeter 2019-10-09T23:01:46.383Z,1570662106.383 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction -35.000000 millimeter 2019-10-09T23:02:20.207Z,1570662140.207 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-10-09T23:02:21.033Z,1570662141.033 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:02:24.665Z,1570662144.665 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:02:27.489Z,1570662147.489 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:02:29.278Z,1570662149.278 [CommandLine](IMPORTANT): got command get platform_mass_position 2019-10-09T23:02:29.279Z,1570662149.279 [CommandLine](IMPORTANT): platform_mass_position -0.034999 m 2019-10-09T23:02:30.313Z,1570662150.313 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:02:32.737Z,1570662152.737 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:02:35.575Z,1570662155.575 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:02:38.797Z,1570662158.797 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:02:40.064Z,1570662160.064 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2019-10-09T23:02:40.065Z,1570662160.065 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-10-09T23:02:40.075Z,1570662160.075 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-10-09T23:02:40.488Z,1570662160.488 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-10-09T23:02:40.488Z,1570662160.488 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2019-10-09T23:02:41.633Z,1570662161.633 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:02:44.869Z,1570662164.869 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:02:47.689Z,1570662167.689 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:02:50.517Z,1570662170.517 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:02:50.936Z,1570662170.936 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 10.000000 millimeter 2019-10-09T23:02:53.381Z,1570662173.381 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:02:56.613Z,1570662176.613 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:02:59.444Z,1570662179.444 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:03:02.676Z,1570662182.676 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:03:05.509Z,1570662185.509 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:03:08.733Z,1570662188.733 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:03:11.557Z,1570662191.557 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:03:14.793Z,1570662194.793 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:03:17.624Z,1570662197.624 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:03:20.856Z,1570662200.856 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:03:23.681Z,1570662203.681 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:03:26.917Z,1570662206.917 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:03:29.737Z,1570662209.737 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:03:32.577Z,1570662212.577 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:03:35.397Z,1570662215.397 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:03:37.425Z,1570662217.425 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:03:39.457Z,1570662219.457 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:03:42.665Z,1570662222.665 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:03:45.505Z,1570662225.505 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:03:48.737Z,1570662228.737 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:03:51.557Z,1570662231.557 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:03:54.789Z,1570662234.789 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:03:57.617Z,1570662237.617 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:04:00.445Z,1570662240.445 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:04:02.869Z,1570662242.869 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:04:05.316Z,1570662245.316 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2019-10-09T23:04:05.319Z,1570662245.319 [BPC1](INFO): Received data from all battery sticks. 2019-10-09T23:04:05.693Z,1570662245.693 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:04:08.929Z,1570662248.929 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:04:11.753Z,1570662251.753 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:04:14.989Z,1570662254.989 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:04:17.821Z,1570662257.821 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:04:20.641Z,1570662260.641 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:04:23.473Z,1570662263.473 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:04:25.489Z,1570662265.489 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:04:27.513Z,1570662267.513 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:04:30.745Z,1570662270.745 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:04:33.569Z,1570662273.569 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:04:36.817Z,1570662276.817 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:04:39.636Z,1570662279.636 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:04:42.867Z,1570662282.867 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:04:45.697Z,1570662285.697 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:04:48.517Z,1570662288.517 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:04:50.945Z,1570662290.945 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:04:53.773Z,1570662293.773 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:04:57.001Z,1570662297.001 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:04:59.833Z,1570662299.833 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:05:03.061Z,1570662303.061 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:05:05.899Z,1570662305.899 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:05:08.721Z,1570662308.721 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:05:11.186Z,1570662311.186 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-10-09T23:00:10.8Z 2019-10-09T23:05:11.186Z,1570662311.186 [Default:CheckIn:Read_GPS] Stopped 2019-10-09T23:05:11.186Z,1570662311.186 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-10-09T23:05:11.557Z,1570662311.557 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:05:11.611Z,1570662311.611 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-10-09T23:05:12.194Z,1570662312.194 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 20.000000 millimeter 2019-10-09T23:05:14.630Z,1570662314.630 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20191009T225352/Courier0004.lzma 2019-10-09T23:05:14.997Z,1570662314.997 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:05:16.502Z,1570662316.502 [DataOverHttps](INFO): Moved sent file to Logs/20191009T225352/Courier0004.lzma.bak 2019-10-09T23:05:16.502Z,1570662316.502 [DataOverHttps](INFO): SBD MOMSN=11867602 2019-10-09T23:05:17.817Z,1570662317.817 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:05:21.045Z,1570662321.045 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:05:23.873Z,1570662323.873 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:05:27.105Z,1570662327.105 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:05:29.933Z,1570662329.933 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:05:32.765Z,1570662332.765 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:05:33.225Z,1570662333.225 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20191009T225628/Courier0000.lzma 2019-10-09T23:05:35.230Z,1570662335.230 [DataOverHttps](INFO): Moved sent file to Logs/20191009T225628/Courier0000.lzma.bak 2019-10-09T23:05:35.230Z,1570662335.230 [DataOverHttps](INFO): SBD MOMSN=11867604 2019-10-09T23:05:35.589Z,1570662335.589 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:05:37.609Z,1570662337.609 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:05:39.645Z,1570662339.645 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:05:41.281Z,1570662341.281 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2019-10-09T23:05:41.281Z,1570662341.281 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-10-09T23:05:41.350Z,1570662341.350 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-10-09T23:05:41.683Z,1570662341.683 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-10-09T23:05:41.683Z,1570662341.683 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2019-10-09T23:05:42.869Z,1570662342.869 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:05:43.899Z,1570662343.899 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 26.000000 millimeter 2019-10-09T23:05:45.693Z,1570662345.693 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:05:48.605Z,1570662348.605 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:05:51.029Z,1570662351.029 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:05:51.976Z,1570662351.976 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20191009T225628/Courier0004.lzma 2019-10-09T23:05:53.869Z,1570662353.869 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:05:53.982Z,1570662353.982 [DataOverHttps](INFO): Moved sent file to Logs/20191009T225628/Courier0004.lzma.bak 2019-10-09T23:05:53.982Z,1570662353.982 [DataOverHttps](INFO): SBD MOMSN=11867613 2019-10-09T23:05:57.089Z,1570662357.089 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:05:59.917Z,1570662359.917 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:06:03.149Z,1570662363.149 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:06:05.985Z,1570662365.985 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:06:08.809Z,1570662368.809 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:06:10.572Z,1570662370.572 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20191009T222436/Express0008.lzma 2019-10-09T23:06:11.637Z,1570662371.637 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:06:12.578Z,1570662372.578 [DataOverHttps](INFO): Moved sent file to Logs/20191009T222436/Express0008.lzma.bak 2019-10-09T23:06:12.578Z,1570662372.578 [DataOverHttps](INFO): SBD MOMSN=11867615 2019-10-09T23:06:13.657Z,1570662373.657 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:06:15.673Z,1570662375.673 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:06:18.905Z,1570662378.905 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:06:21.737Z,1570662381.737 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:06:24.973Z,1570662384.973 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:06:27.801Z,1570662387.801 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:06:30.214Z,1570662390.214 [DataOverHttps](INFO): Sending 916 bytes from file Logs/20191009T225131/Express0001.lzma 2019-10-09T23:06:31.033Z,1570662391.033 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:06:32.226Z,1570662392.226 [DataOverHttps](INFO): Moved sent file to Logs/20191009T225131/Express0001.lzma.bak 2019-10-09T23:06:32.226Z,1570662392.226 [DataOverHttps](INFO): SBD MOMSN=11867624 2019-10-09T23:06:33.679Z,1570662393.679 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 25.000000 millimeter 2019-10-09T23:06:33.853Z,1570662393.853 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:06:36.749Z,1570662396.749 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:06:39.177Z,1570662399.177 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:06:42.005Z,1570662402.005 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:06:45.233Z,1570662405.233 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:06:48.063Z,1570662408.063 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:06:48.906Z,1570662408.906 [DataOverHttps](INFO): Sending 772 bytes from file Logs/20191009T225352/Express0001.lzma 2019-10-09T23:06:50.893Z,1570662410.893 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:06:50.910Z,1570662410.910 [DataOverHttps](INFO): Moved sent file to Logs/20191009T225352/Express0001.lzma.bak 2019-10-09T23:06:50.910Z,1570662410.910 [DataOverHttps](INFO): SBD MOMSN=11867656 2019-10-09T23:06:53.235Z,1570662413.235 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 20.000000 millimeter 2019-10-09T23:06:53.721Z,1570662413.721 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:06:55.785Z,1570662415.785 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:06:59.021Z,1570662419.021 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:07:01.837Z,1570662421.837 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:07:05.065Z,1570662425.065 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:07:07.893Z,1570662427.893 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:07:08.144Z,1570662428.144 [DataOverHttps](INFO): Sending 78 bytes from file Logs/20191009T225352/Express0005.lzma 2019-10-09T23:07:10.150Z,1570662430.150 [DataOverHttps](INFO): Moved sent file to Logs/20191009T225352/Express0005.lzma.bak 2019-10-09T23:07:10.150Z,1570662430.150 [DataOverHttps](INFO): SBD MOMSN=11867700 2019-10-09T23:07:10.721Z,1570662430.721 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:07:11.907Z,1570662431.907 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 25.000000 millimeter 2019-10-09T23:07:12.741Z,1570662432.741 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:07:15.241Z,1570662435.241 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:07:18.062Z,1570662438.062 [NAL9602](FAULT): GPS failed to acquire within timeout. 2019-10-09T23:07:18.062Z,1570662438.062 [NAL9602] Data Fault, FailCount= 1 2019-10-09T23:07:18.062Z,1570662438.062 [NAL9602](ERROR): Data Fault 2019-10-09T23:07:18.161Z,1570662438.161 [CBIT](ERROR): Data Fault in component: NAL9602 2019-10-09T23:07:18.466Z,1570662438.466 [NAL9602](INFO): Powering down 2019-10-09T23:07:19.353Z,1570662439.353 [CBIT](INFO): Clearing failed state for component NAL9602 2019-10-09T23:07:19.353Z,1570662439.353 [NAL9602] No Fault, FailCount= 1 2019-10-09T23:07:26.830Z,1570662446.830 [DataOverHttps](INFO): Sending 665 bytes from file Logs/20191009T225628/Express0001.lzma 2019-10-09T23:07:28.834Z,1570662448.834 [DataOverHttps](INFO): Moved sent file to Logs/20191009T225628/Express0001.lzma.bak 2019-10-09T23:07:28.834Z,1570662448.834 [DataOverHttps](INFO): SBD MOMSN=11867704 2019-10-09T23:07:45.369Z,1570662465.369 [DataOverHttps](INFO): Sending 399 bytes from file Logs/20191009T225628/Express0005.lzma 2019-10-09T23:07:47.374Z,1570662467.374 [DataOverHttps](INFO): Moved sent file to Logs/20191009T225628/Express0005.lzma.bak 2019-10-09T23:07:47.374Z,1570662467.374 [DataOverHttps](INFO): SBD MOMSN=11867722 2019-10-09T23:07:48.762Z,1570662468.762 [NAL9602](INFO): Powering up NAL9602 2019-10-09T23:07:48.811Z,1570662468.811 [Default:CheckIn:Read_Iridium] Stopped 2019-10-09T23:07:48.811Z,1570662468.811 [Default:CheckIn:C.Wait] Running Loop=1 2019-10-09T23:07:48.811Z,1570662468.811 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-10-09T23:07:59.671Z,1570662479.671 [NAL9602](INFO): NAL9602 initialized 2019-10-09T23:08:00.490Z,1570662480.490 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:08:03.317Z,1570662483.317 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:08:06.145Z,1570662486.145 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:08:08.169Z,1570662488.169 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:08:11.397Z,1570662491.397 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:08:14.225Z,1570662494.225 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:08:17.457Z,1570662497.457 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:08:20.289Z,1570662500.289 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:08:23.521Z,1570662503.521 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:08:26.345Z,1570662506.345 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:08:29.173Z,1570662509.173 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:08:31.193Z,1570662511.193 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:08:34.425Z,1570662514.425 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:08:37.253Z,1570662517.253 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:08:40.485Z,1570662520.485 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:08:42.122Z,1570662522.122 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2019-10-09T23:08:42.122Z,1570662522.122 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-10-09T23:08:42.133Z,1570662522.133 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-10-09T23:08:42.544Z,1570662522.544 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-10-09T23:08:42.549Z,1570662522.549 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2019-10-09T23:08:43.325Z,1570662523.325 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:08:46.553Z,1570662526.553 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:08:49.373Z,1570662529.373 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:08:52.201Z,1570662532.201 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:08:54.221Z,1570662534.221 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:08:56.249Z,1570662536.249 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:08:59.481Z,1570662539.481 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:09:02.301Z,1570662542.301 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:09:05.537Z,1570662545.537 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:09:08.365Z,1570662548.365 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:09:11.593Z,1570662551.593 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:09:14.429Z,1570662554.429 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:09:17.257Z,1570662557.257 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:09:19.278Z,1570662559.278 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:09:22.509Z,1570662562.509 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:09:25.337Z,1570662565.337 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:09:28.565Z,1570662568.565 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:09:31.389Z,1570662571.389 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:09:34.625Z,1570662574.625 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:09:37.457Z,1570662577.457 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:09:40.289Z,1570662580.289 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:09:42.297Z,1570662582.297 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:09:44.321Z,1570662584.321 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:09:47.549Z,1570662587.549 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:09:50.381Z,1570662590.381 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:09:53.609Z,1570662593.609 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:09:56.449Z,1570662596.449 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:09:59.681Z,1570662599.681 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:10:02.497Z,1570662602.497 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:10:05.325Z,1570662605.325 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:10:07.345Z,1570662607.345 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:10:10.577Z,1570662610.577 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:10:13.405Z,1570662613.405 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:10:16.637Z,1570662616.637 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:10:19.473Z,1570662619.473 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:10:22.697Z,1570662622.697 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:10:25.525Z,1570662625.525 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:10:28.353Z,1570662628.353 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:10:30.377Z,1570662630.377 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:10:32.401Z,1570662632.401 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:10:35.633Z,1570662635.633 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:10:38.453Z,1570662638.453 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:10:41.689Z,1570662641.689 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:10:44.513Z,1570662644.513 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:10:47.749Z,1570662647.749 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:10:50.577Z,1570662650.577 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:10:53.405Z,1570662653.405 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:10:55.430Z,1570662655.430 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:10:58.653Z,1570662658.653 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:11:01.485Z,1570662661.485 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:11:04.713Z,1570662664.713 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:11:07.541Z,1570662667.541 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:11:10.773Z,1570662670.773 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:11:13.605Z,1570662673.605 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:11:16.433Z,1570662676.433 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:11:18.453Z,1570662678.453 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:11:20.469Z,1570662680.469 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:11:23.701Z,1570662683.701 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:11:26.545Z,1570662686.545 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:11:29.769Z,1570662689.769 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:11:32.597Z,1570662692.597 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:11:35.829Z,1570662695.829 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:11:38.649Z,1570662698.649 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:11:39.898Z,1570662699.898 [CBIT](INFO): Clearing failed state for component DropWeight 2019-10-09T23:11:39.898Z,1570662699.898 [DropWeight] No Fault, FailCount= 1 2019-10-09T23:11:41.477Z,1570662701.477 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:11:43.125Z,1570662703.125 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2019-10-09T23:11:43.125Z,1570662703.125 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-10-09T23:11:43.135Z,1570662703.135 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-10-09T23:11:43.497Z,1570662703.497 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:11:43.549Z,1570662703.549 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-10-09T23:11:43.549Z,1570662703.549 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2019-10-09T23:11:46.729Z,1570662706.729 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:11:49.557Z,1570662709.557 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:11:52.789Z,1570662712.789 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:11:53.746Z,1570662713.746 [CommandLine](IMPORTANT): got command configSet list 2019-10-09T23:11:53.746Z,1570662713.746 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-10-09T23:11:53.747Z,1570662713.747 [CommandLine](IMPORTANT): CBIT.gf24Offset=154 microampere; 2019-10-09T23:11:53.747Z,1570662713.747 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool; 2019-10-09T23:11:53.747Z,1570662713.747 [CommandLine](IMPORTANT): DAT.loadAtStartup=0 bool; 2019-10-09T23:11:53.747Z,1570662713.747 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool; 2019-10-09T23:11:55.617Z,1570662715.617 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:11:58.853Z,1570662718.853 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:12:01.685Z,1570662721.685 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:12:04.505Z,1570662724.505 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:12:06.533Z,1570662726.533 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:12:08.549Z,1570662728.549 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:12:11.786Z,1570662731.786 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:12:14.609Z,1570662734.609 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:12:17.849Z,1570662737.849 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:12:20.669Z,1570662740.669 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:12:23.901Z,1570662743.901 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:12:26.725Z,1570662746.725 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:12:29.561Z,1570662749.561 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:12:31.577Z,1570662751.577 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:12:34.805Z,1570662754.805 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:12:37.637Z,1570662757.637 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:12:40.869Z,1570662760.869 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:12:43.693Z,1570662763.693 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:12:46.929Z,1570662766.929 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:12:49.371Z,1570662769.371 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-10-09T23:12:49.371Z,1570662769.371 [Default:CheckIn:C.Wait] Stopped 2019-10-09T23:12:49.371Z,1570662769.371 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-10-09T23:12:49.371Z,1570662769.371 [Default:CheckIn:D] Running Loop=1 2019-10-09T23:12:49.753Z,1570662769.753 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:12:49.792Z,1570662769.792 [Default:CheckIn:D] Stopped 2019-10-09T23:12:49.792Z,1570662769.792 [Default:CheckIn:E] Running Loop=1 2019-10-09T23:12:50.198Z,1570662770.198 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.878102 min 2019-10-09T23:12:50.198Z,1570662770.198 [Default:CheckIn:E] Stopped 2019-10-09T23:12:50.198Z,1570662770.198 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-10-09T23:12:50.198Z,1570662770.198 [Default:CheckIn] Stopped 2019-10-09T23:12:50.198Z,1570662770.198 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-10-09T23:12:50.198Z,1570662770.198 [Default:CheckIn](INFO): Running loop #2 2019-10-09T23:12:50.198Z,1570662770.198 [Default:CheckIn] Running Loop=2 2019-10-09T23:12:50.198Z,1570662770.198 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-10-09T23:12:50.198Z,1570662770.198 [Default:CheckIn:Read_GPS] Running Loop=1 2019-10-09T23:12:52.581Z,1570662772.581 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:12:54.601Z,1570662774.601 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:12:56.625Z,1570662776.625 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:12:59.857Z,1570662779.857 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:13:02.681Z,1570662782.681 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:13:05.913Z,1570662785.913 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:13:08.745Z,1570662788.745 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:13:11.981Z,1570662791.981 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:13:14.809Z,1570662794.809 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:13:17.629Z,1570662797.629 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:13:19.657Z,1570662799.657 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:13:22.889Z,1570662802.889 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:13:25.713Z,1570662805.713 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:13:28.941Z,1570662808.941 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:13:31.773Z,1570662811.773 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:13:35.001Z,1570662815.001 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:13:37.833Z,1570662817.833 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:13:40.657Z,1570662820.657 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:13:42.689Z,1570662822.689 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:13:45.929Z,1570662825.929 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:13:48.741Z,1570662828.741 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:13:51.969Z,1570662831.969 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:13:54.805Z,1570662834.805 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:13:58.029Z,1570662838.029 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:14:00.861Z,1570662840.861 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:14:03.693Z,1570662843.693 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:14:05.713Z,1570662845.713 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:14:07.725Z,1570662847.725 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:14:10.961Z,1570662850.961 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:14:13.793Z,1570662853.793 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:14:17.025Z,1570662857.025 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:14:19.845Z,1570662859.845 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:14:23.077Z,1570662863.077 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:14:25.905Z,1570662865.905 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:14:28.749Z,1570662868.749 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:14:31.977Z,1570662871.977 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:14:34.801Z,1570662874.801 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:14:38.037Z,1570662878.037 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:14:40.857Z,1570662880.857 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:14:44.097Z,1570662884.097 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:14:44.130Z,1570662884.130 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2019-10-09T23:14:44.130Z,1570662884.130 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-10-09T23:14:44.140Z,1570662884.140 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-10-09T23:14:44.551Z,1570662884.551 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-10-09T23:14:44.551Z,1570662884.551 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2019-10-09T23:14:46.921Z,1570662886.921 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:14:49.749Z,1570662889.749 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:14:51.765Z,1570662891.765 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:14:53.789Z,1570662893.789 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:14:57.033Z,1570662897.033 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:14:59.853Z,1570662899.853 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:15:03.077Z,1570662903.077 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:15:05.913Z,1570662905.913 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:15:09.144Z,1570662909.144 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:15:11.969Z,1570662911.969 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:15:14.793Z,1570662914.793 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:15:16.821Z,1570662916.821 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:15:18.837Z,1570662918.837 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:15:22.077Z,1570662922.077 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:15:24.897Z,1570662924.897 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:15:28.129Z,1570662928.129 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:15:30.957Z,1570662930.957 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:15:34.194Z,1570662934.194 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:15:37.017Z,1570662937.017 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:15:39.841Z,1570662939.841 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:15:41.865Z,1570662941.865 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:15:43.890Z,1570662943.890 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:15:47.121Z,1570662947.121 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:15:49.953Z,1570662949.953 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:15:53.181Z,1570662953.181 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:15:56.005Z,1570662956.005 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:15:59.233Z,1570662959.233 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:16:02.065Z,1570662962.065 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:16:04.896Z,1570662964.896 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:16:06.909Z,1570662966.909 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:16:10.149Z,1570662970.149 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:16:12.973Z,1570662972.973 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:16:16.209Z,1570662976.209 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:16:19.037Z,1570662979.037 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:16:22.269Z,1570662982.269 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:16:25.097Z,1570662985.097 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:16:27.917Z,1570662987.917 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:16:29.937Z,1570662989.937 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:16:33.169Z,1570662993.169 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:16:35.997Z,1570662995.997 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:16:39.237Z,1570662999.237 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:16:41.653Z,1570663001.653 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:16:43.677Z,1570663003.677 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:16:46.097Z,1570663006.097 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:16:48.925Z,1570663008.925 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:16:52.161Z,1570663012.161 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:16:54.993Z,1570663014.993 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:16:57.813Z,1570663017.813 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:17:01.045Z,1570663021.045 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:17:03.877Z,1570663023.877 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:17:06.709Z,1570663026.709 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:17:09.941Z,1570663029.941 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:17:12.761Z,1570663032.761 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:17:15.997Z,1570663035.997 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:17:18.841Z,1570663038.841 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:17:22.061Z,1570663042.061 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:17:24.885Z,1570663044.885 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:17:28.113Z,1570663048.113 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:17:30.941Z,1570663050.941 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:17:34.181Z,1570663054.181 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:17:37.008Z,1570663057.008 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:17:40.241Z,1570663060.241 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:17:43.065Z,1570663063.065 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:17:45.106Z,1570663065.106 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2019-10-09T23:17:45.106Z,1570663065.106 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-10-09T23:17:45.116Z,1570663065.116 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-10-09T23:17:45.520Z,1570663065.520 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-10-09T23:17:45.520Z,1570663065.520 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2019-10-09T23:17:45.889Z,1570663065.889 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:17:48.717Z,1570663068.717 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:17:50.360Z,1570663070.360 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-10-09T23:12:50.2Z 2019-10-09T23:17:50.360Z,1570663070.360 [Default:CheckIn:Read_GPS] Stopped 2019-10-09T23:17:50.360Z,1570663070.360 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-10-09T23:17:50.737Z,1570663070.737 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:17:52.761Z,1570663072.761 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:17:55.993Z,1570663075.993 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:17:57.544Z,1570663077.544 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20191009T225628/Courier0007.lzma 2019-10-09T23:17:58.821Z,1570663078.821 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:17:59.550Z,1570663079.550 [DataOverHttps](INFO): Moved sent file to Logs/20191009T225628/Courier0007.lzma.bak 2019-10-09T23:17:59.550Z,1570663079.550 [DataOverHttps](INFO): SBD MOMSN=11867776 2019-10-09T23:18:02.042Z,1570663082.042 [NAL9602](FAULT): GPS failed to acquire within timeout. 2019-10-09T23:18:02.042Z,1570663082.042 [NAL9602] Data Fault, FailCount= 2 2019-10-09T23:18:02.042Z,1570663082.042 [NAL9602](ERROR): Data Fault 2019-10-09T23:18:02.109Z,1570663082.109 [CBIT](ERROR): Data Fault in component: NAL9602 2019-10-09T23:18:02.442Z,1570663082.442 [NAL9602](INFO): Powering down 2019-10-09T23:18:03.331Z,1570663083.331 [CBIT](INFO): Clearing failed state for component NAL9602 2019-10-09T23:18:03.331Z,1570663083.331 [NAL9602] No Fault, FailCount= 2 2019-10-09T23:18:18.109Z,1570663098.109 [DataOverHttps](INFO): Sending 409 bytes from file Logs/20191009T225628/Express0008.lzma 2019-10-09T23:18:20.114Z,1570663100.114 [DataOverHttps](INFO): Moved sent file to Logs/20191009T225628/Express0008.lzma.bak 2019-10-09T23:18:20.115Z,1570663100.115 [DataOverHttps](INFO): SBD MOMSN=11867778 2019-10-09T23:18:21.467Z,1570663101.467 [Default:CheckIn:Read_Iridium] Stopped 2019-10-09T23:18:21.467Z,1570663101.467 [Default:CheckIn:C.Wait] Running Loop=1 2019-10-09T23:18:21.467Z,1570663101.467 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-10-09T23:18:32.742Z,1570663112.742 [NAL9602](INFO): Powering up NAL9602 2019-10-09T23:18:43.660Z,1570663123.660 [NAL9602](INFO): NAL9602 initialized 2019-10-09T23:18:44.469Z,1570663124.469 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:18:47.301Z,1570663127.301 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:18:50.133Z,1570663130.133 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:18:52.153Z,1570663132.153 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:18:55.377Z,1570663135.377 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:18:58.213Z,1570663138.213 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:19:00.226Z,1570663140.226 [CommandLine](IMPORTANT): got command 2019-10-09T23:19:00.226Z,1570663140.226 [CommandLine](FAULT): Incomplete syntax. Try: help 2019-10-09T23:19:01.445Z,1570663141.445 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:19:04.269Z,1570663144.269 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:19:07.501Z,1570663147.501 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:19:10.329Z,1570663150.329 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:19:13.156Z,1570663153.156 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:19:15.177Z,1570663155.177 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:19:17.193Z,1570663157.193 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:19:20.433Z,1570663160.433 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:19:21.182Z,1570663161.182 [CommandLine](IMPORTANT): got command show 2019-10-09T23:19:21.183Z,1570663161.183 [CommandLine](FAULT): Incomplete syntax. Try: help show 2019-10-09T23:19:23.263Z,1570663163.263 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:19:26.485Z,1570663166.485 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:19:29.321Z,1570663169.321 [NAL9602](DEBUG): Fix Requested 2019-10-09T23:19:29.355Z,1570663169.355 [CommandLine](IMPORTANT): got command show variable par 2019-10-09T23:19:29.388Z,1570663169.388 [CommandLine](IMPORTANT): PAR_Licor.loadControl (none) 2019-10-09T23:19:29.388Z,1570663169.388 [C