2017-12-12T22:02:50.686Z,1513116170.686 [Supervisor](DEBUG): Initializing supervisor. 2017-12-12T22:02:50.688Z,1513116170.688 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2017-12-12T22:02:50.689Z,1513116170.689 [SyncHandler](INFO): Protected caller Thread ID is 806 2017-12-12T22:02:50.689Z,1513116170.689 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2017-12-12T22:02:50.690Z,1513116170.690 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2017-12-12T22:02:50.691Z,1513116170.691 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 807 2017-12-12T22:02:50.693Z,1513116170.693 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2017-12-12T22:02:50.704Z,1513116170.704 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2017-12-12T22:02:50.705Z,1513116170.705 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2017-12-12T22:02:50.705Z,1513116170.705 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 808 2017-12-12T22:02:50.706Z,1513116170.706 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2017-12-12T22:02:50.707Z,1513116170.707 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2017-12-12T22:02:50.707Z,1513116170.707 [logger ThreadHandler](INFO): Protected caller Thread ID is 809 2017-12-12T22:02:50.709Z,1513116170.709 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2017-12-12T22:02:50.710Z,1513116170.710 [Supervisor](INFO): Looking for Config files in directory: Config/ 2017-12-12T22:02:50.712Z,1513116170.712 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2017-12-12T22:02:50.808Z,1513116170.808 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2017-12-12T22:02:50.809Z,1513116170.809 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2017-12-12T22:02:50.909Z,1513116170.909 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2017-12-12T22:02:50.911Z,1513116170.911 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2017-12-12T22:02:51.105Z,1513116171.105 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2017-12-12T22:02:51.106Z,1513116171.106 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2017-12-12T22:02:51.397Z,1513116171.397 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2017-12-12T22:02:51.399Z,1513116171.399 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2017-12-12T22:02:51.710Z,1513116171.710 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2017-12-12T22:02:51.711Z,1513116171.711 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2017-12-12T22:02:52.147Z,1513116172.147 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2017-12-12T22:02:52.147Z,1513116172.147 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2017-12-12T22:02:52.474Z,1513116172.474 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2017-12-12T22:02:52.475Z,1513116172.475 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2017-12-12T22:02:52.893Z,1513116172.893 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2017-12-12T22:02:52.894Z,1513116172.894 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2017-12-12T22:02:52.975Z,1513116172.975 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2017-12-12T22:02:53.260Z,1513116173.260 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2017-12-12T22:02:53.262Z,1513116173.262 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2017-12-12T22:02:53.403Z,1513116173.403 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2017-12-12T22:02:53.405Z,1513116173.405 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2017-12-12T22:02:53.600Z,1513116173.600 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2017-12-12T22:02:53.602Z,1513116173.602 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2017-12-12T22:02:53.796Z,1513116173.796 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2017-12-12T22:02:53.797Z,1513116173.797 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2017-12-12T22:02:53.896Z,1513116173.896 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2017-12-12T22:02:53.897Z,1513116173.897 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2017-12-12T22:02:54.241Z,1513116174.241 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2017-12-12T22:02:54.243Z,1513116174.243 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2017-12-12T22:02:54.736Z,1513116174.736 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2017-12-12T22:02:54.964Z,1513116174.964 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2017-12-12T22:02:54.966Z,1513116174.966 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2017-12-12T22:02:55.043Z,1513116175.043 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2017-12-12T22:02:55.182Z,1513116175.182 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2017-12-12T22:02:55.273Z,1513116175.273 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2017-12-12T22:02:55.358Z,1513116175.358 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2017-12-12T22:02:55.491Z,1513116175.491 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2017-12-12T22:02:55.651Z,1513116175.651 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2017-12-12T22:02:55.747Z,1513116175.747 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2017-12-12T22:02:55.839Z,1513116175.839 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2017-12-12T22:02:55.934Z,1513116175.934 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2017-12-12T22:02:56.017Z,1513116176.017 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2017-12-12T22:02:56.022Z,1513116176.022 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2017-12-12T22:02:56.148Z,1513116176.148 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2017-12-12T22:02:56.150Z,1513116176.150 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2017-12-12T22:02:56.174Z,1513116176.174 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2017-12-12T22:02:56.175Z,1513116176.175 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2017-12-12T22:02:56.231Z,1513116176.231 [DepthRateCalculator] Loaded 2017-12-12T22:02:56.231Z,1513116176.231 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2017-12-12T22:02:56.237Z,1513116176.237 [PitchRateCalculator] Loaded 2017-12-12T22:02:56.237Z,1513116176.237 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2017-12-12T22:02:56.253Z,1513116176.253 [SpeedCalculator] Loaded 2017-12-12T22:02:56.253Z,1513116176.253 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2017-12-12T22:02:56.274Z,1513116176.274 [TempGradientCalculator] Loaded 2017-12-12T22:02:56.275Z,1513116176.275 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2017-12-12T22:02:56.290Z,1513116176.290 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2017-12-12T22:02:56.290Z,1513116176.290 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2017-12-12T22:02:56.296Z,1513116176.296 [YawRateCalculator] Loaded 2017-12-12T22:02:56.296Z,1513116176.296 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2017-12-12T22:02:56.296Z,1513116176.296 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2017-12-12T22:02:56.297Z,1513116176.297 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2017-12-12T22:02:56.333Z,1513116176.333 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2017-12-12T22:02:56.335Z,1513116176.335 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2017-12-12T22:02:56.782Z,1513116176.782 [AcousticModem_Benthos_ATM900] Loaded 2017-12-12T22:02:56.783Z,1513116176.783 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread. 2017-12-12T22:02:56.927Z,1513116176.927 [DataOverHttps] Loaded 2017-12-12T22:02:56.927Z,1513116176.927 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2017-12-12T22:02:56.940Z,1513116176.940 [Depth_Keller] Loaded 2017-12-12T22:02:56.941Z,1513116176.941 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2017-12-12T22:02:56.945Z,1513116176.945 [DropWeight] Loaded 2017-12-12T22:02:56.946Z,1513116176.946 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2017-12-12T22:02:57.042Z,1513116177.042 [NAL9602] Loaded 2017-12-12T22:02:57.042Z,1513116177.042 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2017-12-12T22:02:57.057Z,1513116177.057 [Onboard] Loaded 2017-12-12T22:02:57.058Z,1513116177.058 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2017-12-12T22:02:57.068Z,1513116177.068 [Radio_Surface] Loaded 2017-12-12T22:02:57.068Z,1513116177.068 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2017-12-12T22:02:57.069Z,1513116177.069 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407A44E0 2017-12-12T22:02:57.070Z,1513116177.070 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 888 2017-12-12T22:02:57.222Z,1513116177.222 [PNI_TCM] Loaded 2017-12-12T22:02:57.222Z,1513116177.222 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2017-12-12T22:02:57.282Z,1513116177.282 [Rowe_600LCM] Loaded 2017-12-12T22:02:57.282Z,1513116177.282 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2017-12-12T22:02:57.283Z,1513116177.283 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407D44E0 2017-12-12T22:02:57.284Z,1513116177.284 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 889 2017-12-12T22:02:59.005Z,1513116179.005 [BPC1] Loaded 2017-12-12T22:02:59.005Z,1513116179.005 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2017-12-12T22:02:59.006Z,1513116179.006 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2017-12-12T22:02:59.007Z,1513116179.007 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2017-12-12T22:02:59.160Z,1513116179.160 [SBIT](DEBUG): Construct Startup Built In Test. 2017-12-12T22:02:59.186Z,1513116179.186 [SBIT] Loaded 2017-12-12T22:02:59.186Z,1513116179.186 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2017-12-12T22:02:59.187Z,1513116179.187 [IBIT](DEBUG): Construct Initiated Built In Test. 2017-12-12T22:02:59.198Z,1513116179.198 [IBIT] Loaded 2017-12-12T22:02:59.199Z,1513116179.199 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2017-12-12T22:02:59.202Z,1513116179.202 [CBIT](DEBUG): Construct Continuous Built In Test. 2017-12-12T22:02:59.351Z,1513116179.351 [CBIT] Loaded 2017-12-12T22:02:59.351Z,1513116179.351 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2017-12-12T22:02:59.351Z,1513116179.351 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2017-12-12T22:02:59.352Z,1513116179.352 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2017-12-12T22:02:59.783Z,1513116179.783 [Aanderaa_O2] Loaded 2017-12-12T22:02:59.784Z,1513116179.784 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2017-12-12T22:02:59.860Z,1513116179.860 [CTD_NeilBrown] Loaded 2017-12-12T22:02:59.861Z,1513116179.861 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2017-12-12T22:02:59.862Z,1513116179.862 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4089A4E0 2017-12-12T22:02:59.862Z,1513116179.862 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 890 2017-12-12T22:02:59.894Z,1513116179.894 [CTD_Seabird] Loaded 2017-12-12T22:02:59.894Z,1513116179.894 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2017-12-12T22:02:59.895Z,1513116179.895 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408CA4E0 2017-12-12T22:02:59.896Z,1513116179.896 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 891 2017-12-12T22:02:59.912Z,1513116179.912 [ESPComponent] Loaded 2017-12-12T22:02:59.912Z,1513116179.912 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2017-12-12T22:02:59.926Z,1513116179.926 [PAR_Licor] Loaded 2017-12-12T22:02:59.927Z,1513116179.927 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2017-12-12T22:02:59.974Z,1513116179.974 [WetLabsBB2FL] Loaded 2017-12-12T22:02:59.975Z,1513116179.975 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2017-12-12T22:02:59.976Z,1513116179.976 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408FA4E0 2017-12-12T22:02:59.976Z,1513116179.976 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 892 2017-12-12T22:03:00.019Z,1513116180.019 [WetLabsSeaOWL_UV_A] Loaded 2017-12-12T22:03:00.019Z,1513116180.019 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2017-12-12T22:03:00.020Z,1513116180.020 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 4092A4E0 2017-12-12T22:03:00.021Z,1513116180.021 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 893 2017-12-12T22:03:00.021Z,1513116180.021 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2017-12-12T22:03:00.022Z,1513116180.022 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2017-12-12T22:03:00.302Z,1513116180.302 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2017-12-12T22:03:00.302Z,1513116180.302 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2017-12-12T22:03:00.389Z,1513116180.389 [VerticalControl](DEBUG): Construct VerticalControl. 2017-12-12T22:03:00.487Z,1513116180.487 [VerticalControl] Loaded 2017-12-12T22:03:00.487Z,1513116180.487 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2017-12-12T22:03:00.488Z,1513116180.488 [HorizontalControl](DEBUG): Construct HorizontalControl. 2017-12-12T22:03:00.548Z,1513116180.548 [HorizontalControl] Loaded 2017-12-12T22:03:00.548Z,1513116180.548 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2017-12-12T22:03:00.549Z,1513116180.549 [SpeedControl](DEBUG): Construct SpeedControl. 2017-12-12T22:03:00.554Z,1513116180.554 [SpeedControl] Loaded 2017-12-12T22:03:00.554Z,1513116180.554 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2017-12-12T22:03:00.555Z,1513116180.555 [LoopControl](DEBUG): Construct LoopControl. 2017-12-12T22:03:00.556Z,1513116180.556 [LoopControl] Loaded 2017-12-12T22:03:00.556Z,1513116180.556 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2017-12-12T22:03:00.556Z,1513116180.556 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2017-12-12T22:03:00.557Z,1513116180.557 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2017-12-12T22:03:00.676Z,1513116180.676 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2017-12-12T22:03:00.676Z,1513116180.676 [StratificationFrontDetector](DEBUG): (re)initializing 2017-12-12T22:03:00.676Z,1513116180.676 [StratificationFrontDetector] Loaded 2017-12-12T22:03:00.677Z,1513116180.677 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2017-12-12T22:03:00.677Z,1513116180.677 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2017-12-12T22:03:00.678Z,1513116180.678 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2017-12-12T22:03:00.795Z,1513116180.795 [BuoyancyServo] Loaded 2017-12-12T22:03:00.795Z,1513116180.795 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2017-12-12T22:03:00.806Z,1513116180.806 [ElevatorServo] Loaded 2017-12-12T22:03:00.807Z,1513116180.807 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2017-12-12T22:03:00.817Z,1513116180.817 [MassServo] Loaded 2017-12-12T22:03:00.818Z,1513116180.818 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2017-12-12T22:03:00.829Z,1513116180.829 [RudderServo] Loaded 2017-12-12T22:03:00.829Z,1513116180.829 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2017-12-12T22:03:00.839Z,1513116180.839 [ThrusterServo] Loaded 2017-12-12T22:03:00.840Z,1513116180.840 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2017-12-12T22:03:00.840Z,1513116180.840 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2017-12-12T22:03:00.841Z,1513116180.841 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2017-12-12T22:03:00.953Z,1513116180.953 [DeadReckonUsingMultipleVelocitySources] Loaded 2017-12-12T22:03:00.953Z,1513116180.953 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2017-12-12T22:03:01.008Z,1513116181.008 [DeadReckonUsingSpeedCalculator] Loaded 2017-12-12T22:03:01.008Z,1513116181.008 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2017-12-12T22:03:01.024Z,1513116181.024 [NavChart] Loaded 2017-12-12T22:03:01.024Z,1513116181.024 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2017-12-12T22:03:01.028Z,1513116181.028 [UniversalFixResidualReporter] Loaded 2017-12-12T22:03:01.029Z,1513116181.029 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2017-12-12T22:03:01.029Z,1513116181.029 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2017-12-12T22:03:01.033Z,1513116181.033 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2017-12-12T22:03:01.033Z,1513116181.033 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2017-12-12T22:03:01.040Z,1513116181.040 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2017-12-12T22:03:01.041Z,1513116181.041 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A7C4E0 2017-12-12T22:03:01.042Z,1513116181.042 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 894 2017-12-12T22:03:01.046Z,1513116181.046 [Supervisor](INFO): Main Thread ID is 798 2017-12-12T22:03:01.046Z,1513116181.046 [Supervisor](DEBUG): Running supervisor. 2017-12-12T22:03:01.047Z,1513116181.047 [CommandLine ThreadHandler](INFO): Handler Thread ID is 895 2017-12-12T22:03:01.049Z,1513116181.049 [controlThread ThreadHandler](INFO): Handler Thread ID is 896 2017-12-12T22:03:01.050Z,1513116181.050 [controlThread](DEBUG): Initializing ControlThread 2017-12-12T22:03:01.051Z,1513116181.051 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2017-12-12T22:03:01.051Z,1513116181.051 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2017-12-12T22:03:01.051Z,1513116181.051 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2017-12-12T22:03:01.051Z,1513116181.051 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2017-12-12T22:03:01.052Z,1513116181.052 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2017-12-12T22:03:01.053Z,1513116181.053 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2017-12-12T22:03:01.059Z,1513116181.059 [SBIT](INFO): Initialize SBIT Component. 2017-12-12T22:03:01.060Z,1513116181.060 [SBIT](IMPORTANT): git: 2017-12-11 2017-12-12T22:03:01.060Z,1513116181.060 [SBIT](INFO): git hash: 8651b6fcf04aad5c066f1d23bee794430771ed42 2017-12-12T22:03:01.060Z,1513116181.060 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2017-12-12T22:03:01.061Z,1513116181.061 [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 2017-12-12T22:03:01.062Z,1513116181.062 [SBIT](INFO): Beginning SBIT in 63.000000 seconds. 2017-12-12T22:03:01.063Z,1513116181.063 [IBIT](INFO): Initialize IBIT Component. 2017-12-12T22:03:01.064Z,1513116181.064 [CBIT](DEBUG): Initialize CBIT Component. 2017-12-12T22:03:01.064Z,1513116181.064 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2017-12-12T22:03:01.064Z,1513116181.064 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2017-12-12T22:03:01.065Z,1513116181.065 [logger ThreadHandler](INFO): Handler Thread ID is 897 2017-12-12T22:03:01.085Z,1513116181.085 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 898 2017-12-12T22:03:01.091Z,1513116181.091 [Radio_Surface](INFO): Powering up 2017-12-12T22:03:01.097Z,1513116181.097 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 899 2017-12-12T22:03:01.098Z,1513116181.098 [Rowe_600LCM](INFO): Initializing 2017-12-12T22:03:01.098Z,1513116181.098 [Rowe_600LCM](INFO): Checking LCM 2017-12-12T22:03:01.189Z,1513116181.189 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2017-12-12T22:03:01.191Z,1513116181.191 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2017-12-12T22:03:01.191Z,1513116181.191 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2017-12-12T22:03:01.192Z,1513116181.192 [LoopControl](DEBUG): Initialize LoopControlComponent. 2017-12-12T22:03:01.193Z,1513116181.193 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 900 2017-12-12T22:03:01.194Z,1513116181.194 [CTD_NeilBrown](INFO): Powering down 2017-12-12T22:03:01.219Z,1513116181.219 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 901 2017-12-12T22:03:01.219Z,1513116181.219 [CTD_Seabird](INFO): Initializing 2017-12-12T22:03:01.246Z,1513116181.246 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2017-12-12T22:03:01.247Z,1513116181.247 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2017-12-12T22:03:01.247Z,1513116181.247 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2017-12-12T22:03:01.248Z,1513116181.248 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-12-12T22:03:01.248Z,1513116181.248 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-12-12T22:03:01.250Z,1513116181.250 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 903 2017-12-12T22:03:01.251Z,1513116181.251 [WetLabsBB2FL](INFO): Powering down 2017-12-12T22:03:01.270Z,1513116181.270 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-12-12T22:03:01.271Z,1513116181.271 [NavChart](DEBUG): Initialize NavChart Navigation. 2017-12-12T22:03:01.273Z,1513116181.273 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2017-12-12T22:03:01.274Z,1513116181.274 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2017-12-12T22:03:01.414Z,1513116181.414 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 904 2017-12-12T22:03:01.416Z,1513116181.416 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 905 2017-12-12T22:03:01.433Z,1513116181.433 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2017-12-12T22:03:01.436Z,1513116181.436 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2017-12-12T22:03:01.436Z,1513116181.436 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2017-12-12T22:03:01.436Z,1513116181.436 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2017-12-12T22:03:01.437Z,1513116181.437 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2017-12-12T22:03:01.437Z,1513116181.437 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2017-12-12T22:03:01.437Z,1513116181.437 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2017-12-12T22:03:01.437Z,1513116181.437 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2017-12-12T22:03:01.437Z,1513116181.437 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2017-12-12T22:03:01.438Z,1513116181.438 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2017-12-12T22:03:01.438Z,1513116181.438 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2017-12-12T22:03:01.438Z,1513116181.438 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2017-12-12T22:03:01.438Z,1513116181.438 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2017-12-12T22:03:01.438Z,1513116181.438 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2017-12-12T22:03:01.438Z,1513116181.438 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2017-12-12T22:03:01.439Z,1513116181.439 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2017-12-12T22:03:01.439Z,1513116181.439 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2017-12-12T22:03:01.540Z,1513116181.540 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2017-12-12T22:03:01.541Z,1513116181.541 [CTD_Seabird](INFO): Powering down 2017-12-12T22:03:01.568Z,1513116181.568 [MissionManager](DEBUG): 2017-12-12T22:03:01.577Z,1513116181.577 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2017-12-12T22:03:01.714Z,1513116181.714 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2017-12-12T22:03:01.715Z,1513116181.715 [Default:A.Wait](DEBUG): Construct Wait. 2017-12-12T22:03:01.716Z,1513116181.716 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2017-12-12T22:03:01.725Z,1513116181.725 [Rowe_600LCM](INFO): LCM OK 2017-12-12T22:03:01.725Z,1513116181.725 [Rowe_600LCM](INFO): Powering up 2017-12-12T22:03:01.750Z,1513116181.750 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2017-12-12T22:03:01.752Z,1513116181.752 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2017-12-12T22:03:01.798Z,1513116181.798 [Default:E.Execute](DEBUG): Construct Execute. 2017-12-12T22:03:01.802Z,1513116181.802 [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 2017-12-12T22:03:01.817Z,1513116181.817 [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, 2017-12-12T22:03:01.825Z,1513116181.825 [AcousticModem_Benthos_ATM900](INFO): Powering up 2017-12-12T22:03:01.826Z,1513116181.826 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2017-12-12T22:03:01.851Z,1513116181.851 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2017-12-12T22:03:02.247Z,1513116182.247 [ESPComponent](INFO): powering down ESP 2017-12-12T22:03:02.762Z,1513116182.762 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-12T22:03:02.831Z,1513116182.831 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-12T22:03:02.942Z,1513116182.942 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2017-12-12T22:03:02.949Z,1513116182.949 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2017-12-12T22:03:02.977Z,1513116182.977 [ElevatorServo](DEBUG): Initializing EZServoServo. 2017-12-12T22:03:02.985Z,1513116182.985 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2017-12-12T22:03:02.999Z,1513116182.999 [MassServo](DEBUG): Initializing EZServoServo. 2017-12-12T22:03:03.006Z,1513116183.006 [MassServo](DEBUG): Initializing MassServo. 2017-12-12T22:03:03.020Z,1513116183.020 [RudderServo](DEBUG): Initializing EZServoServo. 2017-12-12T22:03:03.025Z,1513116183.025 [RudderServo](DEBUG): Initializing RudderServo. 2017-12-12T22:03:03.042Z,1513116183.042 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-12-12T22:03:03.045Z,1513116183.045 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-12-12T22:03:03.277Z,1513116183.277 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-12T22:03:03.278Z,1513116183.278 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-12T22:03:03.560Z,1513116183.560 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-12T22:03:03.577Z,1513116183.577 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-12T22:03:03.909Z,1513116183.909 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2017-12-12T22:03:03.909Z,1513116183.909 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2017-12-12T22:03:03.909Z,1513116183.909 [BuoyancyServo] Communications Fault, FailCount= 1 2017-12-12T22:03:03.909Z,1513116183.909 [BuoyancyServo](ERROR): Communications Fault 2017-12-12T22:03:04.112Z,1513116184.112 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2017-12-12T22:03:04.208Z,1513116184.208 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-12T22:03:04.217Z,1513116184.217 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-12T22:03:04.230Z,1513116184.230 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2017-12-12T22:03:04.231Z,1513116184.231 [BuoyancyServo](INFO): Powering down 2017-12-12T22:03:04.592Z,1513116184.592 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-12T22:03:04.605Z,1513116184.605 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-12T22:03:04.952Z,1513116184.952 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-12T22:03:04.961Z,1513116184.961 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-12T22:03:05.457Z,1513116185.457 [Aanderaa_O2](INFO): Powering down 2017-12-12T22:03:05.511Z,1513116185.511 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-12T22:03:05.513Z,1513116185.513 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-12T22:03:05.714Z,1513116185.714 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2017-12-12T22:03:05.800Z,1513116185.800 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-12T22:03:05.818Z,1513116185.818 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-12T22:03:05.864Z,1513116185.864 [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 & 2017-12-12T22:03:05.995Z,1513116185.995 [Rowe_