2019-03-26T00:04:06.722Z,1553558646.722 [Supervisor](DEBUG): Initializing supervisor. 2019-03-26T00:04:06.725Z,1553558646.725 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-03-26T00:04:06.726Z,1553558646.726 [SyncHandler](INFO): Protected caller Thread ID is 6520 2019-03-26T00:04:06.726Z,1553558646.726 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-03-26T00:04:06.727Z,1553558646.727 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-03-26T00:04:06.727Z,1553558646.727 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6521 2019-03-26T00:04:06.731Z,1553558646.731 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-03-26T00:04:06.743Z,1553558646.743 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-03-26T00:04:06.744Z,1553558646.744 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-03-26T00:04:06.744Z,1553558646.744 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6522 2019-03-26T00:04:06.745Z,1553558646.745 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-03-26T00:04:06.746Z,1553558646.746 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-03-26T00:04:06.746Z,1553558646.746 [logger ThreadHandler](INFO): Protected caller Thread ID is 6523 2019-03-26T00:04:06.748Z,1553558646.748 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-03-26T00:04:06.749Z,1553558646.749 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-03-26T00:04:06.750Z,1553558646.750 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-03-26T00:04:07.250Z,1553558647.250 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-03-26T00:04:07.251Z,1553558647.251 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-03-26T00:04:07.712Z,1553558647.712 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-03-26T00:04:07.712Z,1553558647.712 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-03-26T00:04:07.905Z,1553558647.905 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-03-26T00:04:07.905Z,1553558647.905 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-03-26T00:04:08.051Z,1553558648.051 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-03-26T00:04:08.051Z,1553558648.051 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-03-26T00:04:08.502Z,1553558648.502 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-03-26T00:04:08.503Z,1553558648.503 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-03-26T00:04:08.600Z,1553558648.600 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-03-26T00:04:08.601Z,1553558648.601 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-03-26T00:04:08.743Z,1553558648.743 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-03-26T00:04:08.744Z,1553558648.744 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-03-26T00:04:08.845Z,1553558648.845 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-03-26T00:04:08.845Z,1553558648.845 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-03-26T00:04:08.950Z,1553558648.950 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-03-26T00:04:08.951Z,1553558648.951 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-03-26T00:04:09.331Z,1553558649.331 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-03-26T00:04:09.332Z,1553558649.332 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-03-26T00:04:09.529Z,1553558649.529 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-03-26T00:04:09.530Z,1553558649.530 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-03-26T00:04:09.747Z,1553558649.747 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-03-26T00:04:09.748Z,1553558649.748 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-03-26T00:04:09.830Z,1553558649.830 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-03-26T00:04:10.164Z,1553558650.164 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-03-26T00:04:10.165Z,1553558650.165 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-03-26T00:04:10.479Z,1553558650.479 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-03-26T00:04:10.480Z,1553558650.480 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2019-03-26T00:04:10.481Z,1553558650.481 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2019-03-26T00:04:10.569Z,1553558650.569 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2019-03-26T00:04:10.726Z,1553558650.726 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2019-03-26T00:04:10.826Z,1553558650.826 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2019-03-26T00:04:11.003Z,1553558651.003 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2019-03-26T00:04:11.088Z,1553558651.088 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2019-03-26T00:04:11.314Z,1553558651.314 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-03-26T00:04:11.315Z,1553558651.315 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2019-03-26T00:04:11.431Z,1553558651.431 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2019-03-26T00:04:11.531Z,1553558651.531 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2019-03-26T00:04:11.627Z,1553558651.627 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2019-03-26T00:04:11.729Z,1553558651.729 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2019-03-26T00:04:11.840Z,1553558651.840 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/ 2019-03-26T00:04:11.840Z,1553558651.840 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-03-26T00:04:11.845Z,1553558651.845 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-03-26T00:04:11.887Z,1553558651.887 [DepthRateCalculator] Loaded 2019-03-26T00:04:11.887Z,1553558651.887 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-03-26T00:04:11.893Z,1553558651.893 [PitchRateCalculator] Loaded 2019-03-26T00:04:11.893Z,1553558651.893 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-03-26T00:04:11.908Z,1553558651.908 [SpeedCalculator] Loaded 2019-03-26T00:04:11.909Z,1553558651.909 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-03-26T00:04:11.929Z,1553558651.929 [TempGradientCalculator] Loaded 2019-03-26T00:04:11.929Z,1553558651.929 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-03-26T00:04:11.935Z,1553558651.935 [YawRateCalculator] Loaded 2019-03-26T00:04:11.935Z,1553558651.935 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-03-26T00:04:11.974Z,1553558651.974 [ElevatorOffsetCalculator] Loaded 2019-03-26T00:04:11.974Z,1553558651.974 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-03-26T00:04:11.975Z,1553558651.975 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-03-26T00:04:11.975Z,1553558651.975 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-03-26T00:04:12.204Z,1553558652.204 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-03-26T00:04:12.204Z,1553558652.204 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-03-26T00:04:12.884Z,1553558652.884 [DataOverHttps] Loaded 2019-03-26T00:04:12.884Z,1553558652.884 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-03-26T00:04:12.885Z,1553558652.885 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407DA4E0 2019-03-26T00:04:12.886Z,1553558652.886 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6602 2019-03-26T00:04:12.924Z,1553558652.924 [Depth_Keller] Loaded 2019-03-26T00:04:12.924Z,1553558652.924 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-03-26T00:04:13.019Z,1553558653.019 [NAL9602] Loaded 2019-03-26T00:04:13.019Z,1553558653.019 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-03-26T00:04:13.034Z,1553558653.034 [Onboard] Loaded 2019-03-26T00:04:13.034Z,1553558653.034 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-03-26T00:04:13.040Z,1553558653.040 [Radio_Surface] Loaded 2019-03-26T00:04:13.041Z,1553558653.041 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-03-26T00:04:13.042Z,1553558653.042 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4080A4E0 2019-03-26T00:04:13.042Z,1553558653.042 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6603 2019-03-26T00:04:13.048Z,1553558653.048 [FDI] Loaded 2019-03-26T00:04:13.048Z,1553558653.048 [ComponentRegistry](DEBUG): SyncComponent "FDI" handled in the control thread. 2019-03-26T00:04:14.746Z,1553558654.746 [BPC1] Loaded 2019-03-26T00:04:14.746Z,1553558654.746 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-03-26T00:04:14.747Z,1553558654.747 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-03-26T00:04:14.747Z,1553558654.747 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-03-26T00:04:14.813Z,1553558654.813 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-03-26T00:04:14.813Z,1553558654.813 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-03-26T00:04:14.914Z,1553558654.914 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-03-26T00:04:14.914Z,1553558654.914 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-03-26T00:04:14.971Z,1553558654.971 [DeadReckonUsingSpeedCalculator] Loaded 2019-03-26T00:04:14.972Z,1553558654.972 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2019-03-26T00:04:14.992Z,1553558654.992 [NavChart] Loaded 2019-03-26T00:04:14.992Z,1553558654.992 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-03-26T00:04:14.996Z,1553558654.996 [UniversalFixResidualReporter] Loaded 2019-03-26T00:04:14.996Z,1553558654.996 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-03-26T00:04:14.997Z,1553558654.997 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-03-26T00:04:14.997Z,1553558654.997 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-03-26T00:04:15.243Z,1553558655.243 [CTD_NeilBrown] Loaded 2019-03-26T00:04:15.243Z,1553558655.243 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2019-03-26T00:04:15.244Z,1553558655.244 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4095E4E0 2019-03-26T00:04:15.244Z,1553558655.244 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 6604 2019-03-26T00:04:15.291Z,1553558655.291 [WetLabsBB2FL] Loaded 2019-03-26T00:04:15.291Z,1553558655.291 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-03-26T00:04:15.292Z,1553558655.292 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4098E4E0 2019-03-26T00:04:15.293Z,1553558655.293 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6605 2019-03-26T00:04:15.293Z,1553558655.293 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-03-26T00:04:15.294Z,1553558655.294 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-03-26T00:04:15.360Z,1553558655.360 [VerticalControl](DEBUG): Construct VerticalControl. 2019-03-26T00:04:15.455Z,1553558655.455 [VerticalControl] Loaded 2019-03-26T00:04:15.456Z,1553558655.456 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-03-26T00:04:15.456Z,1553558655.456 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-03-26T00:04:15.523Z,1553558655.523 [HorizontalControl] Loaded 2019-03-26T00:04:15.524Z,1553558655.524 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-03-26T00:04:15.524Z,1553558655.524 [SpeedControl](DEBUG): Construct SpeedControl. 2019-03-26T00:04:15.529Z,1553558655.529 [SpeedControl] Loaded 2019-03-26T00:04:15.530Z,1553558655.530 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-03-26T00:04:15.530Z,1553558655.530 [LoopControl](DEBUG): Construct LoopControl. 2019-03-26T00:04:15.531Z,1553558655.531 [LoopControl] Loaded 2019-03-26T00:04:15.531Z,1553558655.531 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-03-26T00:04:15.531Z,1553558655.531 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-03-26T00:04:15.532Z,1553558655.532 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-03-26T00:04:15.546Z,1553558655.546 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-03-26T00:04:15.546Z,1553558655.546 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-03-26T00:04:15.679Z,1553558655.679 [SBIT](DEBUG): Construct Startup Built In Test. 2019-03-26T00:04:15.690Z,1553558655.690 [SBIT] Loaded 2019-03-26T00:04:15.690Z,1553558655.690 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-03-26T00:04:15.691Z,1553558655.691 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-03-26T00:04:15.702Z,1553558655.702 [IBIT] Loaded 2019-03-26T00:04:15.702Z,1553558655.702 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-03-26T00:04:15.706Z,1553558655.706 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-03-26T00:04:15.840Z,1553558655.840 [CBIT] Loaded 2019-03-26T00:04:15.841Z,1553558655.841 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-03-26T00:04:15.841Z,1553558655.841 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-03-26T00:04:15.842Z,1553558655.842 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-03-26T00:04:15.937Z,1553558655.937 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-03-26T00:04:15.938Z,1553558655.938 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-03-26T00:04:15.965Z,1553558655.965 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-03-26T00:04:15.966Z,1553558655.966 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-03-26T00:04:16.061Z,1553558656.061 [BuoyancyServo] Loaded 2019-03-26T00:04:16.061Z,1553558656.061 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-03-26T00:04:16.072Z,1553558656.072 [ElevatorServo] Loaded 2019-03-26T00:04:16.072Z,1553558656.072 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-03-26T00:04:16.079Z,1553558656.079 [MassServo](DEBUG): LcmSlateWriter::add(): platform_mass_position 2019-03-26T00:04:16.083Z,1553558656.083 [MassServo](INFO): created writer for : platform_mass_position 2019-03-26T00:04:16.086Z,1553558656.086 [MassServo](DEBUG): LcmSlateWriter::add(): fdi_state 2019-03-26T00:04:16.090Z,1553558656.090 [MassServo](INFO): created writer for : fdi_state 2019-03-26T00:04:16.091Z,1553558656.091 [MassServo](DEBUG): LcmSlateWriter::add(): platform_mass_position_delta 2019-03-26T00:04:16.096Z,1553558656.096 [MassServo](INFO): created writer for : platform_mass_position_delta 2019-03-26T00:04:16.097Z,1553558656.097 [MassServo] Loaded 2019-03-26T00:04:16.097Z,1553558656.097 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-03-26T00:04:16.108Z,1553558656.108 [RudderServo] Loaded 2019-03-26T00:04:16.108Z,1553558656.108 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-03-26T00:04:16.119Z,1553558656.119 [ThrusterServo] Loaded 2019-03-26T00:04:16.119Z,1553558656.119 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-03-26T00:04:16.120Z,1553558656.120 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-03-26T00:04:16.123Z,1553558656.123 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-03-26T00:04:16.124Z,1553558656.124 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-03-26T00:04:16.131Z,1553558656.131 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-03-26T00:04:16.132Z,1553558656.132 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AB54E0 2019-03-26T00:04:16.132Z,1553558656.132 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6606 2019-03-26T00:04:16.137Z,1553558656.137 [Supervisor](INFO): Main Thread ID is 2533 2019-03-26T00:04:16.137Z,1553558656.137 [Supervisor](DEBUG): Running supervisor. 2019-03-26T00:04:16.138Z,1553558656.138 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6607 2019-03-26T00:04:16.141Z,1553558656.141 [controlThread ThreadHandler](INFO): Handler Thread ID is 6608 2019-03-26T00:04:16.142Z,1553558656.142 [controlThread](DEBUG): Initializing ControlThread 2019-03-26T00:04:16.142Z,1553558656.142 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-03-26T00:04:16.143Z,1553558656.143 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-03-26T00:04:16.143Z,1553558656.143 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-03-26T00:04:16.143Z,1553558656.143 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-03-26T00:04:16.144Z,1553558656.144 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-03-26T00:04:16.144Z,1553558656.144 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-03-26T00:04:16.150Z,1553558656.150 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:04:16.151Z,1553558656.151 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-03-26T00:04:16.151Z,1553558656.151 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-03-26T00:04:16.151Z,1553558656.151 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-03-26T00:04:16.153Z,1553558656.153 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-03-26T00:04:16.154Z,1553558656.154 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-03-26T00:04:16.154Z,1553558656.154 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-03-26T00:04:16.154Z,1553558656.154 [SBIT](INFO): Initialize SBIT Component. 2019-03-26T00:04:16.155Z,1553558656.155 [SBIT](IMPORTANT): git: 2019-03-04-49-g75acbbf 2019-03-26T00:04:16.155Z,1553558656.155 [SBIT](INFO): git hash: 75acbbfcb98959704e3f16bb810c8f26e4924423 2019-03-26T00:04:16.155Z,1553558656.155 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-03-26T00:04:16.155Z,1553558656.155 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2019-03-26T00:04:16.157Z,1553558656.157 [SBIT](INFO): Beginning SBIT in 28.000000 seconds. 2019-03-26T00:04:16.157Z,1553558656.157 [IBIT](INFO): Initialize IBIT Component. 2019-03-26T00:04:16.158Z,1553558656.158 [CBIT](DEBUG): Initialize CBIT Component. 2019-03-26T00:04:16.159Z,1553558656.159 [logger ThreadHandler](INFO): Handler Thread ID is 6609 2019-03-26T00:04:16.169Z,1553558656.169 [CBIT](DEBUG): Initialized mux pins. 2019-03-26T00:04:16.169Z,1553558656.169 [CBIT](DEBUG): Initializing the watchdog timer. 2019-03-26T00:04:16.177Z,1553558656.177 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6610 2019-03-26T00:04:16.178Z,1553558656.178 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-03-26T00:04:16.189Z,1553558656.189 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6611 2019-03-26T00:04:16.193Z,1553558656.193 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-03-26T00:04:16.193Z,1553558656.193 [CBIT](DEBUG): Initializing heartbeat. 2019-03-26T00:04:16.201Z,1553558656.201 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 6612 2019-03-26T00:04:16.209Z,1553558656.209 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6613 2019-03-26T00:04:16.210Z,1553558656.210 [WetLabsBB2FL](INFO): Powering down 2019-03-26T00:04:16.237Z,1553558656.237 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6614 2019-03-26T00:04:16.241Z,1553558656.241 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-03-26T00:04:16.241Z,1553558656.241 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-03-26T00:04:16.241Z,1553558656.241 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-03-26T00:04:16.241Z,1553558656.241 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-03-26T00:04:16.242Z,1553558656.242 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-03-26T00:04:16.242Z,1553558656.242 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-03-26T00:04:16.242Z,1553558656.242 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-03-26T00:04:16.242Z,1553558656.242 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-03-26T00:04:16.242Z,1553558656.242 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-03-26T00:04:16.243Z,1553558656.243 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-03-26T00:04:16.243Z,1553558656.243 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-03-26T00:04:16.243Z,1553558656.243 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-03-26T00:04:16.243Z,1553558656.243 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-03-26T00:04:16.243Z,1553558656.243 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-03-26T00:04:16.244Z,1553558656.244 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-03-26T00:04:16.244Z,1553558656.244 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-03-26T00:04:16.265Z,1553558656.265 [CBIT](DEBUG): Deactivating GF circuits. 2019-03-26T00:04:16.265Z,1553558656.265 [CBIT](DEBUG): Deactivating emergency mode. 2019-03-26T00:04:16.303Z,1553558656.303 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-03-26T00:04:16.314Z,1553558656.314 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-03-26T00:04:16.337Z,1553558656.337 [MissionManager](DEBUG): 2019-03-26T00:04:16.338Z,1553558656.338 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-03-26T00:04:16.392Z,1553558656.392 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-03-26T00:04:16.414Z,1553558656.414 [Default:A.Wait](DEBUG): Construct Wait. 2019-03-26T00:04:16.415Z,1553558656.415 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-03-26T00:04:16.450Z,1553558656.450 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-03-26T00:04:16.469Z,1553558656.469 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-03-26T00:04:16.474Z,1553558656.474 [Default:E.Execute](DEBUG): Construct Execute. 2019-03-26T00:04:16.489Z,1553558656.489 [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-03-26T00:04:16.517Z,1553558656.517 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,NAL9602,Onboard,FDI,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-03-26T00:04:16.558Z,1553558656.558 [Depth_Keller](ERROR): Pressure reading out of range: 1628.471313 decibar 2019-03-26T00:04:16.593Z,1553558656.593 [Radio_Surface](INFO): Powering up 2019-03-26T00:04:16.609Z,1553558656.609 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-03-26T00:04:16.647Z,1553558656.647 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-03-26T00:04:16.669Z,1553558656.669 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-03-26T00:04:16.670Z,1553558656.670 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-03-26T00:04:16.677Z,1553558656.677 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-03-26T00:04:16.678Z,1553558656.678 [MassServo](DEBUG): Initializing EZServoServo. 2019-03-26T00:04:16.685Z,1553558656.685 [MassServo](DEBUG): Initializing MassServo. 2019-03-26T00:04:16.686Z,1553558656.686 [RudderServo](DEBUG): Initializing EZServoServo. 2019-03-26T00:04:16.693Z,1553558656.693 [RudderServo](DEBUG): Initializing RudderServo. 2019-03-26T00:04:16.694Z,1553558656.694 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-03-26T00:04:16.701Z,1553558656.701 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-03-26T00:04:17.669Z,1553558657.669 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2019-03-26T00:04:17.669Z,1553558657.669 [RudderServo](FAULT): Rudder failed to initialize 2019-03-26T00:04:17.669Z,1553558657.669 [RudderServo] Communications Fault, FailCount= 1 2019-03-26T00:04:17.669Z,1553558657.669 [RudderServo](ERROR): Communications Fault 2019-03-26T00:04:17.780Z,1553558657.780 [CBIT](ERROR): Communications Fault in component: RudderServo 2019-03-26T00:04:17.938Z,1553558657.938 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-03-26T00:04:17.938Z,1553558657.938 [RudderServo](INFO): Powering down 2019-03-26T00:04:18.625Z,1553558658.625 [RudderServo](DEBUG): Initializing EZServoServo. 2019-03-26T00:04:18.746Z,1553558658.746 [RudderServo](DEBUG): Initializing RudderServo. 2019-03-26T00:04:18.750Z,1553558658.750 [CBIT](INFO): Clearing failed state for component RudderServo 2019-03-26T00:04:18.750Z,1553558658.750 [RudderServo] No Fault, FailCount= 1 2019-03-26T00:04:23.541Z,1553558663.541 [RudderServo](FAULT): Overload Error 2019-03-26T00:04:23.541Z,1553558663.541 [RudderServo] Hardware Fault, FailCount= 2 2019-03-26T00:04:23.541Z,1553558663.541 [RudderServo](ERROR): Hardware Fault 2019-03-26T00:04:23.581Z,1553558663.581 [CBIT](ERROR): Hardware Fault in component: RudderServo 2019-03-26T00:04:23.915Z,1553558663.915 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-03-26T00:04:23.915Z,1553558663.915 [RudderServo](INFO): Powering down 2019-03-26T00:04:24.735Z,1553558664.735 [RudderServo](DEBUG): Initializing EZServoServo. 2019-03-26T00:04:24.856Z,1553558664.856 [RudderServo](DEBUG): Initializing RudderServo. 2019-03-26T00:04:24.861Z,1553558664.861 [CBIT](INFO): Clearing failed state for component RudderServo 2019-03-26T00:04:24.861Z,1553558664.861 [RudderServo] No Fault, FailCount= 2 2019-03-26T00:04:29.577Z,1553558669.577 [RudderServo](FAULT): Overload Error 2019-03-26T00:04:29.577Z,1553558669.577 [RudderServo] Hardware Fault, FailCount= 3 2019-03-26T00:04:29.577Z,1553558669.577 [RudderServo](ERROR): Hardware Fault 2019-03-26T00:04:29.617Z,1553558669.617 [CBIT](ERROR): Hardware Fault in component: RudderServo 2019-03-26T00:04:29.617Z,1553558669.617 [CBIT](CRITICAL): Hardware Fault in component: RudderServo 2019-03-26T00:04:29.949Z,1553558669.949 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-03-26T00:04:29.949Z,1553558669.949 [RudderServo](INFO): Powering down 2019-03-26T00:04:29.951Z,1553558669.951 [CommandLine](FAULT): Scheduling is paused 2019-03-26T00:04:29.951Z,1553558669.951 [CBIT](INFO): Critical error at 20190326T000429 2019-03-26T00:04:29.951Z,1553558669.951 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2019-03-26T00:04:30.747Z,1553558670.747 [RudderServo](DEBUG): Initializing EZServoServo. 2019-03-26T00:04:30.866Z,1553558670.866 [RudderServo](DEBUG): Initializing RudderServo. 2019-03-26T00:04:43.242Z,1553558683.242 [NAL9602](INFO): Powering up NAL9602 2019-03-26T00:04:44.887Z,1553558684.887 [SBIT](IMPORTANT): Beginning Startup BIT 2019-03-26T00:04:44.891Z,1553558684.891 [CBIT](IMPORTANT): Beginning ground fault scan 2019-03-26T00:04:46.929Z,1553558686.929 [ElevatorServo](FAULT): Overload Error 2019-03-26T00:04:46.929Z,1553558686.929 [ElevatorServo] Hardware Fault, FailCount= 1 2019-03-26T00:04:46.929Z,1553558686.929 [ElevatorServo](ERROR): Hardware Fault 2019-03-26T00:04:47.351Z,1553558687.351 [CBIT](ERROR): Hardware Fault in component: ElevatorServo 2019-03-26T00:04:47.393Z,1553558687.393 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-03-26T00:04:47.393Z,1553558687.393 [ElevatorServo](INFO): Powering down 2019-03-26T00:04:48.201Z,1553558688.201 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-03-26T00:04:48.318Z,1553558688.318 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-03-26T00:04:48.363Z,1553558688.363 [CBIT](INFO): Clearing failed state for component ElevatorServo 2019-03-26T00:04:48.363Z,1553558688.363 [ElevatorServo] No Fault, FailCount= 1 2019-03-26T00:04:53.105Z,1553558693.105 [ElevatorServo](FAULT): Overload Error 2019-03-26T00:04:53.105Z,1553558693.105 [ElevatorServo] Hardware Fault, FailCount= 2 2019-03-26T00:04:53.105Z,1553558693.105 [ElevatorServo](ERROR): Hardware Fault 2019-03-26T00:04:53.147Z,1553558693.147 [CBIT](ERROR): Hardware Fault in component: ElevatorServo 2019-03-26T00:04:53.445Z,1553558693.445 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-03-26T00:04:53.445Z,1553558693.445 [ElevatorServo](INFO): Powering down 2019-03-26T00:04:54.226Z,1553558694.226 [NAL9602](INFO): NAL9602 initialized 2019-03-26T00:04:54.253Z,1553558694.253 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-03-26T00:04:54.374Z,1553558694.374 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-03-26T00:04:54.419Z,1553558694.419 [CBIT](INFO): Clearing failed state for component ElevatorServo 2019-03-26T00:04:54.419Z,1553558694.419 [ElevatorServo] No Fault, FailCount= 2 2019-03-26T00:04:55.053Z,1553558695.053 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:04:55.954Z,1553558695.954 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.007230 CHAN A1 (24V): 0.002122 CHAN A2 (12V): -0.005681 CHAN A3 (5V): -0.002954 CHAN B0 (3.3V): -0.001196 CHAN B1 (3.15aV): -0.001120 CHAN B2 (3.15bV): -0.001525 CHAN B3 (GND): 0.000005 OPEN: -0.001387 Full Scale Calc: 4.765 mA, -1.589 mA 2019-03-26T00:04:58.086Z,1553558698.086 [SBIT](ERROR): Could not read rudderAngleReader_. 2019-03-26T00:04:59.541Z,1553558699.541 [ElevatorServo](FAULT): Overload Error 2019-03-26T00:04:59.541Z,1553558699.541 [ElevatorServo] Hardware Fault, FailCount= 3 2019-03-26T00:04:59.541Z,1553558699.541 [ElevatorServo](ERROR): Hardware Fault 2019-03-26T00:04:59.630Z,1553558699.630 [CBIT](ERROR): Hardware Fault in component: ElevatorServo 2019-03-26T00:04:59.630Z,1553558699.630 [CBIT](CRITICAL): Hardware Fault in component: ElevatorServo 2019-03-26T00:04:59.909Z,1553558699.909 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-03-26T00:04:59.910Z,1553558699.910 [ElevatorServo](INFO): Powering down 2019-03-26T00:05:00.289Z,1553558700.289 [CBIT](INFO): Critical error at 20190326T000459 2019-03-26T00:05:00.729Z,1553558700.729 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-03-26T00:05:00.846Z,1553558700.846 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-03-26T00:05:24.595Z,1553558724.595 [SBIT](ERROR): Could not read elevatorAngleReader_. 2019-03-26T00:05:24.595Z,1553558724.595 [SBIT](ERROR): Could not read rudderAngleReader_. 2019-03-26T00:05:24.596Z,1553558724.596 [SBIT](FAULT): Control surface position failure. 2019-03-26T00:05:38.355Z,1553558738.355 [SBIT](ERROR): Could not read elevatorAngleReader_. 2019-03-26T00:05:38.355Z,1553558738.355 [SBIT](ERROR): Could not read rudderAngleReader_. 2019-03-26T00:05:38.356Z,1553558738.356 [SBIT](FAULT): Control surface position failure. 2019-03-26T00:05:38.772Z,1553558738.772 [SBIT](CRITICAL): SBIT FAILED 2019-03-26T00:05:38.787Z,1553558738.787 [CBIT](INFO): Critical error at 20190326T000538 2019-03-26T00:05:38.831Z,1553558738.831 [CommandLine](IMPORTANT): got command configSet list 2019-03-26T00:05:38.831Z,1553558738.831 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-03-26T00:05:38.833Z,1553558738.833 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool; 2019-03-26T00:05:38.835Z,1553558738.835 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool; 2019-03-26T00:05:38.835Z,1553558738.835 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2019-03-26T00:05:39.137Z,1553558739.137 [MissionManager](IMPORTANT): Started mission Startup 2019-03-26T00:05:39.138Z,1553558739.138 [Startup] Running Loop=1 2019-03-26T00:05:39.138Z,1553558739.138 [Startup](DEBUG): Aggregate::initialize Startup 2019-03-26T00:05:39.138Z,1553558739.138 [Startup:A.GoToSurface] Running Loop=1 2019-03-26T00:05:39.138Z,1553558739.138 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-03-26T00:05:39.139Z,1553558739.139 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-03-26T00:05:39.139Z,1553558739.139 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-03-26T00:05:39.139Z,1553558739.139 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-03-26T00:05:39.140Z,1553558739.140 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-03-26T00:05:39.140Z,1553558739.140 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-03-26T00:05:39.142Z,1553558739.142 [Startup:StartupSatComms] Running Loop=1 2019-03-26T00:05:39.142Z,1553558739.142 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-03-26T00:05:39.142Z,1553558739.142 [Startup:StartupSatComms:A] Running Loop=1 2019-03-26T00:05:39.542Z,1553558739.542 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-03-26T00:06:16.312Z,1553558776.312 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:06:16.312Z,1553558776.312 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-03-26T00:06:16.312Z,1553558776.312 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:06:16.314Z,1553558776.314 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:06:16.314Z,1553558776.314 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2019-03-26T00:06:16.314Z,1553558776.314 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:06:16.325Z,1553558776.325 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:06:16.325Z,1553558776.325 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:06:16.722Z,1553558776.722 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-26T00:06:16.722Z,1553558776.722 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-03-26T00:06:16.723Z,1553558776.723 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-26T00:06:16.723Z,1553558776.723 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2019-03-26T00:06:17.104Z,1553558777.104 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:06:39.342Z,1553558799.342 [Startup:StartupSatComms:A](INFO): Timed out from 2019-03-26T00:05:39.1Z 2019-03-26T00:06:39.342Z,1553558799.342 [Startup:StartupSatComms:A] Stopped 2019-03-26T00:06:39.342Z,1553558799.342 [Startup:StartupSatComms:B] Running Loop=1 2019-03-26T00:06:39.734Z,1553558799.734 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-03-26T00:06:42.131Z,1553558802.131 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004809 2019-03-26T00:06:48.620Z,1553558808.620 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20190325T203051/Courier0064.lzma 2019-03-26T00:06:49.426Z,1553558809.426 [DataOverHttps](INFO): Moved sent file to Logs/20190325T203051/Courier0064.lzma.bak 2019-03-26T00:06:49.427Z,1553558809.427 [DataOverHttps](INFO): SBD MOMSN=10245123 2019-03-26T00:07:01.484Z,1553558821.484 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20190326T000406/Courier0000.lzma 2019-03-26T00:07:02.290Z,1553558822.290 [DataOverHttps](INFO): Moved sent file to Logs/20190326T000406/Courier0000.lzma.bak 2019-03-26T00:07:02.290Z,1553558822.290 [DataOverHttps](INFO): SBD MOMSN=10245125 2019-03-26T00:07:15.764Z,1553558835.764 [DataOverHttps](INFO): Sending 172 bytes from file Logs/20190325T203051/Express0065.lzma 2019-03-26T00:07:16.570Z,1553558836.570 [DataOverHttps](INFO): Moved sent file to Logs/20190325T203051/Express0065.lzma.bak 2019-03-26T00:07:16.571Z,1553558836.571 [DataOverHttps](INFO): SBD MOMSN=10245131 2019-03-26T00:07:29.994Z,1553558849.994 [DataOverHttps](INFO): Sending 923 bytes from file Logs/20190326T000406/Express0001.lzma 2019-03-26T00:07:30.798Z,1553558850.798 [DataOverHttps](INFO): Moved sent file to Logs/20190326T000406/Express0001.lzma.bak 2019-03-26T00:07:30.798Z,1553558850.798 [DataOverHttps](INFO): SBD MOMSN=10245136 2019-03-26T00:07:34.737Z,1553558854.737 [Startup:StartupSatComms:B] Stopped 2019-03-26T00:07:34.737Z,1553558854.737 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2019-03-26T00:07:34.737Z,1553558854.737 [Startup:StartupSatComms] Stopped 2019-03-26T00:07:34.738Z,1553558854.738 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-03-26T00:07:34.738Z,1553558854.738 [Startup](INFO): Completed Startup 2019-03-26T00:07:34.739Z,1553558854.739 [MissionManager](INFO): Startup is completed. 2019-03-26T00:07:34.739Z,1553558854.739 [MissionManager](INFO): Uninitializing Mission Startup 2019-03-26T00:07:34.739Z,1553558854.739 [Startup] Stopped 2019-03-26T00:07:34.739Z,1553558854.739 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-03-26T00:07:34.739Z,1553558854.739 [Startup:A.GoToSurface] Stopped 2019-03-26T00:07:34.739Z,1553558854.739 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-03-26T00:07:35.142Z,1553558855.142 [MissionManager](IMPORTANT): Started mission Default 2019-03-26T00:07:35.142Z,1553558855.142 [Default] Running Loop=1 2019-03-26T00:07:35.142Z,1553558855.142 [Default](DEBUG): Aggregate::initialize Default 2019-03-26T00:07:35.143Z,1553558855.143 [Default:B.GoToSurface] Running Loop=1 2019-03-26T00:07:35.143Z,1553558855.143 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-03-26T00:07:35.143Z,1553558855.143 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-03-26T00:07:35.143Z,1553558855.143 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-03-26T00:07:35.144Z,1553558855.144 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-03-26T00:07:35.144Z,1553558855.144 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-03-26T00:07:35.144Z,1553558855.144 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-03-26T00:07:35.145Z,1553558855.145 [Default:A.Wait] Running Loop=1 2019-03-26T00:07:35.145Z,1553558855.145 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-03-26T00:07:48.466Z,1553558868.466 [Default:A.Wait](INFO): Done Waiting. 2019-03-26T00:07:48.466Z,1553558868.466 [Default:A.Wait] Stopped 2019-03-26T00:07:48.466Z,1553558868.466 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-03-26T00:07:48.874Z,1553558868.874 [Default:CheckIn] Running Loop=1 2019-03-26T00:07:48.874Z,1553558868.874 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-03-26T00:07:48.875Z,1553558868.875 [Default:CheckIn:Read_GPS] Running Loop=1 2019-03-26T00:07:49.270Z,1553558869.270 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2019-03-26T00:08:17.160Z,1553558897.160 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:08:17.160Z,1553558897.160 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2019-03-26T00:08:17.160Z,1553558897.160 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:08:17.161Z,1553558897.161 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:08:17.161Z,1553558897.161 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2019-03-26T00:08:17.161Z,1553558897.161 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:08:17.240Z,1553558897.240 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:08:17.240Z,1553558897.240 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:08:17.559Z,1553558897.559 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-26T00:08:17.559Z,1553558897.559 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2019-03-26T00:08:17.560Z,1553558897.560 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-26T00:08:17.560Z,1553558897.560 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2019-03-26T00:08:17.956Z,1553558897.956 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:09:47.814Z,1553558987.814 [CommandLine](IMPORTANT): got command help run 2019-03-26T00:09:57.334Z,1553558997.334 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-03-26T00:09:58.153Z,1553558998.153 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:09:59.435Z,1553558999.435 [CommandLine](IMPORTANT): got command run /mnt/mmc/LRAUV/Missions/Maintenance/FDI_on.xml 2019-03-26T00:09:59.435Z,1553558999.435 [MissionManager](INFO): Loading Mission: /mnt/mmc/LRAUV/Missions/Maintenance/FDI_on.xml 2019-03-26T00:09:59.446Z,1553558999.446 [FDI_on:TestDrive:B.Wait](DEBUG): Construct Wait. 2019-03-26T00:09:59.450Z,1553558999.450 [MissionManager](DEBUG): 5 2019-03-26T00:09:59.452Z,1553558999.452 [CommandLine](IMPORTANT): Running /mnt/mmc/LRAUV/Missions/Maintenance/FDI_on.xml 2019-03-26T00:09:59.785Z,1553558999.785 [Default] Stopped 2019-03-26T00:09:59.785Z,1553558999.785 [Default](DEBUG): Aggregate::uninitialize Default 2019-03-26T00:09:59.785Z,1553558999.785 [Default:B.GoToSurface] Stopped 2019-03-26T00:09:59.785Z,1553558999.785 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-03-26T00:09:59.785Z,1553558999.785 [Default:CheckIn] Stopped 2019-03-26T00:09:59.785Z,1553558999.786 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-03-26T00:09:59.786Z,1553558999.786 [Default:CheckIn:Read_GPS] Stopped 2019-03-26T00:09:59.786Z,1553558999.786 [MissionManager](IMPORTANT): Started mission FDI_on 2019-03-26T00:09:59.786Z,1553558999.786 [FDI_on] Running Loop=1 2019-03-26T00:09:59.786Z,1553558999.786 [FDI_on](DEBUG): Aggregate::initialize FDI_on 2019-03-26T00:09:59.786Z,1553558999.786 [FDI_on:TestDrive] Running Loop=1 2019-03-26T00:09:59.786Z,1553558999.786 [FDI_on:TestDrive](DEBUG): Aggregate::initialize FDI_on:TestDrive 2019-03-26T00:09:59.786Z,1553558999.786 [FDI_on:TestDrive:A] Running Loop=1 2019-03-26T00:09:59.786Z,1553558999.786 [FDI_on:TestDrive:B.Wait] Running Loop=1 2019-03-26T00:09:59.787Z,1553558999.787 [FDI_on:TestDrive:B.Wait](DEBUG): Initialize Wait Component. 2019-03-26T00:09:59.787Z,1553558999.787 [FDI_on:TestDrive:A] Running Loop=1 2019-03-26T00:09:59.787Z,1553558999.787 [FDI_on:TestDrive:A](DEBUG): Initialize ReadDataComponent to sense FDI.runFDI 2019-03-26T00:10:00.172Z,1553559000.172 [FDI](INFO): Powering up 2019-03-26T00:10:03.401Z,1553559003.401 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:06.233Z,1553559006.233 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:09.465Z,1553559009.465 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:12.289Z,1553559012.289 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:15.521Z,1553559015.521 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:17.956Z,1553559017.956 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:10:17.956Z,1553559017.956 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2019-03-26T00:10:17.956Z,1553559017.956 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:10:17.958Z,1553559017.958 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:10:17.958Z,1553559017.958 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2019-03-26T00:10:17.958Z,1553559017.958 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:10:17.969Z,1553559017.969 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:10:17.969Z,1553559017.969 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:10:18.349Z,1553559018.349 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:18.381Z,1553559018.381 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-26T00:10:18.381Z,1553559018.381 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2019-03-26T00:10:18.382Z,1553559018.382 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-26T00:10:18.382Z,1553559018.382 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2019-03-26T00:10:18.780Z,1553559018.780 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:10:21.581Z,1553559021.581 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:24.413Z,1553559024.413 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:27.261Z,1553559027.261 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:30.473Z,1553559030.473 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:33.301Z,1553559033.301 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:36.533Z,1553559036.533 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:39.361Z,1553559039.361 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:42.185Z,1553559042.185 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:44.205Z,1553559044.205 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:46.229Z,1553559046.229 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:49.461Z,1553559049.461 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:52.289Z,1553559052.289 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:55.525Z,1553559055.525 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:10:58.349Z,1553559058.349 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:01.577Z,1553559061.577 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:04.409Z,1553559064.409 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:07.241Z,1553559067.241 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:10.469Z,1553559070.469 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:13.301Z,1553559073.301 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:16.525Z,1553559076.525 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:19.357Z,1553559079.357 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:22.185Z,1553559082.185 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:24.205Z,1553559084.205 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:26.221Z,1553559086.221 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:28.245Z,1553559088.245 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:31.477Z,1553559091.477 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:34.305Z,1553559094.305 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:37.537Z,1553559097.537 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:40.365Z,1553559100.365 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:41.202Z,1553559101.202 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6. 2019-03-26T00:11:43.189Z,1553559103.189 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:45.213Z,1553559105.213 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:47.233Z,1553559107.233 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:50.461Z,1553559110.461 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:53.289Z,1553559113.289 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:56.525Z,1553559116.525 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:11:59.353Z,1553559119.353 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:02.185Z,1553559122.185 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:04.201Z,1553559124.201 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:06.221Z,1553559126.221 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:08.241Z,1553559128.241 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:11.473Z,1553559131.473 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:14.301Z,1553559134.301 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:17.533Z,1553559137.533 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:19.156Z,1553559139.156 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:12:19.156Z,1553559139.156 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2019-03-26T00:12:19.156Z,1553559139.156 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:12:19.157Z,1553559139.157 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:12:19.157Z,1553559139.157 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2019-03-26T00:12:19.158Z,1553559139.158 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:12:19.168Z,1553559139.168 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:12:19.168Z,1553559139.168 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:12:19.581Z,1553559139.581 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-26T00:12:19.581Z,1553559139.581 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2019-03-26T00:12:19.581Z,1553559139.581 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-26T00:12:19.581Z,1553559139.581 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2019-03-26T00:12:19.960Z,1553559139.960 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:12:20.361Z,1553559140.361 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:23.189Z,1553559143.189 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:25.209Z,1553559145.209 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:27.233Z,1553559147.233 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:30.461Z,1553559150.461 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:33.289Z,1553559153.289 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:36.525Z,1553559156.525 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:39.349Z,1553559159.349 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:42.581Z,1553559162.581 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:45.409Z,1553559165.409 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:48.233Z,1553559168.233 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:51.469Z,1553559171.469 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:54.297Z,1553559174.297 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:12:57.529Z,1553559177.529 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:00.357Z,1553559180.357 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:03.181Z,1553559183.181 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:05.205Z,1553559185.205 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:07.229Z,1553559187.229 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:10.457Z,1553559190.457 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:13.285Z,1553559193.285 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:16.517Z,1553559196.517 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:19.345Z,1553559199.345 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:22.577Z,1553559202.577 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:25.405Z,1553559205.405 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:28.233Z,1553559208.233 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:31.465Z,1553559211.465 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:34.293Z,1553559214.293 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:37.521Z,1553559217.521 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:40.349Z,1553559220.349 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:43.181Z,1553559223.181 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:45.197Z,1553559225.197 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:47.221Z,1553559227.221 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:49.245Z,1553559229.245 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:52.473Z,1553559232.473 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:55.301Z,1553559235.301 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:13:58.529Z,1553559238.529 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:01.361Z,1553559241.361 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:04.185Z,1553559244.185 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:06.209Z,1553559246.209 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:08.232Z,1553559248.232 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:11.457Z,1553559251.457 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:14.285Z,1553559254.285 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:17.521Z,1553559257.521 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:20.349Z,1553559260.349 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:20.367Z,1553559260.367 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:14:20.367Z,1553559260.367 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2019-03-26T00:14:20.367Z,1553559260.367 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:14:20.368Z,1553559260.368 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:14:20.368Z,1553559260.368 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2019-03-26T00:14:20.368Z,1553559260.368 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:14:20.408Z,1553559260.408 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:14:20.408Z,1553559260.408 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:14:20.789Z,1553559260.789 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-26T00:14:20.789Z,1553559260.789 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2019-03-26T00:14:20.789Z,1553559260.789 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-26T00:14:20.789Z,1553559260.789 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2019-03-26T00:14:21.176Z,1553559261.176 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:14:23.181Z,1553559263.181 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:25.197Z,1553559265.197 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:27.217Z,1553559267.217 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:29.237Z,1553559269.237 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:32.473Z,1553559272.473 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:35.297Z,1553559275.297 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:38.525Z,1553559278.525 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:41.357Z,1553559281.357 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:44.185Z,1553559284.185 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:46.205Z,1553559286.205 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:48.221Z,1553559288.221 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:51.457Z,1553559291.457 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:54.285Z,1553559294.285 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:14:57.506Z,1553559297.506 [NAL9602](FAULT): GPS failed to acquire within timeout. 2019-03-26T00:14:57.506Z,1553559297.506 [NAL9602] Data Fault, FailCount= 1 2019-03-26T00:14:57.506Z,1553559297.506 [NAL9602](ERROR): Data Fault 2019-03-26T00:14:57.535Z,1553559297.535 [CBIT](ERROR): Data Fault in component: NAL9602 2019-03-26T00:14:57.906Z,1553559297.906 [NAL9602](INFO): Powering down 2019-03-26T00:14:58.743Z,1553559298.743 [CBIT](INFO): Clearing failed state for component NAL9602 2019-03-26T00:14:58.743Z,1553559298.743 [NAL9602] No Fault, FailCount= 1 2019-03-26T00:14:59.951Z,1553559299.951 [FDI_on:TestDrive:B.Wait](INFO): Done Waiting. 2019-03-26T00:14:59.952Z,1553559299.952 [FDI_on:TestDrive:B.Wait] Stopped 2019-03-26T00:14:59.952Z,1553559299.952 [FDI_on:TestDrive:B.Wait](DEBUG): Uninitialize Wait Component. 2019-03-26T00:14:59.952Z,1553559299.952 [FDI_on:TestDrive](INFO): Completed FDI_on:TestDrive 2019-03-26T00:14:59.952Z,1553559299.952 [FDI_on](INFO): Completed FDI_on 2019-03-26T00:14:59.952Z,1553559299.952 [MissionManager](INFO): FDI_on is completed. 2019-03-26T00:14:59.953Z,1553559299.953 [MissionManager](INFO): Uninitializing Mission FDI_on 2019-03-26T00:14:59.953Z,1553559299.953 [FDI_on] Stopped 2019-03-26T00:14:59.953Z,1553559299.953 [FDI_on](DEBUG): Aggregate::uninitialize FDI_on 2019-03-26T00:14:59.953Z,1553559299.953 [FDI_on:TestDrive] Stopped 2019-03-26T00:14:59.953Z,1553559299.953 [FDI_on:TestDrive](DEBUG): Aggregate::uninitialize FDI_on:TestDrive 2019-03-26T00:14:59.953Z,1553559299.953 [FDI_on:TestDrive:A] Stopped 2019-03-26T00:15:00.358Z,1553559300.358 [MissionManager](IMPORTANT): Started mission Default 2019-03-26T00:15:00.358Z,1553559300.358 [Default] Running Loop=1 2019-03-26T00:15:00.358Z,1553559300.358 [Default](DEBUG): Aggregate::initialize Default 2019-03-26T00:15:00.358Z,1553559300.358 [Default:B.GoToSurface] Running Loop=1 2019-03-26T00:15:00.358Z,1553559300.358 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-03-26T00:15:00.359Z,1553559300.359 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-03-26T00:15:00.359Z,1553559300.359 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-03-26T00:15:00.359Z,1553559300.359 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-03-26T00:15:00.360Z,1553559300.360 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-03-26T00:15:00.360Z,1553559300.360 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-03-26T00:15:00.360Z,1553559300.360 [Default:A.Wait] Running Loop=1 2019-03-26T00:15:00.360Z,1553559300.360 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-03-26T00:15:13.687Z,1553559313.687 [Default:A.Wait](INFO): Done Waiting. 2019-03-26T00:15:13.687Z,1553559313.687 [Default:A.Wait] Stopped 2019-03-26T00:15:13.687Z,1553559313.687 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-03-26T00:15:14.094Z,1553559314.094 [Default:CheckIn] Running Loop=1 2019-03-26T00:15:14.094Z,1553559314.094 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-03-26T00:15:14.094Z,1553559314.094 [Default:CheckIn:Read_GPS] Running Loop=1 2019-03-26T00:15:28.210Z,1553559328.210 [NAL9602](INFO): Powering up NAL9602 2019-03-26T00:15:39.118Z,1553559339.118 [NAL9602](INFO): NAL9602 initialized 2019-03-26T00:15:39.937Z,1553559339.937 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:15:42.361Z,1553559342.361 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:15:45.189Z,1553559345.189 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:15:47.209Z,1553559347.209 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:15:50.441Z,1553559350.441 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:15:53.269Z,1553559353.269 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:15:56.497Z,1553559356.497 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:15:59.325Z,1553559359.325 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:02.157Z,1553559362.157 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:04.177Z,1553559364.177 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:06.197Z,1553559366.197 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:08.211Z,1553559368.211 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:11.453Z,1553559371.453 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:14.277Z,1553559374.277 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:17.509Z,1553559377.509 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:20.329Z,1553559380.329 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:21.558Z,1553559381.558 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:16:21.558Z,1553559381.558 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2019-03-26T00:16:21.558Z,1553559381.558 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:16:21.559Z,1553559381.559 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:16:21.559Z,1553559381.559 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6 2019-03-26T00:16:21.559Z,1553559381.559 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:16:21.571Z,1553559381.571 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:16:21.571Z,1553559381.571 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:16:21.974Z,1553559381.974 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-26T00:16:21.974Z,1553559381.974 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2019-03-26T00:16:21.974Z,1553559381.974 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-26T00:16:21.974Z,1553559381.974 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6 2019-03-26T00:16:22.372Z,1553559382.372 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:16:23.165Z,1553559383.165 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:25.177Z,1553559385.177 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:27.205Z,1553559387.205 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:30.437Z,1553559390.437 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:33.261Z,1553559393.261 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:36.497Z,1553559396.497 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:39.325Z,1553559399.325 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:42.149Z,1553559402.149 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:44.173Z,1553559404.173 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:46.193Z,1553559406.193 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:48.209Z,1553559408.209 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:51.445Z,1553559411.445 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:54.269Z,1553559414.269 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:16:57.505Z,1553559417.505 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:00.333Z,1553559420.333 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:03.161Z,1553559423.161 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:05.181Z,1553559425.181 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:05.206Z,1553559425.206 [CommandLine](IMPORTANT): got command stop 2019-03-26T00:17:05.206Z,1553559425.206 [CommandLine](IMPORTANT): Scheduling is paused 2019-03-26T00:17:05.207Z,1553559425.207 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop 2019-03-26T00:17:07.197Z,1553559427.197 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:10.433Z,1553559430.433 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:13.265Z,1553559433.265 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:16.493Z,1553559436.493 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:19.321Z,1553559439.321 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:22.553Z,1553559442.553 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:25.381Z,1553559445.381 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:28.201Z,1553559448.201 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:31.441Z,1553559451.441 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:34.269Z,1553559454.269 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:37.501Z,1553559457.501 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:40.321Z,1553559460.321 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:43.153Z,1553559463.153 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:45.173Z,1553559465.173 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:47.193Z,1553559467.193 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:49.217Z,1553559469.217 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:52.441Z,1553559472.441 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:55.277Z,1553559475.277 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:17:58.509Z,1553559478.509 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:01.337Z,1553559481.337 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:04.165Z,1553559484.165 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:06.185Z,1553559486.185 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:08.205Z,1553559488.205 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:11.437Z,1553559491.437 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:14.261Z,1553559494.261 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:17.493Z,1553559497.493 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:20.325Z,1553559500.325 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:22.756Z,1553559502.756 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:18:22.756Z,1553559502.756 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2019-03-26T00:18:22.756Z,1553559502.756 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:18:22.758Z,1553559502.758 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:18:22.758Z,1553559502.758 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7 2019-03-26T00:18:22.758Z,1553559502.758 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:18:22.770Z,1553559502.770 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:18:22.770Z,1553559502.770 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:18:23.153Z,1553559503.153 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:23.186Z,1553559503.186 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-26T00:18:23.186Z,1553559503.186 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2019-03-26T00:18:23.186Z,1553559503.186 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-26T00:18:23.186Z,1553559503.186 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7 2019-03-26T00:18:23.584Z,1553559503.584 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:18:25.169Z,1553559505.169 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:27.193Z,1553559507.193 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:29.213Z,1553559509.213 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:32.445Z,1553559512.445 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:35.269Z,1553559515.269 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:38.497Z,1553559518.497 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:41.329Z,1553559521.329 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:44.161Z,1553559524.161 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:46.181Z,1553559526.181 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:48.193Z,1553559528.193 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:51.433Z,1553559531.433 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:54.261Z,1553559534.261 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:18:57.493Z,1553559537.493 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:00.313Z,1553559540.313 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:03.553Z,1553559543.553 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:06.381Z,1553559546.381 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:09.209Z,1553559549.209 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:12.433Z,1553559552.433 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:15.269Z,1553559555.269 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:18.501Z,1553559558.501 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:21.329Z,1553559561.329 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:24.157Z,1553559564.157 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:26.177Z,1553559566.177 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:28.193Z,1553559568.193 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:29.843Z,1553559569.843 [CBIT](INFO): Clearing failed state for component RudderServo 2019-03-26T00:19:29.843Z,1553559569.843 [RudderServo] No Fault, FailCount= 3 2019-03-26T00:19:31.429Z,1553559571.429 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:34.249Z,1553559574.249 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:34.321Z,1553559574.321 [RudderServo](FAULT): Overload Error 2019-03-26T00:19:34.321Z,1553559574.321 [RudderServo] Hardware Fault, FailCount= 1 2019-03-26T00:19:34.321Z,1553559574.321 [RudderServo](ERROR): Hardware Fault 2019-03-26T00:19:34.361Z,1553559574.361 [CBIT](ERROR): Hardware Fault in component: RudderServo 2019-03-26T00:19:34.691Z,1553559574.691 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-03-26T00:19:34.691Z,1553559574.691 [RudderServo](INFO): Powering down 2019-03-26T00:19:35.484Z,1553559575.484 [RudderServo](DEBUG): Initializing EZServoServo. 2019-03-26T00:19:35.602Z,1553559575.602 [RudderServo](DEBUG): Initializing RudderServo. 2019-03-26T00:19:35.606Z,1553559575.606 [CBIT](INFO): Clearing failed state for component RudderServo 2019-03-26T00:19:35.606Z,1553559575.606 [RudderServo] No Fault, FailCount= 1 2019-03-26T00:19:37.489Z,1553559577.489 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:40.317Z,1553559580.317 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:40.381Z,1553559580.381 [RudderServo](FAULT): Overload Error 2019-03-26T00:19:40.381Z,1553559580.381 [RudderServo] Hardware Fault, FailCount= 2 2019-03-26T00:19:40.381Z,1553559580.381 [RudderServo](ERROR): Hardware Fault 2019-03-26T00:19:40.421Z,1553559580.421 [CBIT](ERROR): Hardware Fault in component: RudderServo 2019-03-26T00:19:40.755Z,1553559580.755 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-03-26T00:19:40.756Z,1553559580.756 [RudderServo](INFO): Powering down 2019-03-26T00:19:41.551Z,1553559581.551 [RudderServo](DEBUG): Initializing EZServoServo. 2019-03-26T00:19:41.666Z,1553559581.666 [RudderServo](DEBUG): Initializing RudderServo. 2019-03-26T00:19:41.673Z,1553559581.673 [CBIT](INFO): Clearing failed state for component RudderServo 2019-03-26T00:19:41.673Z,1553559581.673 [RudderServo] No Fault, FailCount= 2 2019-03-26T00:19:43.145Z,1553559583.145 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:45.157Z,1553559585.157 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:46.417Z,1553559586.417 [RudderServo](FAULT): Overload Error 2019-03-26T00:19:46.417Z,1553559586.417 [RudderServo] Hardware Fault, FailCount= 3 2019-03-26T00:19:46.417Z,1553559586.417 [RudderServo](ERROR): Hardware Fault 2019-03-26T00:19:46.473Z,1553559586.473 [CBIT](ERROR): Hardware Fault in component: RudderServo 2019-03-26T00:19:46.473Z,1553559586.473 [CBIT](CRITICAL): Hardware Fault in component: RudderServo 2019-03-26T00:19:46.797Z,1553559586.797 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-03-26T00:19:46.797Z,1553559586.797 [RudderServo](INFO): Powering down 2019-03-26T00:19:46.799Z,1553559586.799 [CommandLine](FAULT): Scheduling is paused 2019-03-26T00:19:46.800Z,1553559586.800 [CBIT](INFO): Critical error at 20190326T001946 2019-03-26T00:19:47.188Z,1553559587.188 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:47.627Z,1553559587.627 [RudderServo](DEBUG): Initializing EZServoServo. 2019-03-26T00:19:47.750Z,1553559587.750 [RudderServo](DEBUG): Initializing RudderServo. 2019-03-26T00:19:49.201Z,1553559589.201 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:52.437Z,1553559592.437 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:55.265Z,1553559595.265 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:58.493Z,1553559598.493 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:19:59.728Z,1553559599.728 [CBIT](INFO): Clearing failed state for component ElevatorServo 2019-03-26T00:19:59.728Z,1553559599.728 [ElevatorServo] No Fault, FailCount= 3 2019-03-26T00:20:01.325Z,1553559601.325 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:04.197Z,1553559604.197 [ElevatorServo](FAULT): Overload Error 2019-03-26T00:20:04.197Z,1553559604.197 [ElevatorServo] Hardware Fault, FailCount= 1 2019-03-26T00:20:04.197Z,1553559604.197 [ElevatorServo](ERROR): Hardware Fault 2019-03-26T00:20:04.238Z,1553559604.238 [CBIT](ERROR): Hardware Fault in component: ElevatorServo 2019-03-26T00:20:04.553Z,1553559604.553 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:04.586Z,1553559604.586 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-03-26T00:20:04.586Z,1553559604.586 [ElevatorServo](INFO): Powering down 2019-03-26T00:20:05.386Z,1553559605.386 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-03-26T00:20:05.506Z,1553559605.506 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-03-26T00:20:05.511Z,1553559605.511 [CBIT](INFO): Clearing failed state for component ElevatorServo 2019-03-26T00:20:05.511Z,1553559605.511 [ElevatorServo] No Fault, FailCount= 1 2019-03-26T00:20:07.389Z,1553559607.389 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:10.209Z,1553559610.209 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:10.669Z,1553559610.669 [ElevatorServo](FAULT): Overload Error 2019-03-26T00:20:10.669Z,1553559610.669 [ElevatorServo] Hardware Fault, FailCount= 2 2019-03-26T00:20:10.669Z,1553559610.669 [ElevatorServo](ERROR): Hardware Fault 2019-03-26T00:20:10.723Z,1553559610.723 [CBIT](ERROR): Hardware Fault in component: ElevatorServo 2019-03-26T00:20:11.050Z,1553559611.050 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-03-26T00:20:11.050Z,1553559611.050 [ElevatorServo](INFO): Powering down 2019-03-26T00:20:11.842Z,1553559611.842 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-03-26T00:20:11.962Z,1553559611.962 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-03-26T00:20:11.967Z,1553559611.967 [CBIT](INFO): Clearing failed state for component ElevatorServo 2019-03-26T00:20:11.967Z,1553559611.967 [ElevatorServo] No Fault, FailCount= 2 2019-03-26T00:20:13.441Z,1553559613.441 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:14.270Z,1553559614.270 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-03-26T00:15:14.1Z 2019-03-26T00:20:14.270Z,1553559614.270 [Default:CheckIn:Read_GPS] Stopped 2019-03-26T00:20:14.270Z,1553559614.270 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-03-26T00:20:14.674Z,1553559614.674 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-03-26T00:20:16.265Z,1553559616.265 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:17.125Z,1553559617.125 [ElevatorServo](FAULT): Overload Error 2019-03-26T00:20:17.125Z,1553559617.125 [ElevatorServo] Hardware Fault, FailCount= 3 2019-03-26T00:20:17.125Z,1553559617.125 [ElevatorServo](ERROR): Hardware Fault 2019-03-26T00:20:17.170Z,1553559617.170 [CBIT](ERROR): Hardware Fault in component: ElevatorServo 2019-03-26T00:20:17.171Z,1553559617.171 [CBIT](CRITICAL): Hardware Fault in component: ElevatorServo 2019-03-26T00:20:17.498Z,1553559617.498 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-03-26T00:20:17.498Z,1553559617.498 [ElevatorServo](INFO): Powering down 2019-03-26T00:20:17.502Z,1553559617.502 [CBIT](INFO): Critical error at 20190326T002017 2019-03-26T00:20:18.298Z,1553559618.298 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-03-26T00:20:18.418Z,1553559618.418 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-03-26T00:20:19.497Z,1553559619.497 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:21.086Z,1553559621.086 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20190326T000406/Courier0004.lzma 2019-03-26T00:20:21.818Z,1553559621.818 [DataOverHttps](INFO): Moved sent file to Logs/20190326T000406/Courier0004.lzma.bak 2019-03-26T00:20:21.818Z,1553559621.818 [DataOverHttps](INFO): SBD MOMSN=10245265 2019-03-26T00:20:22.329Z,1553559622.329 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:23.952Z,1553559623.952 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:20:23.952Z,1553559623.952 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2019-03-26T00:20:23.952Z,1553559623.952 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:20:23.954Z,1553559623.954 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:20:23.954Z,1553559623.954 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8 2019-03-26T00:20:23.954Z,1553559623.954 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:20:23.978Z,1553559623.978 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:20:23.978Z,1553559623.978 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:20:24.377Z,1553559624.377 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-26T00:20:24.377Z,1553559624.377 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2019-03-26T00:20:24.378Z,1553559624.378 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-26T00:20:24.378Z,1553559624.378 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8 2019-03-26T00:20:24.762Z,1553559624.762 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:20:25.156Z,1553559625.156 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:27.173Z,1553559627.173 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:29.193Z,1553559629.193 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:32.425Z,1553559632.425 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:35.265Z,1553559635.265 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:35.474Z,1553559635.474 [DataOverHttps](INFO): Sending 504 bytes from file Logs/20190326T000406/Express0005.lzma 2019-03-26T00:20:36.278Z,1553559636.278 [DataOverHttps](INFO): Moved sent file to Logs/20190326T000406/Express0005.lzma.bak 2019-03-26T00:20:36.278Z,1553559636.278 [DataOverHttps](INFO): SBD MOMSN=10245268 2019-03-26T00:20:38.489Z,1553559638.489 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:40.110Z,1553559640.110 [Default:CheckIn:Read_Iridium] Stopped 2019-03-26T00:20:40.110Z,1553559640.110 [Default:CheckIn:C.Wait] Running Loop=1 2019-03-26T00:20:40.110Z,1553559640.110 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-03-26T00:20:41.321Z,1553559641.321 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:44.545Z,1553559644.545 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:47.373Z,1553559647.373 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:50.201Z,1553559650.201 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:53.437Z,1553559653.437 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:56.265Z,1553559656.265 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:20:59.497Z,1553559659.497 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:02.329Z,1553559662.329 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:05.149Z,1553559665.149 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:07.173Z,1553559667.173 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:09.197Z,1553559669.197 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:12.425Z,1553559672.425 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:15.249Z,1553559675.249 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:18.485Z,1553559678.485 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:21.313Z,1553559681.313 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:24.541Z,1553559684.541 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:27.369Z,1553559687.369 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:30.205Z,1553559690.205 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:33.433Z,1553559693.433 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:36.261Z,1553559696.261 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:39.489Z,1553559699.489 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:42.321Z,1553559702.321 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:45.549Z,1553559705.549 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:48.381Z,1553559708.381 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:51.209Z,1553559711.209 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:54.441Z,1553559714.441 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:21:57.265Z,1553559717.265 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:00.497Z,1553559720.497 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:03.329Z,1553559723.329 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:06.153Z,1553559726.153 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:08.180Z,1553559728.180 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:10.197Z,1553559730.197 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:13.425Z,1553559733.425 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:16.257Z,1553559736.257 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:19.501Z,1553559739.501 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:22.333Z,1553559742.333 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:24.772Z,1553559744.772 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:22:24.772Z,1553559744.772 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2019-03-26T00:22:24.772Z,1553559744.772 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:22:24.774Z,1553559744.774 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:22:24.774Z,1553559744.774 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9 2019-03-26T00:22:24.774Z,1553559744.774 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:22:24.785Z,1553559744.785 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:22:24.785Z,1553559744.785 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:22:25.161Z,1553559745.161 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:25.194Z,1553559745.194 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-26T00:22:25.194Z,1553559745.194 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2019-03-26T00:22:25.194Z,1553559745.194 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-26T00:22:25.195Z,1553559745.195 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9 2019-03-26T00:22:25.588Z,1553559745.588 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:22:27.181Z,1553559747.181 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:29.201Z,1553559749.201 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:32.429Z,1553559752.429 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:35.257Z,1553559755.257 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:38.489Z,1553559758.489 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:41.317Z,1553559761.317 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:44.549Z,1553559764.549 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:47.381Z,1553559767.381 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:50.209Z,1553559770.209 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:53.441Z,1553559773.441 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:56.273Z,1553559776.273 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:22:59.501Z,1553559779.501 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:02.333Z,1553559782.333 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:05.149Z,1553559785.149 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:07.177Z,1553559787.177 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:09.197Z,1553559789.197 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:12.425Z,1553559792.425 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:15.253Z,1553559795.253 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:18.481Z,1553559798.481 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:21.317Z,1553559801.317 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:24.541Z,1553559804.541 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:27.377Z,1553559807.377 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:30.201Z,1553559810.201 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:33.437Z,1553559813.437 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:36.265Z,1553559816.265 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:39.493Z,1553559819.493 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:42.325Z,1553559822.325 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:45.149Z,1553559825.149 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:47.177Z,1553559827.177 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:49.189Z,1553559829.189 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:51.205Z,1553559831.205 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:54.445Z,1553559834.445 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:23:57.269Z,1553559837.269 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:00.505Z,1553559840.505 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:03.325Z,1553559843.325 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:06.157Z,1553559846.157 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:08.173Z,1553559848.173 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:10.201Z,1553559850.201 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:13.429Z,1553559853.429 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:16.261Z,1553559856.261 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:19.493Z,1553559859.493 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:22.317Z,1553559862.317 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:25.141Z,1553559865.141 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:25.980Z,1553559865.980 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:24:25.980Z,1553559865.980 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2019-03-26T00:24:25.980Z,1553559865.980 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-26T00:24:26.016Z,1553559866.016 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-26T00:24:26.016Z,1553559866.016 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10 2019-03-26T00:24:26.016Z,1553559866.016 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-26T00:24:26.027Z,1553559866.027 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-26T00:24:26.027Z,1553559866.027 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-26T00:24:26.382Z,1553559866.382 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-26T00:24:26.382Z,1553559866.382 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2019-03-26T00:24:26.382Z,1553559866.382 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-26T00:24:26.382Z,1553559866.382 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10 2019-03-26T00:24:26.772Z,1553559866.772 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-26T00:24:27.169Z,1553559867.169 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:29.189Z,1553559869.189 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:32.417Z,1553559872.417 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:35.249Z,1553559875.249 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:38.475Z,1553559878.475 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:41.309Z,1553559881.309 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:44.533Z,1553559884.533 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:47.365Z,1553559887.365 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:50.197Z,1553559890.197 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:53.429Z,1553559893.429 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:56.253Z,1553559896.253 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:24:59.509Z,1553559899.509 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:25:02.337Z,1553559902.337 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:25:05.161Z,1553559905.161 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:25:07.189Z,1553559907.189 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:25:09.205Z,1553559909.205 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:25:12.437Z,1553559912.437 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:25:15.269Z,1553559915.269 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:25:18.493Z,1553559918.493 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:25:19.783Z,1553559919.783 [CommandLine](IMPORTANT): got command restart application 2019-03-26T00:25:20.789Z,1553559920.789 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-26T00:25:20.789Z,1553559920.789 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:25:20.837Z,1553559920.837 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2019-03-26T00:25:20.837Z,1553559920.837 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:25:20.837Z,1553559920.837 [CommandLine](INFO): Join timeout helper Thread ID is 6643 2019-03-26T00:25:20.838Z,1553559920.838 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2019-03-26T00:25:20.838Z,1553559920.838 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:25:20.838Z,1553559920.838 [NavChartDb](INFO): Join timeout helper Thread ID is 6644 2019-03-26T00:25:21.057Z,1553559921.057 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-26T00:25:21.057Z,1553559921.057 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:25:21.061Z,1553559921.061 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2019-03-26T00:25:21.061Z,1553559921.061 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:25:21.061Z,1553559921.061 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6645 2019-03-26T00:25:21.205Z,1553559921.205 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-26T00:25:21.205Z,1553559921.205 [WetLabsBB2FL](INFO): Powering down 2019-03-26T00:25:21.206Z,1553559921.206 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:25:21.229Z,1553559921.229 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2019-03-26T00:25:21.229Z,1553559921.229 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:25:21.229Z,1553559921.229 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 6646 2019-03-26T00:25:21.325Z,1553559921.325 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-26T00:25:21.325Z,1553559921.325 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:25:21.326Z,1553559921.326 [NAL9602](DEBUG): Fix Requested 2019-03-26T00:25:21.329Z,1553559921.329 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2019-03-26T00:25:21.329Z,1553559921.329 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:25:21.330Z,1553559921.330 [Radio_Surface](INFO): Join timeout helper Thread ID is 6647 2019-03-26T00:25:21.541Z,1553559921.541 [Radio_Surface](INFO): Powering down 2019-03-26T00:25:21.542Z,1553559921.542 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-26T00:25:21.542Z,1553559921.542 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:25:21.554Z,1553559921.554 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2019-03-26T00:25:21.554Z,1553559921.554 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:25:21.554Z,1553559921.554 [DataOverHttps](INFO): Join timeout helper Thread ID is 6648 2019-03-26T00:25:21.661Z,1553559921.661 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-26T00:25:21.661Z,1553559921.661 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:25:21.673Z,1553559921.673 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2019-03-26T00:25:21.674Z,1553559921.674 [logger ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:25:21.674Z,1553559921.674 [logger](INFO): Join timeout helper Thread ID is 6649 2019-03-26T00:25:21.742Z,1553559921.742 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-26T00:25:21.742Z,1553559921.742 [logger ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:25:21.754Z,1553559921.754 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2019-03-26T00:25:21.754Z,1553559921.754 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:25:21.754Z,1553559921.754 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2019-03-26T00:25:21.754Z,1553559921.754 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:25:21.754Z,1553559921.754 [controlThread](INFO): Join timeout helper Thread ID is 6650 2019-03-26T00:25:21.770Z,1553559921.770 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-26T00:25:21.770Z,1553559921.770 [controlThread](DEBUG): Uninitializing ControlThread 2019-03-26T00:25:21.770Z,1553559921.770 [NAL9602](INFO): Powering down 2019-03-26T00:25:21.772Z,1553559921.772 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2019-03-26T00:25:21.773Z,1553559921.773 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2019-03-26T00:25:21.774Z,1553559921.774 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2019-03-26T00:25:21.774Z,1553559921.774 [MissionManager](INFO): Uninitializing Mission Default 2019-03-26T00:25:21.774Z,1553559921.774 [Default] Stopped 2019-03-26T00:25:21.774Z,1553559921.774 [Default](DEBUG): Aggregate::uninitialize Default 2019-03-26T00:25:21.774Z,1553559921.774 [Default:B.GoToSurface] Stopped 2019-03-26T00:25:21.774Z,1553559921.774 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-03-26T00:25:21.774Z,1553559921.774 [Default:CheckIn] Stopped 2019-03-26T00:25:21.775Z,1553559921.775 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-03-26T00:25:21.775Z,1553559921.775 [Default:CheckIn:C.Wait] Stopped 2019-03-26T00:25:21.775Z,1553559921.775 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-03-26T00:25:21.777Z,1553559921.777 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2019-03-26T00:25:21.777Z,1553559921.777 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2019-03-26T00:25:21.778Z,1553559921.778 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2019-03-26T00:25:21.778Z,1553559921.778 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2019-03-26T00:25:21.778Z,1553559921.778 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-03-26T00:25:21.778Z,1553559921.778 [BuoyancyServo](INFO): Powering down 2019-03-26T00:25:21.793Z,1553559921.793 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-03-26T00:25:21.793Z,1553559921.793 [ElevatorServo](INFO): Powering down 2019-03-26T00:25:21.794Z,1553559921.794 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-03-26T00:25:21.794Z,1553559921.794 [MassServo](INFO): Powering down 2019-03-26T00:25:21.795Z,1553559921.795 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-03-26T00:25:21.795Z,1553559921.795 [RudderServo](INFO): Powering down 2019-03-26T00:25:21.795Z,1553559921.795 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-03-26T00:25:21.795Z,1553559921.795 [ThrusterServo](INFO): Powering down 2019-03-26T00:25:21.796Z,1553559921.796 [SBIT](DEBUG): Uninitialize SBIT Component. 2019-03-26T00:25:21.797Z,1553559921.797 [IBIT](DEBUG): Uninitialize IBIT Component. 2019-03-26T00:25:21.797Z,1553559921.797 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-03-26T00:25:21.797Z,1553559921.797 [CBIT](DEBUG): Powering off loads. 2019-03-26T00:25:21.809Z,1553559921.809 [CBIT](DEBUG): Disabling WDT. 2019-03-26T00:25:21.821Z,1553559921.821 [CBIT](DEBUG): Opening all GF detection circuits. 2019-03-26T00:25:21.822Z,1553559921.822 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:25:21.893Z,1553559921.893 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:25:21.898Z,1553559921.898 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:25:21.934Z,1553559921.934 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:25:21.936Z,1553559921.936 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:25:22.218Z,1553559922.218 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-03-26T00:25:22.274Z,1553559922.274 [logger ThreadHandler](INFO): Thread cancelled.