2018-01-31T18:36:00.284Z,1517423760.284 [Supervisor](DEBUG): Initializing supervisor. 2018-01-31T18:36:00.287Z,1517423760.287 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-01-31T18:36:00.288Z,1517423760.288 [SyncHandler](INFO): Protected caller Thread ID is 7934 2018-01-31T18:36:00.288Z,1517423760.288 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-01-31T18:36:00.289Z,1517423760.289 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-01-31T18:36:00.290Z,1517423760.290 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7935 2018-01-31T18:36:00.292Z,1517423760.292 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-01-31T18:36:00.304Z,1517423760.304 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-01-31T18:36:00.305Z,1517423760.305 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-01-31T18:36:00.305Z,1517423760.305 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7936 2018-01-31T18:36:00.306Z,1517423760.306 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-01-31T18:36:00.307Z,1517423760.307 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-01-31T18:36:00.307Z,1517423760.307 [logger ThreadHandler](INFO): Protected caller Thread ID is 7937 2018-01-31T18:36:00.309Z,1517423760.309 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-01-31T18:36:00.309Z,1517423760.309 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-01-31T18:36:00.311Z,1517423760.311 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-01-31T18:36:00.403Z,1517423760.403 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-01-31T18:36:00.404Z,1517423760.404 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-01-31T18:36:00.500Z,1517423760.500 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-01-31T18:36:00.500Z,1517423760.500 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-01-31T18:36:00.682Z,1517423760.682 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-01-31T18:36:00.682Z,1517423760.682 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-01-31T18:36:00.965Z,1517423760.965 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-01-31T18:36:00.966Z,1517423760.966 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-01-31T18:36:01.262Z,1517423761.262 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-01-31T18:36:01.262Z,1517423761.262 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-01-31T18:36:01.690Z,1517423761.690 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-01-31T18:36:01.690Z,1517423761.690 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-01-31T18:36:02.007Z,1517423762.007 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-01-31T18:36:02.008Z,1517423762.008 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-01-31T18:36:02.411Z,1517423762.411 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-01-31T18:36:02.412Z,1517423762.412 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-01-31T18:36:02.489Z,1517423762.489 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-01-31T18:36:02.763Z,1517423762.763 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-01-31T18:36:02.764Z,1517423762.764 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-01-31T18:36:02.934Z,1517423762.934 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-01-31T18:36:02.935Z,1517423762.935 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-01-31T18:36:03.162Z,1517423763.162 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-01-31T18:36:03.163Z,1517423763.163 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-01-31T18:36:03.351Z,1517423763.351 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-01-31T18:36:03.351Z,1517423763.351 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-01-31T18:36:03.445Z,1517423763.445 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-01-31T18:36:03.446Z,1517423763.446 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-01-31T18:36:03.578Z,1517423763.578 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-01-31T18:36:03.580Z,1517423763.580 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2018-01-31T18:36:03.581Z,1517423763.581 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2018-01-31T18:36:03.799Z,1517423763.799 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-01-31T18:36:03.800Z,1517423763.800 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2018-01-31T18:36:03.876Z,1517423763.876 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2018-01-31T18:36:04.032Z,1517423764.032 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2018-01-31T18:36:04.291Z,1517423764.291 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2018-01-31T18:36:04.375Z,1517423764.375 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2018-01-31T18:36:04.503Z,1517423764.503 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2018-01-31T18:36:04.657Z,1517423764.657 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2018-01-31T18:36:04.750Z,1517423764.750 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2018-01-31T18:36:04.838Z,1517423764.838 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2018-01-31T18:36:04.931Z,1517423764.931 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2018-01-31T18:36:05.012Z,1517423765.012 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/ 2018-01-31T18:36:05.012Z,1517423765.012 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2018-01-31T18:36:05.014Z,1517423765.014 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-01-31T18:36:05.111Z,1517423765.111 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-01-31T18:36:05.112Z,1517423765.112 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-01-31T18:36:05.125Z,1517423765.125 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-01-31T18:36:05.126Z,1517423765.126 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-01-31T18:36:05.167Z,1517423765.167 [DepthRateCalculator] Loaded 2018-01-31T18:36:05.167Z,1517423765.167 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-01-31T18:36:05.173Z,1517423765.173 [PitchRateCalculator] Loaded 2018-01-31T18:36:05.173Z,1517423765.173 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-01-31T18:36:05.188Z,1517423765.188 [SpeedCalculator] Loaded 2018-01-31T18:36:05.189Z,1517423765.189 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-01-31T18:36:05.209Z,1517423765.209 [TempGradientCalculator] Loaded 2018-01-31T18:36:05.209Z,1517423765.209 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-01-31T18:36:05.224Z,1517423765.224 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2018-01-31T18:36:05.224Z,1517423765.224 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2018-01-31T18:36:05.229Z,1517423765.229 [YawRateCalculator] Loaded 2018-01-31T18:36:05.230Z,1517423765.230 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-01-31T18:36:05.230Z,1517423765.230 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-01-31T18:36:05.231Z,1517423765.231 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-01-31T18:36:05.255Z,1517423765.255 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-01-31T18:36:05.255Z,1517423765.255 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-01-31T18:36:05.497Z,1517423765.497 [AcousticModem_Benthos_ATM900] Loaded 2018-01-31T18:36:05.497Z,1517423765.497 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread. 2018-01-31T18:36:05.572Z,1517423765.572 [DataOverHttps] Loaded 2018-01-31T18:36:05.573Z,1517423765.573 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-01-31T18:36:05.586Z,1517423765.586 [Depth_Keller] Loaded 2018-01-31T18:36:05.586Z,1517423765.586 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-01-31T18:36:05.590Z,1517423765.590 [DropWeight] Loaded 2018-01-31T18:36:05.591Z,1517423765.591 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-01-31T18:36:05.683Z,1517423765.683 [NAL9602] Loaded 2018-01-31T18:36:05.683Z,1517423765.683 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-01-31T18:36:05.698Z,1517423765.698 [Onboard] Loaded 2018-01-31T18:36:05.698Z,1517423765.698 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-01-31T18:36:05.708Z,1517423765.708 [Radio_Surface] Loaded 2018-01-31T18:36:05.708Z,1517423765.708 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-01-31T18:36:05.709Z,1517423765.709 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407A54E0 2018-01-31T18:36:05.710Z,1517423765.710 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8017 2018-01-31T18:36:05.755Z,1517423765.755 [PNI_TCM] Loaded 2018-01-31T18:36:05.755Z,1517423765.755 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2018-01-31T18:36:05.812Z,1517423765.812 [Rowe_600LCM] Loaded 2018-01-31T18:36:05.813Z,1517423765.813 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2018-01-31T18:36:05.814Z,1517423765.814 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407D54E0 2018-01-31T18:36:05.814Z,1517423765.814 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 8018 2018-01-31T18:36:07.470Z,1517423767.470 [BPC1] Loaded 2018-01-31T18:36:07.471Z,1517423767.471 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-01-31T18:36:07.471Z,1517423767.471 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-01-31T18:36:07.472Z,1517423767.472 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-01-31T18:36:07.595Z,1517423767.595 [SBIT](DEBUG): Construct Startup Built In Test. 2018-01-31T18:36:07.620Z,1517423767.620 [SBIT] Loaded 2018-01-31T18:36:07.620Z,1517423767.620 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-01-31T18:36:07.621Z,1517423767.621 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-01-31T18:36:07.632Z,1517423767.632 [IBIT] Loaded 2018-01-31T18:36:07.632Z,1517423767.632 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-01-31T18:36:07.635Z,1517423767.635 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-01-31T18:36:07.780Z,1517423767.780 [CBIT] Loaded 2018-01-31T18:36:07.780Z,1517423767.780 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-01-31T18:36:07.781Z,1517423767.781 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-01-31T18:36:07.781Z,1517423767.781 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-01-31T18:36:07.921Z,1517423767.921 [Aanderaa_O2] Loaded 2018-01-31T18:36:07.922Z,1517423767.922 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-01-31T18:36:07.967Z,1517423767.967 [CTD_Seabird] Loaded 2018-01-31T18:36:07.967Z,1517423767.967 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2018-01-31T18:36:07.968Z,1517423767.968 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4089C4E0 2018-01-31T18:36:07.969Z,1517423767.969 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 8019 2018-01-31T18:36:07.984Z,1517423767.984 [ESPComponent] Loaded 2018-01-31T18:36:07.984Z,1517423767.984 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2018-01-31T18:36:07.998Z,1517423767.998 [PAR_Licor] Loaded 2018-01-31T18:36:07.998Z,1517423767.998 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2018-01-31T18:36:08.045Z,1517423768.045 [WetLabsBB2FL] Loaded 2018-01-31T18:36:08.045Z,1517423768.045 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-01-31T18:36:08.046Z,1517423768.046 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408CC4E0 2018-01-31T18:36:08.047Z,1517423768.047 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8020 2018-01-31T18:36:08.047Z,1517423768.047 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-01-31T18:36:08.048Z,1517423768.048 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-01-31T18:36:08.276Z,1517423768.276 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-01-31T18:36:08.277Z,1517423768.277 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-01-31T18:36:08.342Z,1517423768.342 [VerticalControl](DEBUG): Construct VerticalControl. 2018-01-31T18:36:08.435Z,1517423768.435 [VerticalControl] Loaded 2018-01-31T18:36:08.436Z,1517423768.436 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-01-31T18:36:08.436Z,1517423768.436 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-01-31T18:36:08.494Z,1517423768.494 [HorizontalControl] Loaded 2018-01-31T18:36:08.495Z,1517423768.495 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-01-31T18:36:08.495Z,1517423768.495 [SpeedControl](DEBUG): Construct SpeedControl. 2018-01-31T18:36:08.500Z,1517423768.500 [SpeedControl] Loaded 2018-01-31T18:36:08.500Z,1517423768.500 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-01-31T18:36:08.501Z,1517423768.501 [LoopControl](DEBUG): Construct LoopControl. 2018-01-31T18:36:08.502Z,1517423768.502 [LoopControl] Loaded 2018-01-31T18:36:08.502Z,1517423768.502 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-01-31T18:36:08.502Z,1517423768.502 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-01-31T18:36:08.503Z,1517423768.503 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-01-31T18:36:08.585Z,1517423768.585 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2018-01-31T18:36:08.586Z,1517423768.586 [StratificationFrontDetector](DEBUG): (re)initializing 2018-01-31T18:36:08.586Z,1517423768.586 [StratificationFrontDetector] Loaded 2018-01-31T18:36:08.586Z,1517423768.586 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2018-01-31T18:36:08.587Z,1517423768.587 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-01-31T18:36:08.587Z,1517423768.587 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-01-31T18:36:08.681Z,1517423768.681 [BuoyancyServo] Loaded 2018-01-31T18:36:08.682Z,1517423768.682 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-01-31T18:36:08.692Z,1517423768.692 [ElevatorServo] Loaded 2018-01-31T18:36:08.693Z,1517423768.693 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-01-31T18:36:08.703Z,1517423768.703 [MassServo] Loaded 2018-01-31T18:36:08.703Z,1517423768.703 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-01-31T18:36:08.714Z,1517423768.714 [RudderServo] Loaded 2018-01-31T18:36:08.714Z,1517423768.714 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-01-31T18:36:08.724Z,1517423768.724 [ThrusterServo] Loaded 2018-01-31T18:36:08.725Z,1517423768.725 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-01-31T18:36:08.725Z,1517423768.725 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-01-31T18:36:08.726Z,1517423768.726 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-01-31T18:36:08.819Z,1517423768.819 [DeadReckonUsingMultipleVelocitySources] Loaded 2018-01-31T18:36:08.819Z,1517423768.819 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2018-01-31T18:36:08.871Z,1517423768.871 [DeadReckonUsingSpeedCalculator] Loaded 2018-01-31T18:36:08.871Z,1517423768.871 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-01-31T18:36:08.887Z,1517423768.887 [NavChart] Loaded 2018-01-31T18:36:08.887Z,1517423768.887 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-01-31T18:36:08.891Z,1517423768.891 [UniversalFixResidualReporter] Loaded 2018-01-31T18:36:08.891Z,1517423768.891 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-01-31T18:36:08.892Z,1517423768.892 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-01-31T18:36:08.895Z,1517423768.895 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-01-31T18:36:08.896Z,1517423768.896 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-01-31T18:36:08.903Z,1517423768.903 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-01-31T18:36:08.904Z,1517423768.904 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A214E0 2018-01-31T18:36:08.904Z,1517423768.904 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8021 2018-01-31T18:36:08.909Z,1517423768.909 [Supervisor](INFO): Main Thread ID is 7933 2018-01-31T18:36:08.909Z,1517423768.909 [Supervisor](DEBUG): Running supervisor. 2018-01-31T18:36:08.910Z,1517423768.910 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8022 2018-01-31T18:36:08.913Z,1517423768.913 [controlThread ThreadHandler](INFO): Handler Thread ID is 8023 2018-01-31T18:36:08.913Z,1517423768.913 [controlThread](DEBUG): Initializing ControlThread 2018-01-31T18:36:08.914Z,1517423768.914 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-01-31T18:36:08.914Z,1517423768.914 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-01-31T18:36:08.915Z,1517423768.915 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-01-31T18:36:08.915Z,1517423768.915 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-01-31T18:36:08.915Z,1517423768.915 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2018-01-31T18:36:08.916Z,1517423768.916 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-01-31T18:36:08.923Z,1517423768.923 [SBIT](INFO): Initialize SBIT Component. 2018-01-31T18:36:08.924Z,1517423768.924 [SBIT](IMPORTANT): git: 2018-01-24-5-gf4d39a4 2018-01-31T18:36:08.924Z,1517423768.924 [SBIT](INFO): git hash: f4d39a4c29739a809be6cb58be6d8cf4bd7ee196 2018-01-31T18:36:08.924Z,1517423768.924 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-01-31T18:36:08.925Z,1517423768.925 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #1 PREEMPT Wed Nov 29 17:23:09 PST 2017 2018-01-31T18:36:08.926Z,1517423768.926 [SBIT](INFO): Beginning SBIT in 63.000000 seconds. 2018-01-31T18:36:08.927Z,1517423768.927 [IBIT](INFO): Initialize IBIT Component. 2018-01-31T18:36:08.928Z,1517423768.928 [CBIT](DEBUG): Initialize CBIT Component. 2018-01-31T18:36:08.928Z,1517423768.928 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2018-01-31T18:36:08.928Z,1517423768.928 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2018-01-31T18:36:08.929Z,1517423768.929 [logger ThreadHandler](INFO): Handler Thread ID is 8024 2018-01-31T18:36:08.949Z,1517423768.949 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8025 2018-01-31T18:36:08.955Z,1517423768.955 [Radio_Surface](INFO): Powering up 2018-01-31T18:36:08.961Z,1517423768.961 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 8026 2018-01-31T18:36:08.962Z,1517423768.962 [Rowe_600LCM](INFO): Initializing 2018-01-31T18:36:08.963Z,1517423768.963 [Rowe_600LCM](INFO): Checking LCM 2018-01-31T18:36:08.971Z,1517423768.971 [Rowe_600LCM](INFO): LCM OK 2018-01-31T18:36:08.971Z,1517423768.971 [Rowe_600LCM](INFO): Powering up 2018-01-31T18:36:08.981Z,1517423768.981 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 8027 2018-01-31T18:36:08.982Z,1517423768.982 [CTD_Seabird](INFO): Initializing 2018-01-31T18:36:08.982Z,1517423768.982 [CTD_Seabird](INFO): Checking LCM 2018-01-31T18:36:08.983Z,1517423768.983 [CTD_Seabird](INFO): LCM OK 2018-01-31T18:36:08.983Z,1517423768.983 [CTD_Seabird](INFO): Powering up 2018-01-31T18:36:08.997Z,1517423768.997 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8028 2018-01-31T18:36:08.999Z,1517423768.999 [WetLabsBB2FL](INFO): Powering down 2018-01-31T18:36:09.025Z,1517423769.025 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8029 2018-01-31T18:36:09.030Z,1517423769.030 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-01-31T18:36:09.030Z,1517423769.030 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-01-31T18:36:09.030Z,1517423769.030 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-01-31T18:36:09.030Z,1517423769.030 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-01-31T18:36:09.031Z,1517423769.031 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-01-31T18:36:09.031Z,1517423769.031 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-01-31T18:36:09.031Z,1517423769.031 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-01-31T18:36:09.031Z,1517423769.031 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-01-31T18:36:09.031Z,1517423769.031 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-01-31T18:36:09.032Z,1517423769.032 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-01-31T18:36:09.032Z,1517423769.032 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-01-31T18:36:09.032Z,1517423769.032 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-01-31T18:36:09.032Z,1517423769.032 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-01-31T18:36:09.032Z,1517423769.032 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-01-31T18:36:09.037Z,1517423769.037 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-01-31T18:36:09.039Z,1517423769.039 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-01-31T18:36:09.040Z,1517423769.040 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-01-31T18:36:09.041Z,1517423769.041 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-01-31T18:36:09.041Z,1517423769.041 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-01-31T18:36:09.040Z,1517423769.040 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-01-31T18:36:09.047Z,1517423769.047 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-01-31T18:36:09.048Z,1517423769.048 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-01-31T18:36:09.073Z,1517423769.073 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-01-31T18:36:09.073Z,1517423769.073 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-01-31T18:36:09.074Z,1517423769.074 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-01-31T18:36:09.074Z,1517423769.074 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-01-31T18:36:09.074Z,1517423769.074 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-01-31T18:36:09.075Z,1517423769.075 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-01-31T18:36:09.076Z,1517423769.076 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-01-31T18:36:09.104Z,1517423769.104 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-01-31T18:36:09.149Z,1517423769.149 [MissionManager](DEBUG): 2018-01-31T18:36:09.150Z,1517423769.150 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-01-31T18:36:09.287Z,1517423769.287 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-01-31T18:36:09.288Z,1517423769.288 [Default:A.Wait](DEBUG): Construct Wait. 2018-01-31T18:36:09.310Z,1517423769.310 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-01-31T18:36:09.396Z,1517423769.396 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-01-31T18:36:09.411Z,1517423769.411 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-01-31T18:36:09.449Z,1517423769.449 [Default:E.Execute](DEBUG): Construct Execute. 2018-01-31T18:36:09.452Z,1517423769.452 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn 300 Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2018-01-31T18:36:09.470Z,1517423769.470 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-01-31T18:36:09.493Z,1517423769.493 [AcousticModem_Benthos_ATM900](INFO): Powering up 2018-01-31T18:36:09.493Z,1517423769.493 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2018-01-31T18:36:09.519Z,1517423769.519 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-01-31T18:36:09.822Z,1517423769.822 [BPC1](INFO): Start 2018-01-31T18:36:09.931Z,1517423769.931 [ESPComponent](INFO): powering down ESP 2018-01-31T18:36:10.449Z,1517423770.449 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-31T18:36:10.473Z,1517423770.473 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-31T18:36:10.574Z,1517423770.574 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-01-31T18:36:10.581Z,1517423770.581 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-01-31T18:36:10.603Z,1517423770.603 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-01-31T18:36:10.609Z,1517423770.609 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-01-31T18:36:10.631Z,1517423770.631 [MassServo](DEBUG): Initializing EZServoServo. 2018-01-31T18:36:10.638Z,1517423770.638 [MassServo](DEBUG): Initializing MassServo. 2018-01-31T18:36:10.643Z,1517423770.643 [RudderServo](DEBUG): Initializing EZServoServo. 2018-01-31T18:36:10.649Z,1517423770.649 [RudderServo](DEBUG): Initializing RudderServo. 2018-01-31T18:36:10.670Z,1517423770.670 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-01-31T18:36:10.677Z,1517423770.677 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-01-31T18:36:10.878Z,1517423770.878 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2018-01-31T18:36:10.878Z,1517423770.878 [DropWeight] Hardware Fault, FailCount= 1 2018-01-31T18:36:10.878Z,1517423770.878 [DropWeight](ERROR): Hardware Fault 2018-01-31T18:36:10.909Z,1517423770.909 [BPC1](INFO): Starting 2018-01-31T18:36:10.930Z,1517423770.930 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-31T18:36:10.931Z,1517423770.931 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-31T18:36:10.955Z,1517423770.955 [CommandLine](FAULT): Scheduling is paused 2018-01-31T18:36:10.965Z,1517423770.965 [CBIT](INFO): Critical error at 20180131T183610 2018-01-31T18:36:10.965Z,1517423770.965 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2018-01-31T18:36:10.968Z,1517423770.968 [CBIT](ERROR): Hardware Fault in component: DropWeight 2018-01-31T18:36:10.968Z,1517423770.968 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2018-01-31T18:36:13.275Z,1517423773.275 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-01-31T18:36:13.412Z,1517423773.412 [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 38400 >& /dev/null & 2018-01-31T18:36:13.468Z,1517423773.468 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-01-31T18:36:13.468Z,1517423773.468 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-01-31T18:36:13.485Z,1517423773.485 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-01-31T18:36:14.571Z,1517423774.571 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-01-31T18:36:14.732Z,1517423774.732 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null & 2018-01-31T18:36:14.733Z,1517423774.733 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd 2018-01-31T18:36:15.099Z,1517423775.099 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-01-31T18:36:15.099Z,1517423775.099 [CTD_Seabird](INFO): Powering down 2018-01-31T18:36:20.102Z,1517423780.102 [BPC1](INFO): Starting 2018-01-31T18:36:20.103Z,1517423780.103 [BPC1](INFO): Initialized. 2018-01-31T18:36:20.104Z,1517423780.104 [Aanderaa_O2](FAULT): Timed out starting 2018-01-31T18:36:20.104Z,1517423780.104 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-01-31T18:36:20.104Z,1517423780.104 [Aanderaa_O2](ERROR): Communications Fault 2018-01-31T18:36:20.168Z,1517423780.168 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-31T18:36:20.177Z,1517423780.177 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-31T18:36:20.475Z,1517423780.475 [CBIT](INFO): Critical error at 20180131T183610 2018-01-31T18:36:20.477Z,1517423780.477 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-01-31T18:36:20.537Z,1517423780.537 [BPC1](INFO): Bank A: initialized. 2018-01-31T18:36:20.565Z,1517423780.565 [BPC1](INFO): Bank B: initialized. 2018-01-31T18:36:20.565Z,1517423780.565 [BPC1](INFO): NAN at stick 0! SERIAL: 00A2