2017-12-12T17:20:59.157Z,1513099259.157 [Supervisor](DEBUG): Initializing supervisor. 2017-12-12T17:20:59.160Z,1513099259.160 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0 2017-12-12T17:20:59.161Z,1513099259.161 [SyncHandler](INFO): Protected caller Thread ID is 769 2017-12-12T17:20:59.161Z,1513099259.161 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2017-12-12T17:20:59.162Z,1513099259.162 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0 2017-12-12T17:20:59.162Z,1513099259.162 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 770 2017-12-12T17:20:59.165Z,1513099259.165 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2017-12-12T17:20:59.176Z,1513099259.176 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2017-12-12T17:20:59.177Z,1513099259.177 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0 2017-12-12T17:20:59.178Z,1513099259.178 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 771 2017-12-12T17:20:59.178Z,1513099259.178 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2017-12-12T17:20:59.179Z,1513099259.179 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0 2017-12-12T17:20:59.180Z,1513099259.180 [logger ThreadHandler](INFO): Protected caller Thread ID is 772 2017-12-12T17:20:59.182Z,1513099259.182 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2017-12-12T17:20:59.182Z,1513099259.182 [Supervisor](INFO): Looking for Config files in directory: Config/ 2017-12-12T17:20:59.186Z,1513099259.186 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2017-12-12T17:20:59.365Z,1513099259.365 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2017-12-12T17:20:59.366Z,1513099259.366 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2017-12-12T17:20:59.504Z,1513099259.504 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2017-12-12T17:20:59.504Z,1513099259.504 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2017-12-12T17:20:59.819Z,1513099259.819 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2017-12-12T17:20:59.819Z,1513099259.819 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2017-12-12T17:20:59.955Z,1513099259.955 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2017-12-12T17:20:59.956Z,1513099259.956 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2017-12-12T17:21:00.041Z,1513099260.041 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2017-12-12T17:21:00.231Z,1513099260.231 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2017-12-12T17:21:00.233Z,1513099260.233 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2017-12-12T17:21:00.334Z,1513099260.334 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2017-12-12T17:21:00.336Z,1513099260.336 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2017-12-12T17:21:00.661Z,1513099260.661 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2017-12-12T17:21:00.662Z,1513099260.662 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2017-12-12T17:21:01.012Z,1513099261.012 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2017-12-12T17:21:01.013Z,1513099261.013 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2017-12-12T17:21:01.317Z,1513099261.317 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2017-12-12T17:21:01.318Z,1513099261.318 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2017-12-12T17:21:01.800Z,1513099261.800 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2017-12-12T17:21:01.801Z,1513099261.801 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2017-12-12T17:21:02.028Z,1513099262.028 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2017-12-12T17:21:06.133Z,1513099266.133 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2017-12-12T17:21:06.293Z,1513099266.293 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2017-12-12T17:21:06.294Z,1513099266.294 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2017-12-12T17:21:06.816Z,1513099266.816 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2017-12-12T17:21:06.817Z,1513099266.817 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2017-12-12T17:21:06.982Z,1513099266.982 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2017-12-12T17:21:06.984Z,1513099266.984 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2017-12-12T17:21:09.735Z,1513099269.735 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2017-12-12T17:21:10.120Z,1513099270.120 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2017-12-12T17:21:10.120Z,1513099270.120 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2017-12-12T17:21:10.223Z,1513099270.223 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2017-12-12T17:21:10.317Z,1513099270.317 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2017-12-12T17:21:10.421Z,1513099270.421 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2017-12-12T17:21:10.510Z,1513099270.510 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2017-12-12T17:21:10.637Z,1513099270.637 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2017-12-12T17:21:10.786Z,1513099270.786 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2017-12-12T17:21:10.885Z,1513099270.885 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2017-12-12T17:21:10.980Z,1513099270.980 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2017-12-12T17:21:11.069Z,1513099271.069 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2017-12-12T17:21:11.264Z,1513099271.264 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2017-12-12T17:21:11.348Z,1513099271.348 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/ 2017-12-12T17:21:11.348Z,1513099271.348 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2017-12-12T17:21:11.364Z,1513099271.364 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2017-12-12T17:21:11.503Z,1513099271.503 [SBIT](DEBUG): Construct Startup Built In Test. 2017-12-12T17:21:11.531Z,1513099271.531 [SBIT] Loaded 2017-12-12T17:21:11.532Z,1513099271.532 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2017-12-12T17:21:11.532Z,1513099271.532 [IBIT](DEBUG): Construct Initiated Built In Test. 2017-12-12T17:21:11.559Z,1513099271.559 [IBIT] Loaded 2017-12-12T17:21:11.559Z,1513099271.559 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2017-12-12T17:21:11.563Z,1513099271.563 [CBIT](DEBUG): Construct Continuous Built In Test. 2017-12-12T17:21:11.712Z,1513099271.712 [CBIT] Loaded 2017-12-12T17:21:11.712Z,1513099271.712 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2017-12-12T17:21:11.713Z,1513099271.713 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2017-12-12T17:21:11.713Z,1513099271.713 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2017-12-12T17:21:11.796Z,1513099271.796 [VerticalControl](DEBUG): Construct VerticalControl. 2017-12-12T17:21:11.891Z,1513099271.891 [VerticalControl] Loaded 2017-12-12T17:21:11.892Z,1513099271.892 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2017-12-12T17:21:11.892Z,1513099271.892 [HorizontalControl](DEBUG): Construct HorizontalControl. 2017-12-12T17:21:11.950Z,1513099271.950 [HorizontalControl] Loaded 2017-12-12T17:21:11.951Z,1513099271.951 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2017-12-12T17:21:11.951Z,1513099271.951 [SpeedControl](DEBUG): Construct SpeedControl. 2017-12-12T17:21:11.957Z,1513099271.957 [SpeedControl] Loaded 2017-12-12T17:21:11.957Z,1513099271.957 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2017-12-12T17:21:11.958Z,1513099271.958 [LoopControl](DEBUG): Construct LoopControl. 2017-12-12T17:21:11.958Z,1513099271.958 [LoopControl] Loaded 2017-12-12T17:21:11.958Z,1513099271.958 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2017-12-12T17:21:11.959Z,1513099271.959 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2017-12-12T17:21:11.959Z,1513099271.959 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2017-12-12T17:21:12.011Z,1513099272.011 [DepthRateCalculator] Loaded 2017-12-12T17:21:12.011Z,1513099272.011 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2017-12-12T17:21:12.016Z,1513099272.016 [PitchRateCalculator] Loaded 2017-12-12T17:21:12.017Z,1513099272.017 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2017-12-12T17:21:12.032Z,1513099272.032 [SpeedCalculator] Loaded 2017-12-12T17:21:12.032Z,1513099272.032 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2017-12-12T17:21:12.052Z,1513099272.052 [TempGradientCalculator] Loaded 2017-12-12T17:21:12.052Z,1513099272.052 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2017-12-12T17:21:12.068Z,1513099272.068 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2017-12-12T17:21:12.068Z,1513099272.068 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2017-12-12T17:21:12.073Z,1513099272.073 [YawRateCalculator] Loaded 2017-12-12T17:21:12.073Z,1513099272.073 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2017-12-12T17:21:12.074Z,1513099272.074 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2017-12-12T17:21:12.075Z,1513099272.075 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2017-12-12T17:21:16.667Z,1513099276.667 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2017-12-12T17:21:16.668Z,1513099276.668 [StratificationFrontDetector](DEBUG): (re)initializing 2017-12-12T17:21:16.668Z,1513099276.668 [StratificationFrontDetector] Loaded 2017-12-12T17:21:16.668Z,1513099276.668 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2017-12-12T17:21:16.669Z,1513099276.669 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2017-12-12T17:21:16.669Z,1513099276.669 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2017-12-12T17:21:16.918Z,1513099276.918 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2017-12-12T17:21:16.919Z,1513099276.919 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2017-12-12T17:21:17.026Z,1513099277.026 [DeadReckonUsingSpeedCalculator] Loaded 2017-12-12T17:21:17.026Z,1513099277.026 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2017-12-12T17:21:17.078Z,1513099277.078 [DeadReckonWithRespectToSeafloor] Loaded 2017-12-12T17:21:17.078Z,1513099277.078 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2017-12-12T17:21:17.093Z,1513099277.093 [NavChart] Loaded 2017-12-12T17:21:17.093Z,1513099277.093 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2017-12-12T17:21:17.098Z,1513099277.098 [UniversalFixResidualReporter] Loaded 2017-12-12T17:21:17.098Z,1513099277.098 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2017-12-12T17:21:17.098Z,1513099277.098 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2017-12-12T17:21:17.099Z,1513099277.099 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2017-12-12T17:21:17.125Z,1513099277.125 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2017-12-12T17:21:17.125Z,1513099277.125 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2017-12-12T17:21:17.313Z,1513099277.313 [Aanderaa_O2] Loaded 2017-12-12T17:21:17.313Z,1513099277.313 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2017-12-12T17:21:17.325Z,1513099277.325 [CANONSampler] Loaded 2017-12-12T17:21:17.325Z,1513099277.325 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2017-12-12T17:21:17.400Z,1513099277.400 [CTD_NeilBrown] Loaded 2017-12-12T17:21:17.400Z,1513099277.400 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2017-12-12T17:21:17.401Z,1513099277.401 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 407984E0 2017-12-12T17:21:17.402Z,1513099277.402 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 856 2017-12-12T17:21:17.433Z,1513099277.433 [CTD_Seabird] Loaded 2017-12-12T17:21:17.433Z,1513099277.433 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2017-12-12T17:21:17.434Z,1513099277.434 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 407C84E0 2017-12-12T17:21:17.435Z,1513099277.435 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 857 2017-12-12T17:21:17.449Z,1513099277.449 [PAR_Licor] Loaded 2017-12-12T17:21:17.449Z,1513099277.449 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2017-12-12T17:21:17.496Z,1513099277.496 [WetLabsBB2FL] Loaded 2017-12-12T17:21:17.496Z,1513099277.496 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2017-12-12T17:21:17.497Z,1513099277.497 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407F84E0 2017-12-12T17:21:17.498Z,1513099277.498 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 858 2017-12-12T17:21:17.540Z,1513099277.540 [WetLabsSeaOWL_UV_A] Loaded 2017-12-12T17:21:17.540Z,1513099277.540 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2017-12-12T17:21:17.541Z,1513099277.541 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408284E0 2017-12-12T17:21:17.541Z,1513099277.541 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 859 2017-12-12T17:21:17.542Z,1513099277.542 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2017-12-12T17:21:17.542Z,1513099277.542 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2017-12-12T17:21:22.552Z,1513099282.552 [DataOverHttps] Loaded 2017-12-12T17:21:22.553Z,1513099282.553 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2017-12-12T17:21:22.565Z,1513099282.565 [Depth_Keller] Loaded 2017-12-12T17:21:22.566Z,1513099282.566 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2017-12-12T17:21:22.570Z,1513099282.570 [DropWeight] Loaded 2017-12-12T17:21:22.571Z,1513099282.571 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2017-12-12T17:21:22.657Z,1513099282.657 [NAL9602] Loaded 2017-12-12T17:21:22.658Z,1513099282.658 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2017-12-12T17:21:22.663Z,1513099282.663 [Onboard] Loaded 2017-12-12T17:21:22.663Z,1513099282.663 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2017-12-12T17:21:22.670Z,1513099282.670 [Radio_Surface] Loaded 2017-12-12T17:21:22.671Z,1513099282.671 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2017-12-12T17:21:22.672Z,1513099282.672 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 41C864E0 2017-12-12T17:21:22.672Z,1513099282.672 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 860 2017-12-12T17:21:22.863Z,1513099282.863 [PNI_TCM] Loaded 2017-12-12T17:21:22.863Z,1513099282.863 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2017-12-12T17:21:24.584Z,1513099284.584 [BPC1] Loaded 2017-12-12T17:21:24.584Z,1513099284.584 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2017-12-12T17:21:24.584Z,1513099284.584 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2017-12-12T17:21:24.585Z,1513099284.585 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2017-12-12T17:21:24.698Z,1513099284.698 [BuoyancyServo] Loaded 2017-12-12T17:21:24.699Z,1513099284.699 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2017-12-12T17:21:24.710Z,1513099284.710 [ElevatorServo] Loaded 2017-12-12T17:21:24.711Z,1513099284.711 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2017-12-12T17:21:24.721Z,1513099284.721 [MassServo] Loaded 2017-12-12T17:21:24.722Z,1513099284.722 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2017-12-12T17:21:24.733Z,1513099284.733 [RudderServo] Loaded 2017-12-12T17:21:24.733Z,1513099284.733 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2017-12-12T17:21:24.744Z,1513099284.744 [ThrusterServo] Loaded 2017-12-12T17:21:24.745Z,1513099284.745 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2017-12-12T17:21:24.745Z,1513099284.745 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2017-12-12T17:21:24.746Z,1513099284.746 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2017-12-12T17:21:24.864Z,1513099284.864 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2017-12-12T17:21:24.864Z,1513099284.864 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2017-12-12T17:21:24.901Z,1513099284.901 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2017-12-12T17:21:24.904Z,1513099284.904 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2017-12-12T17:21:24.905Z,1513099284.905 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2017-12-12T17:21:24.912Z,1513099284.912 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2017-12-12T17:21:24.913Z,1513099284.913 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 41D2D4E0 2017-12-12T17:21:24.914Z,1513099284.914 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 861 2017-12-12T17:21:24.918Z,1513099284.918 [Supervisor](INFO): Main Thread ID is 764 2017-12-12T17:21:24.918Z,1513099284.918 [Supervisor](DEBUG): Running supervisor. 2017-12-12T17:21:24.919Z,1513099284.919 [CommandLine ThreadHandler](INFO): Handler Thread ID is 862 2017-12-12T17:21:24.922Z,1513099284.922 [controlThread ThreadHandler](INFO): Handler Thread ID is 863 2017-12-12T17:21:24.922Z,1513099284.922 [controlThread](DEBUG): Initializing ControlThread 2017-12-12T17:21:24.923Z,1513099284.923 [SBIT](INFO): Initialize SBIT Component. 2017-12-12T17:21:24.924Z,1513099284.924 [SBIT](IMPORTANT): git: 2017-11-20A-26-g04509bd1 2017-12-12T17:21:24.924Z,1513099284.924 [SBIT](INFO): git hash: 04509bd12c114a9354ed8ab387fbec868dd64126 2017-12-12T17:21:24.925Z,1513099284.925 [SBIT](INFO): Kernel Reporting Different Release From Configuration. Kernel Expected: 2.6.27.8 Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty 2017-12-12T17:21:24.926Z,1513099284.926 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #646 PREEMPT Thu Feb 11 17:01:12 PST 2016 2017-12-12T17:21:24.927Z,1513099284.927 [SBIT](INFO): Beginning SBIT in 44.000000 seconds. 2017-12-12T17:21:24.927Z,1513099284.927 [IBIT](INFO): Initialize IBIT Component. 2017-12-12T17:21:24.928Z,1513099284.928 [CBIT](DEBUG): Initialize CBIT Component. 2017-12-12T17:21:24.928Z,1513099284.928 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2017-12-12T17:21:24.929Z,1513099284.929 [logger ThreadHandler](INFO): Handler Thread ID is 864 2017-12-12T17:21:24.955Z,1513099284.955 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 865 2017-12-12T17:21:24.956Z,1513099284.956 [CTD_NeilBrown](INFO): Powering down 2017-12-12T17:21:24.999Z,1513099284.999 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 866 2017-12-12T17:21:24.000Z,1513099285.000 [CTD_Seabird](INFO): Initializing 2017-12-12T17:21:25.031Z,1513099285.031 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2017-12-12T17:21:25.033Z,1513099285.033 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2017-12-12T17:21:25.034Z,1513099285.034 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2017-12-12T17:21:25.035Z,1513099285.035 [LoopControl](DEBUG): Initialize LoopControlComponent. 2017-12-12T17:21:25.035Z,1513099285.035 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2017-12-12T17:21:25.036Z,1513099285.036 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2017-12-12T17:21:25.036Z,1513099285.036 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2017-12-12T17:21:25.036Z,1513099285.036 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2017-12-12T17:21:25.037Z,1513099285.037 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2017-12-12T17:21:25.038Z,1513099285.038 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2017-12-12T17:21:25.039Z,1513099285.039 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-12-12T17:21:25.040Z,1513099285.040 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-12-12T17:21:25.040Z,1513099285.040 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-12-12T17:21:25.041Z,1513099285.041 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2017-12-12T17:21:25.041Z,1513099285.041 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2017-12-12T17:21:25.041Z,1513099285.041 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2017-12-12T17:21:25.042Z,1513099285.042 [NavChart](DEBUG): Initialize NavChart Navigation. 2017-12-12T17:21:25.042Z,1513099285.042 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2017-12-12T17:21:25.059Z,1513099285.059 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 868 2017-12-12T17:21:25.061Z,1513099285.061 [WetLabsBB2FL](INFO): Powering down 2017-12-12T17:21:25.088Z,1513099285.088 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2017-12-12T17:21:25.099Z,1513099285.099 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 869 2017-12-12T17:21:25.112Z,1513099285.112 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 870 2017-12-12T17:21:25.120Z,1513099285.120 [Radio_Surface](INFO): Powering up 2017-12-12T17:21:25.139Z,1513099285.139 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 871 2017-12-12T17:21:25.155Z,1513099285.155 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2017-12-12T17:21:25.155Z,1513099285.155 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2017-12-12T17:21:25.155Z,1513099285.155 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2017-12-12T17:21:25.155Z,1513099285.155 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2017-12-12T17:21:25.155Z,1513099285.155 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2017-12-12T17:21:25.156Z,1513099285.156 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2017-12-12T17:21:25.156Z,1513099285.156 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2017-12-12T17:21:25.156Z,1513099285.156 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2017-12-12T17:21:25.156Z,1513099285.156 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2017-12-12T17:21:25.156Z,1513099285.156 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2017-12-12T17:21:25.157Z,1513099285.157 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2017-12-12T17:21:25.157Z,1513099285.157 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2017-12-12T17:21:25.157Z,1513099285.157 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2017-12-12T17:21:25.157Z,1513099285.157 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2017-12-12T17:21:25.157Z,1513099285.157 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2017-12-12T17:21:25.158Z,1513099285.158 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2017-12-12T17:21:25.171Z,1513099285.171 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2017-12-12T17:21:25.224Z,1513099285.224 [MissionManager](DEBUG): 2017-12-12T17:21:25.225Z,1513099285.225 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2017-12-12T17:21:25.302Z,1513099285.302 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2017-12-12T17:21:25.327Z,1513099285.327 [Default:A.Wait](DEBUG): Construct Wait. 2017-12-12T17:21:25.328Z,1513099285.328 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2017-12-12T17:21:25.360Z,1513099285.360 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2017-12-12T17:21:25.360Z,1513099285.360 [CTD_Seabird](INFO): Powering down 2017-12-12T17:21:25.384Z,1513099285.384 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2017-12-12T17:21:25.407Z,1513099285.407 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2017-12-12T17:21:25.413Z,1513099285.413 [Default:E.Execute](DEBUG): Construct Execute. 2017-12-12T17:21:25.436Z,1513099285.436 [MissionManager](DEBUG):