2019-02-15T02:13:37.300Z,1550196817.300 [Supervisor](DEBUG): Initializing supervisor. 2019-02-15T02:13:37.302Z,1550196817.302 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-02-15T02:13:37.303Z,1550196817.303 [SyncHandler](INFO): Protected caller Thread ID is 806 2019-02-15T02:13:37.303Z,1550196817.303 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-02-15T02:13:37.304Z,1550196817.304 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-02-15T02:13:37.305Z,1550196817.305 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 807 2019-02-15T02:13:37.307Z,1550196817.307 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-02-15T02:13:37.319Z,1550196817.319 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-02-15T02:13:37.320Z,1550196817.320 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-02-15T02:13:37.320Z,1550196817.320 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 808 2019-02-15T02:13:37.321Z,1550196817.321 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-02-15T02:13:37.322Z,1550196817.322 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-02-15T02:13:37.322Z,1550196817.322 [logger ThreadHandler](INFO): Protected caller Thread ID is 809 2019-02-15T02:13:37.324Z,1550196817.324 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-02-15T02:13:37.325Z,1550196817.325 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-02-15T02:13:37.329Z,1550196817.329 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-02-15T02:13:37.534Z,1550196817.534 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-02-15T02:13:37.535Z,1550196817.535 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-02-15T02:13:37.617Z,1550196817.617 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-02-15T02:13:38.075Z,1550196818.075 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-02-15T02:13:38.076Z,1550196818.076 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-02-15T02:13:38.418Z,1550196818.418 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-02-15T02:13:38.419Z,1550196818.419 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-02-15T02:13:38.517Z,1550196818.517 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-02-15T02:13:38.517Z,1550196818.517 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-02-15T02:13:38.828Z,1550196818.828 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-02-15T02:13:38.829Z,1550196818.829 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-02-15T02:13:39.033Z,1550196819.033 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-02-15T02:13:39.033Z,1550196819.033 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-02-15T02:13:39.497Z,1550196819.497 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-02-15T02:13:39.498Z,1550196819.498 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-02-15T02:13:39.604Z,1550196819.604 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-02-15T02:13:39.606Z,1550196819.606 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-02-15T02:13:39.710Z,1550196819.710 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-02-15T02:13:39.711Z,1550196819.711 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-02-15T02:13:40.213Z,1550196820.213 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-02-15T02:13:40.214Z,1550196820.214 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-02-15T02:13:40.623Z,1550196820.623 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-02-15T02:13:40.624Z,1550196820.624 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-02-15T02:13:40.961Z,1550196820.961 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-02-15T02:13:40.961Z,1550196820.961 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-02-15T02:13:41.515Z,1550196821.515 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-02-15T02:13:41.516Z,1550196821.516 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-02-15T02:13:41.669Z,1550196821.669 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-02-15T02:13:41.671Z,1550196821.671 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2019-02-15T02:13:41.675Z,1550196821.675 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2019-02-15T02:13:41.769Z,1550196821.769 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2019-02-15T02:13:41.857Z,1550196821.857 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2019-02-15T02:13:41.965Z,1550196821.965 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2019-02-15T02:13:42.050Z,1550196822.050 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2019-02-15T02:13:42.149Z,1550196822.149 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2019-02-15T02:13:42.250Z,1550196822.250 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2019-02-15T02:13:42.439Z,1550196822.439 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2019-02-15T02:13:42.517Z,1550196822.517 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2019-02-15T02:13:42.685Z,1550196822.685 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2019-02-15T02:13:42.815Z,1550196822.815 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2019-02-15T02:13:42.937Z,1550196822.937 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2019-02-15T02:13:43.168Z,1550196823.168 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-02-15T02:13:43.169Z,1550196823.169 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2019-02-15T02:13:43.173Z,1550196823.173 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-02-15T02:13:43.259Z,1550196823.259 [VerticalControl](DEBUG): Construct VerticalControl. 2019-02-15T02:13:43.375Z,1550196823.375 [VerticalControl] Loaded 2019-02-15T02:13:43.375Z,1550196823.375 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-02-15T02:13:43.376Z,1550196823.376 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-02-15T02:13:43.445Z,1550196823.445 [HorizontalControl] Loaded 2019-02-15T02:13:43.445Z,1550196823.445 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-02-15T02:13:43.446Z,1550196823.446 [SpeedControl](DEBUG): Construct SpeedControl. 2019-02-15T02:13:43.452Z,1550196823.452 [SpeedControl] Loaded 2019-02-15T02:13:43.452Z,1550196823.452 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-02-15T02:13:43.453Z,1550196823.453 [LoopControl](DEBUG): Construct LoopControl. 2019-02-15T02:13:43.454Z,1550196823.454 [LoopControl] Loaded 2019-02-15T02:13:43.454Z,1550196823.454 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-02-15T02:13:43.455Z,1550196823.455 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-02-15T02:13:43.455Z,1550196823.455 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-02-15T02:13:43.479Z,1550196823.479 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-02-15T02:13:43.480Z,1550196823.480 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-02-15T02:13:43.606Z,1550196823.606 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-02-15T02:13:43.607Z,1550196823.607 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-02-15T02:13:43.749Z,1550196823.749 [BuoyancyServo] Loaded 2019-02-15T02:13:43.749Z,1550196823.749 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-02-15T02:13:43.761Z,1550196823.761 [ElevatorServo] Loaded 2019-02-15T02:13:43.761Z,1550196823.761 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-02-15T02:13:43.772Z,1550196823.772 [MassServo] Loaded 2019-02-15T02:13:43.772Z,1550196823.772 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-02-15T02:13:43.783Z,1550196823.783 [RudderServo] Loaded 2019-02-15T02:13:43.784Z,1550196823.784 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-02-15T02:13:43.795Z,1550196823.795 [ThrusterServo] Loaded 2019-02-15T02:13:43.795Z,1550196823.795 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-02-15T02:13:43.796Z,1550196823.796 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-02-15T02:13:43.796Z,1550196823.796 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-02-15T02:13:43.921Z,1550196823.921 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-02-15T02:13:43.921Z,1550196823.921 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-02-15T02:13:43.980Z,1550196823.980 [DeadReckonUsingSpeedCalculator] Loaded 2019-02-15T02:13:43.981Z,1550196823.981 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2019-02-15T02:13:44.040Z,1550196824.040 [DeadReckonWithRespectToSeafloor] Loaded 2019-02-15T02:13:44.040Z,1550196824.040 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2019-02-15T02:13:44.056Z,1550196824.056 [NavChart] Loaded 2019-02-15T02:13:44.056Z,1550196824.056 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-02-15T02:13:44.060Z,1550196824.060 [UniversalFixResidualReporter] Loaded 2019-02-15T02:13:44.060Z,1550196824.060 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-02-15T02:13:44.061Z,1550196824.061 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-02-15T02:13:44.061Z,1550196824.061 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-02-15T02:13:44.165Z,1550196824.165 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-02-15T02:13:44.166Z,1550196824.166 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-02-15T02:13:44.755Z,1550196824.755 [DataOverHttps] Loaded 2019-02-15T02:13:44.756Z,1550196824.756 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2019-02-15T02:13:44.769Z,1550196824.769 [Depth_Keller] Loaded 2019-02-15T02:13:44.769Z,1550196824.769 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-02-15T02:13:44.775Z,1550196824.775 [DropWeight] Loaded 2019-02-15T02:13:44.775Z,1550196824.775 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-02-15T02:13:44.873Z,1550196824.873 [NAL9602] Loaded 2019-02-15T02:13:44.873Z,1550196824.873 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-02-15T02:13:44.890Z,1550196824.890 [Onboard] Loaded 2019-02-15T02:13:44.890Z,1550196824.890 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-02-15T02:13:44.897Z,1550196824.897 [Radio_Surface] Loaded 2019-02-15T02:13:44.897Z,1550196824.897 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-02-15T02:13:44.899Z,1550196824.899 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408624E0 2019-02-15T02:13:44.899Z,1550196824.899 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 891 2019-02-15T02:13:45.051Z,1550196825.051 [PNI_TCM] Loaded 2019-02-15T02:13:45.052Z,1550196825.052 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2019-02-15T02:13:45.112Z,1550196825.112 [Rowe_600LCM] Loaded 2019-02-15T02:13:45.113Z,1550196825.113 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2019-02-15T02:13:45.114Z,1550196825.114 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 408924E0 2019-02-15T02:13:45.114Z,1550196825.114 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 892 2019-02-15T02:13:45.115Z,1550196825.115 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-02-15T02:13:45.115Z,1550196825.115 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-02-15T02:13:45.170Z,1550196825.170 [DepthRateCalculator] Loaded 2019-02-15T02:13:45.171Z,1550196825.171 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-02-15T02:13:45.176Z,1550196825.176 [PitchRateCalculator] Loaded 2019-02-15T02:13:45.176Z,1550196825.176 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-02-15T02:13:45.188Z,1550196825.188 [SpeedCalculator] Loaded 2019-02-15T02:13:45.188Z,1550196825.188 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-02-15T02:13:45.209Z,1550196825.209 [TempGradientCalculator] Loaded 2019-02-15T02:13:45.210Z,1550196825.210 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-02-15T02:13:45.215Z,1550196825.215 [YawRateCalculator] Loaded 2019-02-15T02:13:45.215Z,1550196825.215 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-02-15T02:13:45.245Z,1550196825.245 [ElevatorOffsetCalculator] Loaded 2019-02-15T02:13:45.246Z,1550196825.246 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-02-15T02:13:45.246Z,1550196825.246 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-02-15T02:13:45.247Z,1550196825.247 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-02-15T02:13:45.479Z,1550196825.479 [Aanderaa_O2] Loaded 2019-02-15T02:13:45.479Z,1550196825.479 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2019-02-15T02:13:45.563Z,1550196825.563 [CTD_NeilBrown] Loaded 2019-02-15T02:13:45.563Z,1550196825.563 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2019-02-15T02:13:45.564Z,1550196825.564 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4095C4E0 2019-02-15T02:13:45.565Z,1550196825.565 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 893 2019-02-15T02:13:45.574Z,1550196825.574 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2019-02-15T02:13:45.579Z,1550196825.579 [CTD_Seabird](INFO): created writer for : sea_water_density 2019-02-15T02:13:45.581Z,1550196825.581 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2019-02-15T02:13:45.586Z,1550196825.586 [CTD_Seabird](INFO): created writer for : depth 2019-02-15T02:13:45.586Z,1550196825.586 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2019-02-15T02:13:45.591Z,1550196825.591 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2019-02-15T02:13:45.592Z,1550196825.592 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2019-02-15T02:13:45.597Z,1550196825.597 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2019-02-15T02:13:45.597Z,1550196825.597 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2019-02-15T02:13:45.603Z,1550196825.603 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2019-02-15T02:13:45.603Z,1550196825.603 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2019-02-15T02:13:45.609Z,1550196825.609 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2019-02-15T02:13:45.609Z,1550196825.609 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2019-02-15T02:13:45.614Z,1550196825.614 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2019-02-15T02:13:45.641Z,1550196825.641 [CTD_Seabird] Loaded 2019-02-15T02:13:45.641Z,1550196825.641 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2019-02-15T02:13:45.643Z,1550196825.643 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4098C4E0 2019-02-15T02:13:45.643Z,1550196825.643 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 894 2019-02-15T02:13:45.658Z,1550196825.658 [PAR_Licor] Loaded 2019-02-15T02:13:45.658Z,1550196825.658 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2019-02-15T02:13:45.706Z,1550196825.706 [WetLabsBB2FL] Loaded 2019-02-15T02:13:45.707Z,1550196825.707 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-02-15T02:13:45.708Z,1550196825.708 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409BC4E0 2019-02-15T02:13:45.708Z,1550196825.708 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 895 2019-02-15T02:13:45.709Z,1550196825.709 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-02-15T02:13:45.709Z,1550196825.709 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-02-15T02:13:45.753Z,1550196825.753 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-02-15T02:13:45.753Z,1550196825.753 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-02-15T02:13:46.035Z,1550196826.035 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-02-15T02:13:46.036Z,1550196826.036 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-02-15T02:13:46.545Z,1550196826.545 [SBIT](DEBUG): Construct Startup Built In Test. 2019-02-15T02:13:46.556Z,1550196826.556 [SBIT] Loaded 2019-02-15T02:13:46.557Z,1550196826.557 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-02-15T02:13:46.557Z,1550196826.557 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-02-15T02:13:46.570Z,1550196826.570 [IBIT] Loaded 2019-02-15T02:13:46.571Z,1550196826.571 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-02-15T02:13:46.574Z,1550196826.574 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-02-15T02:13:46.966Z,1550196826.966 [CBIT] Loaded 2019-02-15T02:13:46.967Z,1550196826.967 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-02-15T02:13:46.967Z,1550196826.967 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-02-15T02:13:46.971Z,1550196826.971 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-02-15T02:13:46.972Z,1550196826.972 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-02-15T02:13:46.979Z,1550196826.979 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-02-15T02:13:46.980Z,1550196826.980 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AA64E0 2019-02-15T02:13:46.980Z,1550196826.980 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 896 2019-02-15T02:13:46.985Z,1550196826.985 [Supervisor](INFO): Main Thread ID is 802 2019-02-15T02:13:46.985Z,1550196826.985 [Supervisor](DEBUG): Running supervisor. 2019-02-15T02:13:46.985Z,1550196826.985 [CommandLine ThreadHandler](INFO): Handler Thread ID is 897 2019-02-15T02:13:46.988Z,1550196826.988 [controlThread ThreadHandler](INFO): Handler Thread ID is 898 2019-02-15T02:13:46.988Z,1550196826.988 [controlThread](DEBUG): Initializing ControlThread 2019-02-15T02:13:46.989Z,1550196826.989 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-02-15T02:13:46.991Z,1550196826.991 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-02-15T02:13:46.991Z,1550196826.991 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-02-15T02:13:46.992Z,1550196826.992 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-02-15T02:13:46.994Z,1550196826.994 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-02-15T02:13:46.995Z,1550196826.995 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-02-15T02:13:46.995Z,1550196826.995 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-02-15T02:13:47.001Z,1550196827.001 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-02-15T02:13:47.001Z,1550196827.001 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-02-15T02:13:47.001Z,1550196827.001 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-02-15T02:13:47.001Z,1550196827.001 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-02-15T02:13:47.002Z,1550196827.002 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-02-15T02:13:47.002Z,1550196827.002 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-02-15T02:13:47.007Z,1550196827.007 [SBIT](INFO): Initialize SBIT Component. 2019-02-15T02:13:47.007Z,1550196827.007 [SBIT](IMPORTANT): git: 2019-01-14A-50-g9cfb34a 2019-02-15T02:13:47.008Z,1550196827.008 [SBIT](INFO): git hash: 9cfb34a5380b67c155f39980646156fa9a5482a4 2019-02-15T02:13:47.008Z,1550196827.008 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-02-15T02:13:47.009Z,1550196827.009 [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-02-15T02:13:47.010Z,1550196827.010 [SBIT](INFO): Beginning SBIT in 23.000000 seconds. 2019-02-15T02:13:47.011Z,1550196827.011 [IBIT](INFO): Initialize IBIT Component. 2019-02-15T02:13:47.012Z,1550196827.012 [CBIT](DEBUG): Initialize CBIT Component. 2019-02-15T02:13:47.012Z,1550196827.012 [logger ThreadHandler](INFO): Handler Thread ID is 899 2019-02-15T02:13:47.023Z,1550196827.023 [CBIT](DEBUG): Initialized mux pins. 2019-02-15T02:13:47.023Z,1550196827.023 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2019-02-15T02:13:47.023Z,1550196827.023 [CBIT](DEBUG): Initializing the watchdog timer. 2019-02-15T02:13:47.031Z,1550196827.031 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 900 2019-02-15T02:13:47.037Z,1550196827.037 [Radio_Surface](INFO): Powering up 2019-02-15T02:13:47.043Z,1550196827.043 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 901 2019-02-15T02:13:47.044Z,1550196827.044 [Rowe_600LCM](INFO): Initializing 2019-02-15T02:13:47.051Z,1550196827.051 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-02-15T02:13:47.051Z,1550196827.051 [CBIT](DEBUG): Initializing heartbeat. 2019-02-15T02:13:47.139Z,1550196827.139 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 902 2019-02-15T02:13:47.140Z,1550196827.140 [CBIT](DEBUG): Deactivating GF circuits. 2019-02-15T02:13:47.140Z,1550196827.140 [CBIT](DEBUG): Deactivating emergency mode. 2019-02-15T02:13:47.141Z,1550196827.141 [CTD_NeilBrown](INFO): Powering down 2019-02-15T02:13:47.167Z,1550196827.167 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 903 2019-02-15T02:13:47.167Z,1550196827.167 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-02-15T02:13:47.172Z,1550196827.172 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-02-15T02:13:47.267Z,1550196827.267 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-02-15T02:13:47.278Z,1550196827.278 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 905 2019-02-15T02:13:47.279Z,1550196827.279 [WetLabsBB2FL](INFO): Powering down 2019-02-15T02:13:47.297Z,1550196827.297 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 906 2019-02-15T02:13:47.307Z,1550196827.307 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-02-15T02:13:47.307Z,1550196827.307 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-02-15T02:13:47.308Z,1550196827.308 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-02-15T02:13:47.308Z,1550196827.308 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-02-15T02:13:47.308Z,1550196827.308 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-02-15T02:13:47.308Z,1550196827.308 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-02-15T02:13:47.308Z,1550196827.308 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-02-15T02:13:47.308Z,1550196827.308 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-02-15T02:13:47.309Z,1550196827.309 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-02-15T02:13:47.309Z,1550196827.309 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-02-15T02:13:47.309Z,1550196827.309 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-02-15T02:13:47.309Z,1550196827.309 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-02-15T02:13:47.309Z,1550196827.309 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-02-15T02:13:47.310Z,1550196827.310 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-02-15T02:13:47.310Z,1550196827.310 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-02-15T02:13:47.310Z,1550196827.310 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-02-15T02:13:47.432Z,1550196827.432 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-02-15T02:13:47.444Z,1550196827.444 [MissionManager](DEBUG): 2019-02-15T02:13:47.445Z,1550196827.445 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-02-15T02:13:47.600Z,1550196827.600 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-02-15T02:13:47.601Z,1550196827.601 [Default:A.Wait](DEBUG): Construct Wait. 2019-02-15T02:13:47.615Z,1550196827.615 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-02-15T02:13:47.638Z,1550196827.638 [Rowe_600LCM](INFO): Powering up 2019-02-15T02:13:47.665Z,1550196827.665 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-02-15T02:13:47.688Z,1550196827.688 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-02-15T02:13:47.694Z,1550196827.694 [Default:E.Execute](DEBUG): Construct Execute. 2019-02-15T02:13:47.726Z,1550196827.726 [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-02-15T02:13:47.745Z,1550196827.745 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,Aanderaa_O2,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-02-15T02:13:47.789Z,1550196827.789 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-02-15T02:13:47.816Z,1550196827.816 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar 2019-02-15T02:13:48.084Z,1550196828.084 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-02-15T02:13:48.531Z,1550196828.531 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-02-15T02:13:48.543Z,1550196828.543 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-02-15T02:13:48.561Z,1550196828.561 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-02-15T02:13:48.570Z,1550196828.570 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-02-15T02:13:48.611Z,1550196828.611 [MassServo](DEBUG): Initializing EZServoServo. 2019-02-15T02:13:48.619Z,1550196828.619 [MassServo](DEBUG): Initializing MassServo. 2019-02-15T02:13:48.644Z,1550196828.644 [RudderServo](DEBUG): Initializing EZServoServo. 2019-02-15T02:13:48.659Z,1550196828.659 [RudderServo](DEBUG): Initializing RudderServo. 2019-02-15T02:13:48.674Z,1550196828.674 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-02-15T02:13:48.695Z,1550196828.695 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-02-15T02:13:48.756Z,1550196828.756 [CBIT](FAULT): Main Battery Failure. Count: 1 2019-02-15T02:13:48.977Z,1550196828.977 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2019-02-15T02:13:48.977Z,1550196828.977 [DropWeight] Hardware Fault, FailCount= 1 2019-02-15T02:13:48.977Z,1550196828.977 [DropWeight](ERROR): Hardware Fault 2019-02-15T02:13:49.387Z,1550196829.387 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2019-02-15T02:13:49.387Z,1550196829.387 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2019-02-15T02:13:49.387Z,1550196829.387 [BuoyancyServo] Communications Fault, FailCount= 1 2019-02-15T02:13:49.387Z,1550196829.387 [BuoyancyServo](ERROR): Communications Fault 2019-02-15T02:13:49.700Z,1550196829.700 [CommandLine](FAULT): Scheduling is paused 2019-02-15T02:13:49.701Z,1550196829.701 [CBIT](INFO): Critical error at 20190215T021348 2019-02-15T02:13:49.701Z,1550196829.701 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2019-02-15T02:13:49.704Z,1550196829.704 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2019-02-15T02:13:49.704Z,1550196829.704 [CBIT](ERROR): Hardware Fault in component: DropWeight 2019-02-15T02:13:49.704Z,1550196829.704 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2019-02-15T02:13:49.795Z,1550196829.795 [CBIT](INFO): Critical error at 20190215T021349 2019-02-15T02:13:50.923Z,1550196830.923 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2019-02-15T02:13:50.923Z,1550196830.923 [MassServo] Communications Fault, FailCount= 1 2019-02-15T02:13:50.923Z,1550196830.923 [MassServo](ERROR): Communications Fault 2019-02-15T02:13:50.927Z,1550196830.927 [CBIT](ERROR): Communications Fault in component: MassServo 2019-02-15T02:13:51.023Z,1550196831.023 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-02-15T02:13:51.023Z,1550196831.023 [MassServo](INFO): Powering down 2019-02-15T02:13:51.591Z,1550196831.591 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2019-02-15T02:13:51.673Z,1550196831.673 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null & 2019-02-15T02:13:51.923Z,1550196831.923 [MassServo](DEBUG): Initializing EZServoServo. 2019-02-15T02:13:51.924Z,1550196831.924 [MassServo](DEBUG): Initializing MassServo. 2019-02-15T02:13:52.027Z,1550196832.027 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2019-02-15T02:13:52.027Z,1550196832.027 [BuoyancyServo] No Fault, FailCount= 1 2019-02-15T02:13:52.028Z,1550196832.028 [CBIT](INFO): Clearing failed state for component MassServo 2019-02-15T02:13:52.028Z,1550196832.028 [MassServo] No Fault, FailCount= 1 2019-02-15T02:13:52.238Z,1550196832.238 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-02-15T02:13:52.255Z,1550196832.255 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-02-15T02:13:53.084Z,1550196833.084 [CBIT](FAULT): Main Battery Failure. Count: 11 2019-02-15T02:13:54.111Z,1550196834.111 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2019-02-15T02:13:54.111Z,1550196834.111 [MassServo] Communications Fault, FailCount= 2 2019-02-15T02:13:54.111Z,1550196834.111 [MassServo](ERROR): Communications Fault 2019-02-15T02:13:54.115Z,1550196834.115 [CBIT](ERROR): Communications Fault in component: MassServo 2019-02-15T02:13:54.210Z,1550196834.210 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-02-15T02:13:54.210Z,1550196834.210 [MassServo](INFO): Powering down 2019-02-15T02:13:55.010Z,1550196835.010 [MassServo](DEBUG): Initializing EZServoServo. 2019-02-15T02:13:55.023Z,1550196835.023 [MassServo](DEBUG): Initializing MassServo. 2019-02-15T02:13:55.027Z,1550196835.027 [CBIT](INFO): Clearing failed state for component MassServo 2019-02-15T02:13:55.027Z,1550196835.027 [MassServo] No Fault, FailCount= 2 2019-02-15T02:13:55.755Z,1550196835.755 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2019-02-15T02:13:55.755Z,1550196835.755 [PNI_TCM] Data Fault, FailCount= 1 2019-02-15T02:13:55.755Z,1550196835.755 [PNI_TCM](ERROR): Data Fault 2019-02-15T02:13:55.863Z,1550196835.863 [CBIT](ERROR): Data Fault in component: PNI_TCM 2019-02-15T02:13:56.154Z,1550196836.154 [PNI_TCM](INFO): Powering down 2019-02-15T02:13:57.318Z,1550196837.318 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2019-02-15T02:13:57.319Z,1550196837.319 [MassServo] Communications Fault, FailCount= 3 2019-02-15T02:13:57.319Z,1550196837.319 [MassServo](ERROR): Communications Fault 2019-02-15T02:13:57.321Z,1550196837.321 [CBIT](FAULT): Main Battery Failure. Count: 21 2019-02-15T02:13:57.324Z,1550196837.324 [CBIT](CRITICAL): Environmental Failure. Press:14.649905 PSI. Humidity:31%. Temp:24 C. ABORTING MISSION 2019-02-15T02:13:57.324Z,1550196837.324 [CBIT](ERROR): Communications Fault in component: MassServo 2019-02-15T02:13:57.487Z,1550196837.487 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-02-15T02:13:57.488Z,1550196837.488 [MassServo](INFO): Powering down 2019-02-15T02:13:57.491Z,1550196837.491 [CBIT](INFO): Critical error at 20190215T021357 2019-02-15T02:13:57.493Z,1550196837.493 [CBIT](INFO): Clearing failed state for component PNI_TCM 2019-02-15T02:13:57.493Z,1550196837.493 [PNI_TCM] No Fault, FailCount= 1 2019-02-15T02:13:58.155Z,1550196838.155 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2019-02-15T02:13:58.155Z,1550196838.155 [Aanderaa_O2] Communications Fault, FailCount= 1 2019-02-15T02:13:58.155Z,1550196838.155 [Aanderaa_O2](ERROR): Communications Fault 2019-02-15T02:13:58.225Z,1550196838.225 [MassServo](DEBUG): Initializing EZServoServo. 2019-02-15T02:13:58.226Z,1550196838.226 [MassServo](DEBUG): Initializing MassServo. 2019-02-15T02:13:58.235Z,1550196838.235 [CBIT](INFO): Clearing failed state for component MassServo 2019-02-15T02:13:58.235Z,1550196838.235 [MassServo] No Fault, FailCount= 3 2019-02-15T02:13:58.235Z,1550196838.235 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2019-02-15T02:13:58.567Z,1550196838.567 [Aanderaa_O2](INFO): Powering down 2019-02-15T02:13:59.405Z,1550196839.405 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2019-02-15T02:13:59.405Z,1550196839.405 [Aanderaa_O2] No Fault, FailCount= 1 2019-02-15T02:14:00.523Z,1550196840.523 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2019-02-15T02:14:00.523Z,1550196840.523 [MassServo] Communications Fault, FailCount= 4 2019-02-15T02:14:00.523Z,1550196840.523 [MassServo](ERROR): Communications Fault 2019-02-15T02:14:00.527Z,1550196840.527 [CBIT](ERROR): Communications Fault in component: MassServo 2019-02-15T02:14:00.643Z,1550196840.643 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-02-15T02:14:00.643Z,1550196840.643 [MassServo](INFO): Powering down 2019-02-15T02:14:01.008Z,1550196841.008 [CBIT](FAULT): Main Battery Failure. Count: 31 2019-02-15T02:14:01.223Z,1550196841.223 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-02-15T02:14:01.223Z,1550196841.223 [CTD_Seabird] Communications Fault, FailCount= 1 2019-02-15T02:14:01.223Z,1550196841.223 [CTD_Seabird](ERROR): Communications Fault 2019-02-15T02:14:01.400Z,1550196841.400 [MassServo](DEBUG): Initializing EZServoServo. 2019-02-15T02:14:01.400Z,1550196841.400 [MassServo](DEBUG): Initializing MassServo. 2019-02-15T02:14:01.417Z,1550196841.417 [CBIT](INFO): Clearing failed state for component MassServo 2019-02-15T02:14:01.417Z,1550196841.417 [MassServo] No Fault, FailCount= 4 2019-02-15T02:14:01.418Z,1550196841.418 [CBIT](ERROR): Communications Fa