2018-01-11T19:51:46.713Z,1515700306.713 [Supervisor](DEBUG): Initializing supervisor. 2018-01-11T19:51:46.716Z,1515700306.716 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0 2018-01-11T19:51:46.716Z,1515700306.716 [SyncHandler](INFO): Protected caller Thread ID is 768 2018-01-11T19:51:46.717Z,1515700306.717 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-01-11T19:51:46.718Z,1515700306.718 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0 2018-01-11T19:51:46.718Z,1515700306.718 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 769 2018-01-11T19:51:46.721Z,1515700306.721 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-01-11T19:51:46.732Z,1515700306.732 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-01-11T19:51:46.733Z,1515700306.733 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0 2018-01-11T19:51:46.734Z,1515700306.734 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 770 2018-01-11T19:51:46.734Z,1515700306.734 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-01-11T19:51:46.735Z,1515700306.735 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0 2018-01-11T19:51:46.736Z,1515700306.736 [logger ThreadHandler](INFO): Protected caller Thread ID is 771 2018-01-11T19:51:46.738Z,1515700306.738 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-01-11T19:51:46.738Z,1515700306.738 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-01-11T19:51:46.742Z,1515700306.742 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-01-11T19:51:46.925Z,1515700306.925 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-01-11T19:51:46.926Z,1515700306.926 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-01-11T19:51:47.069Z,1515700307.069 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-01-11T19:51:47.069Z,1515700307.069 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-01-11T19:51:47.385Z,1515700307.385 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-01-11T19:51:47.386Z,1515700307.386 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-01-11T19:51:47.524Z,1515700307.524 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-01-11T19:51:47.526Z,1515700307.526 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-01-11T19:51:47.611Z,1515700307.611 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-01-11T19:51:47.804Z,1515700307.804 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-01-11T19:51:47.806Z,1515700307.806 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-01-11T19:51:47.908Z,1515700307.908 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-01-11T19:51:47.908Z,1515700307.908 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-01-11T19:51:48.225Z,1515700308.225 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-01-11T19:51:48.226Z,1515700308.226 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-01-11T19:51:48.574Z,1515700308.574 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-01-11T19:51:48.574Z,1515700308.574 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-01-11T19:51:52.601Z,1515700312.601 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-01-11T19:51:52.602Z,1515700312.602 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-01-11T19:51:53.087Z,1515700313.087 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-01-11T19:51:53.087Z,1515700313.087 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-01-11T19:51:53.290Z,1515700313.290 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-01-11T19:51:53.291Z,1515700313.291 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-01-11T19:51:53.393Z,1515700313.393 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-01-11T19:51:53.394Z,1515700313.394 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-01-11T19:51:53.857Z,1515700313.857 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-01-11T19:51:55.572Z,1515700315.572 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-01-11T19:51:55.688Z,1515700315.688 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-01-11T19:51:55.690Z,1515700315.690 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 2018-01-11T19:51:55.693Z,1515700315.693 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 2018-01-11T19:51:55.969Z,1515700315.969 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-01-11T19:51:55.969Z,1515700315.969 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 2018-01-11T19:51:56.071Z,1515700316.071 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg 2018-01-11T19:51:56.179Z,1515700316.179 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 2018-01-11T19:51:56.281Z,1515700316.281 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 2018-01-11T19:51:56.371Z,1515700316.371 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 2018-01-11T19:51:56.522Z,1515700316.522 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 2018-01-11T19:51:56.694Z,1515700316.694 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 2018-01-11T19:51:56.806Z,1515700316.806 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 2018-01-11T19:51:56.907Z,1515700316.907 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 2018-01-11T19:51:56.996Z,1515700316.996 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 2018-01-11T19:51:57.164Z,1515700317.164 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/root/ 2018-01-11T19:51:57.165Z,1515700317.165 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2018-01-11T19:51:57.172Z,1515700317.172 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-01-11T19:51:57.326Z,1515700317.326 [SBIT](DEBUG): Construct Startup Built In Test. 2018-01-11T19:51:57.356Z,1515700317.356 [SBIT] Loaded 2018-01-11T19:51:57.356Z,1515700317.356 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-01-11T19:51:57.357Z,1515700317.357 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-01-11T19:51:57.384Z,1515700317.384 [IBIT] Loaded 2018-01-11T19:51:57.385Z,1515700317.385 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-01-11T19:51:57.388Z,1515700317.388 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-01-11T19:51:57.543Z,1515700317.543 [CBIT] Loaded 2018-01-11T19:51:57.543Z,1515700317.543 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-01-11T19:51:57.544Z,1515700317.544 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-01-11T19:51:57.545Z,1515700317.545 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-01-11T19:51:57.633Z,1515700317.633 [VerticalControl](DEBUG): Construct VerticalControl. 2018-01-11T19:51:57.732Z,1515700317.732 [VerticalControl] Loaded 2018-01-11T19:51:57.732Z,1515700317.732 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-01-11T19:51:57.733Z,1515700317.733 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-01-11T19:51:57.794Z,1515700317.794 [HorizontalControl] Loaded 2018-01-11T19:51:57.794Z,1515700317.794 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-01-11T19:51:57.795Z,1515700317.795 [SpeedControl](DEBUG): Construct SpeedControl. 2018-01-11T19:51:57.800Z,1515700317.800 [SpeedControl] Loaded 2018-01-11T19:51:57.800Z,1515700317.800 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-01-11T19:51:57.801Z,1515700317.801 [LoopControl](DEBUG): Construct LoopControl. 2018-01-11T19:51:57.802Z,1515700317.802 [LoopControl] Loaded 2018-01-11T19:51:57.802Z,1515700317.802 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-01-11T19:51:57.802Z,1515700317.802 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-01-11T19:51:57.803Z,1515700317.803 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-01-11T19:51:57.859Z,1515700317.859 [DepthRateCalculator] Loaded 2018-01-11T19:51:57.859Z,1515700317.859 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-01-11T19:51:57.865Z,1515700317.865 [PitchRateCalculator] Loaded 2018-01-11T19:51:57.865Z,1515700317.865 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-01-11T19:51:57.881Z,1515700317.881 [SpeedCalculator] Loaded 2018-01-11T19:51:57.881Z,1515700317.881 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-01-11T19:51:57.902Z,1515700317.902 [TempGradientCalculator] Loaded 2018-01-11T19:51:57.903Z,1515700317.903 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-01-11T19:51:57.918Z,1515700317.918 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2018-01-11T19:51:57.918Z,1515700317.918 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2018-01-11T19:51:57.923Z,1515700317.923 [YawRateCalculator] Loaded 2018-01-11T19:51:57.924Z,1515700317.924 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-01-11T19:51:57.924Z,1515700317.924 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-01-11T19:51:57.926Z,1515700317.926 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-01-11T19:51:58.049Z,1515700318.049 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2018-01-11T19:51:58.050Z,1515700318.050 [StratificationFrontDetector](DEBUG): (re)initializing 2018-01-11T19:51:58.050Z,1515700318.050 [StratificationFrontDetector] Loaded 2018-01-11T19:51:58.050Z,1515700318.050 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2018-01-11T19:51:58.051Z,1515700318.051 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-01-11T19:51:58.051Z,1515700318.051 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-01-11T19:51:58.321Z,1515700318.321 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-01-11T19:51:58.321Z,1515700318.321 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-01-11T19:51:58.436Z,1515700318.436 [DeadReckonUsingMultipleVelocitySources] Loaded 2018-01-11T19:51:58.436Z,1515700318.436 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2018-01-11T19:51:58.490Z,1515700318.490 [DeadReckonUsingSpeedCalculator] Loaded 2018-01-11T19:51:58.490Z,1515700318.490 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-01-11T19:51:58.544Z,1515700318.544 [DeadReckonWithRespectToSeafloor] Loaded 2018-01-11T19:51:58.545Z,1515700318.545 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2018-01-11T19:51:58.561Z,1515700318.561 [NavChart] Loaded 2018-01-11T19:51:58.561Z,1515700318.561 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-01-11T19:51:58.565Z,1515700318.565 [UniversalFixResidualReporter] Loaded 2018-01-11T19:51:58.565Z,1515700318.565 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-01-11T19:51:58.566Z,1515700318.566 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-01-11T19:51:58.568Z,1515700318.568 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-01-11T19:51:58.593Z,1515700318.593 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-01-11T19:51:58.594Z,1515700318.594 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-01-11T19:51:58.793Z,1515700318.793 [Aanderaa_O2] Loaded 2018-01-11T19:51:58.794Z,1515700318.794 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-01-11T19:51:58.842Z,1515700318.842 [CTD_Seabird] Loaded 2018-01-11T19:51:58.843Z,1515700318.843 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2018-01-11T19:51:58.844Z,1515700318.844 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4079C4E0 2018-01-11T19:51:58.844Z,1515700318.844 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 852 2018-01-11T19:51:58.859Z,1515700318.859 [PAR_Licor] Loaded 2018-01-11T19:51:58.859Z,1515700318.859 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2018-01-11T19:51:58.908Z,1515700318.908 [WetLabsBB2FL] Loaded 2018-01-11T19:51:58.909Z,1515700318.909 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-01-11T19:51:58.910Z,1515700318.910 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407CC4E0 2018-01-11T19:51:58.910Z,1515700318.910 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 853 2018-01-11T19:51:58.954Z,1515700318.954 [WetLabsSeaOWL_UV_A] Loaded 2018-01-11T19:51:58.954Z,1515700318.954 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2018-01-11T19:51:58.955Z,1515700318.955 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 407FC4E0 2018-01-11T19:51:58.956Z,1515700318.956 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 854 2018-01-11T19:51:58.956Z,1515700318.956 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-01-11T19:51:58.957Z,1515700318.957 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-01-11T19:52:02.037Z,1515700322.037 [DataOverHttps] Loaded 2018-01-11T19:52:02.038Z,1515700322.038 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-01-11T19:52:02.051Z,1515700322.051 [Depth_Keller] Loaded 2018-01-11T19:52:02.051Z,1515700322.051 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-01-11T19:52:02.056Z,1515700322.056 [DropWeight] Loaded 2018-01-11T19:52:02.056Z,1515700322.056 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-01-11T19:52:02.147Z,1515700322.147 [NAL9602] Loaded 2018-01-11T19:52:02.148Z,1515700322.148 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-01-11T19:52:02.153Z,1515700322.153 [Onboard] Loaded 2018-01-11T19:52:02.153Z,1515700322.153 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-01-11T19:52:02.160Z,1515700322.160 [Radio_Surface] Loaded 2018-01-11T19:52:02.161Z,1515700322.161 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-01-11T19:52:02.162Z,1515700322.162 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A444E0 2018-01-11T19:52:02.162Z,1515700322.162 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 855 2018-01-11T19:52:02.283Z,1515700322.283 [DAT] Loaded 2018-01-11T19:52:02.284Z,1515700322.284 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2018-01-11T19:52:02.476Z,1515700322.476 [PNI_TCM] Loaded 2018-01-11T19:52:02.477Z,1515700322.477 [ComponentRegistry](DEBUG): SyncComponent