2015-11-04T00:23:41.992Z,1446596621.992 [Supervisor](DEBUG): Initializing supervisor. 2015-11-04T00:23:41.995Z,1446596621.995 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0 2015-11-04T00:23:41.995Z,1446596621.995 [SyncHandler](INFO): Protected caller Thread ID is 764 2015-11-04T00:23:41.996Z,1446596621.996 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2015-11-04T00:23:41.997Z,1446596621.997 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0 2015-11-04T00:23:41.997Z,1446596621.997 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 765 2015-11-04T00:23:41.000Z,1446596622.000 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2015-11-04T00:23:42.012Z,1446596622.012 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2015-11-04T00:23:42.013Z,1446596622.013 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0 2015-11-04T00:23:42.013Z,1446596622.013 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 766 2015-11-04T00:23:42.014Z,1446596622.014 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2015-11-04T00:23:42.015Z,1446596622.015 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0 2015-11-04T00:23:42.015Z,1446596622.015 [logger ThreadHandler](INFO): Protected caller Thread ID is 767 2015-11-04T00:23:42.017Z,1446596622.017 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2015-11-04T00:23:42.018Z,1446596622.018 [Supervisor](INFO): Looking for Config files in directory: Config/ 2015-11-04T00:23:42.021Z,1446596622.021 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2015-11-04T00:23:42.377Z,1446596622.377 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2015-11-04T00:23:42.379Z,1446596622.379 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2015-11-04T00:23:42.553Z,1446596622.553 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2015-11-04T00:23:42.555Z,1446596622.555 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2015-11-04T00:23:42.686Z,1446596622.686 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2015-11-04T00:23:42.688Z,1446596622.688 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2015-11-04T00:23:42.823Z,1446596622.823 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2015-11-04T00:23:42.824Z,1446596622.824 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2015-11-04T00:23:42.911Z,1446596622.911 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2015-11-04T00:23:43.111Z,1446596623.111 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2015-11-04T00:23:43.112Z,1446596623.112 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2015-11-04T00:23:43.199Z,1446596623.199 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2015-11-04T00:23:43.200Z,1446596623.200 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2015-11-04T00:23:43.485Z,1446596623.485 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2015-11-04T00:23:43.486Z,1446596623.486 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2015-11-04T00:23:43.847Z,1446596623.847 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2015-11-04T00:23:43.848Z,1446596623.848 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2015-11-04T00:23:44.171Z,1446596624.171 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2015-11-04T00:23:44.172Z,1446596624.172 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2015-11-04T00:23:44.714Z,1446596624.714 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2015-11-04T00:23:44.716Z,1446596624.716 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2015-11-04T00:23:44.929Z,1446596624.929 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2015-11-04T00:23:44.930Z,1446596624.930 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2015-11-04T00:23:45.037Z,1446596625.037 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2015-11-04T00:23:45.037Z,1446596625.037 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2015-11-04T00:23:45.501Z,1446596625.501 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2015-11-04T00:23:45.502Z,1446596625.502 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2015-11-04T00:23:45.616Z,1446596625.616 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2015-11-04T00:23:45.618Z,1446596625.618 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2015-11-04T00:23:45.621Z,1446596625.621 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2015-11-04T00:23:45.881Z,1446596625.881 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2015-11-04T00:23:45.882Z,1446596625.882 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2015-11-04T00:23:46.039Z,1446596626.039 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2015-11-04T00:23:46.146Z,1446596626.146 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2015-11-04T00:23:46.249Z,1446596626.249 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2015-11-04T00:23:46.347Z,1446596626.347 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2015-11-04T00:23:46.483Z,1446596626.483 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2015-11-04T00:23:46.643Z,1446596626.643 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2015-11-04T00:23:46.736Z,1446596626.736 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2015-11-04T00:23:46.836Z,1446596626.836 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2015-11-04T00:23:46.933Z,1446596626.933 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2015-11-04T00:23:47.054Z,1446596627.054 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2015-11-04T00:23:47.136Z,1446596627.136 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2015-11-04T00:23:47.148Z,1446596627.148 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2015-11-04T00:23:47.216Z,1446596627.216 [VerticalControl](DEBUG): Construct VerticalControl. 2015-11-04T00:23:47.336Z,1446596627.336 [VerticalControl] Loaded 2015-11-04T00:23:47.337Z,1446596627.337 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2015-11-04T00:23:47.337Z,1446596627.337 [HorizontalControl](DEBUG): Construct HorizontalControl. 2015-11-04T00:23:47.408Z,1446596627.408 [HorizontalControl] Loaded 2015-11-04T00:23:47.409Z,1446596627.409 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2015-11-04T00:23:47.409Z,1446596627.409 [SpeedControl](DEBUG): Construct SpeedControl. 2015-11-04T00:23:47.415Z,1446596627.415 [SpeedControl] Loaded 2015-11-04T00:23:47.415Z,1446596627.415 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2015-11-04T00:23:47.416Z,1446596627.416 [LoopControl](DEBUG): Construct LoopControl. 2015-11-04T00:23:47.417Z,1446596627.417 [LoopControl] Loaded 2015-11-04T00:23:47.417Z,1446596627.417 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2015-11-04T00:23:47.417Z,1446596627.417 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2015-11-04T00:23:47.419Z,1446596627.419 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2015-11-04T00:23:47.562Z,1446596627.562 [SBIT](DEBUG): Construct Startup Built In Test. 2015-11-04T00:23:47.574Z,1446596627.574 [SBIT] Loaded 2015-11-04T00:23:47.574Z,1446596627.574 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2015-11-04T00:23:47.575Z,1446596627.575 [IBIT](DEBUG): Construct Initiated Built In Test. 2015-11-04T00:23:47.604Z,1446596627.604 [IBIT] Loaded 2015-11-04T00:23:47.605Z,1446596627.605 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2015-11-04T00:23:47.607Z,1446596627.607 [CBIT](DEBUG): Construct Continuous Built In Test. 2015-11-04T00:23:47.747Z,1446596627.747 [CBIT] Loaded 2015-11-04T00:23:47.747Z,1446596627.747 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2015-11-04T00:23:47.747Z,1446596627.747 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2015-11-04T00:23:47.748Z,1446596627.748 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2015-11-04T00:23:47.785Z,1446596627.785 [DepthRateCalculator] Loaded 2015-11-04T00:23:47.786Z,1446596627.786 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2015-11-04T00:23:47.791Z,1446596627.791 [PitchRateCalculator] Loaded 2015-11-04T00:23:47.791Z,1446596627.791 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2015-11-04T00:23:47.803Z,1446596627.803 [SpeedCalculator] Loaded 2015-11-04T00:23:47.803Z,1446596627.803 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2015-11-04T00:23:47.820Z,1446596627.820 [TempGradientCalculator] Loaded 2015-11-04T00:23:47.820Z,1446596627.820 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2015-11-04T00:23:47.837Z,1446596627.837 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2015-11-04T00:23:47.837Z,1446596627.837 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2015-11-04T00:23:47.843Z,1446596627.843 [YawRateCalculator] Loaded 2015-11-04T00:23:47.843Z,1446596627.843 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2015-11-04T00:23:47.843Z,1446596627.843 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2015-11-04T00:23:47.845Z,1446596627.845 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2015-11-04T00:23:47.958Z,1446596627.958 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2015-11-04T00:23:47.958Z,1446596627.958 [StratificationFrontDetector](DEBUG): (re)initializing 2015-11-04T00:23:47.958Z,1446596627.958 [StratificationFrontDetector] Loaded 2015-11-04T00:23:47.959Z,1446596627.959 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2015-11-04T00:23:47.959Z,1446596627.959 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2015-11-04T00:23:47.960Z,1446596627.960 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2015-11-04T00:23:48.220Z,1446596628.220 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2015-11-04T00:23:48.221Z,1446596628.221 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2015-11-04T00:23:48.339Z,1446596628.339 [DeadReckonUsingMultipleVelocitySources] Loaded 2015-11-04T00:23:48.339Z,1446596628.339 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2015-11-04T00:23:48.394Z,1446596628.394 [DeadReckonUsingSpeedCalculator] Loaded 2015-11-04T00:23:48.394Z,1446596628.394 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2015-11-04T00:23:48.411Z,1446596628.411 [NavChart] Loaded 2015-11-04T00:23:48.411Z,1446596628.411 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2015-11-04T00:23:48.416Z,1446596628.416 [UniversalFixResidualReporter] Loaded 2015-11-04T00:23:48.416Z,1446596628.416 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2015-11-04T00:23:48.417Z,1446596628.417 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2015-11-04T00:23:48.417Z,1446596628.417 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2015-11-04T00:23:48.426Z,1446596628.426 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2015-11-04T00:23:48.426Z,1446596628.426 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2015-11-04T00:23:48.648Z,1446596628.648 [Aanderaa_O2] Loaded 2015-11-04T00:23:48.648Z,1446596628.648 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2015-11-04T00:23:48.726Z,1446596628.726 [CTD_NeilBrown] Loaded 2015-11-04T00:23:48.726Z,1446596628.726 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2015-11-04T00:23:48.727Z,1446596628.727 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4059D4E0 2015-11-04T00:23:48.728Z,1446596628.728 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 849 2015-11-04T00:23:48.743Z,1446596628.743 [PAR_Licor] Loaded 2015-11-04T00:23:48.744Z,1446596628.744 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2015-11-04T00:23:48.794Z,1446596628.794 [WetLabsBB2FL] Loaded 2015-11-04T00:23:48.794Z,1446596628.794 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2015-11-04T00:23:48.795Z,1446596628.795 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 405CD4E0 2015-11-04T00:23:48.795Z,1446596628.795 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 850 2015-11-04T00:23:48.796Z,1446596628.796 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2015-11-04T00:23:48.797Z,1446596628.797 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2015-11-04T00:23:49.441Z,1446596629.441 [AHRS_sp3003D] Loaded 2015-11-04T00:23:49.442Z,1446596629.442 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread. 2015-11-04T00:23:49.603Z,1446596629.603 [DataOverHttps] Loaded 2015-11-04T00:23:49.603Z,1446596629.603 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2015-11-04T00:23:49.617Z,1446596629.617 [Depth_Keller] Loaded 2015-11-04T00:23:49.617Z,1446596629.617 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2015-11-04T00:23:49.622Z,1446596629.622 [DropWeight] Loaded 2015-11-04T00:23:49.622Z,1446596629.622 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2015-11-04T00:23:49.758Z,1446596629.758 [DVL_micro] Loaded 2015-11-04T00:23:49.759Z,1446596629.759 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2015-11-04T00:23:49.853Z,1446596629.853 [NAL9602] Loaded 2015-11-04T00:23:49.853Z,1446596629.853 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2015-11-04T00:23:49.895Z,1446596629.895 [Onboard] Loaded 2015-11-04T00:23:49.895Z,1446596629.895 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2015-11-04T00:23:49.902Z,1446596629.902 [Radio_Surface] Loaded 2015-11-04T00:23:49.903Z,1446596629.903 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2015-11-04T00:23:49.904Z,1446596629.904 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408124E0 2015-11-04T00:23:49.904Z,1446596629.904 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 851 2015-11-04T00:23:50.045Z,1446596630.045 [DAT] Loaded 2015-11-04T00:23:50.045Z,1446596630.045 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2015-11-04T00:23:51.925Z,1446596631.925 [BPC1] Loaded 2015-11-04T00:23:51.926Z,1446596631.926 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2015-11-04T00:23:51.926Z,1446596631.926 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2015-11-04T00:23:51.927Z,1446596631.927 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2015-11-04T00:23:52.048Z,1446596632.048 [BuoyancyServo] Loaded 2015-11-04T00:23:52.048Z,1446596632.048 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2015-11-04T00:23:52.061Z,1446596632.061 [ElevatorServo] Loaded 2015-11-04T00:23:52.061Z,1446596632.061 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2015-11-04T00:23:52.073Z,1446596632.073 [MassServo] Loaded 2015-11-04T00:23:52.074Z,1446596632.074 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2015-11-04T00:23:52.086Z,1446596632.086 [RudderServo] Loaded 2015-11-04T00:23:52.086Z,1446596632.086 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2015-11-04T00:23:52.098Z,1446596632.098 [ThrusterServo] Loaded 2015-11-04T00:23:52.098Z,1446596632.098 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2015-11-04T00:23:52.098Z,1446596632.098 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2015-11-04T00:23:52.099Z,1446596632.099 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2015-11-04T00:23:52.227Z,1446596632.227 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2015-11-04T00:23:52.228Z,1446596632.228 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2015-11-04T00:23:52.266Z,1446596632.266 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2015-11-04T00:23:52.270Z,1446596632.270 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2015-11-04T00:23:52.271Z,1446596632.271 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2015-11-04T00:23:52.278Z,1446596632.278 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2015-11-04T00:23:52.279Z,1446596632.279 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 408C04E0 2015-11-04T00:23:52.279Z,1446596632.279 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 852 2015-11-04T00:23:52.284Z,1446596632.284 [Supervisor](INFO): Main Thread ID is 761 2015-11-04T00:23:52.284Z,1446596632.284 [Supervisor](DEBUG): Running supervisor. 2015-11-04T00:23:52.285Z,1446596632.285 [CommandLine ThreadHandler](INFO): Handler Thread ID is 853 2015-11-04T00:23:52.288Z,1446596632.288 [controlThread ThreadHandler](INFO): Handler Thread ID is 854 2015-11-04T00:23:52.288Z,1446596632.288 [controlThread](DEBUG): Initializing ControlThread 2015-11-04T00:23:52.289Z,1446596632.289 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2015-11-04T00:23:52.291Z,1446596632.291 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2015-11-04T00:23:52.292Z,1446596632.292 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2015-11-04T00:23:52.293Z,1446596632.293 [LoopControl](DEBUG): Initialize LoopControlComponent. 2015-11-04T00:23:52.293Z,1446596632.293 [SBIT](INFO): Initialize SBIT Component. 2015-11-04T00:23:52.293Z,1446596632.293 [SBIT](IMPORTANT): Tethys CM Info: SVN revision:12656 2015-11-04T00:23:52.294Z,1446596632.294 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2015-11-04T00:23:52.295Z,1446596632.295 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #641 PREEMPT Mon Sep 28 16:45:10 PDT 2015 2015-11-04T00:23:52.296Z,1446596632.296 [SBIT](INFO): Beginning SBIT in 32.000000 seconds. 2015-11-04T00:23:52.296Z,1446596632.296 [IBIT](INFO): Initialize IBIT Component. 2015-11-04T00:23:52.297Z,1446596632.297 [CBIT](DEBUG): Initialize CBIT Component. 2015-11-04T00:23:52.298Z,1446596632.298 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2015-11-04T00:23:52.299Z,1446596632.299 [logger ThreadHandler](INFO): Handler Thread ID is 855 2015-11-04T00:23:52.318Z,1446596632.318 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 856 2015-11-04T00:23:52.319Z,1446596632.319 [CTD_NeilBrown](INFO): Powering down 2015-11-04T00:23:52.322Z,1446596632.322 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2015-11-04T00:23:52.323Z,1446596632.323 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2015-11-04T00:23:52.323Z,1446596632.323 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2015-11-04T00:23:52.323Z,1446596632.323 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2015-11-04T00:23:52.324Z,1446596632.324 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2015-11-04T00:23:52.324Z,1446596632.324 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2015-11-04T00:23:52.325Z,1446596632.325 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2015-11-04T00:23:52.326Z,1446596632.326 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2015-11-04T00:23:52.330Z,1446596632.330 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2015-11-04T00:23:52.330Z,1446596632.330 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2015-11-04T00:23:52.331Z,1446596632.331 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2015-11-04T00:23:52.331Z,1446596632.331 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2015-11-04T00:23:52.332Z,1446596632.332 [NavChart](DEBUG): Initialize NavChart Navigation. 2015-11-04T00:23:52.332Z,1446596632.332 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2015-11-04T00:23:52.342Z,1446596632.342 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2015-11-04T00:23:52.358Z,1446596632.358 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 857 2015-11-04T00:23:52.367Z,1446596632.367 [WetLabsBB2FL](INFO): Powering down 2015-11-04T00:23:52.370Z,1446596632.370 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2015-11-04T00:23:52.409Z,1446596632.409 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 858 2015-11-04T00:23:52.427Z,1446596632.427 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 859 2015-11-04T00:23:52.434Z,1446596632.434 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources 2015-11-04T00:23:52.436Z,1446596632.436 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2015-11-04T00:23:52.437Z,1446596632.437 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2015-11-04T00:23:52.437Z,1446596632.437 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2015-11-04T00:23:52.437Z,1446596632.437 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2015-11-04T00:23:52.437Z,1446596632.437 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2015-11-04T00:23:52.438Z,1446596632.438 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2015-11-04T00:23:52.438Z,1446596632.438 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2015-11-04T00:23:52.438Z,1446596632.438 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2015-11-04T00:23:52.447Z,1446596632.447 [Radio_Surface](INFO): Powering up 2015-11-04T00:23:52.449Z,1446596632.449 [MissionManager](DEBUG): 2015-11-04T00:23:52.449Z,1446596632.449 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2015-11-04T00:23:52.533Z,1446596632.533 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2015-11-04T00:23:52.534Z,1446596632.534 [Default:A.GoToSurface](DEBUG): Construct GoToSurface. 2015-11-04T00:23:52.549Z,1446596632.549 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2015-11-04T00:23:52.576Z,1446596632.576 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2015-11-04T00:23:52.582Z,1446596632.582 [Default:D.Execute](DEBUG): Construct Execute. 2015-11-04T00:23:52.605Z,1446596632.605 [MissionManager](DEBUG): 0 Burn 300 Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2015-11-04T00:23:52.615Z,1446596632.615 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,PAR_Licor,AHRS_sp3003D,DataOverHttps,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,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, 2015-11-04T00:23:52.768Z,1446596632.768 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D. 2015-11-04T00:23:52.797Z,1446596632.797 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:23:53.190Z,1446596633.190 [DVL_micro](INFO): Initializing 2015-11-04T00:23:53.280Z,1446596633.280 [DAT](INFO): Powering up 2015-11-04T00:23:53.280Z,1446596633.280 [DAT](DEBUG): Initializing DAT. 2015-11-04T00:23:53.488Z,1446596633.488 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2015-11-04T00:23:53.508Z,1446596633.508 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2015-11-04T00:23:53.653Z,1446596633.653 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2015-11-04T00:23:53.661Z,1446596633.661 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2015-11-04T00:23:53.675Z,1446596633.675 [ElevatorServo](DEBUG): Initializing EZServoServo. 2015-11-04T00:23:53.688Z,1446596633.688 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2015-11-04T00:23:53.710Z,1446596633.710 [MassServo](DEBUG): Initializing EZServoServo. 2015-11-04T00:23:53.716Z,1446596633.716 [MassServo](DEBUG): Initializing MassServo. 2015-11-04T00:23:53.722Z,1446596633.722 [RudderServo](DEBUG): Initializing EZServoServo. 2015-11-04T00:23:53.728Z,1446596633.728 [RudderServo](DEBUG): Initializing RudderServo. 2015-11-04T00:23:53.754Z,1446596633.754 [ThrusterServo](DEBUG): Initializing EZServoServo. 2015-11-04T00:23:53.761Z,1446596633.761 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2015-11-04T00:23:53.993Z,1446596633.993 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:23:54.111Z,1446596634.111 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2015-11-04T00:23:54.112Z,1446596634.112 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2015-11-04T00:23:54.565Z,1446596634.565 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:23:55.261Z,1446596635.261 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:23:55.265Z,1446596635.265 [NAL9602](INFO): Powering up NAL9602 2015-11-04T00:23:55.685Z,1446596635.685 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:23:56.342Z,1446596636.342 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:23:57.103Z,1446596637.103 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:23:57.780Z,1446596637.780 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:23:58.366Z,1446596638.366 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:23:58.393Z,1446596638.393 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:23:59.207Z,1446596639.207 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:23:59.211Z,1446596639.211 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:23:59.704Z,1446596639.704 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:23:59.708Z,1446596639.708 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:24:00.200Z,1446596640.200 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:24:00.214Z,1446596640.214 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:24:00.921Z,1446596640.921 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:24:00.925Z,1446596640.925 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:24:01.707Z,1446596641.707 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:24:01.711Z,1446596641.711 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:24:02.204Z,1446596642.204 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:24:02.207Z,1446596642.207 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:24:02.708Z,1446596642.708 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:24:02.712Z,1446596642.712 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:24:02.854Z,1446596642.854 [Aanderaa_O2](FAULT): Timed out starting 2015-11-04T00:24:02.854Z,1446596642.854 [Aanderaa_O2] Communications Fault, FailCount= 1 2015-11-04T00:24:02.854Z,1446596642.854 [Aanderaa_O2](ERROR): Communications Fault 2015-11-04T00:24:03.173Z,1446596643.173 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:24:03.177Z,1446596643.177 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:24:03.382Z,1446596643.382 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2015-11-04T00:24:03.507Z,1446596643.507 [Aanderaa_O2](INFO): Powering down 2015-11-04T00:24:03.878Z,1446596643.878 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:24:03.882Z,1446596643.882 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:24:04.419Z,1446596644.419 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:24:04.423Z,1446596644.423 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:24:04.956Z,1446596644.956 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:24:04.960Z,1446596644.960 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:24:05.394Z,1446596645.394 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2015-11-04T00:24:05.394Z,1446596645.394 [Aanderaa_O2] No Fault, FailCount= 1 2015-11-04T00:24:05.725Z,1446596645.725 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:24:05.730Z,1446596645.730 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:24:06.234Z,1446596646.234 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:24:06.238Z,1446596646.238 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:24:06.735Z,1446596646.735 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:24:06.739Z,1446596646.739 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:24:06.763Z,1446596646.763 [NAL9602](INFO): NAL9602 initialized 2015-11-04T00:24:07.267Z,1446596647.267 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:24:07.271Z,1446596647.271 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:24:07.797Z,1446596647.797 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:24:07.805Z,1446596647.805 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:24:08.363Z,1446596648.363 [Aanderaa_O2](INFO): Powering down 2015-11-04T00:24:08.681Z,1446596648.681 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:24:08.686Z,1446596648.686 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:24:09.174Z,1446596649.174 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:24:09.178Z,1446596649.178 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:24:09.667Z,1446596649.667 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:24:09.671Z,1446596649.671 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:24:10.164Z,1446596650.164 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:24:10.168Z,1446596650.168 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:24:10.641Z,1446596650.641 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:24:10.644Z,1446596650.644 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:24:11.145Z,1446596651.145 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:24:11.149Z,1446596651.149 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:24:11.782Z,1446596651.782 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:24:11.786Z,1446596651.786 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:24:12.299Z,1446596652.299 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:24:12.303Z,1446596652.303 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:24:13.036Z,1446596653.036 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:24:13.040Z,1446596653.040 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:24:13.482Z,1446596653.482 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:24:13.485Z,1446596653.485 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:24:13.934Z,1446596653.934 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:24:13.938Z,1446596653.938 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:24:14.402Z,1446596654.402 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:24:14.406Z,1446596654.406 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:24:14.899Z,1446596654.899 [DataOverHttps](DEBUG): tcpConnect 2015-11-04T00:24:14.903Z,1446596654.903 [DVL_micro](DEBUG): LCB error: Software Overcurrent. 2015-11-04T00:24:15.396Z,1446596655.396 [DataOverHttps](DEBUG): tcpConnect 2015-