2019-10-17T17:16:06.770Z,1571332566.770 [Supervisor](DEBUG): Initializing supervisor. 2019-10-17T17:16:06.773Z,1571332566.773 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-10-17T17:16:06.774Z,1571332566.774 [SyncHandler](INFO): Protected caller Thread ID is 2626 2019-10-17T17:16:06.774Z,1571332566.774 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-10-17T17:16:06.775Z,1571332566.775 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-10-17T17:16:06.775Z,1571332566.775 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2627 2019-10-17T17:16:06.778Z,1571332566.778 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-10-17T17:16:06.789Z,1571332566.789 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-10-17T17:16:06.790Z,1571332566.790 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-10-17T17:16:06.790Z,1571332566.790 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2628 2019-10-17T17:16:06.791Z,1571332566.791 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-10-17T17:16:06.792Z,1571332566.792 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-10-17T17:16:06.792Z,1571332566.792 [logger ThreadHandler](INFO): Protected caller Thread ID is 2629 2019-10-17T17:16:06.794Z,1571332566.794 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-10-17T17:16:06.795Z,1571332566.795 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-10-17T17:16:06.796Z,1571332566.796 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-10-17T17:16:06.989Z,1571332566.989 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-10-17T17:16:06.990Z,1571332566.990 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-10-17T17:16:07.067Z,1571332567.067 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-10-17T17:16:07.490Z,1571332567.490 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-10-17T17:16:07.490Z,1571332567.490 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-10-17T17:16:07.806Z,1571332567.806 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-10-17T17:16:07.807Z,1571332567.807 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-10-17T17:16:07.900Z,1571332567.900 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-10-17T17:16:07.900Z,1571332567.900 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-10-17T17:16:08.188Z,1571332568.188 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-10-17T17:16:08.188Z,1571332568.188 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-10-17T17:16:08.376Z,1571332568.376 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-10-17T17:16:08.377Z,1571332568.377 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-10-17T17:16:08.817Z,1571332568.817 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-10-17T17:16:08.817Z,1571332568.817 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-10-17T17:16:08.919Z,1571332568.919 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-10-17T17:16:08.919Z,1571332568.919 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-10-17T17:16:09.017Z,1571332569.017 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-10-17T17:16:09.017Z,1571332569.017 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-10-17T17:16:09.601Z,1571332569.601 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-10-17T17:16:09.602Z,1571332569.602 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-10-17T17:16:09.988Z,1571332569.988 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-10-17T17:16:09.988Z,1571332569.988 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-10-17T17:16:10.176Z,1571332570.176 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-10-17T17:16:10.176Z,1571332570.176 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-10-17T17:16:10.328Z,1571332570.328 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-10-17T17:16:10.329Z,1571332570.329 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-10-17T17:16:10.474Z,1571332570.474 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-10-17T17:16:10.476Z,1571332570.476 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2019-10-17T17:16:10.477Z,1571332570.477 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2019-10-17T17:16:10.566Z,1571332570.566 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2019-10-17T17:16:10.650Z,1571332570.650 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2019-10-17T17:16:10.750Z,1571332570.750 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2019-10-17T17:16:10.831Z,1571332570.831 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2019-10-17T17:16:10.925Z,1571332570.925 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2019-10-17T17:16:11.022Z,1571332571.022 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2019-10-17T17:16:11.226Z,1571332571.226 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2019-10-17T17:16:11.300Z,1571332571.300 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2019-10-17T17:16:11.462Z,1571332571.462 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2019-10-17T17:16:11.627Z,1571332571.627 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2019-10-17T17:16:12.097Z,1571332572.097 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2019-10-17T17:16:12.839Z,1571332572.839 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-10-17T17:16:12.928Z,1571332572.928 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-10-17T17:16:12.934Z,1571332572.934 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-10-17T17:16:13.004Z,1571332573.004 [VerticalControl](DEBUG): Construct VerticalControl. 2019-10-17T17:16:13.118Z,1571332573.118 [VerticalControl] Loaded 2019-10-17T17:16:13.118Z,1571332573.118 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-10-17T17:16:13.119Z,1571332573.119 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-10-17T17:16:13.188Z,1571332573.188 [HorizontalControl] Loaded 2019-10-17T17:16:13.188Z,1571332573.188 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-10-17T17:16:13.189Z,1571332573.189 [SpeedControl](DEBUG): Construct SpeedControl. 2019-10-17T17:16:13.195Z,1571332573.195 [SpeedControl] Loaded 2019-10-17T17:16:13.195Z,1571332573.195 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-10-17T17:16:13.196Z,1571332573.196 [LoopControl](DEBUG): Construct LoopControl. 2019-10-17T17:16:13.196Z,1571332573.196 [LoopControl] Loaded 2019-10-17T17:16:13.197Z,1571332573.197 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-10-17T17:16:13.197Z,1571332573.197 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-10-17T17:16:13.198Z,1571332573.197 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-10-17T17:16:13.211Z,1571332573.211 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-10-17T17:16:13.211Z,1571332573.211 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-10-17T17:16:13.313Z,1571332573.313 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-10-17T17:16:13.314Z,1571332573.314 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-10-17T17:16:13.417Z,1571332573.417 [BuoyancyServo] Loaded 2019-10-17T17:16:13.418Z,1571332573.418 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-10-17T17:16:13.429Z,1571332573.429 [ElevatorServo] Loaded 2019-10-17T17:16:13.429Z,1571332573.429 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-10-17T17:16:13.440Z,1571332573.440 [MassServo] Loaded 2019-10-17T17:16:13.440Z,1571332573.440 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-10-17T17:16:13.451Z,1571332573.451 [RudderServo] Loaded 2019-10-17T17:16:13.452Z,1571332573.452 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-10-17T17:16:13.463Z,1571332573.463 [ThrusterServo] Loaded 2019-10-17T17:16:13.463Z,1571332573.463 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-10-17T17:16:13.463Z,1571332573.463 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-10-17T17:16:13.464Z,1571332573.464 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-10-17T17:16:13.573Z,1571332573.573 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-10-17T17:16:13.573Z,1571332573.573 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-10-17T17:16:13.594Z,1571332573.594 [NavChart] Loaded 2019-10-17T17:16:13.594Z,1571332573.594 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-10-17T17:16:13.598Z,1571332573.598 [UniversalFixResidualReporter] Loaded 2019-10-17T17:16:13.598Z,1571332573.598 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-10-17T17:16:13.599Z,1571332573.599 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-10-17T17:16:13.599Z,1571332573.599 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-10-17T17:16:13.672Z,1571332573.672 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-10-17T17:16:13.673Z,1571332573.673 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-10-17T17:16:14.010Z,1571332574.010 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2019-10-17T17:16:14.016Z,1571332574.016 [AHRS_M2](INFO): created writer for : platform_orientation 2019-10-17T17:16:14.018Z,1571332574.018 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2019-10-17T17:16:14.024Z,1571332574.024 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2019-10-17T17:16:14.024Z,1571332574.024 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2019-10-17T17:16:14.029Z,1571332574.029 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2019-10-17T17:16:14.030Z,1571332574.030 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2019-10-17T17:16:14.035Z,1571332574.035 [AHRS_M2](INFO): created writer for : platform_roll_angle 2019-10-17T17:16:14.107Z,1571332574.107 [AHRS_M2] Loaded 2019-10-17T17:16:14.107Z,1571332574.107 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2019-10-17T17:16:14.184Z,1571332574.184 [DataOverHttps] Loaded 2019-10-17T17:16:14.184Z,1571332574.184 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-10-17T17:16:14.186Z,1571332574.186 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087D4E0 2019-10-17T17:16:14.186Z,1571332574.186 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2713 2019-10-17T17:16:14.199Z,1571332574.199 [Depth_Keller] Loaded 2019-10-17T17:16:14.200Z,1571332574.200 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-10-17T17:16:14.205Z,1571332574.205 [DropWeight] Loaded 2019-10-17T17:16:14.205Z,1571332574.205 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-10-17T17:16:14.303Z,1571332574.303 [NAL9602] Loaded 2019-10-17T17:16:14.304Z,1571332574.304 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-10-17T17:16:14.319Z,1571332574.319 [Onboard] Loaded 2019-10-17T17:16:14.319Z,1571332574.319 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-10-17T17:16:14.323Z,1571332574.323 [Radio_Surface] Loaded 2019-10-17T17:16:14.323Z,1571332574.323 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-10-17T17:16:14.324Z,1571332574.324 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408AD4E0 2019-10-17T17:16:14.324Z,1571332574.324 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2714 2019-10-17T17:16:15.860Z,1571332575.860 [BPC1] Loaded 2019-10-17T17:16:15.860Z,1571332575.860 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-10-17T17:16:15.861Z,1571332575.861 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-10-17T17:16:15.861Z,1571332575.861 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-10-17T17:16:15.904Z,1571332575.904 [DepthRateCalculator] Loaded 2019-10-17T17:16:15.904Z,1571332575.904 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-10-17T17:16:15.909Z,1571332575.909 [PitchRateCalculator] Loaded 2019-10-17T17:16:15.909Z,1571332575.909 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-10-17T17:16:15.921Z,1571332575.921 [SpeedCalculator] Loaded 2019-10-17T17:16:15.921Z,1571332575.921 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-10-17T17:16:15.943Z,1571332575.943 [TempGradientCalculator] Loaded 2019-10-17T17:16:15.943Z,1571332575.943 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-10-17T17:16:15.948Z,1571332575.948 [YawRateCalculator] Loaded 2019-10-17T17:16:15.949Z,1571332575.949 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-10-17T17:16:15.979Z,1571332575.979 [ElevatorOffsetCalculator] Loaded 2019-10-17T17:16:15.979Z,1571332575.979 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-10-17T17:16:15.980Z,1571332575.980 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-10-17T17:16:15.980Z,1571332575.980 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-10-17T17:16:16.169Z,1571332576.169 [Aanderaa_O2] Loaded 2019-10-17T17:16:16.169Z,1571332576.169 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2019-10-17T17:16:16.179Z,1571332576.179 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2019-10-17T17:16:16.184Z,1571332576.184 [CTD_Seabird](INFO): created writer for : sea_water_density 2019-10-17T17:16:16.184Z,1571332576.184 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2019-10-17T17:16:16.190Z,1571332576.190 [CTD_Seabird](INFO): created writer for : depth 2019-10-17T17:16:16.190Z,1571332576.190 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2019-10-17T17:16:16.195Z,1571332576.195 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2019-10-17T17:16:16.196Z,1571332576.196 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2019-10-17T17:16:16.201Z,1571332576.201 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2019-10-17T17:16:16.202Z,1571332576.202 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2019-10-17T17:16:16.207Z,1571332576.207 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2019-10-17T17:16:16.208Z,1571332576.208 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2019-10-17T17:16:16.213Z,1571332576.213 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2019-10-17T17:16:16.214Z,1571332576.214 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2019-10-17T17:16:16.219Z,1571332576.219 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2019-10-17T17:16:16.246Z,1571332576.246 [CTD_Seabird] Loaded 2019-10-17T17:16:16.247Z,1571332576.247 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2019-10-17T17:16:16.248Z,1571332576.248 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409B04E0 2019-10-17T17:16:16.248Z,1571332576.248 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2715 2019-10-17T17:16:16.262Z,1571332576.262 [PAR_Licor] Loaded 2019-10-17T17:16:16.263Z,1571332576.263 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2019-10-17T17:16:16.311Z,1571332576.311 [WetLabsBB2FL] Loaded 2019-10-17T17:16:16.311Z,1571332576.311 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-10-17T17:16:16.312Z,1571332576.312 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409E04E0 2019-10-17T17:16:16.313Z,1571332576.313 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2716 2019-10-17T17:16:16.313Z,1571332576.313 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-10-17T17:16:16.314Z,1571332576.314 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-10-17T17:16:16.348Z,1571332576.348 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-10-17T17:16:16.348Z,1571332576.348 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-10-17T17:16:16.649Z,1571332576.649 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-10-17T17:16:16.649Z,1571332576.649 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-10-17T17:16:16.931Z,1571332576.931 [SBIT](DEBUG): Construct Startup Built In Test. 2019-10-17T17:16:16.942Z,1571332576.942 [SBIT] Loaded 2019-10-17T17:16:16.942Z,1571332576.942 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-10-17T17:16:16.943Z,1571332576.943 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-10-17T17:16:16.955Z,1571332576.955 [IBIT] Loaded 2019-10-17T17:16:16.955Z,1571332576.955 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-10-17T17:16:16.958Z,1571332576.958 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-10-17T17:16:17.097Z,1571332577.097 [CBIT] Loaded 2019-10-17T17:16:17.097Z,1571332577.097 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-10-17T17:16:17.097Z,1571332577.097 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-10-17T17:16:17.101Z,1571332577.101 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-10-17T17:16:17.102Z,1571332577.102 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-10-17T17:16:17.108Z,1571332577.108 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-10-17T17:16:17.109Z,1571332577.109 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ADF4E0 2019-10-17T17:16:17.110Z,1571332577.110 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2717 2019-10-17T17:16:17.114Z,1571332577.114 [Supervisor](INFO): Main Thread ID is 2625 2019-10-17T17:16:17.115Z,1571332577.115 [Supervisor](DEBUG): Running supervisor. 2019-10-17T17:16:17.115Z,1571332577.115 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2718 2019-10-17T17:16:17.118Z,1571332577.118 [controlThread ThreadHandler](INFO): Handler Thread ID is 2719 2019-10-17T17:16:17.118Z,1571332577.118 [controlThread](DEBUG): Initializing ControlThread 2019-10-17T17:16:17.119Z,1571332577.119 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-10-17T17:16:17.120Z,1571332577.120 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-10-17T17:16:17.121Z,1571332577.121 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-10-17T17:16:17.122Z,1571332577.122 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-10-17T17:16:17.123Z,1571332577.123 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-10-17T17:16:17.124Z,1571332577.124 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-10-17T17:16:17.127Z,1571332577.127 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-10-17T17:16:17.127Z,1571332577.127 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-10-17T17:16:17.128Z,1571332577.128 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-10-17T17:16:17.128Z,1571332577.128 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-10-17T17:16:17.128Z,1571332577.128 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-10-17T17:16:17.129Z,1571332577.129 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-10-17T17:16:17.133Z,1571332577.133 [SBIT](INFO): Initialize SBIT Component. 2019-10-17T17:16:17.134Z,1571332577.134 [SBIT](IMPORTANT): git: 2019-09-10 2019-10-17T17:16:17.134Z,1571332577.134 [SBIT](INFO): git hash: e950883795b57abbf97d89589ea0268241928c93 2019-10-17T17:16:17.134Z,1571332577.134 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-10-17T17:16:17.135Z,1571332577.135 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018 2019-10-17T17:16:17.136Z,1571332577.136 [SBIT](INFO): Beginning SBIT in 23.000000 seconds. 2019-10-17T17:16:17.137Z,1571332577.137 [IBIT](INFO): Initialize IBIT Component. 2019-10-17T17:16:17.138Z,1571332577.138 [CBIT](DEBUG): Initialize CBIT Component. 2019-10-17T17:16:17.139Z,1571332577.139 [logger ThreadHandler](INFO): Handler Thread ID is 2720 2019-10-17T17:16:17.149Z,1571332577.149 [CBIT](DEBUG): Initialized mux pins. 2019-10-17T17:16:17.149Z,1571332577.149 [CBIT](DEBUG): Initializing the watchdog timer. 2019-10-17T17:16:17.157Z,1571332577.157 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2721 2019-10-17T17:16:17.158Z,1571332577.158 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-10-17T17:16:17.169Z,1571332577.169 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2722 2019-10-17T17:16:17.173Z,1571332577.173 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-10-17T17:16:17.173Z,1571332577.173 [CBIT](DEBUG): Initializing heartbeat. 2019-10-17T17:16:17.181Z,1571332577.181 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2723 2019-10-17T17:16:17.182Z,1571332577.182 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-10-17T17:16:17.185Z,1571332577.185 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-10-17T17:16:17.187Z,1571332577.187 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2725 2019-10-17T17:16:17.188Z,1571332577.188 [WetLabsBB2FL](INFO): Powering down 2019-10-17T17:16:17.215Z,1571332577.215 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2726 2019-10-17T17:16:17.218Z,1571332577.218 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-10-17T17:16:17.218Z,1571332577.218 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-10-17T17:16:17.219Z,1571332577.219 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-10-17T17:16:17.219Z,1571332577.219 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-10-17T17:16:17.219Z,1571332577.219 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-10-17T17:16:17.219Z,1571332577.219 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-10-17T17:16:17.219Z,1571332577.219 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-10-17T17:16:17.220Z,1571332577.220 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-10-17T17:16:17.220Z,1571332577.220 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-10-17T17:16:17.220Z,1571332577.220 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-10-17T17:16:17.220Z,1571332577.220 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-10-17T17:16:17.220Z,1571332577.220 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-10-17T17:16:17.220Z,1571332577.220 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-10-17T17:16:17.221Z,1571332577.221 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-10-17T17:16:17.221Z,1571332577.221 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-10-17T17:16:17.221Z,1571332577.221 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-10-17T17:16:17.245Z,1571332577.245 [CBIT](DEBUG): Deactivating GF circuits. 2019-10-17T17:16:17.245Z,1571332577.245 [CBIT](DEBUG): Deactivating emergency mode. 2019-10-17T17:16:17.281Z,1571332577.281 [CBIT](DEBUG): Backplane powered. 2019-10-17T17:16:17.282Z,1571332577.282 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-10-17T17:16:17.292Z,1571332577.292 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-10-17T17:16:17.302Z,1571332577.302 [MissionManager](DEBUG): 2019-10-17T17:16:17.303Z,1571332577.303 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-10-17T17:16:17.378Z,1571332577.378 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-10-17T17:16:17.379Z,1571332577.379 [Default:A.Wait](DEBUG): Construct Wait. 2019-10-17T17:16:17.397Z,1571332577.397 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-10-17T17:16:17.427Z,1571332577.427 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-10-17T17:16:17.430Z,1571332577.430 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-10-17T17:16:17.451Z,1571332577.451 [Default:E.Execute](DEBUG): Construct Execute. 2019-10-17T17:16:17.455Z,1571332577.455 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2019-10-17T17:16:17.465Z,1571332577.465 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,BPC1,Aanderaa_O2,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-10-17T17:16:17.478Z,1571332577.478 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-10-17T17:16:17.520Z,1571332577.520 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar 2019-10-17T17:16:17.573Z,1571332577.573 [Radio_Surface](INFO): Powering up 2019-10-17T17:16:17.579Z,1571332577.579 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-10-17T17:16:17.589Z,1571332577.589 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-10-17T17:16:17.601Z,1571332577.601 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-10-17T17:16:17.602Z,1571332577.602 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-10-17T17:16:17.609Z,1571332577.609 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-10-17T17:16:17.610Z,1571332577.610 [MassServo](DEBUG): Initializing EZServoServo. 2019-10-17T17:16:17.621Z,1571332577.621 [MassServo](DEBUG): Initializing MassServo. 2019-10-17T17:16:17.622Z,1571332577.622 [RudderServo](DEBUG): Initializing EZServoServo. 2019-10-17T17:16:17.629Z,1571332577.629 [RudderServo](DEBUG): Initializing RudderServo. 2019-10-17T17:16:17.630Z,1571332577.630 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-10-17T17:16:17.641Z,1571332577.641 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-10-17T17:16:17.891Z,1571332577.891 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2019-10-17T17:16:17.891Z,1571332577.891 [DropWeight] Hardware Fault, FailCount= 1 2019-10-17T17:16:17.891Z,1571332577.891 [DropWeight](ERROR): Hardware Fault 2019-10-17T17:16:17.956Z,1571332577.956 [CommandLine](FAULT): Scheduling is paused 2019-10-17T17:16:17.957Z,1571332577.957 [CBIT](INFO): Critical error at 20191017T171617 2019-10-17T17:16:17.957Z,1571332577.957 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2019-10-17T17:16:17.959Z,1571332577.959 [CBIT](ERROR): Hardware Fault in component: DropWeight 2019-10-17T17:16:17.960Z,1571332577.960 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2019-10-17T17:16:18.598Z,1571332578.598 [CBIT](INFO): Critical error at 20191017T171617 2019-10-17T17:16:20.909Z,1571332580.909 [Aanderaa_O2](INFO): Powering down 2019-10-17T17:16:32.030Z,1571332592.030 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.022597 2019-10-17T17:16:40.559Z,1571332600.559 [SBIT](IMPORTANT): Beginning Startup BIT 2019-10-17T17:16:40.571Z,1571332600.571 [CBIT](IMPORTANT): Beginning ground fault scan 2019-10-17T17:16:44.378Z,1571332604.378 [NAL9602](INFO): Powering up NAL9602 2019-10-17T17:16:52.235Z,1571332612.235 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.012202 CHAN A1 (24V): 0.000477 CHAN A2 (12V): 4.697929 CHAN A3 (5V): -0.001421 CHAN B0 (3.3V): 0.000208 CHAN B1 (3.15aV): -0.000340 CHAN B2 (3.15bV): -0.000466 CHAN B3 (GND): 0.000203 OPEN: -0.000218 Full Scale Calc: 4.765 mA, -1.589 mA 2019-10-17T17:16:55.314Z,1571332615.314 [NAL9602](INFO): NAL9602 initialized 2019-10-17T17:16:56.129Z,1571332616.129 [NAL9602](DEBUG): Fix Requested 2019-10-17T17:17:34.787Z,1571332654.787 [SBIT](IMPORTANT): SBIT PASSED 2019-10-17T17:17:34.826Z,1571332654.826 [CommandLine](IMPORTANT): got command configSet list 2019-10-17T17:17:34.827Z,1571332654.827 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-10-17T17:17:34.827Z,1571332654.827 [CommandLine](IMPORTANT): CBIT.gf24Offset=154 microampere; 2019-10-17T17:17:34.828Z,1571332654.828 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool; 2019-10-17T17:17:34.828Z,1571332654.828 [CommandLine](IMPORTANT): DAT.loadAtStartup=0 bool; 2019-10-17T17:17:34.828Z,1571332654.828 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool; 2019-10-17T17:17:35.183Z,1571332655.183 [MissionManager](IMPORTANT): Started mission Startup 2019-10-17T17:17:35.183Z,1571332655.183 [Startup] Running Loop=1 2019-10-17T17:17:35.183Z,1571332655.183 [Startup](DEBUG): Aggregate::initialize Startup 2019-10-17T17:17:35.183Z,1571332655.183 [Startup:A.GoToSurface] Running Loop=1 2019-10-17T17:17:35.184Z,1571332655.184 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-10-17T17:17:35.184Z,1571332655.184 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-10-17T17:17:35.209Z,1571332655.209 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-10-17T17:17:35.209Z,1571332655.209 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-10-17T17:17:35.210Z,1571332655.210 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-10-17T17:17:35.210Z,1571332655.210 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-10-17T17:17:35.211Z,1571332655.211 [Startup:StartupSatComms] Running Loop=1 2019-10-17T17:17:35.211Z,1571332655.211 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-10-17T17:17:35.211Z,1571332655.211 [Startup:StartupSatComms:A] Running Loop=1 2019-10-17T17:17:35.595Z,1571332655.595 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-10-17T17:18:17.186Z,1571332697.186 [CommandLine](IMPORTANT): got command stop 2019-10-17T17:18:17.187Z,1571332697.187 [CommandLine](IMPORTANT): Scheduling is paused 2019-10-17T17: