2018-11-01T18:08:23.462Z,1541095703.462 [Supervisor](DEBUG): Initializing supervisor. 2018-11-01T18:08:23.464Z,1541095703.464 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-11-01T18:08:23.465Z,1541095703.465 [SyncHandler](INFO): Protected caller Thread ID is 4058 2018-11-01T18:08:23.465Z,1541095703.465 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-11-01T18:08:23.466Z,1541095703.466 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-11-01T18:08:23.467Z,1541095703.467 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4059 2018-11-01T18:08:23.469Z,1541095703.469 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-11-01T18:08:23.481Z,1541095703.481 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-11-01T18:08:23.482Z,1541095703.482 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-11-01T18:08:23.482Z,1541095703.482 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4060 2018-11-01T18:08:23.483Z,1541095703.483 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-11-01T18:08:23.484Z,1541095703.484 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-11-01T18:08:23.484Z,1541095703.484 [logger ThreadHandler](INFO): Protected caller Thread ID is 4061 2018-11-01T18:08:23.486Z,1541095703.486 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-11-01T18:08:23.487Z,1541095703.487 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-11-01T18:08:23.488Z,1541095703.488 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-11-01T18:08:23.919Z,1541095703.919 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-11-01T18:08:23.920Z,1541095703.920 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-11-01T18:08:24.364Z,1541095704.364 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-11-01T18:08:24.364Z,1541095704.364 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-11-01T18:08:24.557Z,1541095704.557 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-11-01T18:08:24.557Z,1541095704.557 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-11-01T18:08:24.701Z,1541095704.701 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-11-01T18:08:24.702Z,1541095704.702 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-11-01T18:08:25.140Z,1541095705.140 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-11-01T18:08:25.140Z,1541095705.140 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-11-01T18:08:25.235Z,1541095705.235 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-11-01T18:08:25.235Z,1541095705.235 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-11-01T18:08:25.401Z,1541095705.401 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-11-01T18:08:25.401Z,1541095705.401 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-11-01T18:08:25.545Z,1541095705.545 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-11-01T18:08:25.546Z,1541095705.546 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-11-01T18:08:25.648Z,1541095705.648 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-11-01T18:08:25.649Z,1541095705.649 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-11-01T18:08:25.990Z,1541095705.990 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-11-01T18:08:25.991Z,1541095705.991 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-11-01T18:08:26.188Z,1541095706.188 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-11-01T18:08:26.189Z,1541095706.189 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-11-01T18:08:26.399Z,1541095706.399 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-11-01T18:08:26.400Z,1541095706.400 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-11-01T18:08:26.479Z,1541095706.479 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-11-01T18:08:26.829Z,1541095706.829 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-11-01T18:08:26.830Z,1541095706.830 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-11-01T18:08:27.144Z,1541095707.144 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-11-01T18:08:27.146Z,1541095707.146 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2018-11-01T18:08:27.147Z,1541095707.147 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2018-11-01T18:08:27.231Z,1541095707.231 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2018-11-01T18:08:27.380Z,1541095707.380 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2018-11-01T18:08:27.478Z,1541095707.478 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2018-11-01T18:08:27.648Z,1541095707.648 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2018-11-01T18:08:27.729Z,1541095707.729 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2018-11-01T18:08:27.954Z,1541095707.954 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-11-01T18:08:27.954Z,1541095707.954 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2018-11-01T18:08:28.068Z,1541095708.068 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2018-11-01T18:08:28.167Z,1541095708.167 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2018-11-01T18:08:28.261Z,1541095708.261 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2018-11-01T18:08:28.363Z,1541095708.363 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2018-11-01T18:08:28.486Z,1541095708.486 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/ 2018-11-01T18:08:28.486Z,1541095708.486 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2018-11-01T18:08:28.492Z,1541095708.492 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-11-01T18:08:28.536Z,1541095708.536 [DepthRateCalculator] Loaded 2018-11-01T18:08:28.537Z,1541095708.537 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-11-01T18:08:28.542Z,1541095708.542 [PitchRateCalculator] Loaded 2018-11-01T18:08:28.543Z,1541095708.543 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-11-01T18:08:28.599Z,1541095708.599 [SpeedCalculator] Loaded 2018-11-01T18:08:28.599Z,1541095708.599 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-11-01T18:08:28.620Z,1541095708.620 [TempGradientCalculator] Loaded 2018-11-01T18:08:28.620Z,1541095708.620 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-11-01T18:08:28.640Z,1541095708.640 [YawRateCalculator] Loaded 2018-11-01T18:08:28.641Z,1541095708.641 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-11-01T18:08:28.680Z,1541095708.680 [ElevatorOffsetCalculator] Loaded 2018-11-01T18:08:28.681Z,1541095708.681 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-11-01T18:08:28.681Z,1541095708.681 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-11-01T18:08:28.682Z,1541095708.682 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-11-01T18:08:29.190Z,1541095709.190 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-11-01T18:08:29.191Z,1541095709.191 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-11-01T18:08:29.582Z,1541095709.582 [DataOverHttps] Loaded 2018-11-01T18:08:29.582Z,1541095709.582 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-11-01T18:08:29.606Z,1541095709.606 [Depth_Keller] Loaded 2018-11-01T18:08:29.607Z,1541095709.607 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-11-01T18:08:29.612Z,1541095709.612 [DropWeight] Loaded 2018-11-01T18:08:29.612Z,1541095709.612 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-11-01T18:08:29.710Z,1541095709.710 [NAL9602] Loaded 2018-11-01T18:08:29.710Z,1541095709.710 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-11-01T18:08:29.726Z,1541095709.726 [Onboard] Loaded 2018-11-01T18:08:29.727Z,1541095709.727 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-11-01T18:08:29.737Z,1541095709.737 [Radio_Surface] Loaded 2018-11-01T18:08:29.737Z,1541095709.737 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-11-01T18:08:29.738Z,1541095709.738 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407C34E0 2018-11-01T18:08:29.739Z,1541095709.739 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4141 2018-11-01T18:08:29.745Z,1541095709.745 [SCPI] Loaded 2018-11-01T18:08:29.745Z,1541095709.745 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread. 2018-11-01T18:08:31.529Z,1541095711.529 [BPC1] Loaded 2018-11-01T18:08:31.529Z,1541095711.529 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-11-01T18:08:31.529Z,1541095711.529 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-11-01T18:08:31.530Z,1541095711.530 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-11-01T18:08:31.596Z,1541095711.596 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-11-01T18:08:31.597Z,1541095711.597 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-11-01T18:08:31.693Z,1541095711.693 [DeadReckonUsingMultipleVelocitySources] Loaded 2018-11-01T18:08:31.693Z,1541095711.693 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2018-11-01T18:08:31.748Z,1541095711.748 [DeadReckonUsingSpeedCalculator] Loaded 2018-11-01T18:08:31.748Z,1541095711.748 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-11-01T18:08:31.764Z,1541095711.764 [NavChart] Loaded 2018-11-01T18:08:31.765Z,1541095711.765 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-11-01T18:08:31.769Z,1541095711.769 [UniversalFixResidualReporter] Loaded 2018-11-01T18:08:31.769Z,1541095711.769 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-11-01T18:08:31.769Z,1541095711.769 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-11-01T18:08:31.770Z,1541095711.770 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-11-01T18:08:31.997Z,1541095711.997 [CTD_NeilBrown] Loaded 2018-11-01T18:08:31.998Z,1541095711.998 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2018-11-01T18:08:31.999Z,1541095711.999 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408CE4E0 2018-11-01T18:08:31.999Z,1541095711.999 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 4142 2018-11-01T18:08:32.049Z,1541095712.049 [WetLabsBB2FL] Loaded 2018-11-01T18:08:32.049Z,1541095712.049 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-11-01T18:08:32.050Z,1541095712.050 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408FE4E0 2018-11-01T18:08:32.051Z,1541095712.051 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4143 2018-11-01T18:08:32.051Z,1541095712.051 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-11-01T18:08:32.052Z,1541095712.052 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-11-01T18:08:32.122Z,1541095712.122 [VerticalControl](DEBUG): Construct VerticalControl. 2018-11-01T18:08:32.221Z,1541095712.221 [VerticalControl] Loaded 2018-11-01T18:08:32.221Z,1541095712.221 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-11-01T18:08:32.222Z,1541095712.222 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-11-01T18:08:32.291Z,1541095712.291 [HorizontalControl] Loaded 2018-11-01T18:08:32.291Z,1541095712.291 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-11-01T18:08:32.292Z,1541095712.292 [SpeedControl](DEBUG): Construct SpeedControl. 2018-11-01T18:08:32.297Z,1541095712.297 [SpeedControl] Loaded 2018-11-01T18:08:32.297Z,1541095712.297 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-11-01T18:08:32.298Z,1541095712.298 [LoopControl](DEBUG): Construct LoopControl. 2018-11-01T18:08:32.298Z,1541095712.298 [LoopControl] Loaded 2018-11-01T18:08:32.298Z,1541095712.298 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-11-01T18:08:32.299Z,1541095712.299 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-11-01T18:08:32.300Z,1541095712.300 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-11-01T18:08:32.313Z,1541095712.313 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-11-01T18:08:32.313Z,1541095712.313 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-11-01T18:08:32.446Z,1541095712.446 [SBIT](DEBUG): Construct Startup Built In Test. 2018-11-01T18:08:32.457Z,1541095712.457 [SBIT] Loaded 2018-11-01T18:08:32.458Z,1541095712.458 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-11-01T18:08:32.458Z,1541095712.458 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-11-01T18:08:32.470Z,1541095712.470 [IBIT] Loaded 2018-11-01T18:08:32.470Z,1541095712.470 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-11-01T18:08:32.473Z,1541095712.473 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-11-01T18:08:32.623Z,1541095712.623 [CBIT] Loaded 2018-11-01T18:08:32.623Z,1541095712.623 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-11-01T18:08:32.624Z,1541095712.624 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-11-01T18:08:32.624Z,1541095712.624 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-11-01T18:08:32.728Z,1541095712.728 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-11-01T18:08:32.729Z,1541095712.729 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-11-01T18:08:32.754Z,1541095712.754 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-11-01T18:08:32.755Z,1541095712.755 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-11-01T18:08:32.854Z,1541095712.854 [BuoyancyServo] Loaded 2018-11-01T18:08:32.855Z,1541095712.855 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-11-01T18:08:32.866Z,1541095712.866 [ElevatorServo] Loaded 2018-11-01T18:08:32.866Z,1541095712.866 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-11-01T18:08:32.877Z,1541095712.877 [MassServo] Loaded 2018-11-01T18:08:32.877Z,1541095712.877 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-11-01T18:08:32.889Z,1541095712.889 [RudderServo] Loaded 2018-11-01T18:08:32.889Z,1541095712.889 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-11-01T18:08:32.900Z,1541095712.900 [ThrusterServo] Loaded 2018-11-01T18:08:32.900Z,1541095712.900 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-11-01T18:08:32.900Z,1541095712.900 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-11-01T18:08:32.904Z,1541095712.904 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-11-01T18:08:32.905Z,1541095712.905 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-11-01T18:08:32.911Z,1541095712.911 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-11-01T18:08:32.912Z,1541095712.912 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A164E0 2018-11-01T18:08:32.913Z,1541095712.913 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4144 2018-11-01T18:08:32.917Z,1541095712.917 [Supervisor](INFO): Main Thread ID is 4057 2018-11-01T18:08:32.918Z,1541095712.918 [Supervisor](DEBUG): Running supervisor. 2018-11-01T18:08:32.918Z,1541095712.918 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4145 2018-11-01T18:08:32.921Z,1541095712.921 [controlThread ThreadHandler](INFO): Handler Thread ID is 4146 2018-11-01T18:08:32.921Z,1541095712.921 [controlThread](DEBUG): Initializing ControlThread 2018-11-01T18:08:32.922Z,1541095712.922 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-11-01T18:08:32.922Z,1541095712.922 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-11-01T18:08:32.922Z,1541095712.922 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-11-01T18:08:32.923Z,1541095712.923 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-11-01T18:08:32.923Z,1541095712.923 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-11-01T18:08:32.924Z,1541095712.924 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-11-01T18:08:32.930Z,1541095712.930 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-11-01T18:08:32.931Z,1541095712.931 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-11-01T18:08:32.932Z,1541095712.932 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-11-01T18:08:32.932Z,1541095712.932 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-11-01T18:08:32.933Z,1541095712.933 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-11-01T18:08:32.933Z,1541095712.933 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-11-01T18:08:32.933Z,1541095712.933 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-11-01T18:08:32.934Z,1541095712.934 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-11-01T18:08:32.934Z,1541095712.934 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-11-01T18:08:32.936Z,1541095712.936 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-11-01T18:08:32.937Z,1541095712.937 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-11-01T18:08:32.937Z,1541095712.937 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-11-01T18:08:32.937Z,1541095712.937 [SBIT](INFO): Initialize SBIT Component. 2018-11-01T18:08:32.938Z,1541095712.938 [SBIT](IMPORTANT): git: 2018-10-09-48-g4fd666d 2018-11-01T18:08:32.938Z,1541095712.938 [SBIT](INFO): git hash: 4fd666d54b99c906f16fbf4152e9c4493ce57b95 2018-11-01T18:08:32.938Z,1541095712.938 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-11-01T18:08:32.939Z,1541095712.939 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2018-11-01T18:08:32.940Z,1541095712.940 [SBIT](INFO): Beginning SBIT in 28.000000 seconds. 2018-11-01T18:08:32.940Z,1541095712.940 [IBIT](INFO): Initialize IBIT Component. 2018-11-01T18:08:32.941Z,1541095712.941 [CBIT](DEBUG): Initialize CBIT Component. 2018-11-01T18:08:32.941Z,1541095712.941 [CBIT](CRITICAL): Watchdog Timer failed to initialize. 2018-11-01T18:08:32.941Z,1541095712.941 [CBIT] Hardware Fault, FailCount= 1 2018-11-01T18:08:32.941Z,1541095712.941 [CBIT](ERROR): Hardware Fault 2018-11-01T18:08:32.942Z,1541095712.942 [logger ThreadHandler](INFO): Handler Thread ID is 4147 2018-11-01T18:08:32.960Z,1541095712.960 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4148 2018-11-01T18:08:32.966Z,1541095712.966 [Radio_Surface](INFO): Powering up 2018-11-01T18:08:32.972Z,1541095712.972 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 4149 2018-11-01T18:08:32.984Z,1541095712.984 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4150 2018-11-01T18:08:32.985Z,1541095712.985 [WetLabsBB2FL](INFO): Powering down 2018-11-01T18:08:33.012Z,1541095713.012 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4151 2018-11-01T18:08:33.015Z,1541095713.015 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-11-01T18:08:33.015Z,1541095713.015 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-11-01T18:08:33.016Z,1541095713.016 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-11-01T18:08:33.016Z,1541095713.016 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-11-01T18:08:33.016Z,1541095713.016 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-11-01T18:08:33.016Z,1541095713.016 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-11-01T18:08:33.016Z,1541095713.016 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-11-01T18:08:33.017Z,1541095713.017 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-11-01T18:08:33.017Z,1541095713.017 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-11-01T18:08:33.017Z,1541095713.017 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-11-01T18:08:33.017Z,1541095713.017 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-11-01T18:08:33.017Z,1541095713.017 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-11-01T18:08:33.018Z,1541095713.018 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-11-01T18:08:33.018Z,1541095713.018 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-11-01T18:08:33.018Z,1541095713.018 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-11-01T18:08:33.018Z,1541095713.018 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-11-01T18:08:33.049Z,1541095713.049 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-11-01T18:08:33.060Z,1541095713.060 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-11-01T18:08:33.071Z,1541095713.071 [MissionManager](DEBUG): 2018-11-01T18:08:33.088Z,1541095713.088 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-11-01T18:08:33.168Z,1541095713.168 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-11-01T18:08:33.169Z,1541095713.169 [Default:A.Wait](DEBUG): Construct Wait. 2018-11-01T18:08:33.171Z,1541095713.171 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-11-01T18:08:33.221Z,1541095713.221 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-11-01T18:08:33.224Z,1541095713.224 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-11-01T18:08:33.245Z,1541095713.245 [Default:E.Execute](DEBUG): Construct Execute. 2018-11-01T18:08:33.249Z,1541095713.249 [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 2018-11-01T18:08:33.254Z,1541095713.254 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,SCPI,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, 2018-11-01T18:08:33.278Z,1541095713.278 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-11-01T18:08:33.303Z,1541095713.303 [Depth_Keller](ERROR): Pressure reading out of range: -14.548559 decibar 2018-11-01T18:08:33.442Z,1541095713.442 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:33.684Z,1541095713.684 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-11-01T18:08:33.692Z,1541095713.692 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-11-01T18:08:33.702Z,1541095713.702 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-11-01T18:08:33.708Z,1541095713.708 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-11-01T18:08:33.714Z,1541095713.714 [MassServo](DEBUG): Initializing EZServoServo. 2018-11-01T18:08:33.720Z,1541095713.720 [MassServo](DEBUG): Initializing MassServo. 2018-11-01T18:08:33.726Z,1541095713.726 [RudderServo](DEBUG): Initializing EZServoServo. 2018-11-01T18:08:33.732Z,1541095713.732 [RudderServo](DEBUG): Initializing RudderServo. 2018-11-01T18:08:33.739Z,1541095713.739 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-11-01T18:08:33.744Z,1541095713.744 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-11-01T18:08:33.790Z,1541095713.790 [CBIT](DEBUG): Uninitialize CBIT Component. 2018-11-01T18:08:33.893Z,1541095713.893 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2018-11-01T18:08:33.893Z,1541095713.893 [DropWeight] Hardware Fault, FailCount= 1 2018-11-01T18:08:33.893Z,1541095713.893 [DropWeight](ERROR): Hardware Fault 2018-11-01T18:08:33.936Z,1541095713.936 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:34.232Z,1541095714.232 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:34.563Z,1541095714.563 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-11-01T18:08:34.564Z,1541095714.564 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-11-01T18:08:34.564Z,1541095714.564 [BuoyancyServo] Communications Fault, FailCount= 1 2018-11-01T18:08:34.564Z,1541095714.564 [BuoyancyServo](ERROR): Communications Fault 2018-11-01T18:08:34.875Z,1541095714.875 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout 2018-11-01T18:08:34.876Z,1541095714.876 [ElevatorServo](FAULT): Elevator failed to initialize 2018-11-01T18:08:34.876Z,1541095714.876 [ElevatorServo] Communications Fault, FailCount= 1 2018-11-01T18:08:34.876Z,1541095714.876 [ElevatorServo](ERROR): Communications Fault 2018-11-01T18:08:35.179Z,1541095715.179 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2018-11-01T18:08:35.180Z,1541095715.180 [RudderServo](FAULT): Rudder failed to initialize 2018-11-01T18:08:35.180Z,1541095715.180 [RudderServo] Communications Fault, FailCount= 1 2018-11-01T18:08:35.180Z,1541095715.180 [RudderServo](ERROR): Communications Fault 2018-11-01T18:08:35.503Z,1541095715.503 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2018-11-01T18:08:35.827Z,1541095715.827 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout 2018-11-01T18:08:35.828Z,1541095715.828 [ThrusterServo](FAULT): Thruster failed to initialize 2018-11-01T18:08:35.828Z,1541095715.828 [ThrusterServo] Communications Fault, FailCount= 1 2018-11-01T18:08:35.828Z,1541095715.828 [ThrusterServo](ERROR): Communications Fault 2018-11-01T18:08:35.864Z,1541095715.864 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:35.888Z,1541095715.888 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-11-01T18:08:35.889Z,1541095715.889 [BuoyancyServo](INFO): Powering down 2018-11-01T18:08:35.968Z,1541095715.968 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-11-01T18:08:35.968Z,1541095715.968 [ElevatorServo](INFO): Powering down 2018-11-01T18:08:36.331Z,1541095716.331 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2018-11-01T18:08:36.332Z,1541095716.332 [MassServo] Communications Fault, FailCount= 1 2018-11-01T18:08:36.332Z,1541095716.332 [MassServo](ERROR): Communications Fault 2018-11-01T18:08:36.332Z,1541095716.332 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-11-01T18:08:36.332Z,1541095716.332 [RudderServo](INFO): Powering down 2018-11-01T18:08:36.376Z,1541095716.376 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-11-01T18:08:36.376Z,1541095716.376 [ThrusterServo](INFO): Powering down 2018-11-01T18:08:36.496Z,1541095716.496 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:36.521Z,1541095716.521 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-11-01T18:08:36.522Z,1541095716.522 [MassServo](INFO): Powering down 2018-11-01T18:08:36.852Z,1541095716.852 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:36.877Z,1541095716.877 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-11-01T18:08:36.996Z,1541095716.996 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-11-01T18:08:36.998Z,1541095716.998 [RudderServo](DEBUG): Initializing EZServoServo. 2018-11-01T18:08:37.116Z,1541095717.116 [RudderServo](DEBUG): Initializing RudderServo. 2018-11-01T18:08:37.296Z,1541095717.296 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:37.313Z,1541095717.313 [MassServo](DEBUG): Initializing EZServoServo. 2018-11-01T18:08:37.432Z,1541095717.432 [MassServo](DEBUG): Initializing MassServo. 2018-11-01T18:08:37.668Z,1541095717.668 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:38.064Z,1541095718.064 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:38.468Z,1541095718.468 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:38.864Z,1541095718.864 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:39.268Z,1541095719.268 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:39.664Z,1541095719.664 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:40.068Z,1541095720.068 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:40.468Z,1541095720.468 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:40.868Z,1541095720.868 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:41.276Z,1541095721.276 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:41.668Z,1541095721.668 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:42.064Z,1541095722.064 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:42.468Z,1541095722.468 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:42.875Z,1541095722.875 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:43.268Z,1541095723.268 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:43.668Z,1541095723.668 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:44.068Z,1541095724.068 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:44.464Z,1541095724.464 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:44.868Z,1541095724.868 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:45.268Z,1541095725.268 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:45.668Z,1541095725.668 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:46.068Z,1541095726.068 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:46.468Z,1541095726.468 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:46.868Z,1541095726.868 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:47.268Z,1541095727.268 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:47.664Z,1541095727.664 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:48.068Z,1541095728.068 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:48.468Z,1541095728.468 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:48.868Z,1541095728.868 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:49.264Z,1541095729.264 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:49.672Z,1541095729.672 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:50.068Z,1541095730.068 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:50.468Z,1541095730.468 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:50.868Z,1541095730.868 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:51.268Z,1541095731.268 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:51.666Z,1541095731.666 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:52.068Z,1541095732.068 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:52.464Z,1541095732.464 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:52.868Z,1541095732.868 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:53.268Z,1541095733.268 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:53.676Z,1541095733.676 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:54.064Z,1541095734.064 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:54.468Z,1541095734.468 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:54.868Z,1541095734.868 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:55.268Z,1541095735.268 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:55.664Z,1541095735.664 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:56.064Z,1541095736.064 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:56.464Z,1541095736.464 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:56.868Z,1541095736.868 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:57.268Z,1541095737.268 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:57.664Z,1541095737.664 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:58.064Z,1541095738.064 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:58.464Z,1541095738.464 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:58.864Z,1541095738.864 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:59.269Z,1541095739.269 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:08:59.668Z,1541095739.668 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:00.048Z,1541095740.048 [NAL9602](INFO): Powering up NAL9602 2018-11-01T18:09:00.068Z,1541095740.068 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:00.464Z,1541095740.464 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:00.868Z,1541095740.868 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:01.264Z,1541095741.264 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:01.664Z,1541095741.664 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:01.673Z,1541095741.673 [SBIT](IMPORTANT): Beginning Startup BIT 2018-11-01T18:09:02.064Z,1541095742.064 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:02.468Z,1541095742.468 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:02.864Z,1541095742.864 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:03.268Z,1541095743.268 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:03.664Z,1541095743.664 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:04.072Z,1541095744.072 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:04.468Z,1541095744.468 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:04.872Z,1541095744.872 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:05.268Z,1541095745.268 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:05.672Z,1541095745.672 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:06.068Z,1541095746.068 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:06.472Z,1541095746.472 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:06.869Z,1541095746.869 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:07.272Z,1541095747.272 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:07.668Z,1541095747.668 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:07.852Z,1541095747.852 [CommandLine](IMPORTANT): got command failComponent 2018-11-01T18:09:07.852Z,1541095747.852 [CommandLine](IMPORTANT): Failed components: 2018-11-01T18:09:07.853Z,1541095747.853 [CommandLine](IMPORTANT): DropWeight: Hardware Fault 2018-11-01T18:09:07.853Z,1541095747.853 [CommandLine](IMPORTANT): CBIT: Hardware Fault 2018-11-01T18:09:07.853Z,1541095747.853 [CommandLine](IMPORTANT): BuoyancyServo: Communications Fault 2018-11-01T18:09:07.853Z,1541095747.853 [CommandLine](IMPORTANT): ElevatorServo: Communications Fault 2018-11-01T18:09:07.854Z,1541095747.854 [CommandLine](IMPORTANT): MassServo: Communications Fault 2018-11-01T18:09:07.854Z,1541095747.854 [CommandLine](IMPORTANT): RudderServo: Communications Fault 2018-11-01T18:09:07.854Z,1541095747.854 [CommandLine](IMPORTANT): ThrusterServo: Communications Fault 2018-11-01T18:09:08.075Z,1541095748.075 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:08.464Z,1541095748.464 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:08.864Z,1541095748.864 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:09.264Z,1541095749.264 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:09.675Z,1541095749.675 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:10.064Z,1541095750.064 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:10.475Z,1541095750.475 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:10.884Z,1541095750.884 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:11.275Z,1541095751.275 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:11.692Z,1541095751.692 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:12.076Z,1541095752.076 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:12.464Z,1541095752.464 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:12.896Z,1541095752.896 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:13.274Z,1541095753.274 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:13.674Z,1541095753.674 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:14.074Z,1541095754.074 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:14.474Z,1541095754.474 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:14.873Z,1541095754.873 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:14.883Z,1541095754.883 [SBIT](ERROR): Could not read elevatorAngleReader_. 2018-11-01T18:09:14.883Z,1541095754.883 [SBIT](ERROR): Could not read rudderAngleReader_. 2018-11-01T18:09:14.884Z,1541095754.884 [SBIT](ERROR): Could not read massPosReader_. 2018-11-01T18:09:15.275Z,1541095755.275 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:15.673Z,1541095755.673 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:16.074Z,1541095756.074 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:16.475Z,1541095756.475 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:16.874Z,1541095756.874 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:17.273Z,1541095757.273 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:17.675Z,1541095757.675 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:18.073Z,1541095758.073 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:18.475Z,1541095758.475 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:18.875Z,1541095758.875 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:19.264Z,1541095759.264 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:19.674Z,1541095759.674 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:20.076Z,1541095760.076 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:20.475Z,1541095760.475 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:20.875Z,1541095760.875 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:21.248Z,1541095761.248 [NAL9602](ERROR): NAL9602 initialization error. 2018-11-01T18:09:21.249Z,1541095761.249 [NAL9602] Communications Fault, FailCount= 1 2018-11-01T18:09:21.249Z,1541095761.249 [NAL9602](ERROR): Communications Fault 2018-11-01T18:09:21.286Z,1541095761.286 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:21.648Z,1541095761.648 [NAL9602](INFO): Powering down 2018-11-01T18:09:21.685Z,1541095761.685 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:22.064Z,1541095762.064 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:22.468Z,1541095762.468 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:22.864Z,1541095762.864 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:23.264Z,1541095763.264 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:23.664Z,1541095763.664 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:24.076Z,1541095764.076 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:24.468Z,1541095764.468 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:24.868Z,1541095764.868 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:25.268Z,1541095765.268 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:25.661Z,1541095765.661 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:26.068Z,1541095766.068 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:26.468Z,1541095766.468 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:26.867Z,1541095766.867 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:27.264Z,1541095767.264 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:27.668Z,1541095767.668 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:28.061Z,1541095768.061 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:28.472Z,1541095768.472 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:28.864Z,1541095768.864 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:29.268Z,1541095769.268 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:29.668Z,1541095769.668 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:30.064Z,1541095770.064 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:30.464Z,1541095770.464 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:30.864Z,1541095770.864 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:31.268Z,1541095771.268 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:31.661Z,1541095771.661 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:32.068Z,1541095772.068 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:32.468Z,1541095772.468 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:32.864Z,1541095772.864 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:33.267Z,1541095773.267 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:33.664Z,1541095773.664 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:34.096Z,1541095774.096 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:34.496Z,1541095774.496 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:34.872Z,1541095774.872 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:35.268Z,1541095775.268 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:35.665Z,1541095775.665 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:36.072Z,1541095776.072 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:36.469Z,1541095776.469 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:36.872Z,1541095776.872 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:37.272Z,1541095777.272 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:37.669Z,1541095777.669 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:38.073Z,1541095778.073 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:38.472Z,1541095778.472 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:38.868Z,1541095778.868 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:39.276Z,1541095779.276 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:39.672Z,1541095779.672 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:40.091Z,1541095780.091 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:40.516Z,1541095780.516 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:40.916Z,1541095780.916 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:41.296Z,1541095781.296 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:41.306Z,1541095781.306 [SBIT](ERROR): Could not read elevatorAngleReader_. 2018-11-01T18:09:41.306Z,1541095781.306 [SBIT](ERROR): Could not read rudderAngleReader_. 2018-11-01T18:09:41.306Z,1541095781.306 [SBIT](ERROR): Could not read massPosReader_. 2018-11-01T18:09:41.306Z,1541095781.306 [SBIT](FAULT): Control surface position failure. 2018-11-01T18:09:41.690Z,1541095781.690 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:42.068Z,1541095782.068 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:42.464Z,1541095782.464 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:42.868Z,1541095782.868 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:43.272Z,1541095783.272 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:43.668Z,1541095783.668 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:44.062Z,1541095784.062 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:44.468Z,1541095784.468 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:44.869Z,1541095784.869 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:45.264Z,1541095785.264 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:45.668Z,1541095785.668 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:46.064Z,1541095786.064 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:46.464Z,1541095786.464 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:46.868Z,1541095786.868 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:47.272Z,1541095787.272 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:47.662Z,1541095787.662 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:48.072Z,1541095788.072 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:48.465Z,1541095788.465 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:48.792Z,1541095788.792 [CommandLine](IMPORTANT): got command quit 2018-11-01T18:09:48.872Z,1541095788.872 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:49.264Z,1541095789.264 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:49.668Z,1541095789.668 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:49.795Z,1541095789.795 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2018-11-01T18:09:49.798Z,1541095789.798 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-01T18:09:49.799Z,1541095789.799 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-11-01T18:09:49.885Z,1541095789.885 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2018-11-01T18:09:49.885Z,1541095789.885 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-11-01T18:09:49.886Z,1541095789.886 [CommandLine](INFO): Join timeout helper Thread ID is 4161 2018-11-01T18:09:49.886Z,1541095789.886 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2018-11-01T18:09:49.886Z,1541095789.886 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-11-01T18:09:49.887Z,1541095789.887 [NavChartDb](INFO): Join timeout helper Thread ID is 4162 2018-11-01T18:09:50.068Z,1541095790.068 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:50.183Z,1541095790.183 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-01T18:09:50.184Z,1541095790.184 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-11-01T18:09:50.191Z,1541095790.191 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2018-11-01T18:09:50.192Z,1541095790.192 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-11-01T18:09:50.192Z,1541095790.192 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4163 2018-11-01T18:09:50.472Z,1541095790.472 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:50.676Z,1541095790.676 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-01T18:09:50.676Z,1541095790.676 [WetLabsBB2FL](INFO): Powering down 2018-11-01T18:09:50.677Z,1541095790.677 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-11-01T18:09:50.695Z,1541095790.695 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2018-11-01T18:09:50.696Z,1541095790.696 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2018-11-01T18:09:50.696Z,1541095790.696 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 4164 2018-11-01T18:09:50.868Z,1541095790.868 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-11-01T18:09:50.892Z,1541095790.892 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-01T18:09:50.892Z,1541095790.892 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2018-11-01T18:09:50.895Z,1541095790.895 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2018-11-01T18:09:50.896Z,1541095790.896 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-11-01T18:09:50.896Z,1541095790.896 [Radio_Surface](INFO): Join timeout helper Thread ID is 4165 2018-11-01T18:09:51.012Z,1541095791.012 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-01T18:09:51.012Z,1541095791.012 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-11-01T18:09:51.024Z,1541095791.024 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2018-11-01T18:09:51.024Z,1541095791.024 [logger ThreadHandler](INFO): Thread cancelled. 2018-11-01T18:09:51.025Z,1541095791.025 [logger](INFO): Join timeout helper Thread ID is 4166 2018-11-01T18:09:51.031Z,1541095791.031 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-01T18:09:51.032Z,1541095791.032 [logger ThreadHandler](INFO): Thread cancelled. 2018-11-01T18:09:51.044Z,1541095791.044 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2018-11-01T18:09:51.044Z,1541095791.044 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-11-01T18:09:51.044Z,1541095791.044 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2018-11-01T18:09:51.044Z,1541095791.044 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-11-01T18:09:51.045Z,1541095791.045 [controlThread](INFO): Join timeout helper Thread ID is 4167 2018-11-01T18:09:51.240Z,1541095791.240 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2018-11-01T18:09:51.240Z,1541095791.240 [controlThread](DEBUG): Uninitializing ControlThread 2018-11-01T18:09:51.241Z,1541095791.241 [NAL9602](INFO): Powering down 2018-11-01T18:09:51.243Z,1541095791.243 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-11-01T18:09:51.244Z,1541095791.244 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2018-11-01T18:09:51.244Z,1541095791.244 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2018-11-01T18:09:51.245Z,1541095791.245 [MissionManager](INFO): Uninitializing Mission Default 2018-11-01T18:09:51.247Z,1541095791.247 [MissionManager](INFO): Uninitializing Mission Startup 2018-11-01T18:09:51.249Z,1541095791.249 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2018-11-01T18:09:51.249Z,1541095791.249 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2018-11-01T18:09:51.249Z,1541095791.249 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2018-11-01T18:09:51.249Z,1541095791.249 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2018-11-01T18:09:51.249Z,1541095791.249 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-11-01T18:09:51.250Z,1541095791.250 [BuoyancyServo](INFO): Powering down 2018-11-01T18:09:51.263Z,1541095791.263 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-11-01T18:09:51.264Z,1541095791.264 [ElevatorServo](INFO): Powering down 2018-11-01T18:09:51.264Z,1541095791.264 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-11-01T18:09:51.265Z,1541095791.265 [MassServo](INFO): Powering down 2018-11-01T18:09:51.265Z,1541095791.265 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-11-01T18:09:51.265Z,1541095791.265 [RudderServo](INFO): Powering down 2018-11-01T18:09:51.266Z,1541095791.266 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-11-01T18:09:51.266Z,1541095791.266 [ThrusterServo](INFO): Powering down 2018-11-01T18:09:51.267Z,1541095791.267 [SBIT](DEBUG): Uninitialize SBIT Component. 2018-11-01T18:09:51.268Z,1541095791.268 [IBIT](DEBUG): Uninitialize IBIT Component. 2018-11-01T18:09:51.268Z,1541095791.268 [CBIT](DEBUG): Uninitialize CBIT Component. 2018-11-01T18:09:51.282Z,1541095791.282 [CBIT](CRITICAL): run-time exception #7 2018-11-01T18:09:51.282Z,1541095791.282 [CBIT](CRITICAL): Bad address: 0x4041E004 2018-11-01T18:09:51.283Z,1541095791.283 [CBIT](CRITICAL): Backtrace: ./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x169f00] 2018-11-01T18:09:51.283Z,1541095791.283 [CBIT] Software Fault, FailCount= 1 2018-11-01T18:09:51.283Z,1541095791.283 [CBIT](ERROR): Software Fault 2018-11-01T18:09:51.284Z,1541095791.284 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-11-01T18:09:51.285Z,1541095791.285 [SyncHandler](INFO): Protected caller Thread ID is 4168 2018-11-01T18:09:51.285Z,1541095791.285 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-11-01T18:09:51.349Z,1541095791.349 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-11-01T18:09:51.385Z,1541095791.385 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-11-01T18:09:51.387Z,1541095791.387 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2018-11-01T18:09:51.789Z,1541095791.789 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-11-01T18:09:51.842Z,1541095791.842 [logger ThreadHandler](INFO): Thread cancelled.