2018-08-27T20:33:37.706Z,1535402017.706 [Supervisor](DEBUG): Initializing supervisor. 2018-08-27T20:33:37.709Z,1535402017.709 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-08-27T20:33:37.709Z,1535402017.709 [SyncHandler](INFO): Protected caller Thread ID is 2300 2018-08-27T20:33:37.710Z,1535402017.710 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-08-27T20:33:37.711Z,1535402017.711 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-08-27T20:33:37.711Z,1535402017.711 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2301 2018-08-27T20:33:37.714Z,1535402017.714 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-08-27T20:33:37.725Z,1535402017.725 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-08-27T20:33:37.726Z,1535402017.726 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-08-27T20:33:37.727Z,1535402017.727 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2302 2018-08-27T20:33:37.727Z,1535402017.727 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-08-27T20:33:37.728Z,1535402017.728 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-08-27T20:33:37.729Z,1535402017.729 [logger ThreadHandler](INFO): Protected caller Thread ID is 2303 2018-08-27T20:33:37.731Z,1535402017.731 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-08-27T20:33:37.731Z,1535402017.731 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-08-27T20:33:37.735Z,1535402017.735 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-08-27T20:33:37.914Z,1535402017.914 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-08-27T20:33:37.916Z,1535402017.916 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-08-27T20:33:38.020Z,1535402018.020 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-08-27T20:33:38.022Z,1535402018.022 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-08-27T20:33:38.233Z,1535402018.233 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-08-27T20:33:38.235Z,1535402018.235 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-08-27T20:33:38.573Z,1535402018.573 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-08-27T20:33:38.575Z,1535402018.575 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-08-27T20:33:38.927Z,1535402018.927 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-08-27T20:33:38.927Z,1535402018.927 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-08-27T20:33:39.405Z,1535402019.405 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-08-27T20:33:39.406Z,1535402019.406 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-08-27T20:33:39.844Z,1535402019.844 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-08-27T20:33:39.844Z,1535402019.844 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-08-27T20:33:40.382Z,1535402020.382 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-08-27T20:33:40.382Z,1535402020.382 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-08-27T20:33:40.682Z,1535402020.682 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-08-27T20:33:41.111Z,1535402021.111 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-08-27T20:33:41.113Z,1535402021.113 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-08-27T20:33:41.263Z,1535402021.263 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-08-27T20:33:41.264Z,1535402021.264 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-08-27T20:33:41.479Z,1535402021.479 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-08-27T20:33:41.481Z,1535402021.481 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-08-27T20:33:41.700Z,1535402021.700 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-08-27T20:33:41.701Z,1535402021.701 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-08-27T20:33:41.803Z,1535402021.803 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-08-27T20:33:41.803Z,1535402021.803 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-08-27T20:33:41.950Z,1535402021.950 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-08-27T20:33:41.952Z,1535402021.952 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2018-08-27T20:33:41.954Z,1535402021.954 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2018-08-27T20:33:42.192Z,1535402022.192 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-08-27T20:33:42.193Z,1535402022.193 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2018-08-27T20:33:42.301Z,1535402022.301 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2018-08-27T20:33:42.450Z,1535402022.450 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2018-08-27T20:33:42.546Z,1535402022.546 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2018-08-27T20:33:42.634Z,1535402022.634 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2018-08-27T20:33:42.782Z,1535402022.782 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2018-08-27T20:33:42.968Z,1535402022.968 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2018-08-27T20:33:43.069Z,1535402023.069 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2018-08-27T20:33:43.167Z,1535402023.167 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2018-08-27T20:33:43.267Z,1535402023.267 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2018-08-27T20:33:43.353Z,1535402023.353 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2018-08-27T20:33:43.370Z,1535402023.370 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-08-27T20:33:43.493Z,1535402023.493 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-08-27T20:33:43.494Z,1535402023.494 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-08-27T20:33:43.514Z,1535402023.514 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-08-27T20:33:43.515Z,1535402023.515 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-08-27T20:33:43.567Z,1535402023.567 [DepthRateCalculator] Loaded 2018-08-27T20:33:43.567Z,1535402023.567 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-08-27T20:33:43.573Z,1535402023.573 [PitchRateCalculator] Loaded 2018-08-27T20:33:43.573Z,1535402023.573 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-08-27T20:33:43.589Z,1535402023.589 [SpeedCalculator] Loaded 2018-08-27T20:33:43.590Z,1535402023.590 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-08-27T20:33:43.610Z,1535402023.610 [TempGradientCalculator] Loaded 2018-08-27T20:33:43.611Z,1535402023.611 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-08-27T20:33:43.626Z,1535402023.626 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2018-08-27T20:33:43.626Z,1535402023.626 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2018-08-27T20:33:43.632Z,1535402023.632 [YawRateCalculator] Loaded 2018-08-27T20:33:43.632Z,1535402023.632 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-08-27T20:33:43.672Z,1535402023.672 [ElevatorOffsetCalculator] Loaded 2018-08-27T20:33:43.673Z,1535402023.673 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-08-27T20:33:43.673Z,1535402023.673 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-08-27T20:33:43.674Z,1535402023.674 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-08-27T20:33:43.708Z,1535402023.708 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-08-27T20:33:43.709Z,1535402023.709 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-08-27T20:33:44.083Z,1535402024.083 [DataOverHttps] Loaded 2018-08-27T20:33:44.084Z,1535402024.084 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-08-27T20:33:44.108Z,1535402024.108 [Depth_Keller] Loaded 2018-08-27T20:33:44.108Z,1535402024.108 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-08-27T20:33:44.207Z,1535402024.207 [NAL9602] Loaded 2018-08-27T20:33:44.208Z,1535402024.208 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-08-27T20:33:44.223Z,1535402024.223 [Onboard] Loaded 2018-08-27T20:33:44.223Z,1535402024.223 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-08-27T20:33:44.234Z,1535402024.234 [Radio_Surface] Loaded 2018-08-27T20:33:44.234Z,1535402024.234 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-08-27T20:33:44.235Z,1535402024.235 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407B84E0 2018-08-27T20:33:44.235Z,1535402024.235 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2382 2018-08-27T20:33:44.283Z,1535402024.283 [PNI_TCM] Loaded 2018-08-27T20:33:44.283Z,1535402024.283 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2018-08-27T20:33:44.345Z,1535402024.345 [Rowe_600LCM] Loaded 2018-08-27T20:33:44.345Z,1535402024.345 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2018-08-27T20:33:44.346Z,1535402024.346 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407E84E0 2018-08-27T20:33:44.346Z,1535402024.346 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 2383 2018-08-27T20:33:44.347Z,1535402024.347 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-08-27T20:33:44.347Z,1535402024.347 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-08-27T20:33:44.497Z,1535402024.497 [SBIT](DEBUG): Construct Startup Built In Test. 2018-08-27T20:33:44.520Z,1535402024.520 [SBIT] Loaded 2018-08-27T20:33:44.521Z,1535402024.521 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-08-27T20:33:44.521Z,1535402024.521 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-08-27T20:33:44.534Z,1535402024.534 [IBIT] Loaded 2018-08-27T20:33:44.534Z,1535402024.534 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-08-27T20:33:44.537Z,1535402024.537 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-08-27T20:33:44.685Z,1535402024.685 [CBIT] Loaded 2018-08-27T20:33:44.686Z,1535402024.686 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-08-27T20:33:44.686Z,1535402024.686 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-08-27T20:33:44.687Z,1535402024.687 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-08-27T20:33:44.862Z,1535402024.862 [Aanderaa_O2] Loaded 2018-08-27T20:33:44.862Z,1535402024.862 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-08-27T20:33:44.915Z,1535402024.915 [CTD_Seabird] Loaded 2018-08-27T20:33:44.915Z,1535402024.915 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2018-08-27T20:33:44.916Z,1535402024.916 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408AC4E0 2018-08-27T20:33:44.916Z,1535402024.916 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2384 2018-08-27T20:33:44.934Z,1535402024.934 [ESPComponent] Loaded 2018-08-27T20:33:44.934Z,1535402024.934 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2018-08-27T20:33:44.948Z,1535402024.948 [PAR_Licor] Loaded 2018-08-27T20:33:44.949Z,1535402024.949 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2018-08-27T20:33:44.997Z,1535402024.997 [WetLabsBB2FL] Loaded 2018-08-27T20:33:44.998Z,1535402024.998 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-08-27T20:33:44.999Z,1535402024.999 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408DC4E0 2018-08-27T20:33:44.999Z,1535402024.999 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2385 2018-08-27T20:33:44.000Z,1535402025.000 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-08-27T20:33:45.000Z,1535402025.000 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-08-27T20:33:45.270Z,1535402025.270 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-08-27T20:33:45.270Z,1535402025.270 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-08-27T20:33:45.354Z,1535402025.354 [VerticalControl](DEBUG): Construct VerticalControl. 2018-08-27T20:33:45.447Z,1535402025.447 [VerticalControl] Loaded 2018-08-27T20:33:45.448Z,1535402025.448 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-08-27T20:33:45.449Z,1535402025.449 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-08-27T20:33:45.510Z,1535402025.510 [HorizontalControl] Loaded 2018-08-27T20:33:45.510Z,1535402025.510 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-08-27T20:33:45.511Z,1535402025.511 [SpeedControl](DEBUG): Construct SpeedControl. 2018-08-27T20:33:45.516Z,1535402025.516 [SpeedControl] Loaded 2018-08-27T20:33:45.516Z,1535402025.516 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-08-27T20:33:45.517Z,1535402025.517 [LoopControl](DEBUG): Construct LoopControl. 2018-08-27T20:33:45.517Z,1535402025.517 [LoopControl] Loaded 2018-08-27T20:33:45.518Z,1535402025.518 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-08-27T20:33:45.518Z,1535402025.518 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-08-27T20:33:45.519Z,1535402025.519 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-08-27T20:33:46.082Z,1535402026.082 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2018-08-27T20:33:46.082Z,1535402026.082 [StratificationFrontDetector](DEBUG): (re)initializing 2018-08-27T20:33:46.082Z,1535402026.082 [StratificationFrontDetector] Loaded 2018-08-27T20:33:46.082Z,1535402026.082 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2018-08-27T20:33:46.083Z,1535402026.083 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-08-27T20:33:46.083Z,1535402026.083 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-08-27T20:33:46.271Z,1535402026.271 [BuoyancyServo] Loaded 2018-08-27T20:33:46.271Z,1535402026.271 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-08-27T20:33:46.283Z,1535402026.283 [ElevatorServo] Loaded 2018-08-27T20:33:46.283Z,1535402026.283 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-08-27T20:33:46.294Z,1535402026.294 [MassServo] Loaded 2018-08-27T20:33:46.294Z,1535402026.294 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-08-27T20:33:46.306Z,1535402026.306 [RudderServo] Loaded 2018-08-27T20:33:46.306Z,1535402026.306 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-08-27T20:33:46.317Z,1535402026.317 [ThrusterServo] Loaded 2018-08-27T20:33:46.317Z,1535402026.317 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-08-27T20:33:46.318Z,1535402026.318 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-08-27T20:33:46.318Z,1535402026.318 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-08-27T20:33:46.419Z,1535402026.419 [DeadReckonUsingSpeedCalculator] Loaded 2018-08-27T20:33:46.420Z,1535402026.420 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-08-27T20:33:46.424Z,1535402026.424 [UniversalFixResidualReporter] Loaded 2018-08-27T20:33:46.424Z,1535402026.424 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-08-27T20:33:46.425Z,1535402026.425 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-08-27T20:33:46.428Z,1535402026.428 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-08-27T20:33:46.429Z,1535402026.429 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-08-27T20:33:46.436Z,1535402026.436 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-08-27T20:33:46.437Z,1535402026.437 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A354E0 2018-08-27T20:33:46.437Z,1535402026.437 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2386 2018-08-27T20:33:46.442Z,1535402026.442 [Supervisor](INFO): Main Thread ID is 796 2018-08-27T20:33:46.442Z,1535402026.442 [Supervisor](DEBUG): Running supervisor. 2018-08-27T20:33:46.443Z,1535402026.443 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2387 2018-08-27T20:33:46.445Z,1535402026.445 [controlThread ThreadHandler](INFO): Handler Thread ID is 2388 2018-08-27T20:33:46.445Z,1535402026.445 [controlThread](DEBUG): Initializing ControlThread 2018-08-27T20:33:46.446Z,1535402026.446 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-08-27T20:33:46.447Z,1535402026.447 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-08-27T20:33:46.447Z,1535402026.447 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-08-27T20:33:46.447Z,1535402026.447 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-08-27T20:33:46.448Z,1535402026.448 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2018-08-27T20:33:46.449Z,1535402026.449 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-08-27T20:33:46.449Z,1535402026.449 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-08-27T20:33:46.454Z,1535402026.454 [SBIT](INFO): Initialize SBIT Component. 2018-08-27T20:33:46.454Z,1535402026.454 [SBIT](IMPORTANT): git: 2018-08-23a 2018-08-27T20:33:46.455Z,1535402026.455 [SBIT](INFO): git hash: f7a0edcfaf5a05cdb3ccc2a17aa2780e938d0460 2018-08-27T20:33:46.455Z,1535402026.455 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-08-27T20:33:46.456Z,1535402026.456 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2018-08-27T20:33:46.457Z,1535402026.457 [SBIT](INFO): Beginning SBIT in 68.000000 seconds. 2018-08-27T20:33:46.458Z,1535402026.458 [IBIT](INFO): Initialize IBIT Component. 2018-08-27T20:33:46.459Z,1535402026.459 [CBIT](DEBUG): Initialize CBIT Component. 2018-08-27T20:33:46.459Z,1535402026.459 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2018-08-27T20:33:46.460Z,1535402026.460 [logger ThreadHandler](INFO): Handler Thread ID is 2389 2018-08-27T20:33:46.477Z,1535402026.477 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2390 2018-08-27T20:33:46.483Z,1535402026.483 [Radio_Surface](INFO): Powering up 2018-08-27T20:33:46.489Z,1535402026.489 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 2391 2018-08-27T20:33:46.490Z,1535402026.490 [Rowe_600LCM](INFO): Initializing 2018-08-27T20:33:46.490Z,1535402026.490 [Rowe_600LCM](INFO): Checking LCM 2018-08-27T20:33:46.502Z,1535402026.502 [Rowe_600LCM](INFO): LCM OK 2018-08-27T20:33:46.502Z,1535402026.502 [Rowe_600LCM](INFO): Powering up 2018-08-27T20:33:46.509Z,1535402026.509 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2392 2018-08-27T20:33:46.510Z,1535402026.510 [CTD_Seabird](INFO): Initializing 2018-08-27T20:33:46.511Z,1535402026.511 [CTD_Seabird](INFO): Checking LCM 2018-08-27T20:33:46.511Z,1535402026.511 [CTD_Seabird](INFO): LCM OK 2018-08-27T20:33:46.511Z,1535402026.511 [CTD_Seabird](INFO): Powering up 2018-08-27T20:33:46.521Z,1535402026.521 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2393 2018-08-27T20:33:46.522Z,1535402026.522 [WetLabsBB2FL](INFO): Powering down 2018-08-27T20:33:46.545Z,1535402026.545 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2394 2018-08-27T20:33:46.550Z,1535402026.550 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI11M.000 2018-08-27T20:33:46.551Z,1535402026.551 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI11M.000 2018-08-27T20:33:46.551Z,1535402026.551 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI07M.000 2018-08-27T20:33:46.551Z,1535402026.551 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5MI07M.000 2018-08-27T20:33:46.551Z,1535402026.551 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5OH07M.000 2018-08-27T20:33:46.551Z,1535402026.551 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5OH07M.000 2018-08-27T20:33:46.552Z,1535402026.552 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5OH31M.000 2018-08-27T20:33:46.552Z,1535402026.552 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5OH31M.000 2018-08-27T20:33:46.569Z,1535402026.569 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-08-27T20:33:46.571Z,1535402026.571 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-08-27T20:33:46.572Z,1535402026.572 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-08-27T20:33:46.572Z,1535402026.572 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-08-27T20:33:46.574Z,1535402026.574 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-08-27T20:33:46.575Z,1535402026.575 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-08-27T20:33:46.575Z,1535402026.575 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-08-27T20:33:46.576Z,1535402026.576 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-08-27T20:33:46.577Z,1535402026.577 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-08-27T20:33:46.609Z,1535402026.609 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-08-27T20:33:46.650Z,1535402026.650 [MissionManager](DEBUG): 2018-08-27T20:33:46.651Z,1535402026.651 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-08-27T20:33:46.724Z,1535402026.724 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-08-27T20:33:46.725Z,1535402026.725 [Default:A.Wait](DEBUG): Construct Wait. 2018-08-27T20:33:46.727Z,1535402026.727 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-08-27T20:33:46.758Z,1535402026.758 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-08-27T20:33:46.778Z,1535402026.778 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-08-27T20:33:46.788Z,1535402026.788 [Default:E.Execute](DEBUG): Construct Execute. 2018-08-27T20:33:46.803Z,1535402026.803 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2018-08-27T20:33:46.808Z,1535402026.808 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,NAL9602,Onboard,PNI_TCM,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-08-27T20:33:46.845Z,1535402026.845 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-08-27T20:33:47.047Z,1535402027.047 [ESPComponent](INFO): powering down ESP 2018-08-27T20:33:47.617Z,1535402027.617 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-08-27T20:33:47.726Z,1535402027.726 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-08-27T20:33:47.733Z,1535402027.733 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-08-27T20:33:47.760Z,1535402027.760 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-08-27T20:33:47.765Z,1535402027.765 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-08-27T20:33:47.771Z,1535402027.771 [MassServo](DEBUG): Initializing EZServoServo. 2018-08-27T20:33:47.777Z,1535402027.777 [MassServo](DEBUG): Initializing MassServo. 2018-08-27T20:33:47.783Z,1535402027.783 [RudderServo](DEBUG): Initializing EZServoServo. 2018-08-27T20:33:47.789Z,1535402027.789 [RudderServo](DEBUG): Initializing RudderServo. 2018-08-27T20:33:47.811Z,1535402027.811 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-08-27T20:33:47.817Z,1535402027.817 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-08-27T20:33:48.114Z,1535402028.114 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-08-27T20:33:48.397Z,1535402028.397 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-08-27T20:33:48.839Z,1535402028.839 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-08-27T20:33:49.237Z,1535402029.237 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-08-27T20:33:49.649Z,1535402029.649 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-08-27T20:33:50.129Z,1535402030.129 [Aanderaa_O2](INFO): Powering down 2018-08-27T20:33:50.237Z,1535402030.237 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-08-27T20:33:50.443Z,1535402030.443 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-08-27T20:33:50.766Z,1535402030.766 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-08-27T20:33:50.904Z,1535402030.904 [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 & 2018-08-27T20:33:50.982Z,1535402030.982 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-08-27T20:33:50.982Z,1535402030.982 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-08-27T20:33:50.983Z,1535402030.983 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-08-27T20:33:52.271Z,1535402032.271 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-08-27T20:33:52.396Z,1535402032.396 [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-08-27T20:33:52.403Z,1535402032.403 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd 2018-08-27T20:33:53.249Z,1535402033.249 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-08-27T20:33:53.249Z,1535402033.249 [CTD_Seabird](INFO): Powering down 2018-08-27T20:34:14.498Z,1535402054.498 [NAL9602](INFO): Powering up NAL9602 2018-08-27T20:34:25.303Z,1535402065.303 [NAL9602](INFO): NAL9602 initialized 2018-08-27T20:34:54.985Z,1535402094.985 [SBIT](IMPORTANT): Beginning Startup BIT 2018-08-27T20:34:54.993Z,1535402094.993 [CBIT](IMPORTANT): Beginning ground fault scan 2018-08-27T20:35:06.131Z,1535402106.131 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.004527 CHAN A1 (24V): -0.007991 CHAN A2 (12V): -0.001350 CHAN A3 (5V): -0.000801 CHAN B0 (3.3V): -0.000699 CHAN B1 (3.15aV): 0.000024 CHAN B2 (3.15bV): -0.000813 CHAN B3 (GND): -0.000001 OPEN: 0.005205 Full Scale Calc: 4.765 mA, -1.589 mA 2018-08-27T20:35:47.173Z,1535402147.173 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-08-27T20:35:47.173Z,1535402147.173 [Rowe_600LCM] Communications Fault, FailCount= 1 2018-08-27T20:35:47.173Z,1535402147.173 [Rowe_600LCM](ERROR): Communications Fault 2018-08-27T20:35:47.389Z,1535402147.389 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-08-27T20:35:47.577Z,1535402147.577 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-08-27T20:35:47.915Z,1535402147.915 [Rowe_600LCM](INFO): Powering down 2018-08-27T20:35:48.903Z,1535402148.903 [SBIT](IMPORTANT): SBIT PASSED 2018-08-27T20:35:48.978Z,1535402148.978 [CommandLine](IMPORTANT): got command configSet list 2018-08-27T20:35:48.978Z,1535402148.978 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2018-08-27T20:35:48.979Z,1535402148.979 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.loadAtStartup=0 bool; 2018-08-27T20:35:48.979Z,1535402148.979 [CommandLine](IMPORTANT): BPC1.loadAtStartup=0 bool; 2018-08-27T20:35:48.980Z,1535402148.980 [CommandLine](IMPORTANT): CTD_Seabird.minSalinityBound=0.01 practical_salinity_unit; 2018-08-27T20:35:48.980Z,1535402148.980 [CommandLine](IMPORTANT): Depth_Keller.offset=-0.56 decibar; 2018-08-27T20:35:48.980Z,1535402148.980 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2018-08-27T20:35:48.980Z,1535402148.980 [CommandLine](IMPORTANT): Express linearApproximation CTD_Seabird.bin_median_sea_water_salinity 0.000500 practical_salinity_unit; 2018-08-27T20:35:48.980Z,1535402148.980 [CommandLine](IMPORTANT): Express linearApproximation CTD_Seabird.bin_median_sea_water_temperature 0.005000 kelvin; 2018-08-27T20:35:48.980Z,1535402148.980 [CommandLine](IMPORTANT): Express linearApproximation WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 0.100000 microgram_per_liter; 2018-08-27T20:35:48.980Z,1535402148.980 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 2.000000 meter; 2018-08-27T20:35:48.981Z,1535402148.981 [CommandLine](IMPORTANT): Express linearApproximation mass_concentration_of_chlorophyll_in_sea_water 1.000000 microgram_per_liter; 2018-08-27T20:35:48.981Z,1535402148.981 [CommandLine](IMPORTANT): IBIT.batteryHibernationTimeout=1 minute; 2018-08-27T20:35:48.981Z,1535402148.981 [CommandLine](IMPORTANT): IBIT.batteryMissingStickThreshold=18 count; 2018-08-27T20:35:48.981Z,1535402148.981 [CommandLine](IMPORTANT): NavChart.loadAtStartup=0 bool; 2018-08-27T20:35:48.981Z,1535402148.981 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=539 cubic_centimeter; 2018-08-27T20:35:48.981Z,1535402148.981 [CommandLine](IMPORTANT): VerticalControl.massDefault=-12 millimeter; 2018-08-27T20:35:49.292Z,1535402149.292 [MissionManager](IMPORTANT): Started mission Startup 2018-08-27T20:35:49.293Z,1535402149.293 [Startup] Running Loop=1 2018-08-27T20:35:49.293Z,1535402149.293 [Startup](DEBUG): Aggregate::initialize Startup 2018-08-27T20:35:49.293Z,1535402149.293 [Startup:A.GoToSurface] Running Loop=1 2018-08-27T20:35:49.293Z,1535402149.293 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-08-27T20:35:49.294Z,1535402149.294 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-08-27T20:35:49.295Z,1535402149.295 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-08-27T20:35:49.295Z,1535402149.295 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-08-27T20:35:49.295Z,1535402149.295 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using